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-trunk - Build # 732 - Still Failing
Date Tue, 07 Jul 2015 05:46:16 GMT
Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/732/

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

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

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=12709, name=collection3, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest]
Caused by: org.apache.solr.common.SolrException: Error reading cluster properties
	at __randomizedtesting.SeedInfo.seed([DF132F92C61724CA]:0)
	at org.apache.solr.common.cloud.ZkStateReader.getClusterProps(ZkStateReader.java:780)
	at org.apache.solr.common.cloud.ZkStateReader.getBaseUrlForNodeName(ZkStateReader.java:866)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:985)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:856)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:799)
	at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220)
	at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:894)
Caused by: org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /clusterprops.json
	at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
	at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
	at org.apache.zookeeper.ZooKeeper.exists(ZooKeeper.java:1045)
	at org.apache.solr.common.cloud.SolrZkClient$5.execute(SolrZkClient.java:319)
	at org.apache.solr.common.cloud.SolrZkClient$5.execute(SolrZkClient.java:316)
	at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:61)
	at org.apache.solr.common.cloud.SolrZkClient.exists(SolrZkClient.java:316)
	at org.apache.solr.common.cloud.ZkStateReader.getClusterProps(ZkStateReader.java:773)
	... 6 more


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

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

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=74616, name=collection4, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest]
Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:47562: Could not find collection : awholynewstresscollection_collection4_0
	at __randomizedtesting.SeedInfo.seed([DF132F92C61724CA]: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:1086)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:856)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:799)
	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 10609 lines...]
   [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
   [junit4]   2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_DF132F92C61724CA-001/init-core-data-001
   [junit4]   2> 1223913 INFO  (SUITE-CollectionsAPIDistributedZkTest-seed#[DF132F92C61724CA]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true)
   [junit4]   2> 1223913 INFO  (SUITE-CollectionsAPIDistributedZkTest-seed#[DF132F92C61724CA]-worker) [    ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /ff/g
   [junit4]   2> 1223917 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1223918 INFO  (Thread-8171) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1223918 INFO  (Thread-8171) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 1224018 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.ZkTestServer start zk server on port:46704
   [junit4]   2> 1224018 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1224019 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1224021 INFO  (zkCallback-731-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4e9426cb name:ZooKeeperConnection Watcher:127.0.0.1:46704 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1224021 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1224021 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1224021 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 1224024 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1224025 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1224025 INFO  (zkCallback-732-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@70dcc73b name:ZooKeeperConnection Watcher:127.0.0.1:46704/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1224026 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1224026 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1224026 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1
   [junit4]   2> 1224028 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
   [junit4]   2> 1224029 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
   [junit4]   2> 1224030 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
   [junit4]   2> 1224032 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
   [junit4]   2> 1224032 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 1224034 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
   [junit4]   2> 1224034 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
   [junit4]   2> 1224036 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 1224036 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 1224038 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
   [junit4]   2> 1224038 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
   [junit4]   2> 1224039 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
   [junit4]   2> 1224040 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
   [junit4]   2> 1224041 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
   [junit4]   2> 1224041 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
   [junit4]   2> 1224042 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
   [junit4]   2> 1224043 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
   [junit4]   2> 1224044 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
   [junit4]   2> 1224044 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
   [junit4]   2> 1224046 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 1224046 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 1224047 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
   [junit4]   2> 1224047 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
   [junit4]   2> 1224049 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
   [junit4]   2> 1224049 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
   [junit4]   2> 1224051 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1224052 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1224053 INFO  (zkCallback-733-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@29b24252 name:ZooKeeperConnection Watcher:127.0.0.1:46704/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1224053 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1224053 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1224055 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1224055 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1224056 INFO  (zkCallback-734-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4e76f908 name:ZooKeeperConnection Watcher:127.0.0.1:46704 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1224056 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1224057 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1224057 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 1224058 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1224059 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1224060 INFO  (zkCallback-735-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2a0397c2 name:ZooKeeperConnection Watcher:127.0.0.1:46704/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1224060 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1224060 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1224060 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf2/solrconfig.xml
   [junit4]   2> 1224061 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.xml
   [junit4]   2> 1224063 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf2/schema.xml
   [junit4]   2> 1224063 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/schema.xml
   [junit4]   2> 1224065 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf2/enumsConfig.xml
   [junit4]   2> 1224065 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/enumsConfig.xml
   [junit4]   2> 1224066 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf2/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 1224066 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 1224068 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf2/stopwords.txt
   [junit4]   2> 1224068 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/stopwords.txt
   [junit4]   2> 1224069 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf2/protwords.txt
   [junit4]   2> 1224070 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/protwords.txt
   [junit4]   2> 1224071 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf2/currency.xml
   [junit4]   2> 1224071 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/currency.xml
   [junit4]   2> 1224073 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf2/open-exchange-rates.json
   [junit4]   2> 1224073 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/open-exchange-rates.json
   [junit4]   2> 1224074 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf2/mapping-ISOLatin1Accent.txt
   [junit4]   2> 1224075 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/mapping-ISOLatin1Accent.txt
   [junit4]   2> 1224076 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf2/old_synonyms.txt
   [junit4]   2> 1224076 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/old_synonyms.txt
   [junit4]   2> 1224078 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf2/synonyms.txt
   [junit4]   2> 1224078 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/synonyms.txt
   [junit4]   2> 1224079 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/elevate.xml to /configs/conf2/elevate.xml
   [junit4]   2> 1224080 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/elevate.xml
   [junit4]   2> 1224383 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_DF132F92C61724CA-001/control-001/cores/collection1
   [junit4]   2> 1224385 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.e.j.s.Server jetty-9.2.11.v20150529
   [junit4]   2> 1224387 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@375776d1{/ff/g,null,AVAILABLE}
   [junit4]   2> 1224392 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.e.j.s.ServerConnector Started ServerConnector@4cb8a95a{SSL-http/1.1}{127.0.0.1:37478}
   [junit4]   2> 1224393 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.e.j.s.Server Started @1227724ms
   [junit4]   2> 1224393 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_DF132F92C61724CA-001/tempDir-001/control/data, hostContext=/ff/g, hostPort=37478, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_DF132F92C61724CA-001/control-001/cores}
   [junit4]   2> 1224394 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@c387f44
   [junit4]   2> 1224394 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_DF132F92C61724CA-001/control-001/'
   [junit4]   2> 1224409 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_DF132F92C61724CA-001/control-001/solr.xml
   [junit4]   2> 1224414 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_DF132F92C61724CA-001/control-001/cores
   [junit4]   2> 1224414 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.CoreContainer New CoreContainer 537061647
   [junit4]   2> 1224414 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_DF132F92C61724CA-001/control-001/]
   [junit4]   2> 1224414 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_DF132F92C61724CA-001/control-001/lib
   [junit4]   2> 1224414 WARN  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] 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-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_DF132F92C61724CA-001/control-001/lib).
   [junit4]   2> 1224421 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] 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> 1224423 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 1224425 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1224425 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1224425 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46704/solr
   [junit4]   2> 1224425 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 1224425 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1224426 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1224427 INFO  (zkCallback-737-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2b878a5f name:ZooKeeperConnection Watcher:127.0.0.1:46704 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1224428 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1224428 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1224430 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1224431 INFO  (zkCallback-738-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@507fd520 name:ZooKeeperConnection Watcher:127.0.0.1:46704/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1224431 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1224432 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
   [junit4]   2> 1224434 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
   [junit4]   2> 1224436 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
   [junit4]   2> 1224438 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
   [junit4]   2> 1224439 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
   [junit4]   2> 1224441 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 1224443 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 1224444 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 1224445 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37478_ff%2Fg
   [junit4]   2> 1224445 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:37478_ff%2Fg
   [junit4]   2> 1224446 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
   [junit4]   2> 1224448 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
   [junit4]   2> 1224449 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1224449 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94125763616636934-127.0.0.1:37478_ff%2Fg-n_0000000000
   [junit4]   2> 1224450 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:37478_ff%2Fg
   [junit4]   2> 1224450 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
   [junit4]   2> 1224451 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.Overseer Overseer (id=94125763616636934-127.0.0.1:37478_ff%2Fg-n_0000000000) starting
   [junit4]   2> 1224453 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
   [junit4]   2> 1224458 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000
   [junit4]   2> 1224459 INFO  (OverseerCollectionProcessor-94125763616636934-127.0.0.1:37478_ff%2Fg-n_0000000000) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.OverseerCollectionProcessor Process current queue of collection creations
   [junit4]   2> 1224459 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 1224459 INFO  (OverseerStateUpdate-94125763616636934-127.0.0.1:37478_ff%2Fg-n_0000000000) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.Overseer Starting to work on the main queue
   [junit4]   2> 1224462 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 1224463 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 1224464 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_DF132F92C61724CA-001/control-001/cores
   [junit4]   2> 1224464 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_DF132F92C61724CA-001/control-001/cores/collection1, collection=control_collection, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_DF132F92C61724CA-001/control-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=}
   [junit4]   2> 1224465 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_DF132F92C61724CA-001/control-001/cores/collection1/
   [junit4]   2> 1224465 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.CoresLocator Found 1 core definitions
   [junit4]   2> 1224466 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2
   [junit4]   2> 1224466 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection   x:collection1] o.a.s.c.ZkController publishing state=down
   [junit4]   2> 1224466 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection   x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
   [junit4]   2> 1224466 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 1224466 INFO  (zkCallback-738-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 1224466 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection   x:collection1] o.a.s.c.ZkController look for our core node name
   [junit4]   2> 1224467 INFO  (OverseerStateUpdate-94125763616636934-127.0.0.1:37478_ff%2Fg-n_0000000000) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"https://127.0.0.1:37478/ff/g",
   [junit4]   2>   "node_name":"127.0.0.1:37478_ff%2Fg",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"} current state version: 0
   [junit4]   2> 1224468 INFO  (OverseerStateUpdate-94125763616636934-127.0.0.1:37478_ff%2Fg-n_0000000000) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"https://127.0.0.1:37478/ff/g",
   [junit4]   2>   "node_name":"127.0.0.1:37478_ff%2Fg",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 1224468 INFO  (OverseerStateUpdate-94125763616636934-127.0.0.1:37478_ff%2Fg-n_0000000000) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection
   [junit4]   2> 1224468 INFO  (OverseerStateUpdate-94125763616636934-127.0.0.1:37478_ff%2Fg-n_0000000000) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
   [junit4]   2> 1224469 INFO  (zkCallback-738-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg    ] 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> 1224471 INFO  (zkCallback-738-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 1
   [junit4]   2> 1225467 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection   x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
   [junit4]   2> 1225467 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection   x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection
   [junit4]   2> 1225468 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection   x:collection1] o.a.s.c.ZkController Collection zkNode exists
   [junit4]   2> 1225468 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection
   [junit4]   2> 1225469 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 1225469 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection   x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_DF132F92C61724CA-001/control-001/cores/collection1/'
   [junit4]   2> 1225482 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection   x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 
   [junit4]   2> 1225487 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection   x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 1225491 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection   x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
   [junit4]   2> 1225499 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection   x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1225500 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection   x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml
   [junit4]   2> 1225505 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection   x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1225613 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 1225619 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection   x:collection1] o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 1225621 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection   x:collection1] o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 1225629 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection   x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1225631 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection   x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1225632 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1225633 WARN  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 1225633 WARN  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 1225633 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1225634 WARN  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 1225635 WARN  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 1225635 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection   x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection
   [junit4]   2> 1225635 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 1225635 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg 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-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_DF132F92C61724CA-001/control-001/cores/collection1/], dataDir=[null]
   [junit4]   2> 1225635 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg 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@5cbd4312
   [junit4]   2> 1225636 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg 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-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_DF132F92C61724CA-001/control-001/cores/collection1/data
   [junit4]   2> 1225636 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg 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-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_DF132F92C61724CA-001/control-001/cores/collection1/data/index/
   [junit4]   2> 1225636 WARN  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg 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-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_DF132F92C61724CA-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index...
   [junit4]   2> 1225637 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg 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-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_DF132F92C61724CA-001/control-001/cores/collection1/data/index
   [junit4]   2> 1225637 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg 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@75edb358
   [junit4]   2> 1225644 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg 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(NIOFSDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_DF132F92C61724CA-001/index-NIOFSDirectory-001 lockFactory=org.apache.lucene.store.NativeFSLockFactory@612fbd97),segFN=segments_1,generation=1}
   [junit4]   2> 1225644 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 1225645 INFO  (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:37478_ff%2Fg 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-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_DF132F92C61724CA-001/control-001/cores/collection1/data/
   [junit4]   2> 1225645 WARN  (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:37478_ff%2Fg c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_DF132F92C61724CA-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories.
   [junit4]   2> 1225650 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 1225650 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 1225650 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 1225650 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1225651 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1225651 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg 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> 1225651 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg 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> 1225651 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg 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> 1225651 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg 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> 1225653 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1225654 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1225655 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1225656 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1225659 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
   [junit4]   2> 1225659 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg 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> 1225660 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg 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> 1225660 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg 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> 1225661 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
   [junit4]   2> 1225661 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
   [junit4]   2> 1225661 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=25, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=2.9140625, floorSegmentMB=0.248046875, forceMergeDeletesPctAllowed=29.80819139854172, segmentsPerTier=48.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1225663 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg 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(NIOFSDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_DF132F92C61724CA-001/index-NIOFSDirectory-001 lockFactory=org.apache.lucene.store.NativeFSLockFactory@612fbd97),segFN=segments_1,generation=1}
   [junit4]   2> 1225663 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 1225663 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@43e8ecfe[collection1] main
   [junit4]   2> 1225664 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg 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> 1225665 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg 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> 1225665 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg 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> 1225665 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 1225665 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
   [junit4]   2> 1225666 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg 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> 1225666 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg 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> 1225666 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg 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> 1225666 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 1225667 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1225667 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg 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> 1225667 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg 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> 1225667 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg 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> 1225667 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg 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 1506012219699101696
   [junit4]   2> 1225667 INFO  (searcherExecutor-2888-thread-1-processing-n:127.0.0.1:37478_ff%2Fg x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:37478_ff%2Fg c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@43e8ecfe[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1225669 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg 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 1506012219699101696
   [junit4]   2> 1225670 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1
   [junit4]   2> 1225670 INFO  (coreLoadExecutor-2887-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 1225670 INFO  (coreZkRegister-2881-thread-1-processing-n:127.0.0.1:37478_ff%2Fg x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:37478_ff%2Fg c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController Register replica - core:collection1 address:https://127.0.0.1:37478/ff/g collection:control_collection shard:shard1
   [junit4]   2> 1225671 INFO  (coreZkRegister-2881-thread-1-processing-n:127.0.0.1:37478_ff%2Fg x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:37478_ff%2Fg 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> 1225674 INFO  (coreZkRegister-2881-thread-1-processing-n:127.0.0.1:37478_ff%2Fg x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:37478_ff%2Fg 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/94125763616636934-core_node1-n_0000000000
   [junit4]   2> 1225675 INFO  (coreZkRegister-2881-thread-1-processing-n:127.0.0.1:37478_ff%2Fg x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:37478_ff%2Fg c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1
   [junit4]   2> 1225676 INFO  (zkCallback-738-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 1225677 INFO  (coreZkRegister-2881-thread-1-processing-n:127.0.0.1:37478_ff%2Fg x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:37478_ff%2Fg c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 1225677 INFO  (coreZkRegister-2881-thread-1-processing-n:127.0.0.1:37478_ff%2Fg x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:37478_ff%2Fg 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> 1225677 INFO  (coreZkRegister-2881-thread-1-processing-n:127.0.0.1:37478_ff%2Fg x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:37478_ff%2Fg c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:37478/ff/g/collection1/
   [junit4]   2> 1225677 INFO  (OverseerStateUpdate-94125763616636934-127.0.0.1:37478_ff%2Fg-n_0000000000) [n:127.0.0.1:37478_ff%2Fg    ] 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> 1225677 INFO  (coreZkRegister-2881-thread-1-processing-n:127.0.0.1:37478_ff%2Fg x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:37478_ff%2Fg c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 1225677 INFO  (coreZkRegister-2881-thread-1-processing-n:127.0.0.1:37478_ff%2Fg x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:37478_ff%2Fg c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy https://127.0.0.1:37478/ff/g/collection1/ has no replicas
   [junit4]   2> 1225677 INFO  (coreZkRegister-2881-thread-1-processing-n:127.0.0.1:37478_ff%2Fg x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:37478_ff%2Fg c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:37478/ff/g/collection1/ shard1
   [junit4]   2> 1225678 INFO  (coreZkRegister-2881-thread-1-processing-n:127.0.0.1:37478_ff%2Fg x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:37478_ff%2Fg c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1
   [junit4]   2> 1225681 INFO  (zkCallback-738-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 1225681 INFO  (OverseerStateUpdate-94125763616636934-127.0.0.1:37478_ff%2Fg-n_0000000000) [n:127.0.0.1:37478_ff%2Fg    ] 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":"https://127.0.0.1:37478/ff/g",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "state":"active"} current state version: 1
   [junit4]   2> 1225784 INFO  (zkCallback-738-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg    ] 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> 1225785 INFO  (zkCallback-738-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 2
   [junit4]   2> 1225832 INFO  (coreZkRegister-2881-thread-1-processing-n:127.0.0.1:37478_ff%2Fg x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:37478_ff%2Fg c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are https://127.0.0.1:37478/ff/g/collection1/ and leader is https://127.0.0.1:37478/ff/g/collection1/
   [junit4]   2> 1225833 INFO  (coreZkRegister-2881-thread-1-processing-n:127.0.0.1:37478_ff%2Fg x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:37478_ff%2Fg c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:37478/ff/g
   [junit4]   2> 1225833 INFO  (coreZkRegister-2881-thread-1-processing-n:127.0.0.1:37478_ff%2Fg x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:37478_ff%2Fg c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 1225833 INFO  (coreZkRegister-2881-thread-1-processing-n:127.0.0.1:37478_ff%2Fg x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:37478_ff%2Fg c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active
   [junit4]   2> 1225833 INFO  (coreZkRegister-2881-thread-1-processing-n:127.0.0.1:37478_ff%2Fg x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:37478_ff%2Fg 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> 1225834 INFO  (zkCallback-738-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 1225836 INFO  (OverseerStateUpdate-94125763616636934-127.0.0.1:37478_ff%2Fg-n_0000000000) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "core_node_name":"core_node1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"https://127.0.0.1:37478/ff/g",
   [junit4]   2>   "node_name":"127.0.0.1:37478_ff%2Fg",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"} current state version: 2
   [junit4]   2> 1225837 INFO  (OverseerStateUpdate-94125763616636934-127.0.0.1:37478_ff%2Fg-n_0000000000) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "core_node_name":"core_node1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"https://127.0.0.1:37478/ff/g",
   [junit4]   2>   "node_name":"127.0.0.1:37478_ff%2Fg",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 1225940 INFO  (zkCallback-738-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg    ] 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> 1225942 INFO  (zkCallback-738-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 3
   [junit4]   2> 1225970 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1225970 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1225972 INFO  (zkCallback-740-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@19515a9c name:ZooKeeperConnection Watcher:127.0.0.1:46704/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1225972 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1225972 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1225972 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 1225975 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
   [junit4]   2> 1225975 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2
   [junit4]   2> 1225975 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1225980 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1225980 INFO  (zkCallback-741-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@52605ee4 name:ZooKeeperConnection Watcher:127.0.0.1:46704/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1225980 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1225980 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1225982 INFO  (zkCallback-738-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 1225983 INFO  (OverseerStateUpdate-94125763616636934-127.0.0.1:37478_ff%2Fg-n_0000000000) [n:127.0.0.1:37478_ff%2Fg    ] 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: 3
   [junit4]   2> 1225983 INFO  (OverseerStateUpdate-94125763616636934-127.0.0.1:37478_ff%2Fg-n_0000000000) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1
   [junit4]   2> 1226089 INFO  (OverseerStateUpdate-94125763616636934-127.0.0.1:37478_ff%2Fg-n_0000000000) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/collection1/state.json
   [junit4]   2> 1226090 INFO  (zkCallback-740-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> 1226090 INFO  (zkCallback-738-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg    ] 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> 1226092 INFO  (zkCallback-740-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 4
   [junit4]   2> 1226093 INFO  (zkCallback-738-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 4
   [junit4]   2> 1226286 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_DF132F92C61724CA-001/shard-1-001/cores/collection1
   [junit4]   2> 1226287 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_DF132F92C61724CA-001/shard-1-001
   [junit4]   2> 1226288 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.e.j.s.Server jetty-9.2.11.v20150529
   [junit4]   2> 1226289 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@13653c0c{/ff/g,null,AVAILABLE}
   [junit4]   2> 1226293 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.e.j.s.ServerConnector Started ServerConnector@3df8aa2d{SSL-http/1.1}{127.0.0.1:44106}
   [junit4]   2> 1226293 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.e.j.s.Server Started @1229624ms
   [junit4]   2> 1226293 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_DF132F92C61724CA-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/ff/g, hostPort=44106, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_DF132F92C61724CA-001/shard-1-001/cores}
   [junit4]   2> 1226294 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@c387f44
   [junit4]   2> 1226294 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_DF132F92C61724CA-001/shard-1-001/'
   [junit4]   2> 1226309 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_DF132F92C61724CA-001/shard-1-001/solr.xml
   [junit4]   2> 1226314 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_DF132F92C61724CA-001/shard-1-001/cores
   [junit4]   2> 1226314 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.CoreContainer New CoreContainer 1220872007
   [junit4]   2> 1226314 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_DF132F92C61724CA-001/shard-1-001/]
   [junit4]   2> 1226315 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_DF132F92C61724CA-001/shard-1-001/lib
   [junit4]   2> 1226315 WARN  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] 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-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_DF132F92C61724CA-001/shard-1-001/lib).
   [junit4]   2> 1226322 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] 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> 1226328 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 1226330 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1226330 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1226330 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46704/solr
   [junit4]   2> 1226330 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 1226330 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1226331 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1226332 INFO  (zkCallback-743-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@427c3b0b name:ZooKeeperConnection Watcher:127.0.0.1:46704 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1226333 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1226334 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1226337 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [n:127.0.0.1:44106_ff%2Fg    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1226337 INFO  (zkCallback-744-thread-1-processing-n:127.0.0.1:44106_ff%2Fg) [n:127.0.0.1:44106_ff%2Fg    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@58888dda name:ZooKeeperConnection Watcher:127.0.0.1:46704/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1226337 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [n:127.0.0.1:44106_ff%2Fg    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1226341 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [n:127.0.0.1:44106_ff%2Fg    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 1227345 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [n:127.0.0.1:44106_ff%2Fg    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44106_ff%2Fg
   [junit4]   2> 1227346 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [n:127.0.0.1:44106_ff%2Fg    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:44106_ff%2Fg
   [junit4]   2> 1227348 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [n:127.0.0.1:44106_ff%2Fg    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1227349 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [n:127.0.0.1:44106_ff%2Fg    ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94125763616636938-127.0.0.1:44106_ff%2Fg-n_0000000001
   [junit4]   2> 1227349 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [n:127.0.0.1:44106_ff%2Fg    ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/94125763616636934-127.0.0.1:37478_ff%2Fg-n_0000000000 to know if I could be the leader
   [junit4]   2> 1227350 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [n:127.0.0.1:44106_ff%2Fg    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 1227350 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [n:127.0.0.1:44106_ff%2Fg    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 1227351 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [n:127.0.0.1:44106_ff%2Fg    ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_DF132F92C61724CA-001/shard-1-001/cores
   [junit4]   2> 1227352 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [n:127.0.0.1:44106_ff%2Fg    ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_DF132F92C61724CA-001/shard-1-001/cores/collection1, collection=collection1, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_DF132F92C61724CA-001/shard-1-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=}
   [junit4]   2> 1227352 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [n:127.0.0.1:44106_ff%2Fg    ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_DF132F92C61724CA-001/shard-1-001/cores/collection1/
   [junit4]   2> 1227352 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [n:127.0.0.1:44106_ff%2Fg    ] o.a.s.c.CoresLocator Found 1 core definitions
   [junit4]   2> 1227353 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [n:127.0.0.1:44106_ff%2Fg    ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2
   [junit4]   2> 1227353 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DF132F92C61724CA]) [n:127.0.0.1:44106_ff%2Fg    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 1227354 INFO  (coreLoadExecutor-2898-thread-1-processing-n:127.0.0.1:44106_ff%2Fg) [n:127.0.0.1:44106_ff%2Fg c:collection1   x:collection1] o.a.s.c.ZkController publishing state=down
   [junit4]   2> 1227354 INFO  (coreLoadExecutor-2898-thread-1-processing-n:127.0.0.1:44106_ff%2Fg) [n:127.0.0.1:44106_ff%2Fg c:collection1   x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
   [junit4]   2> 1227355 INFO  (zkCallback-738-thread-1-processing-n:127.0.0.1:37478_ff%2Fg) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 1227355 INFO  (coreLoadExecutor-2898-thread-1-processing-n:127.0.0.1:44106_ff%2Fg) [n:127.0.0.1:44106_ff%2Fg c:collection1   x:collection1] o.a.s.c.ZkController Registering watch for external collection collection1
   [junit4]   2> 1227355 INFO  (coreLoadExecutor-2898-thread-1-processing-n:127.0.0.1:44106_ff%2Fg) [n:127.0.0.1:44106_ff%2Fg c:collection1   x:collection1] o.a.s.c.c.ZkStateReader addZkWatch collection1
   [junit4]   2> 1227355 INFO  (coreLoadExecutor-2898-thread-1-processing-n:127.0.0.1:44106_ff%2Fg) [n:127.0.0.1:44106_ff%2Fg c:collection1   x:collection1] o.a.s.c.c.ZkStateReader Updating collection state at /collections/collection1/state.json from ZooKeeper... 
   [junit4]   2> 1227355 INFO  (OverseerStateUpdate-94125763616636934-127.0.0.1:37478_ff%2Fg-n_0000000000) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"https://127.0.0.1:44106/ff/g",
   [junit4]   2>   "node_name":"127.0.0.1:44106_ff%2Fg",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"collection1",
   [junit4]   2>   "operation":"state"} current state version: 4
   [junit4]   2> 1227356 INFO  (OverseerStateUpdate-94125763616636934-127.0.0.1:37478_ff%2Fg-n_0000000000) [n:127.0.0.1:37478_ff%2Fg    ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"https://127.0.0.1:44106/ff/g",
   [junit4]   2>   "node_name

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

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:62)
   [junit4]   2> 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]   2> 	at java.lang.reflect.Method.invoke(Method.java:483)
   [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> 3866096 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[DF132F92C61724CA]-worker) [n:127.0.0.1:47562_ c:addReplicaColl s:shard2 r:core_node6 x:addReplicaColl_shard2_replica3] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> Jul 07, 2015 1:43:35 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 2 leaked thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene53): {other_tl1=Lucene50(blocksize=128), range_facet_l_dv=FSTOrd50, _version_=Lucene50(blocksize=128), multiDefault=PostingsFormat(name=Memory doPackFST= true), a_t=Lucene50(blocksize=128), intDefault=Lucene50(blocksize=128), a_i1=Lucene50(blocksize=128), id=FSTOrd50, range_facet_i_dv=Lucene50(blocksize=128), text=BlockTreeOrds(blocksize=128), range_facet_l=Lucene50(blocksize=128), timestamp=Lucene50(blocksize=128)}, docValues:{range_facet_l_dv=DocValuesFormat(name=Lucene50), range_facet_i_dv=DocValuesFormat(name=Direct), timestamp=DocValuesFormat(name=Direct)}, sim=RandomSimilarityProvider(queryNorm=true,coord=no): {}, locale=uk_UA, timezone=Asia/Ashgabat
   [junit4]   2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.8.0_25 (64-bit)/cpus=4,threads=2,free=136577072,total=532676608
   [junit4]   2> NOTE: All tests run in this JVM: [DocValuesMissingTest, HdfsChaosMonkeySafeLeaderTest, TestFunctionQuery, TestUpdate, MergeStrategyTest, SignatureUpdateProcessorFactoryTest, HdfsDirectoryFactoryTest, TestReplicationHandler, SimplePostToolTest, HdfsUnloadDistributedZkTest, TestRandomDVFaceting, SyncSliceTest, ParsingFieldUpdateProcessorsTest, VMParamsZkACLAndCredentialsProvidersTest, TestFileDictionaryLookup, BJQParserTest, TestMacros, TestPostingsSolrHighlighter, SolrIndexConfigTest, TestJsonRequest, TestTrackingShardHandlerFactory, RequestHandlersTest, TestCSVLoader, TestLeaderElectionZkExpiry, SuggesterFSTTest, CSVRequestHandlerTest, CollectionStateFormat2Test, QueryElevationComponentTest, TestSolr4Spatial, DeleteShardTest, HighlighterConfigTest, TimeZoneUtilsTest, SolrCoreTest, DistributedFacetPivotWhiteBoxTest, TestRebalanceLeaders, PrimitiveFieldTypeTest, TestSolrIndexConfig, DistributedIntervalFacetingTest, TestManagedSchemaFieldTypeResource, SegmentsInfoRequestHandlerTest, TestSimpleQParserPlugin, TestRestManager, ResponseHeaderTest, TestMergePolicyConfig, DistributedFacetPivotLongTailTest, TestRestoreCore, TestDefaultSearchFieldResource, DateFieldTest, UniqFieldsUpdateProcessorFactoryTest, TestExceedMaxTermLength, TestWordDelimiterFilterFactory, ZkNodePropsTest, TestCollapseQParserPlugin, FacetPivotSmallTest, TestJmxMonitoredMap, IgnoreCommitOptimizeUpdateProcessorFactoryTest, BasicZkTest, ConnectionManagerTest, AnalyticsMergeStrategyTest, TestCloudManagedSchema, TestFaceting, SolrRequestParserTest, TestNonDefinedSimilarityFactory, TestIntervalFaceting, TestIBSimilarityFactory, TestManagedSynonymFilterFactory, TestInfoStreamLogging, SuggesterWFSTTest, TestSortingResponseWriter, PeerSyncTest, TestCloudPivotFacet, TestIndexingPerformance, PingRequestHandlerTest, BasicDistributedZkTest, SolrTestCaseJ4Test, TestBadConfig, TestDistributedSearch, DataDrivenBlockJoinTest, TestSimpleTrackingShardHandler, ExternalFileFieldSortTest, FastVectorHighlighterTest, DeleteInactiveReplicaTest, TestTrieFacet, TestRTGBase, TestCursorMarkWithoutUniqueKey, TestHighlightDedupGrouping, AsyncMigrateRouteKeyTest, DistribDocExpirationUpdateProcessorTest, MigrateRouteKeyTest, OutOfBoxZkACLAndCredentialsProvidersTest, OverriddenZkACLAndCredentialsProvidersTest, OverseerRolesTest, SharedFSAutoReplicaFailoverTest, SharedFSAutoReplicaFailoverUtilsTest, SolrCloudExampleTest, SolrXmlInZkTest, TestAuthenticationFramework, TestCloudInspectUtil, TestCryptoKeys, TestDownShardTolerantSearch, TestMiniSolrCloudClusterSSL, TestReplicaProperties, TestRequestStatusCollectionAPI, HdfsCollectionsAPIDistributedZkTest]
   [junit4] Completed [503/514] on J0 in 925.31s, 1 test, 1 error <<< FAILURES!

[...truncated 45 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:533: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:474: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:61: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/extra-targets.xml:39: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build.xml:230: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/common-build.xml:513: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1426: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:981: There were test failures: 514 suites, 2022 tests, 2 errors, 20 ignored (10 assumptions)

Total time: 180 minutes 14 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
Sending email for trigger: Failure



Mime
View raw message