lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Jenkins Server <jenk...@builds.apache.org>
Subject [JENKINS] Lucene-Solr-NightlyTests-5.3 - Build # 15 - Still Failing
Date Mon, 18 Jan 2016 17:02:20 GMT
Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-5.3/15/

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

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

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


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

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

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




Build Log:
[...truncated 10310 lines...]
   [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
   [junit4]   2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7AB1EEB318D46550-001/init-core-data-001
   [junit4]   2> 152224 INFO  (SUITE-CollectionsAPIDistributedZkTest-seed#[7AB1EEB318D46550]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
   [junit4]   2> 152224 INFO  (SUITE-CollectionsAPIDistributedZkTest-seed#[7AB1EEB318D46550]-worker) [    ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /p/j
   [junit4]   2> 152230 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 152231 INFO  (Thread-3996) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 152231 INFO  (Thread-3996) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 152331 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.ZkTestServer start zk server on port:36882
   [junit4]   2> 152331 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 152332 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 152334 INFO  (zkCallback-37-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@50ea2583 name:ZooKeeperConnection Watcher:127.0.0.1:36882 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 152335 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 152335 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 152335 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 152340 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 152341 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 152342 INFO  (zkCallback-38-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3d993e70 name:ZooKeeperConnection Watcher:127.0.0.1:36882/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 152343 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 152345 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 152345 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1
   [junit4]   2> 152349 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
   [junit4]   2> 152352 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
   [junit4]   2> 152354 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
   [junit4]   2> 152357 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
   [junit4]   2> 152357 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 152361 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
   [junit4]   2> 152361 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
   [junit4]   2> 152364 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 152364 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 152367 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
   [junit4]   2> 152367 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
   [junit4]   2> 152370 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
   [junit4]   2> 152370 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
   [junit4]   2> 152373 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
   [junit4]   2> 152373 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
   [junit4]   2> 152375 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
   [junit4]   2> 152376 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
   [junit4]   2> 152378 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
   [junit4]   2> 152379 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
   [junit4]   2> 152381 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 152381 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 152384 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
   [junit4]   2> 152384 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
   [junit4]   2> 152387 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
   [junit4]   2> 152387 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
   [junit4]   2> 152391 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 152392 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 152393 INFO  (zkCallback-39-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@168310c1 name:ZooKeeperConnection Watcher:127.0.0.1:36882 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 152393 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 152394 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 152394 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 152402 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 152404 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 152405 INFO  (zkCallback-40-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7855b10 name:ZooKeeperConnection Watcher:127.0.0.1:36882/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 152406 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 152406 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 152407 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf2/solrconfig.xml
   [junit4]   2> 152407 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.xml
   [junit4]   2> 152412 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf2/schema.xml
   [junit4]   2> 152412 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/schema.xml
   [junit4]   2> 152416 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf2/enumsConfig.xml
   [junit4]   2> 152416 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/enumsConfig.xml
   [junit4]   2> 152419 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf2/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 152419 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 152422 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf2/stopwords.txt
   [junit4]   2> 152423 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/stopwords.txt
   [junit4]   2> 152426 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf2/protwords.txt
   [junit4]   2> 152426 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/protwords.txt
   [junit4]   2> 152430 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf2/currency.xml
   [junit4]   2> 152430 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/currency.xml
   [junit4]   2> 152434 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf2/open-exchange-rates.json
   [junit4]   2> 152434 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/open-exchange-rates.json
   [junit4]   2> 152437 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf2/mapping-ISOLatin1Accent.txt
   [junit4]   2> 152438 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/mapping-ISOLatin1Accent.txt
   [junit4]   2> 152441 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf2/old_synonyms.txt
   [junit4]   2> 152442 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/old_synonyms.txt
   [junit4]   2> 152444 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf2/synonyms.txt
   [junit4]   2> 152445 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/synonyms.txt
   [junit4]   2> 152447 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/elevate.xml to /configs/conf2/elevate.xml
   [junit4]   2> 152448 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/elevate.xml
   [junit4]   2> 152558 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7AB1EEB318D46550-001/control-001/cores/collection1
   [junit4]   2> 152560 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.e.j.s.Server jetty-9.2.11.v20150529
   [junit4]   2> 152563 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@51aef44e{/p/j,null,AVAILABLE}
   [junit4]   2> 152565 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.e.j.s.ServerConnector Started ServerConnector@2172218b{HTTP/1.1}{127.0.0.1:37125}
   [junit4]   2> 152565 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.e.j.s.Server Started @155579ms
   [junit4]   2> 152565 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7AB1EEB318D46550-001/tempDir-001/control/data, hostContext=/p/j, hostPort=37125, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7AB1EEB318D46550-001/control-001/cores}
   [junit4]   2> 152566 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@7b3cb2c6
   [junit4]   2> 152566 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7AB1EEB318D46550-001/control-001/'
   [junit4]   2> 152585 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 152586 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 152588 INFO  (zkCallback-41-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5d22fcbb name:ZooKeeperConnection Watcher:127.0.0.1:36882/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 152588 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 152588 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 152591 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
   [junit4]   2> 152591 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7AB1EEB318D46550-001/control-001/solr.xml
   [junit4]   2> 152612 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7AB1EEB318D46550-001/control-001/cores
   [junit4]   2> 152612 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.CoreContainer New CoreContainer 601835083
   [junit4]   2> 152613 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7AB1EEB318D46550-001/control-001/]
   [junit4]   2> 152613 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7AB1EEB318D46550-001/control-001/lib
   [junit4]   2> 152613 WARN  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7AB1EEB318D46550-001/control-001/lib).
   [junit4]   2> 152623 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] 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> 152625 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 152625 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 152626 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 152626 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36882/solr
   [junit4]   2> 152626 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 152626 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 152627 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 152629 INFO  (zkCallback-43-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@641d7948 name:ZooKeeperConnection Watcher:127.0.0.1:36882 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 152629 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 152630 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 152632 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 152634 INFO  (zkCallback-44-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@23182215 name:ZooKeeperConnection Watcher:127.0.0.1:36882/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 152634 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 152636 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
   [junit4]   2> 152639 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
   [junit4]   2> 152642 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
   [junit4]   2> 152645 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
   [junit4]   2> 152649 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
   [junit4]   2> 152652 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 152655 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 152658 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 152661 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 152662 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37125_p%2Fj
   [junit4]   2> 152663 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:37125_p%2Fj
   [junit4]   2> 152666 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
   [junit4]   2> 152668 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
   [junit4]   2> 152670 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 152671 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95232287596150790-127.0.0.1:37125_p%2Fj-n_0000000000
   [junit4]   2> 152672 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:37125_p%2Fj
   [junit4]   2> 152673 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
   [junit4]   2> 152675 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.Overseer Overseer (id=95232287596150790-127.0.0.1:37125_p%2Fj-n_0000000000) starting
   [junit4]   2> 152691 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
   [junit4]   2> 152704 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000
   [junit4]   2> 152712 INFO  (OverseerStateUpdate-95232287596150790-127.0.0.1:37125_p%2Fj-n_0000000000) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.Overseer Starting to work on the main queue
   [junit4]   2> 152710 INFO  (OverseerCollectionProcessor-95232287596150790-127.0.0.1:37125_p%2Fj-n_0000000000) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.OverseerCollectionProcessor Process current queue of collection creations
   [junit4]   2> 152717 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 152761 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 152762 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 152763 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7AB1EEB318D46550-001/control-001/cores
   [junit4]   2> 152764 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.SolrCore Created CoreDescriptor: {absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7AB1EEB318D46550-001/control-001/cores/collection1/, name=collection1, config=solrconfig.xml, shard=, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7AB1EEB318D46550-001/control-001/cores/collection1, dataDir=data/, coreNodeName=, collection=control_collection, loadOnStartup=true, schema=schema.xml, transient=false, configSetProperties=configsetprops.json}
   [junit4]   2> 152764 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7AB1EEB318D46550-001/control-001/cores/collection1/
   [junit4]   2> 152765 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.CoresLocator Found 1 core definitions
   [junit4]   2> 152766 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1
   [junit4]   2> 152767 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection   x:collection1] o.a.s.c.ZkController publishing state=down
   [junit4]   2> 152767 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection   x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
   [junit4]   2> 152767 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 152769 INFO  (zkCallback-44-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 152769 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection   x:collection1] o.a.s.c.ZkController look for our core node name
   [junit4]   2> 152770 INFO  (OverseerStateUpdate-95232287596150790-127.0.0.1:37125_p%2Fj-n_0000000000) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:37125/p/j",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "operation":"state",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "node_name":"127.0.0.1:37125_p%2Fj"} current state version: 0
   [junit4]   2> 152771 INFO  (OverseerStateUpdate-95232287596150790-127.0.0.1:37125_p%2Fj-n_0000000000) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:37125/p/j",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "operation":"state",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "node_name":"127.0.0.1:37125_p%2Fj"}
   [junit4]   2> 152771 INFO  (OverseerStateUpdate-95232287596150790-127.0.0.1:37125_p%2Fj-n_0000000000) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection
   [junit4]   2> 152772 INFO  (OverseerStateUpdate-95232287596150790-127.0.0.1:37125_p%2Fj-n_0000000000) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
   [junit4]   2> 152774 INFO  (zkCallback-44-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj    ] 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> 152778 INFO  (zkCallback-44-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 1
   [junit4]   2> 153769 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection   x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
   [junit4]   2> 153770 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection   x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection
   [junit4]   2> 153771 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection   x:collection1] o.a.s.c.ZkController Collection zkNode exists
   [junit4]   2> 153771 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection
   [junit4]   2> 153772 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 153773 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection   x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7AB1EEB318D46550-001/control-001/cores/collection1/'
   [junit4]   2> 153794 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection   x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 
   [junit4]   2> 153818 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection   x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 153838 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection   x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.3.2
   [junit4]   2> 153883 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection   x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 153885 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection   x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml
   [junit4]   2> 153935 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection   x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 154281 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 154300 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection   x:collection1] o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 154303 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection   x:collection1] o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 154332 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection   x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 154337 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection   x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 154342 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 154344 WARN  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 154344 WARN  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 154344 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 154345 WARN  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 154345 WARN  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 154347 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection   x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1
   [junit4]   2> 154347 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection   x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection
   [junit4]   2> 154347 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 154348 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7AB1EEB318D46550-001/control-001/cores/collection1/], dataDir=[null]
   [junit4]   2> 154348 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj 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@bbe5d86
   [junit4]   2> 154350 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7AB1EEB318D46550-001/control-001/cores/collection1/data
   [junit4]   2> 154350 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7AB1EEB318D46550-001/control-001/cores/collection1/data/index/
   [junit4]   2> 154350 WARN  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7AB1EEB318D46550-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index...
   [junit4]   2> 154354 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7AB1EEB318D46550-001/control-001/cores/collection1/data/index
   [junit4]   2> 154354 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=39, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
   [junit4]   2> 154365 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(SimpleFSDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7AB1EEB318D46550-001/index-SimpleFSDirectory-001 lockFactory=org.apache.lucene.store.NativeFSLockFactory@391533f1),segFN=segments_1,generation=1}
   [junit4]   2> 154365 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 154366 INFO  (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:37125_p%2Fj 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 /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7AB1EEB318D46550-001/control-001/cores/collection1/data/
   [junit4]   2> 154367 WARN  (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:37125_p%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7AB1EEB318D46550-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories.
   [junit4]   2> 154375 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 154376 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 154377 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 154377 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 154377 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 154377 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj 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> 154378 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj 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> 154378 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj 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> 154379 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj 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> 154382 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 154383 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 154385 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 154386 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 154393 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/threads,/update,/admin/mbeans,/admin/properties,/admin/plugins,/admin/luke,/admin/system,/update/json/docs,/update/csv,/admin/segments,/replication,/admin/file,/config,/schema,/admin/ping,/get,standard,/admin/logging,/update/json
   [junit4]   2> 154394 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj 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> 154395 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 154395 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj 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> 154396 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
   [junit4]   2> 154397 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
   [junit4]   2> 154397 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj 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@3b0f5e01
   [junit4]   2> 154405 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(SimpleFSDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7AB1EEB318D46550-001/index-SimpleFSDirectory-001 lockFactory=org.apache.lucene.store.NativeFSLockFactory@391533f1),segFN=segments_1,generation=1}
   [junit4]   2> 154406 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 154406 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@2274261e[collection1] main
   [junit4]   2> 154406 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj 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> 154408 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj 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> 154408 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj 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> 154410 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 154410 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
   [junit4]   2> 154410 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj 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> 154411 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj 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> 154412 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj 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> 154412 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 154412 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 154413 INFO  (searcherExecutor-134-thread-1-processing-r:core_node1 c:control_collection x:collection1 n:127.0.0.1:37125_p%2Fj s:shard1) [n:127.0.0.1:37125_p%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2274261e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 154414 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj 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> 154415 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj 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> 154415 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj 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> 154415 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj 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 1523716603826405376
   [junit4]   2> 154417 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 2 ms to seed version buckets with highest version 1523716603826405376
   [junit4]   2> 154417 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1
   [junit4]   2> 154418 INFO  (coreLoadExecutor-133-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 154419 INFO  (coreZkRegister-127-thread-1-processing-r:core_node1 c:control_collection x:collection1 n:127.0.0.1:37125_p%2Fj s:shard1) [n:127.0.0.1:37125_p%2Fj 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:37125/p/j collection:control_collection shard:shard1
   [junit4]   2> 154420 INFO  (coreZkRegister-127-thread-1-processing-r:core_node1 c:control_collection x:collection1 n:127.0.0.1:37125_p%2Fj s:shard1) [n:127.0.0.1:37125_p%2Fj 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> 154428 INFO  (coreZkRegister-127-thread-1-processing-r:core_node1 c:control_collection x:collection1 n:127.0.0.1:37125_p%2Fj s:shard1) [n:127.0.0.1:37125_p%2Fj 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/95232287596150790-core_node1-n_0000000000
   [junit4]   2> 154430 INFO  (coreZkRegister-127-thread-1-processing-r:core_node1 c:control_collection x:collection1 n:127.0.0.1:37125_p%2Fj s:shard1) [n:127.0.0.1:37125_p%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1
   [junit4]   2> 154442 INFO  (zkCallback-44-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 154443 INFO  (coreZkRegister-127-thread-1-processing-r:core_node1 c:control_collection x:collection1 n:127.0.0.1:37125_p%2Fj s:shard1) [n:127.0.0.1:37125_p%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 154444 INFO  (coreZkRegister-127-thread-1-processing-r:core_node1 c:control_collection x:collection1 n:127.0.0.1:37125_p%2Fj s:shard1) [n:127.0.0.1:37125_p%2Fj 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> 154444 INFO  (coreZkRegister-127-thread-1-processing-r:core_node1 c:control_collection x:collection1 n:127.0.0.1:37125_p%2Fj s:shard1) [n:127.0.0.1:37125_p%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:37125/p/j/collection1/
   [junit4]   2> 154444 INFO  (coreZkRegister-127-thread-1-processing-r:core_node1 c:control_collection x:collection1 n:127.0.0.1:37125_p%2Fj s:shard1) [n:127.0.0.1:37125_p%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 154444 INFO  (coreZkRegister-127-thread-1-processing-r:core_node1 c:control_collection x:collection1 n:127.0.0.1:37125_p%2Fj s:shard1) [n:127.0.0.1:37125_p%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:37125/p/j/collection1/ has no replicas
   [junit4]   2> 154444 INFO  (coreZkRegister-127-thread-1-processing-r:core_node1 c:control_collection x:collection1 n:127.0.0.1:37125_p%2Fj s:shard1) [n:127.0.0.1:37125_p%2Fj 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:37125/p/j/collection1/ shard1
   [junit4]   2> 154444 INFO  (OverseerStateUpdate-95232287596150790-127.0.0.1:37125_p%2Fj-n_0000000000) [n:127.0.0.1:37125_p%2Fj    ] 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> 154445 INFO  (coreZkRegister-127-thread-1-processing-r:core_node1 c:control_collection x:collection1 n:127.0.0.1:37125_p%2Fj s:shard1) [n:127.0.0.1:37125_p%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1
   [junit4]   2> 154446 INFO  (zkCallback-44-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj    ] 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> 154451 INFO  (zkCallback-44-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 2
   [junit4]   2> 154456 INFO  (OverseerStateUpdate-95232287596150790-127.0.0.1:37125_p%2Fj-n_0000000000) [n:127.0.0.1:37125_p%2Fj    ] 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:37125/p/j",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "state":"active"} current state version: 2
   [junit4]   2> 154459 INFO  (zkCallback-44-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 154460 INFO  (zkCallback-44-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj    ] 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> 154464 INFO  (zkCallback-44-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 3
   [junit4]   2> 154506 INFO  (coreZkRegister-127-thread-1-processing-r:core_node1 c:control_collection x:collection1 n:127.0.0.1:37125_p%2Fj s:shard1) [n:127.0.0.1:37125_p%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:37125/p/j/collection1/ and leader is http://127.0.0.1:37125/p/j/collection1/
   [junit4]   2> 154506 INFO  (coreZkRegister-127-thread-1-processing-r:core_node1 c:control_collection x:collection1 n:127.0.0.1:37125_p%2Fj s:shard1) [n:127.0.0.1:37125_p%2Fj 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:37125/p/j
   [junit4]   2> 154506 INFO  (coreZkRegister-127-thread-1-processing-r:core_node1 c:control_collection x:collection1 n:127.0.0.1:37125_p%2Fj s:shard1) [n:127.0.0.1:37125_p%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 154507 INFO  (coreZkRegister-127-thread-1-processing-r:core_node1 c:control_collection x:collection1 n:127.0.0.1:37125_p%2Fj s:shard1) [n:127.0.0.1:37125_p%2Fj c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active
   [junit4]   2> 154507 INFO  (coreZkRegister-127-thread-1-processing-r:core_node1 c:control_collection x:collection1 n:127.0.0.1:37125_p%2Fj s:shard1) [n:127.0.0.1:37125_p%2Fj 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> 154510 INFO  (zkCallback-44-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 154511 INFO  (OverseerStateUpdate-95232287596150790-127.0.0.1:37125_p%2Fj-n_0000000000) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:37125/p/j",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "operation":"state",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "core_node_name":"core_node1",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "node_name":"127.0.0.1:37125_p%2Fj"} current state version: 3
   [junit4]   2> 154513 INFO  (OverseerStateUpdate-95232287596150790-127.0.0.1:37125_p%2Fj-n_0000000000) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:37125/p/j",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "operation":"state",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "core_node_name":"core_node1",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "node_name":"127.0.0.1:37125_p%2Fj"}
   [junit4]   2> 154619 INFO  (zkCallback-44-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj    ] 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> 154623 INFO  (zkCallback-44-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 4
   [junit4]   2> 154770 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 154770 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 154772 INFO  (zkCallback-46-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@49360bd0 name:ZooKeeperConnection Watcher:127.0.0.1:36882/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 154773 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 154773 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 154773 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 154779 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
   [junit4]   2> 154779 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2
   [junit4]   2> 154779 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 154781 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 154782 INFO  (zkCallback-47-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@36e6e06e name:ZooKeeperConnection Watcher:127.0.0.1:36882/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 154782 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 154783 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 154786 INFO  (zkCallback-44-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 154788 INFO  (OverseerStateUpdate-95232287596150790-127.0.0.1:37125_p%2Fj-n_0000000000) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "operation":"create",
   [junit4]   2>   "name":"collection1",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "stateFormat":"2"} current state version: 4
   [junit4]   2> 154788 INFO  (OverseerStateUpdate-95232287596150790-127.0.0.1:37125_p%2Fj-n_0000000000) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1
   [junit4]   2> 154894 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7AB1EEB318D46550-001/shard-1-001/cores/collection1
   [junit4]   2> 154895 INFO  (OverseerStateUpdate-95232287596150790-127.0.0.1:37125_p%2Fj-n_0000000000) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/collection1/state.json
   [junit4]   2> 154896 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7AB1EEB318D46550-001/shard-1-001
   [junit4]   2> 154897 INFO  (zkCallback-44-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj    ] 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> 154897 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.e.j.s.Server jetty-9.2.11.v20150529
   [junit4]   2> 154897 INFO  (zkCallback-46-thread-1) [    ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
   [junit4]   2> 154904 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@29adde92{/p/j,null,AVAILABLE}
   [junit4]   2> 154904 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.e.j.s.ServerConnector Started ServerConnector@55ef589e{HTTP/1.1}{127.0.0.1:60559}
   [junit4]   2> 154905 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.e.j.s.Server Started @157918ms
   [junit4]   2> 154905 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7AB1EEB318D46550-001/tempDir-001/jetty1, hostContext=/p/j, solrconfig=solrconfig.xml, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7AB1EEB318D46550-001/shard-1-001/cores, hostPort=60559}
   [junit4]   2> 154905 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@7b3cb2c6
   [junit4]   2> 154905 INFO  (zkCallback-44-thread-1-processing-n:127.0.0.1:37125_p%2Fj) [n:127.0.0.1:37125_p%2Fj    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 5
   [junit4]   2> 154905 INFO  (zkCallback-46-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 5
   [junit4]   2> 154905 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7AB1EEB318D46550-001/shard-1-001/'
   [junit4]   2> 154925 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 154926 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 154928 INFO  (zkCallback-48-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@10b11d0c name:ZooKeeperConnection Watcher:127.0.0.1:36882/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 154930 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 154930 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 154933 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
   [junit4]   2> 154933 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7AB1EEB318D46550-001/shard-1-001/solr.xml
   [junit4]   2> 154953 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7AB1EEB318D46550-001/shard-1-001/cores
   [junit4]   2> 154953 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.CoreContainer New CoreContainer 98415100
   [junit4]   2> 154953 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7AB1EEB318D46550-001/shard-1-001/]
   [junit4]   2> 154954 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7AB1EEB318D46550-001/shard-1-001/lib
   [junit4]   2> 154954 WARN  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7AB1EEB318D46550-001/shard-1-001/lib).
   [junit4]   2> 154964 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] 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> 154965 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 154966 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 154966 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 154966 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36882/solr
   [junit4]   2> 154966 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 154967 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 154967 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 154969 INFO  (zkCallback-50-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6eb5d55a name:ZooKeeperConnection Watcher:127.0.0.1:36882 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 154969 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 154970 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 154974 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [n:127.0.0.1:60559_p%2Fj    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 154976 INFO  (zkCallback-51-thread-1-processing-n:127.0.0.1:60559_p%2Fj) [n:127.0.0.1:60559_p%2Fj    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@74b4cfb7 name:ZooKeeperConnection Watcher:127.0.0.1:36882/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 154976 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [n:127.0.0.1:60559_p%2Fj    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 154985 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [n:127.0.0.1:60559_p%2Fj    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 155998 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [n:127.0.0.1:60559_p%2Fj    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60559_p%2Fj
   [junit4]   2> 155999 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [n:127.0.0.1:60559_p%2Fj    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:60559_p%2Fj
   [junit4]   2> 156004 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [n:127.0.0.1:60559_p%2Fj    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 156005 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [n:127.0.0.1:60559_p%2Fj    ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95232287596150795-127.0.0.1:60559_p%2Fj-n_0000000001
   [junit4]   2> 156007 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [n:127.0.0.1:60559_p%2Fj    ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/95232287596150790-127.0.0.1:37125_p%2Fj-n_0000000000 to know if I could be the leader
   [junit4]   2> 156025 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [n:127.0.0.1:60559_p%2Fj    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 156025 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [n:127.0.0.1:60559_p%2Fj    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 156026 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [n:127.0.0.1:60559_p%2Fj    ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7AB1EEB318D46550-001/shard-1-001/cores
   [junit4]   2> 156027 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[7AB1EEB318D46550]) [n:127.0.0.1:60559_p%2Fj    ] o.a.s.c.SolrCore Created CoreDescriptor: {shard=, name=collection1, dataDir=data/, collection=collection1, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7AB1EEB318D46550-001/shard-1-001/cores/collection1/, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7AB1EEB318D46550-001/shard-1

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

tor interrupted: java.lang.InterruptedException: sleep interrupted
   [junit4]   2> 1743768 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[7AB1EEB318D46550]-worker) [n:127.0.0.1:44990_u%2Fkm c:addReplicaColl s:shard2 r:core_node6 x:addReplicaColl_shard2_replica3] o.m.log Stopped HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:0
   [junit4]   2> 1743869 ERROR (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[7AB1EEB318D46550]-worker) [n:127.0.0.1:44990_u%2Fkm c:addReplicaColl s:shard2 r:core_node6 x:addReplicaColl_shard2_replica3] o.a.h.m.l.MethodMetric Error invoking method getBlocksTotal
   [junit4]   2> java.lang.reflect.InvocationTargetException
   [junit4]   2> 	at sun.reflect.GeneratedMethodAccessor54.invoke(Unknown Source)
   [junit4]   2> 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]   2> 	at java.lang.reflect.Method.invoke(Method.java:606)
   [junit4]   2> 	at org.apache.hadoop.metrics2.lib.MethodMetric$2.snapshot(MethodMetric.java:111)
   [junit4]   2> 	at org.apache.hadoop.metrics2.lib.MethodMetric.snapshot(MethodMetric.java:144)
   [junit4]   2> 	at org.apache.hadoop.metrics2.lib.MetricsRegistry.snapshot(MetricsRegistry.java:387)
   [junit4]   2> 	at org.apache.hadoop.metrics2.lib.MetricsSourceBuilder$1.getMetrics(MetricsSourceBuilder.java:79)
   [junit4]   2> 	at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.getMetrics(MetricsSourceAdapter.java:195)
   [junit4]   2> 	at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.updateJmxCache(MetricsSourceAdapter.java:172)
   [junit4]   2> 	at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.getMBeanInfo(MetricsSourceAdapter.java:151)
   [junit4]   2> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getClassName(DefaultMBeanServerInterceptor.java:1804)
   [junit4]   2> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.safeGetClassName(DefaultMBeanServerInterceptor.java:1595)
   [junit4]   2> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.checkMBeanPermission(DefaultMBeanServerInterceptor.java:1813)
   [junit4]   2> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:430)
   [junit4]   2> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:415)
   [junit4]   2> 	at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:546)
   [junit4]   2> 	at org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:81)
   [junit4]   2> 	at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.stopMBeans(MetricsSourceAdapter.java:227)
   [junit4]   2> 	at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.stop(MetricsSourceAdapter.java:212)
   [junit4]   2> 	at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.stopSources(MetricsSystemImpl.java:461)
   [junit4]   2> 	at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.stop(MetricsSystemImpl.java:212)
   [junit4]   2> 	at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.shutdown(MetricsSystemImpl.java:592)
   [junit4]   2> 	at org.apache.hadoop.metrics2.lib.DefaultMetricsSystem.shutdownInstance(DefaultMetricsSystem.java:72)
   [junit4]   2> 	at org.apache.hadoop.metrics2.lib.DefaultMetricsSystem.shutdown(DefaultMetricsSystem.java:68)
   [junit4]   2> 	at org.apache.hadoop.hdfs.server.namenode.metrics.NameNodeMetrics.shutdown(NameNodeMetrics.java:145)
   [junit4]   2> 	at org.apache.hadoop.hdfs.server.namenode.NameNode.stop(NameNode.java:822)
   [junit4]   2> 	at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:1720)
   [junit4]   2> 	at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:1699)
   [junit4]   2> 	at org.apache.solr.cloud.hdfs.HdfsTestUtil.teardownClass(HdfsTestUtil.java:197)
   [junit4]   2> 	at org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.teardownClass(HdfsCollectionsAPIDistributedZkTest.java:50)
   [junit4]   2> 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   [junit4]   2> 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
   [junit4]   2> 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]   2> 	at java.lang.reflect.Method.invoke(Method.java:606)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:799)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
   [junit4]   2> 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> Caused by: java.lang.NullPointerException
   [junit4]   2> 	at org.apache.hadoop.hdfs.server.blockmanagement.BlocksMap.size(BlocksMap.java:198)
   [junit4]   2> 	at org.apache.hadoop.hdfs.server.blockmanagement.BlockManager.getTotalBlocks(BlockManager.java:3291)
   [junit4]   2> 	at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.getBlocksTotal(FSNamesystem.java:6223)
   [junit4]   2> 	... 53 more
   [junit4]   2> 1743873 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[7AB1EEB318D46550]-worker) [n:127.0.0.1:44990_u%2Fkm c:addReplicaColl s:shard2 r:core_node6 x:addReplicaColl_shard2_replica3] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> Jan 18, 2016 3:29:40 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 2 leaked thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene53): {_version_=PostingsFormat(name=MockRandom), range_facet_i_dv=PostingsFormat(name=Direct), range_facet_l=PostingsFormat(name=Direct), timestamp=PostingsFormat(name=Direct), intDefault=PostingsFormat(name=MockRandom), a_i1=PostingsFormat(name=Direct), range_facet_l_dv=PostingsFormat(name=LuceneVarGapFixedInterval), multiDefault=PostingsFormat(name=Direct), text=PostingsFormat(name=LuceneFixedGap), id=PostingsFormat(name=LuceneVarGapFixedInterval), other_tl1=PostingsFormat(name=MockRandom), a_t=PostingsFormat(name=Direct)}, docValues:{range_facet_i_dv=DocValuesFormat(name=Asserting), timestamp=DocValuesFormat(name=Asserting), range_facet_l_dv=DocValuesFormat(name=Lucene50)}, sim=DefaultSimilarity, locale=sr_BA, timezone=America/Atikokan
   [junit4]   2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.7.0_80 (64-bit)/cpus=4,threads=2,free=142108824,total=462946304
   [junit4]   2> NOTE: All tests run in this JVM: [SolrCoreCheckLockOnStartupTest, TestLRUStatsCache, RecoveryZkTest, TestDocSet, OutputWriterTest, TestHdfsUpdateLog, HdfsCollectionsAPIDistributedZkTest]
   [junit4] Completed [176/533] on J2 in 1392.44s, 1 test, 1 error <<< FAILURES!

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

Total time: 243 minutes 54 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