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 # 74 - Failure
Date Thu, 26 May 2016 15:07:01 GMT
Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-6.x/74/

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

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

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=50121, name=collection4, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest]
Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:50220: Could not find collection : awholynewstresscollection_collection4_4
	at __randomizedtesting.SeedInfo.seed([3B1B88D3E57A4A81]: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:404)
	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:357)
	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.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:994)




Build Log:
[...truncated 12402 lines...]
   [junit4] JVM J2: stdout was not empty, see: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/temp/junit4-J2-20160526_132605_163.sysout
   [junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
   [junit4] java.lang.OutOfMemoryError: GC overhead limit exceeded
   [junit4] Dumping heap to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/heapdumps/java_pid32111.hprof ...
   [junit4] Heap dump file created [605410946 bytes in 8.599 secs]
   [junit4] <<< JVM J2: EOF ----

   [junit4] JVM J2: stderr was not empty, see: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/temp/junit4-J2-20160526_132605_163.syserr
   [junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
   [junit4] WARN: Unhandled exception in event serialization. -> java.lang.OutOfMemoryError: GC overhead limit exceeded
   [junit4] <<< JVM J2: EOF ----

[...truncated 193 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_3B1B88D3E57A4A81-001/init-core-data-001
   [junit4]   2> 4664708 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[3B1B88D3E57A4A81]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
   [junit4]   2> 4664710 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[3B1B88D3E57A4A81]-worker) [    ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
   [junit4]   1> Formatting using clusterid: testClusterID
   [junit4]   2> 4664751 WARN  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[3B1B88D3E57A4A81]-worker) [    ] o.a.h.m.i.MetricsConfig Cannot locate configuration: tried hadoop-metrics2-namenode.properties,hadoop-metrics2.properties
   [junit4]   2> 4664770 WARN  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[3B1B88D3E57A4A81]-worker) [    ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
   [junit4]   2> 4664771 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[3B1B88D3E57A4A81]-worker) [    ] o.m.log jetty-6.1.26
   [junit4]   2> 4664781 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[3B1B88D3E57A4A81]-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_37901_hdfs____.jww2m8/webapp
   [junit4]   2> 4664869 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[3B1B88D3E57A4A81]-worker) [    ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet
   [junit4]   2> 4665164 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[3B1B88D3E57A4A81]-worker) [    ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:37901
   [junit4]   2> 4665293 WARN  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[3B1B88D3E57A4A81]-worker) [    ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
   [junit4]   2> 4665294 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[3B1B88D3E57A4A81]-worker) [    ] o.m.log jetty-6.1.26
   [junit4]   2> 4665321 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[3B1B88D3E57A4A81]-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_55423_datanode____.99yzlw/webapp
   [junit4]   2> 4665416 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[3B1B88D3E57A4A81]-worker) [    ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet
   [junit4]   2> 4665931 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[3B1B88D3E57A4A81]-worker) [    ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:55423
   [junit4]   2> 4666012 WARN  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[3B1B88D3E57A4A81]-worker) [    ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
   [junit4]   2> 4666013 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[3B1B88D3E57A4A81]-worker) [    ] o.m.log jetty-6.1.26
   [junit4]   2> 4666023 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[3B1B88D3E57A4A81]-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_50395_datanode____at169n/webapp
   [junit4]   2> 4666105 INFO  (IPC Server handler 4 on 56995) [    ] BlockStateChange BLOCK* processReport: from storage DS-89fe5e72-412c-439e-b703-2c77d89f0b7f node DatanodeRegistration(127.0.0.1, datanodeUuid=085d0575-d0f1-4982-beb3-3c6c00742ed9, infoPort=55423, ipcPort=49262, storageInfo=lv=-56;cid=testClusterID;nsid=2067215607;c=0), blocks: 0, hasStaleStorages: true, processing time: 8 msecs
   [junit4]   2> 4666105 INFO  (IPC Server handler 4 on 56995) [    ] BlockStateChange BLOCK* processReport: from storage DS-13678d63-3f3f-49d8-b5f8-a4c8bb33ffc7 node DatanodeRegistration(127.0.0.1, datanodeUuid=085d0575-d0f1-4982-beb3-3c6c00742ed9, infoPort=55423, ipcPort=49262, storageInfo=lv=-56;cid=testClusterID;nsid=2067215607;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs
   [junit4]   2> 4666152 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[3B1B88D3E57A4A81]-worker) [    ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet
   [junit4]   2> 4666481 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[3B1B88D3E57A4A81]-worker) [    ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:50395
   [junit4]   2> 4666600 INFO  (IPC Server handler 6 on 56995) [    ] BlockStateChange BLOCK* processReport: from storage DS-c7aadb99-eec9-4a7a-8f24-f76027ccde50 node DatanodeRegistration(127.0.0.1, datanodeUuid=a8bf57c5-a148-49a1-834d-b4d6cbb3e7a6, infoPort=50395, ipcPort=55835, storageInfo=lv=-56;cid=testClusterID;nsid=2067215607;c=0), blocks: 0, hasStaleStorages: true, processing time: 0 msecs
   [junit4]   2> 4666600 INFO  (IPC Server handler 6 on 56995) [    ] BlockStateChange BLOCK* processReport: from storage DS-b611679a-3383-42fb-835e-ddcfb1af3f40 node DatanodeRegistration(127.0.0.1, datanodeUuid=a8bf57c5-a148-49a1-834d-b4d6cbb3e7a6, infoPort=50395, ipcPort=55835, storageInfo=lv=-56;cid=testClusterID;nsid=2067215607;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs
   [junit4]   2> 4666778 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 4666778 INFO  (Thread-37028) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 4666778 INFO  (Thread-37028) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 4666878 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.ZkTestServer start zk server on port:53702
   [junit4]   2> 4666879 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 4666879 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 4666882 INFO  (zkCallback-2837-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6556a596 name:ZooKeeperConnection Watcher:127.0.0.1:53702 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 4666882 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 4666882 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 4666882 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 4666884 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 4666886 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 4666886 INFO  (zkCallback-2838-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4eb9daf name:ZooKeeperConnection Watcher:127.0.0.1:53702/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 4666886 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 4666887 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 4666887 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1
   [junit4]   2> 4666887 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
   [junit4]   2> 4666888 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
   [junit4]   2> 4666889 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
   [junit4]   2> 4666890 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] 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> 4666890 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 4666891 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] 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> 4666891 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
   [junit4]   2> 4666892 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] 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> 4666892 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 4666893 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] 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> 4666893 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
   [junit4]   2> 4666894 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] 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> 4666894 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
   [junit4]   2> 4666894 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] 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> 4666894 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
   [junit4]   2> 4666895 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] 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> 4666895 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
   [junit4]   2> 4666896 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] 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> 4666896 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
   [junit4]   2> 4666897 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] 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> 4666897 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 4666897 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] 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> 4666897 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
   [junit4]   2> 4666898 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] 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> 4666898 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
   [junit4]   2> 4666899 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 4666901 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 4666906 INFO  (zkCallback-2839-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2c5942fc name:ZooKeeperConnection Watcher:127.0.0.1:53702 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 4666906 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 4666906 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 4666906 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 4666912 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 4666918 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 4666925 INFO  (zkCallback-2840-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1c71266 name:ZooKeeperConnection Watcher:127.0.0.1:53702/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 4666926 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 4666926 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 4666926 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] 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> 4666926 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.xml
   [junit4]   2> 4666929 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] 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> 4666929 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/schema.xml
   [junit4]   2> 4666930 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] 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> 4666930 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/enumsConfig.xml
   [junit4]   2> 4666932 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] 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> 4666932 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 4666932 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] 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> 4666933 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/stopwords.txt
   [junit4]   2> 4666933 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] 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> 4666933 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/protwords.txt
   [junit4]   2> 4666934 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] 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> 4666934 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/currency.xml
   [junit4]   2> 4666935 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] 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> 4666935 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/open-exchange-rates.json
   [junit4]   2> 4666936 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] 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> 4666936 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/mapping-ISOLatin1Accent.txt
   [junit4]   2> 4666936 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] 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> 4666937 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/old_synonyms.txt
   [junit4]   2> 4666937 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] 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> 4666937 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/synonyms.txt
   [junit4]   2> 4666938 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] 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> 4666938 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/elevate.xml
   [junit4]   2> 4667012 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] 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_3B1B88D3E57A4A81-001/control-001/cores/collection1
   [junit4]   2> 4667013 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.e.j.s.Server jetty-9.3.8.v20160314
   [junit4]   2> 4667015 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7b20325c{/,null,AVAILABLE}
   [junit4]   2> 4667015 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.e.j.s.ServerConnector Started ServerConnector@46f67324{HTTP/1.1,[http/1.1]}{127.0.0.1:58994}
   [junit4]   2> 4667015 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.e.j.s.Server Started @4670418ms
   [junit4]   2> 4667015 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=hdfs://localhost:56995/hdfs__localhost_56995__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-6.x_solr_build_solr-core_test_J1_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_3B1B88D3E57A4A81-001_tempDir-002_control_data, hostContext=/, hostPort=58994, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_3B1B88D3E57A4A81-001/control-001/cores}
   [junit4]   2> 4667015 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93
   [junit4]   2> 4667015 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] 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_3B1B88D3E57A4A81-001/control-001'
   [junit4]   2> 4667016 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 4667016 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 4667016 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 4667016 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 4667021 INFO  (zkCallback-2841-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@694c185e name:ZooKeeperConnection Watcher:127.0.0.1:53702/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 4667021 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 4667022 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 4667023 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
   [junit4]   2> 4667023 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] 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_3B1B88D3E57A4A81-001/control-001/solr.xml
   [junit4]   2> 4667028 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] 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_3B1B88D3E57A4A81-001/control-001/cores
   [junit4]   2> 4667028 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.CoreContainer New CoreContainer 545372189
   [junit4]   2> 4667028 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] 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_3B1B88D3E57A4A81-001/control-001]
   [junit4]   2> 4667028 WARN  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] 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_3B1B88D3E57A4A81-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_3B1B88D3E57A4A81-001/control-001/lib
   [junit4]   2> 4667029 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] 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> 4667030 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 4667030 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 4667030 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 4667031 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:53702/solr
   [junit4]   2> 4667031 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 4667031 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 4667035 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 4667036 INFO  (zkCallback-2844-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4be8509e name:ZooKeeperConnection Watcher:127.0.0.1:53702 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 4667036 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 4667036 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 4667038 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [n:127.0.0.1:58994_    ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$10/1643806837@887026c
   [junit4]   2> 4667039 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [n:127.0.0.1:58994_    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 4667040 INFO  (zkCallback-2845-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6b80e2dd name:ZooKeeperConnection Watcher:127.0.0.1:53702/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 4667040 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [n:127.0.0.1:58994_    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 4667041 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [n:127.0.0.1:58994_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
   [junit4]   2> 4667047 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [n:127.0.0.1:58994_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
   [junit4]   2> 4667049 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [n:127.0.0.1:58994_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
   [junit4]   2> 4667051 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [n:127.0.0.1:58994_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
   [junit4]   2> 4667053 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [n:127.0.0.1:58994_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
   [junit4]   2> 4667070 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [n:127.0.0.1:58994_    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 4667071 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [n:127.0.0.1:58994_    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 4667071 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [n:127.0.0.1:58994_    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 4667072 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [n:127.0.0.1:58994_    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 4667073 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [n:127.0.0.1:58994_    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 4667073 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [n:127.0.0.1:58994_    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 4667073 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [n:127.0.0.1:58994_    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 4667074 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [n:127.0.0.1:58994_    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
   [junit4]   2> 4667075 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [n:127.0.0.1:58994_    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
   [junit4]   2> 4667076 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [n:127.0.0.1:58994_    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 4667076 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [n:127.0.0.1:58994_    ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95962650076184582-127.0.0.1:58994_-n_0000000000
   [junit4]   2> 4667076 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [n:127.0.0.1:58994_    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:58994_
   [junit4]   2> 4667076 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [n:127.0.0.1:58994_    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
   [junit4]   2> 4667077 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [n:127.0.0.1:58994_    ] o.a.s.c.Overseer Overseer (id=95962650076184582-127.0.0.1:58994_-n_0000000000) starting
   [junit4]   2> 4667077 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [n:127.0.0.1:58994_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
   [junit4]   2> 4667081 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [n:127.0.0.1:58994_    ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000
   [junit4]   2> 4667082 INFO  (OverseerCollectionConfigSetProcessor-95962650076184582-127.0.0.1:58994_-n_0000000000) [n:127.0.0.1:58994_    ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations
   [junit4]   2> 4667083 INFO  (OverseerStateUpdate-95962650076184582-127.0.0.1:58994_-n_0000000000) [n:127.0.0.1:58994_    ] o.a.s.c.Overseer Starting to work on the main queue
   [junit4]   2> 4667083 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [n:127.0.0.1:58994_    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58994_
   [junit4]   2> 4667084 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [n:127.0.0.1:58994_    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:58994_
   [junit4]   2> 4667087 INFO  (OverseerStateUpdate-95962650076184582-127.0.0.1:58994_-n_0000000000) [n:127.0.0.1:58994_    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 4667087 INFO  (zkCallback-2845-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_    ] 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> 4667088 INFO  (zkCallback-2845-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 4667103 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [n:127.0.0.1:58994_    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 4667103 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [n:127.0.0.1:58994_    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 4667104 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [n:127.0.0.1:58994_    ] 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_3B1B88D3E57A4A81-001/control-001/cores
   [junit4]   2> 4667104 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [n:127.0.0.1:58994_    ] 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> 4667104 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [n:127.0.0.1:58994_    ] 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_3B1B88D3E57A4A81-001/control-001/cores/collection1
   [junit4]   2> 4667104 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [n:127.0.0.1:58994_    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
   [junit4]   2> 4667105 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection   x:collection1] o.a.s.c.ZkController publishing state=down
   [junit4]   2> 4667105 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection   x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
   [junit4]   2> 4667105 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [n:127.0.0.1:58994_    ] 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> 4667105 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [n:127.0.0.1:58994_    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 4667106 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ 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> 4667106 INFO  (OverseerStateUpdate-95962650076184582-127.0.0.1:58994_-n_0000000000) [n:127.0.0.1:58994_    ] 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:58994",
   [junit4]   2>   "node_name":"127.0.0.1:58994_",
   [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> 4667106 INFO  (OverseerStateUpdate-95962650076184582-127.0.0.1:58994_-n_0000000000) [n:127.0.0.1:58994_    ] 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:58994",
   [junit4]   2>   "node_name":"127.0.0.1:58994_",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 4667106 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader Deleting data for [control_collection]
   [junit4]   2> 4667107 INFO  (OverseerStateUpdate-95962650076184582-127.0.0.1:58994_-n_0000000000) [n:127.0.0.1:58994_    ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection
   [junit4]   2> 4667107 INFO  (OverseerStateUpdate-95962650076184582-127.0.0.1:58994_-n_0000000000) [n:127.0.0.1:58994_    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
   [junit4]   2> 4667107 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection   x:collection1] o.a.s.c.ZkController look for our core node name
   [junit4]   2> 4667108 INFO  (zkCallback-2845-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_    ] 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> 4668108 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection   x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
   [junit4]   2> 4668108 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection   x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection
   [junit4]   2> 4668108 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection   x:collection1] o.a.s.c.ZkController Collection zkNode exists
   [junit4]   2> 4668108 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection]
   [junit4]   2> 4668109 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper
   [junit4]   2> 4668109 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ 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_3B1B88D3E57A4A81-001/control-001/cores/collection1'
   [junit4]   2> 4668109 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection   x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 4668109 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection   x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 4668110 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection   x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 
   [junit4]   2> 4668113 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection   x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 4668116 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection   x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.1.0
   [junit4]   2> 4668123 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection   x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 4668126 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection   x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 4668210 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 4668216 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection   x:collection1] o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 4668217 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection   x:collection1] o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 4668224 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection   x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 4668226 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection   x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 4668228 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 4668229 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 4668230 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ 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> 4668230 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection   x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection
   [junit4]   2> 4668230 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.HdfsDirectoryFactory
   [junit4]   2> 4668230 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory solr.hdfs.home=hdfs://localhost:56995/solr_hdfs_home
   [junit4]   2> 4668230 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled
   [junit4]   2> 4668231 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ 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_3B1B88D3E57A4A81-001/control-001/cores/collection1], dataDir=[hdfs://localhost:56995/solr_hdfs_home/control_collection/core_node1/data/]
   [junit4]   2> 4668231 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ 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@44edce9d
   [junit4]   2> 4668231 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:56995/solr_hdfs_home/control_collection/core_node1/data
   [junit4]   2> 4668242 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:56995/solr_hdfs_home/control_collection/core_node1/data
   [junit4]   2> 4668243 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=hdfs://localhost:56995/solr_hdfs_home/control_collection/core_node1/data/index/
   [junit4]   2> 4668249 WARN  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 'hdfs:/localhost:56995/solr_hdfs_home/control_collection/core_node1/data/index' doesn't exist. Creating new index...
   [junit4]   2> 4668249 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:56995/solr_hdfs_home/control_collection/core_node1/data/index
   [junit4]   2> 4668264 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:56995/solr_hdfs_home/control_collection/core_node1/data/index
   [junit4]   2> 4668265 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@ddad2c6
   [junit4]   2> 4668296 INFO  (IPC Server handler 3 on 56995) [    ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:59454 is added to blk_1073741825_1001{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-c7aadb99-eec9-4a7a-8f24-f76027ccde50:NORMAL:127.0.0.1:52848|RBW], ReplicaUnderConstruction[[DISK]DS-89fe5e72-412c-439e-b703-2c77d89f0b7f:NORMAL:127.0.0.1:59454|FINALIZED]]} size 0
   [junit4]   2> 4668296 INFO  (IPC Server handler 5 on 56995) [    ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:52848 is added to blk_1073741825_1001{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-c7aadb99-eec9-4a7a-8f24-f76027ccde50:NORMAL:127.0.0.1:52848|RBW], ReplicaUnderConstruction[[DISK]DS-89fe5e72-412c-439e-b703-2c77d89f0b7f:NORMAL:127.0.0.1:59454|FINALIZED]]} size 0
   [junit4]   2> 4668298 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ 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:56995/solr_hdfs_home/control_collection/core_node1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5db26f5b; maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1}
   [junit4]   2> 4668298 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 4668300 INFO  (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:58994_ 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:56995/solr_hdfs_home/control_collection/core_node1/data/
   [junit4]   2> 4668301 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 4668301 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 4668301 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 4668301 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 4668301 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 4668301 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ 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> 4668301 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ 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> 4668301 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ 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> 4668302 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ 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> 4668302 WARN  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ 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> 4668303 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 4668303 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 4668304 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 4668304 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 4668306 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ 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> 4668306 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ 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> 4668306 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ 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> 4668306 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ 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> 4668306 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.HdfsUpdateLog Initializing HdfsUpdateLog: tlogDfsReplication=2
   [junit4]   2> 4668315 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 4668315 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 4668316 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ 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=42, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
   [junit4]   2> 4668322 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ 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:56995/solr_hdfs_home/control_collection/core_node1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5db26f5b; maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1}
   [junit4]   2> 4668322 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 4668322 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3087609f[collection1] main]
   [junit4]   2> 4668322 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ 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> 4668323 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ 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> 4668323 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ 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> 4668324 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 4668324 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
   [junit4]   2> 4668324 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ 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> 4668325 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ 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> 4668325 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ 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> 4668325 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 4668325 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 4668327 INFO  (searcherExecutor-8173-thread-1-processing-n:127.0.0.1:58994_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58994_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3087609f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 4668327 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ 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> 4668327 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ 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> 4668327 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ 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> 4668328 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ 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 1535402402842148864
   [junit4]   2> 4668339 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 11.0ms to seed version buckets with highest version 1535402402842148864
   [junit4]   2> 4668339 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1
   [junit4]   2> 4668340 INFO  (coreLoadExecutor-8172-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 4668340 INFO  (coreZkRegister-8166-thread-1-processing-n:127.0.0.1:58994_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58994_ 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:58994 collection:control_collection shard:shard1
   [junit4]   2> 4668343 INFO  (coreZkRegister-8166-thread-1-processing-n:127.0.0.1:58994_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58994_ 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> 4668346 INFO  (coreZkRegister-8166-thread-1-processing-n:127.0.0.1:58994_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58994_ 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/95962650076184582-core_node1-n_0000000000
   [junit4]   2> 4668346 INFO  (coreZkRegister-8166-thread-1-processing-n:127.0.0.1:58994_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58994_ 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> 4668348 INFO  (coreZkRegister-8166-thread-1-processing-n:127.0.0.1:58994_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58994_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 4668348 INFO  (coreZkRegister-8166-thread-1-processing-n:127.0.0.1:58994_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58994_ 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> 4668348 INFO  (coreZkRegister-8166-thread-1-processing-n:127.0.0.1:58994_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58994_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:58994/collection1/
   [junit4]   2> 4668348 INFO  (coreZkRegister-8166-thread-1-processing-n:127.0.0.1:58994_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58994_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 4668348 INFO  (coreZkRegister-8166-thread-1-processing-n:127.0.0.1:58994_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58994_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:58994/collection1/ has no replicas
   [junit4]   2> 4668348 INFO  (OverseerStateUpdate-95962650076184582-127.0.0.1:58994_-n_0000000000) [n:127.0.0.1:58994_    ] 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> 4668349 INFO  (coreZkRegister-8166-thread-1-processing-n:127.0.0.1:58994_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58994_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1
   [junit4]   2> 4668350 INFO  (coreZkRegister-8166-thread-1-processing-n:127.0.0.1:58994_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58994_ 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/95962650076184582-core_node1-n_0000000000
   [junit4]   2> 4668350 INFO  (coreZkRegister-8166-thread-1-processing-n:127.0.0.1:58994_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58994_ 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:58994/collection1/ shard1
   [junit4]   2> 4668351 INFO  (OverseerStateUpdate-95962650076184582-127.0.0.1:58994_-n_0000000000) [n:127.0.0.1:58994_    ] 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:58994",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "state":"active"} current state version: 1
   [junit4]   2> 4668452 INFO  (zkCallback-2845-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_    ] 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> 4668501 INFO  (coreZkRegister-8166-thread-1-processing-n:127.0.0.1:58994_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58994_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:58994/collection1/ and leader is http://127.0.0.1:58994/collection1/
   [junit4]   2> 4668501 INFO  (coreZkRegister-8166-thread-1-processing-n:127.0.0.1:58994_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58994_ 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:58994
   [junit4]   2> 4668501 INFO  (coreZkRegister-8166-thread-1-processing-n:127.0.0.1:58994_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58994_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 4668501 INFO  (coreZkRegister-8166-thread-1-processing-n:127.0.0.1:58994_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58994_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active
   [junit4]   2> 4668501 INFO  (coreZkRegister-8166-thread-1-processing-n:127.0.0.1:58994_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58994_ 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> 4668502 INFO  (OverseerStateUpdate-95962650076184582-127.0.0.1:58994_-n_0000000000) [n:127.0.0.1:58994_    ] 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:56995/solr_hdfs_home/control_collection/core_node1/data/",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:58994",
   [junit4]   2>   "node_name":"127.0.0.1:58994_",
   [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:56995/solr_hdfs_home/control_collection/core_node1/data/tlog"} current state version: 2
   [junit4]   2> 4668502 INFO  (OverseerStateUpdate-95962650076184582-127.0.0.1:58994_-n_0000000000) [n:127.0.0.1:58994_    ] 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:56995/solr_hdfs_home/control_collection/core_node1/data/",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:58994",
   [junit4]   2>   "node_name":"127.0.0.1:58994_",
   [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:56995/solr_hdfs_home/control_collection/core_node1/data/tlog"}
   [junit4]   2> 4668604 INFO  (zkCallback-2845-thread-1-processing-n:127.0.0.1:58994_) [n:127.0.0.1:58994_    ] 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> 4668610 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 4668611 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 4668611 INFO  (zkCallback-2849-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@63f1ddb5 name:ZooKeeperConnection Watcher:127.0.0.1:53702/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 4668611 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 4668612 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 4668612 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 4668612 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 4668613 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 4668614 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
   [junit4]   2> 4668692 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] 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_3B1B88D3E57A4A81-001/shard-1-001/cores/collection1
   [junit4]   2> 4668693 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] 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_3B1B88D3E57A4A81-001/shard-1-001
   [junit4]   2> 4668693 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.e.j.s.Server jetty-9.3.8.v20160314
   [junit4]   2> 4668694 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1e3ced7d{/,null,AVAILABLE}
   [junit4]   2> 4668694 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.e.j.s.ServerConnector Started ServerConnector@2eaae7e1{HTTP/1.1,[http/1.1]}{127.0.0.1:58143}
   [junit4]   2> 4668694 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.e.j.s.Server Started @4672097ms
   [junit4]   2> 4668694 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=hdfs://localhost:56995/hdfs__localhost_56995__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-6.x_solr_build_solr-core_test_J1_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_3B1B88D3E57A4A81-001_tempDir-002_jetty1, solrconfig=solrconfig.xml, hostContext=/, hostPort=58143, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_3B1B88D3E57A4A81-001/shard-1-001/cores}
   [junit4]   2> 4668694 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93
   [junit4]   2> 4668695 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] 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_3B1B88D3E57A4A81-001/shard-1-001'
   [junit4]   2> 4668695 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 4668695 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 4668695 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 4668695 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 4668702 INFO  (zkCallback-2850-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@16df2987 name:ZooKeeperConnection Watcher:127.0.0.1:53702/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 4668702 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 4668703 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 4668703 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
   [junit4]   2> 4668703 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] 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_3B1B88D3E57A4A81-001/shard-1-001/solr.xml
   [junit4]   2> 4668707 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] 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/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_3B1B88D3E57A4A81-001/shard-1-001/cores
   [junit4]   2> 4668707 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.CoreContainer New CoreContainer 355792374
   [junit4]   2> 4668707 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] 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_3B1B88D3E57A4A81-001/shard-1-001]
   [junit4]   2> 4668707 WARN  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] 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_3B1B88D3E57A4A81-001/shard-1-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_3B1B88D3E57A4A81-001/shard-1-001/lib
   [junit4]   2> 4668707 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] 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> 4668708 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 4668708 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 4668708 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 4668708 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:53702/solr
   [junit4]   2> 4668708 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 4668708 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 4668709 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 4668710 INFO  (zkCallback-2853-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@10dca452 name:ZooKeeperConnection Watcher:127.0.0.1:53702 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 4668710 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 4668710 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 4668711 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [n:127.0.0.1:58143_    ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$10/1643806837@764bbec8
   [junit4]   2> 4668711 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [n:127.0.0.1:58143_    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 4668712 INFO  (zkCallback-2854-thread-1-processing-n:127.0.0.1:58143_) [n:127.0.0.1:58143_    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@36e2e5ae name:ZooKeeperConnection Watcher:127.0.0.1:53702/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 4668712 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [n:127.0.0.1:58143_    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 4668716 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [n:127.0.0.1:58143_    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 4668716 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [n:127.0.0.1:58143_    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 4668717 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [n:127.0.0.1:58143_    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 4668718 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [n:127.0.0.1:58143_    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 4668719 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [n:127.0.0.1:58143_    ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95962650076184586-127.0.0.1:58143_-n_0000000001
   [junit4]   2> 4668719 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [n:127.0.0.1:58143_    ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/95962650076184582-127.0.0.1:58994_-n_0000000000 to know if I could be the leader
   [junit4]   2> 4668720 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3B1B88D3E57A4A81]) [n:127.0.0.1:58143_    ] o.a.s.c.ZkController Publish node=127.0.0.1:58143_ as DOWN
   [junit4]   2> 4668721 INFO  (OverseerStateUpdate-95962650076184582-127.0.0.1:58994_-n_0000000000) [n:127.0.0.1:58994_    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "operation":"downnode",
   [junit4]   2>   "node_name":"127.0.0.1:58143_"} current state version: 3
   [junit4]   2> 4668721 INFO  (OverseerStateUpdate-95962650076184582-127.0.0.1:58994_-n_0000000000) [n:127.0.0.1:58994_    ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:58143_
   [junit4]   2> 4668823 INFO  (zkCallback-2854-thread-1-processing-n:127.0.0.1:58143_) [n:127.0.0.1:58143_    ] 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> 4668823 INFO  (zkCallback-2849-thread-1) [    ]

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

g-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-collective-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-event-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-exception-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-journal-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-normalization-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-operational-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-referral-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-schema-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-subtree-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-trigger-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-jdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-jdbm1-2.0.0-M2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-kerberos-codec-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-ldif-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-mavibot-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-protocol-kerberos-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-protocol-ldap-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-protocol-shared-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-xdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/api-all-1.0.0-M20.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/bcprov-jdk15-1.45.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/cglib-nodep-2.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/commons-collections-3.2.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/commons-math3-3.4.1.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/easymock-3.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/ehcache-core-2.4.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/hadoop-common-2.6.0-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/hadoop-hdfs-2.6.0-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/hadoop-minikdc-2.6.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/jackson-annotations-2.5.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/jackson-databind-2.5.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/jersey-core-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/jersey-server-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/jetty-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/jetty-util-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/mina-core-2.0.0-M5.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/mockito-core-1.9.5.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/objenesis-1.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/presto-parser-0.122.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/slice-0.10.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/build/analysis/icu/lucene-analyzers-icu-6.1.0-SNAPSHOT.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-analysis-extras/classes/java:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/build/queryparser/classes/test:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/contrib/analysis-extras/lib/icu4j-56.1.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-launcher.jar:/x1/jenkins/.ant/lib/ivy-2.3.0.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-antlr.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-regexp.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-resolver.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-commons-net.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jmf.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-oro.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-xalan2.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-commons-logging.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jsch.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-junit4.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-javamail.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jai.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jdepend.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-junit.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-bsf.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-swing.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-netrexx.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-bcel.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-testutil.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-log4j.jar:/x1/jenkins/jenkins-slave/tools/hudson.model.JDK/latest1.8/lib/tools.jar:/x1/jenkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.3.4.jar com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe -eventsfile /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/temp/junit4-J2-20160526_132605_163.events @/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/temp/junit4-J2-20160526_132605_163.suites -stdin
   [junit4] ERROR: JVM J2 ended with an exception: Quit event not received from the forked process? This may indicate JVM crash or runner bugs.
   [junit4] 	at com.carrotsearch.ant.tasks.junit4.JUnit4.executeSlave(JUnit4.java:1501)
   [junit4] 	at com.carrotsearch.ant.tasks.junit4.JUnit4.access$000(JUnit4.java:121)
   [junit4] 	at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:955)
   [junit4] 	at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:952)
   [junit4] 	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
   [junit4] 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
   [junit4] 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
   [junit4] 	at java.lang.Thread.run(Thread.java:745)

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: At least one slave process threw an exception, first: Quit event not received from the forked process? This may indicate JVM crash or runner bugs.

Total time: 269 minutes 12 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