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 # 742 - Still Failing
Date Fri, 17 Jul 2015 05:43:57 GMT
Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/742/

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

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

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=2916, name=collection4, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest]
Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:33787/_je: collection already exists: awholynewstresscollection_collection4_0
	at __randomizedtesting.SeedInfo.seed([952A6D9CE6F86E0A]: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.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1572)
	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1593)
	at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:887)


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

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

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=61089, 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:38745: Could not find collection : awholynewstresscollection_collection4_0
	at __randomizedtesting.SeedInfo.seed([952A6D9CE6F86E0A]: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 10422 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/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_952A6D9CE6F86E0A-001/init-core-data-001
   [junit4]   2> 868763 INFO  (SUITE-CollectionsAPIDistributedZkTest-seed#[952A6D9CE6F86E0A]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
   [junit4]   2> 868763 INFO  (SUITE-CollectionsAPIDistributedZkTest-seed#[952A6D9CE6F86E0A]-worker) [    ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /_je/
   [junit4]   2> 868766 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 868773 INFO  (Thread-1708) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 868773 INFO  (Thread-1708) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 868870 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.ZkTestServer start zk server on port:53435
   [junit4]   2> 868871 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 868886 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 868902 INFO  (zkCallback-143-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@f52119e name:ZooKeeperConnection Watcher:127.0.0.1:53435 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 868903 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 868903 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 868903 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 868910 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 868919 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 868922 INFO  (zkCallback-144-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2168bef3 name:ZooKeeperConnection Watcher:127.0.0.1:53435/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 868922 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 868922 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 868922 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1
   [junit4]   2> 868924 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
   [junit4]   2> 868926 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
   [junit4]   2> 868927 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
   [junit4]   2> 868928 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] 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> 868929 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 868931 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] 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> 868931 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
   [junit4]   2> 868932 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] 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> 868933 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 868934 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] 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> 868934 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
   [junit4]   2> 868935 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] 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> 868936 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
   [junit4]   2> 868937 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] 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> 868937 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
   [junit4]   2> 868939 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] 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> 868939 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
   [junit4]   2> 868940 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] 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> 868941 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
   [junit4]   2> 868942 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] 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> 868942 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 868944 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] 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> 868944 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
   [junit4]   2> 868945 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] 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> 868946 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
   [junit4]   2> 868948 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 868950 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 868959 INFO  (zkCallback-145-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@39b8bcc9 name:ZooKeeperConnection Watcher:127.0.0.1:53435 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 868959 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 868959 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 868959 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 868962 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 868966 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 868975 INFO  (zkCallback-146-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6b6d3b57 name:ZooKeeperConnection Watcher:127.0.0.1:53435/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 868975 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 868976 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 868976 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] 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> 868976 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.xml
   [junit4]   2> 868979 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] 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> 868980 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/schema.xml
   [junit4]   2> 868981 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] 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> 868981 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/enumsConfig.xml
   [junit4]   2> 868983 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] 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> 868983 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 868985 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] 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> 868985 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/stopwords.txt
   [junit4]   2> 868990 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] 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> 868990 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/protwords.txt
   [junit4]   2> 868992 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] 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> 868992 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/currency.xml
   [junit4]   2> 868993 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] 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> 868994 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/open-exchange-rates.json
   [junit4]   2> 868995 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] 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> 868996 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/mapping-ISOLatin1Accent.txt
   [junit4]   2> 868997 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] 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> 868997 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/old_synonyms.txt
   [junit4]   2> 868999 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] 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> 868999 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/synonyms.txt
   [junit4]   2> 869003 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] 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> 869003 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/elevate.xml
   [junit4]   2> 869324 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_952A6D9CE6F86E0A-001/control-001/cores/collection1
   [junit4]   2> 869326 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.e.j.s.Server jetty-9.2.11.v20150529
   [junit4]   2> 869328 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5fd2679a{/_je,null,AVAILABLE}
   [junit4]   2> 869336 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.e.j.s.ServerConnector Started ServerConnector@232818b{HTTP/1.1}{127.0.0.1:44153}
   [junit4]   2> 869336 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.e.j.s.Server Started @872541ms
   [junit4]   2> 869336 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] 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/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_952A6D9CE6F86E0A-001/tempDir-001/control/data, hostContext=/_je, hostPort=44153, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_952A6D9CE6F86E0A-001/control-001/cores}
   [junit4]   2> 869339 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a
   [junit4]   2> 869339 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_952A6D9CE6F86E0A-001/control-001/'
   [junit4]   2> 869355 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_952A6D9CE6F86E0A-001/control-001/solr.xml
   [junit4]   2> 869361 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_952A6D9CE6F86E0A-001/control-001/cores
   [junit4]   2> 869361 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.CoreContainer New CoreContainer 369019635
   [junit4]   2> 869361 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_952A6D9CE6F86E0A-001/control-001/]
   [junit4]   2> 869361 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_952A6D9CE6F86E0A-001/control-001/lib
   [junit4]   2> 869362 WARN  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] 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/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_952A6D9CE6F86E0A-001/control-001/lib).
   [junit4]   2> 869369 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] 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> 869371 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 869371 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 869371 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 869371 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:53435/solr
   [junit4]   2> 869372 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 869372 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 869373 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 869375 INFO  (zkCallback-148-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@78317a06 name:ZooKeeperConnection Watcher:127.0.0.1:53435 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 869378 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 869379 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 869383 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [n:127.0.0.1:44153__je    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 869389 INFO  (zkCallback-149-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@d01c485 name:ZooKeeperConnection Watcher:127.0.0.1:53435/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 869390 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [n:127.0.0.1:44153__je    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 869392 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [n:127.0.0.1:44153__je    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
   [junit4]   2> 869394 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [n:127.0.0.1:44153__je    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
   [junit4]   2> 869396 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [n:127.0.0.1:44153__je    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
   [junit4]   2> 869398 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [n:127.0.0.1:44153__je    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
   [junit4]   2> 869401 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [n:127.0.0.1:44153__je    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
   [junit4]   2> 869403 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [n:127.0.0.1:44153__je    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 869405 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [n:127.0.0.1:44153__je    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 869407 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [n:127.0.0.1:44153__je    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 869408 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [n:127.0.0.1:44153__je    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44153__je
   [junit4]   2> 869409 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [n:127.0.0.1:44153__je    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:44153__je
   [junit4]   2> 869410 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [n:127.0.0.1:44153__je    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
   [junit4]   2> 869411 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [n:127.0.0.1:44153__je    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
   [junit4]   2> 869413 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [n:127.0.0.1:44153__je    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 869414 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [n:127.0.0.1:44153__je    ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94182302675173381-127.0.0.1:44153__je-n_0000000000
   [junit4]   2> 869415 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [n:127.0.0.1:44153__je    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:44153__je
   [junit4]   2> 869415 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [n:127.0.0.1:44153__je    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
   [junit4]   2> 869417 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [n:127.0.0.1:44153__je    ] o.a.s.c.Overseer Overseer (id=94182302675173381-127.0.0.1:44153__je-n_0000000000) starting
   [junit4]   2> 869418 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [n:127.0.0.1:44153__je    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
   [junit4]   2> 869425 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [n:127.0.0.1:44153__je    ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000
   [junit4]   2> 869425 INFO  (OverseerCollectionProcessor-94182302675173381-127.0.0.1:44153__je-n_0000000000) [n:127.0.0.1:44153__je    ] o.a.s.c.OverseerCollectionProcessor Process current queue of collection creations
   [junit4]   2> 869426 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [n:127.0.0.1:44153__je    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 869429 INFO  (OverseerStateUpdate-94182302675173381-127.0.0.1:44153__je-n_0000000000) [n:127.0.0.1:44153__je    ] o.a.s.c.Overseer Starting to work on the main queue
   [junit4]   2> 869430 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [n:127.0.0.1:44153__je    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 869431 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [n:127.0.0.1:44153__je    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 869432 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [n:127.0.0.1:44153__je    ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_952A6D9CE6F86E0A-001/control-001/cores
   [junit4]   2> 869433 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [n:127.0.0.1:44153__je    ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_952A6D9CE6F86E0A-001/control-001/cores/collection1, collection=control_collection, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_952A6D9CE6F86E0A-001/control-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=}
   [junit4]   2> 869433 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [n:127.0.0.1:44153__je    ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_952A6D9CE6F86E0A-001/control-001/cores/collection1/
   [junit4]   2> 869433 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [n:127.0.0.1:44153__je    ] o.a.s.c.CoresLocator Found 1 core definitions
   [junit4]   2> 869434 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection   x:collection1] o.a.s.c.ZkController publishing state=down
   [junit4]   2> 869434 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection   x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
   [junit4]   2> 869435 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection   x:collection1] o.a.s.c.ZkController look for our core node name
   [junit4]   2> 869438 INFO  (OverseerStateUpdate-94182302675173381-127.0.0.1:44153__je-n_0000000000) [n:127.0.0.1:44153__je    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:44153/_je",
   [junit4]   2>   "node_name":"127.0.0.1:44153__je",
   [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> 869438 INFO  (OverseerStateUpdate-94182302675173381-127.0.0.1:44153__je-n_0000000000) [n:127.0.0.1:44153__je    ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:44153/_je",
   [junit4]   2>   "node_name":"127.0.0.1:44153__je",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 869439 INFO  (OverseerStateUpdate-94182302675173381-127.0.0.1:44153__je-n_0000000000) [n:127.0.0.1:44153__je    ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection
   [junit4]   2> 869439 INFO  (OverseerStateUpdate-94182302675173381-127.0.0.1:44153__je-n_0000000000) [n:127.0.0.1:44153__je    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
   [junit4]   2> 869447 INFO  (zkCallback-149-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je    ] 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> 869447 INFO  (zkCallback-149-thread-2-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 869449 INFO  (zkCallback-149-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 1
   [junit4]   2> 869451 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [n:127.0.0.1:44153__je    ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1
   [junit4]   2> 869451 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [n:127.0.0.1:44153__je    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 870435 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection   x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
   [junit4]   2> 870436 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection   x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection
   [junit4]   2> 870436 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection   x:collection1] o.a.s.c.ZkController Collection zkNode exists
   [junit4]   2> 870436 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection
   [junit4]   2> 870437 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 870437 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je 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/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_952A6D9CE6F86E0A-001/control-001/cores/collection1/'
   [junit4]   2> 870453 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection   x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 
   [junit4]   2> 870458 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection   x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 870462 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection   x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
   [junit4]   2> 870471 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection   x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 870473 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection   x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml
   [junit4]   2> 870478 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection   x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 870586 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 870594 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection   x:collection1] o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 870595 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection   x:collection1] o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 870611 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection   x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 870614 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection   x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 870616 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 870616 WARN  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 870617 WARN  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 870617 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 870617 WARN  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 870617 WARN  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 870619 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection   x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties
   [junit4]   2> org.apache.solr.core.SolrResourceNotFoundException: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1
   [junit4]   2> 	at org.apache.solr.cloud.ZkSolrResourceLoader.openResource(ZkSolrResourceLoader.java:99)
   [junit4]   2> 	at org.apache.solr.core.ConfigSetProperties.readFromResourceLoader(ConfigSetProperties.java:49)
   [junit4]   2> 	at org.apache.solr.core.ConfigSetService.createConfigSetProperties(ConfigSetService.java:114)
   [junit4]   2> 	at org.apache.solr.core.ConfigSetService.getConfig(ConfigSetService.java:76)
   [junit4]   2> 	at org.apache.solr.core.CoreContainer.create(CoreContainer.java:668)
   [junit4]   2> 	at org.apache.solr.core.CoreContainer$1.call(CoreContainer.java:397)
   [junit4]   2> 	at org.apache.solr.core.CoreContainer$1.call(CoreContainer.java:388)
   [junit4]   2> 	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
   [junit4]   2> 	at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$1.run(ExecutorUtil.java:156)
   [junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
   [junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 870619 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection   x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection
   [junit4]   2> 870619 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 870620 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je 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/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_952A6D9CE6F86E0A-001/control-001/cores/collection1/], dataDir=[null]
   [junit4]   2> 870620 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je 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@954819b
   [junit4]   2> 870620 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je 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/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_952A6D9CE6F86E0A-001/control-001/cores/collection1/data
   [junit4]   2> 870620 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je 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/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_952A6D9CE6F86E0A-001/control-001/cores/collection1/data/index/
   [junit4]   2> 870621 WARN  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je 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/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_952A6D9CE6F86E0A-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index...
   [junit4]   2> 870621 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je 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/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_952A6D9CE6F86E0A-001/control-001/cores/collection1/data/index
   [junit4]   2> 870622 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1194497405, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
   [junit4]   2> 870622 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je 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(RAMDirectory@294d9e8e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@919f8e5),segFN=segments_1,generation=1}
   [junit4]   2> 870622 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 870634 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 870634 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 870635 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 870635 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 870635 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 870635 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je 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> 870635 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je 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> 870636 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je 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> 870636 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je 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> 870638 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 870639 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 870640 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 870641 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 870642 INFO  (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:44153__je 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/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_952A6D9CE6F86E0A-001/control-001/cores/collection1/data/
   [junit4]   2> 870643 WARN  (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:44153__je 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/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_952A6D9CE6F86E0A-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories.
   [junit4]   2> 870655 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je 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> 870657 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je 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> 870659 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je 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> 870659 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je 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> 870660 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
   [junit4]   2> 870660 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
   [junit4]   2> 870664 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=16, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.426234771183681]
   [junit4]   2> 870666 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je 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(RAMDirectory@294d9e8e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@919f8e5),segFN=segments_1,generation=1}
   [junit4]   2> 870666 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 870666 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@7bfd50d2[collection1] main
   [junit4]   2> 870666 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je 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> 870670 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je 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> 870670 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je 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> 870670 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 870670 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
   [junit4]   2> 870671 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je 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> 870671 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je 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> 870671 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je 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> 870672 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 870672 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 870673 INFO  (searcherExecutor-992-thread-1-processing-n:127.0.0.1:44153__je x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44153__je c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7bfd50d2[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 870673 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je 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> 870673 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je 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> 870673 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je 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> 870673 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je 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 1506916844785631232
   [junit4]   2> 870689 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 16 ms to seed version buckets with highest version 1506916844785631232
   [junit4]   2> 870690 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1
   [junit4]   2> 870690 INFO  (coreLoadExecutor-991-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 870695 INFO  (coreZkRegister-985-thread-1-processing-n:127.0.0.1:44153__je x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44153__je c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController Register replica - core:collection1 address:http://127.0.0.1:44153/_je collection:control_collection shard:shard1
   [junit4]   2> 870698 INFO  (coreZkRegister-985-thread-1-processing-n:127.0.0.1:44153__je x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44153__je 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> 870702 INFO  (coreZkRegister-985-thread-1-processing-n:127.0.0.1:44153__je x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44153__je 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/94182302675173381-core_node1-n_0000000000
   [junit4]   2> 870704 INFO  (coreZkRegister-985-thread-1-processing-n:127.0.0.1:44153__je x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44153__je c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1
   [junit4]   2> 870706 INFO  (zkCallback-149-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 870706 INFO  (coreZkRegister-985-thread-1-processing-n:127.0.0.1:44153__je x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44153__je c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 870707 INFO  (OverseerStateUpdate-94182302675173381-127.0.0.1:44153__je-n_0000000000) [n:127.0.0.1:44153__je    ] 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> 870707 INFO  (coreZkRegister-985-thread-1-processing-n:127.0.0.1:44153__je x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44153__je 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> 870707 INFO  (coreZkRegister-985-thread-1-processing-n:127.0.0.1:44153__je x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44153__je c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:44153/_je/collection1/
   [junit4]   2> 870707 INFO  (coreZkRegister-985-thread-1-processing-n:127.0.0.1:44153__je x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44153__je c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 870707 INFO  (coreZkRegister-985-thread-1-processing-n:127.0.0.1:44153__je x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44153__je c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:44153/_je/collection1/ has no replicas
   [junit4]   2> 870708 INFO  (coreZkRegister-985-thread-1-processing-n:127.0.0.1:44153__je x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44153__je c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:44153/_je/collection1/ shard1
   [junit4]   2> 870708 INFO  (coreZkRegister-985-thread-1-processing-n:127.0.0.1:44153__je x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44153__je c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1
   [junit4]   2> 870712 INFO  (zkCallback-149-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 870713 INFO  (OverseerStateUpdate-94182302675173381-127.0.0.1:44153__je-n_0000000000) [n:127.0.0.1:44153__je    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "operation":"leader",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "base_url":"http://127.0.0.1:44153/_je",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "state":"active"} current state version: 1
   [junit4]   2> 870816 INFO  (zkCallback-149-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je    ] 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> 870818 INFO  (zkCallback-149-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 2
   [junit4]   2> 870864 INFO  (coreZkRegister-985-thread-1-processing-n:127.0.0.1:44153__je x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44153__je c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:44153/_je/collection1/ and leader is http://127.0.0.1:44153/_je/collection1/
   [junit4]   2> 870864 INFO  (coreZkRegister-985-thread-1-processing-n:127.0.0.1:44153__je x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44153__je c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:44153/_je
   [junit4]   2> 870864 INFO  (coreZkRegister-985-thread-1-processing-n:127.0.0.1:44153__je x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44153__je c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 870864 INFO  (coreZkRegister-985-thread-1-processing-n:127.0.0.1:44153__je x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44153__je c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active
   [junit4]   2> 870864 INFO  (coreZkRegister-985-thread-1-processing-n:127.0.0.1:44153__je x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44153__je 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> 870866 INFO  (zkCallback-149-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 870867 INFO  (OverseerStateUpdate-94182302675173381-127.0.0.1:44153__je-n_0000000000) [n:127.0.0.1:44153__je    ] 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":"http://127.0.0.1:44153/_je",
   [junit4]   2>   "node_name":"127.0.0.1:44153__je",
   [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> 870868 INFO  (OverseerStateUpdate-94182302675173381-127.0.0.1:44153__je-n_0000000000) [n:127.0.0.1:44153__je    ] 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":"http://127.0.0.1:44153/_je",
   [junit4]   2>   "node_name":"127.0.0.1:44153__je",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 870953 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 870960 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 870971 INFO  (zkCallback-149-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je    ] 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> 870973 INFO  (zkCallback-149-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 3
   [junit4]   2> 870982 INFO  (zkCallback-151-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@711f439d name:ZooKeeperConnection Watcher:127.0.0.1:53435/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 870983 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 870983 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 870983 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 870986 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
   [junit4]   2> 871316 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_952A6D9CE6F86E0A-001/shard-1-001/cores/collection1
   [junit4]   2> 871317 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_952A6D9CE6F86E0A-001/shard-1-001
   [junit4]   2> 871318 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.e.j.s.Server jetty-9.2.11.v20150529
   [junit4]   2> 871319 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1df90dc2{/_je,null,AVAILABLE}
   [junit4]   2> 871320 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.e.j.s.ServerConnector Started ServerConnector@62a42e33{HTTP/1.1}{127.0.0.1:57477}
   [junit4]   2> 871320 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.e.j.s.Server Started @874525ms
   [junit4]   2> 871320 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] 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/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_952A6D9CE6F86E0A-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/_je, hostPort=57477, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_952A6D9CE6F86E0A-001/shard-1-001/cores}
   [junit4]   2> 871321 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a
   [junit4]   2> 871321 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_952A6D9CE6F86E0A-001/shard-1-001/'
   [junit4]   2> 871336 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_952A6D9CE6F86E0A-001/shard-1-001/solr.xml
   [junit4]   2> 871341 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_952A6D9CE6F86E0A-001/shard-1-001/cores
   [junit4]   2> 871341 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.CoreContainer New CoreContainer 326118734
   [junit4]   2> 871341 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_952A6D9CE6F86E0A-001/shard-1-001/]
   [junit4]   2> 871341 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_952A6D9CE6F86E0A-001/shard-1-001/lib
   [junit4]   2> 871341 WARN  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] 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/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_952A6D9CE6F86E0A-001/shard-1-001/lib).
   [junit4]   2> 871349 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] 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> 871350 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 871350 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 871351 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 871351 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:53435/solr
   [junit4]   2> 871351 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 871351 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 871352 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 871361 INFO  (zkCallback-153-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4c9297ad name:ZooKeeperConnection Watcher:127.0.0.1:53435 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 871363 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 871364 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 871370 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [n:127.0.0.1:57477__je    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 871372 INFO  (zkCallback-154-thread-1-processing-n:127.0.0.1:57477__je) [n:127.0.0.1:57477__je    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@39cee76b name:ZooKeeperConnection Watcher:127.0.0.1:53435/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 871373 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [n:127.0.0.1:57477__je    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 871377 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [n:127.0.0.1:57477__je    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 872382 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [n:127.0.0.1:57477__je    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57477__je
   [junit4]   2> 872383 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [n:127.0.0.1:57477__je    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:57477__je
   [junit4]   2> 872390 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [n:127.0.0.1:57477__je    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 872391 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [n:127.0.0.1:57477__je    ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94182302675173384-127.0.0.1:57477__je-n_0000000001
   [junit4]   2> 872392 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [n:127.0.0.1:57477__je    ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/94182302675173381-127.0.0.1:44153__je-n_0000000000 to know if I could be the leader
   [junit4]   2> 872393 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [n:127.0.0.1:57477__je    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 872393 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [n:127.0.0.1:57477__je    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 872394 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [n:127.0.0.1:57477__je    ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_952A6D9CE6F86E0A-001/shard-1-001/cores
   [junit4]   2> 872395 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [n:127.0.0.1:57477__je    ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_952A6D9CE6F86E0A-001/shard-1-001/cores/collection1, collection=collection1, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_952A6D9CE6F86E0A-001/shard-1-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=}
   [junit4]   2> 872395 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [n:127.0.0.1:57477__je    ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_952A6D9CE6F86E0A-001/shard-1-001/cores/collection1/
   [junit4]   2> 872395 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [n:127.0.0.1:57477__je    ] o.a.s.c.CoresLocator Found 1 core definitions
   [junit4]   2> 872398 INFO  (coreLoadExecutor-1002-thread-1-processing-n:127.0.0.1:57477__je) [n:127.0.0.1:57477__je c:collection1   x:collection1] o.a.s.c.ZkController publishing state=down
   [junit4]   2> 872398 INFO  (coreLoadExecutor-1002-thread-1-processing-n:127.0.0.1:57477__je) [n:127.0.0.1:57477__je c:collection1   x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
   [junit4]   2> 872399 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [n:127.0.0.1:57477__je    ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1
   [junit4]   2> 872399 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[952A6D9CE6F86E0A]) [n:127.0.0.1:57477__je    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 872399 INFO  (coreLoadExecutor-1002-thread-1-processing-n:127.0.0.1:57477__je) [n:127.0.0.1:57477__je c:collection1   x:collection1] o.a.s.c.ZkController look for our core node name
   [junit4]   2> 872399 INFO  (zkCallback-149-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 872400 INFO  (OverseerStateUpdate-94182302675173381-127.0.0.1:44153__je-n_0000000000) [n:127.0.0.1:44153__je    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:57477/_je",
   [junit4]   2>   "node_name":"127.0.0.1:57477__je",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"collection1",
   [junit4]   2>   "operation":"state"} current state version: 3
   [junit4]   2> 872401 INFO  (OverseerStateUpdate-94182302675173381-127.0.0.1:44153__je-n_0000000000) [n:127.0.0.1:44153__je    ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:57477/_je",
   [junit4]   2>   "node_name":"127.0.0.1:57477__je",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"collection1",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 872401 INFO  (OverseerStateUpdate-94182302675173381-127.0.0.1:44153__je-n_0000000000) [n:127.0.0.1:44153__je    ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1
   [junit4]   2> 872401 INFO  (OverseerStateUpdate-94182302675173381-127.0.0.1:44153__je-n_0000000000) [n:127.0.0.1:44153__je    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2
   [junit4]   2> 872504 INFO  (zkCallback-151-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: 2)
   [junit4]   2> 872504 INFO  (zkCallback-149-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je    ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 872504 INFO  (zkCallback-154-thread-1-processing-n:127.0.0.1:57477__je) [n:127.0.0.1:57477__je    ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 872508 INFO  (zkCallback-151-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 4
   [junit4]   2> 872508 INFO  (zkCallback-154-thread-1-processing-n:127.0.0.1:57477__je) [n:127.0.0.1:57477__je    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 4
   [junit4]   2> 872508 INFO  (zkCallback-149-thread-1-processing-n:127.0.0.1:44153__je) [n:127.0.0.1:44153__je    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 4
   [junit4]   2> 873400 INFO  (coreLoadExecutor-1002-thread-1-processing-n:127.0.0.1:57477__je) [n:127.0.0.1:57477__je c:collection1   x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
   [junit4]   2> 873400 INFO  (coreLoadExecutor-1002-thread-1-processing-n:127.0.0.1:57477__je) [n:127.0.0.1:57477__je c:collection1   x:collection1] o.a.s.c.ZkController Check for collection zkNode:collection1
   [junit4]   2> 873401 INFO  (coreLoadExecutor-1002-thread-1-processing-n:127.0.0.1:57477__je) [n:127.0.0.1:57477__je c:collection1   x:collection1] o.a.s.c.ZkController Collection zkNode exists
   [junit4]   2> 873401 INFO  (coreLoadExecutor-1002-thread-1-processing-n:127.0.0.1:57477__je) [n:127.0.0.1:57477__je c:collection1   x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/collection1
   [junit4]   2> 873402 INFO  (coreLoadExecutor-1002-thread-1-processing-n:127.0.0.1:57477__je) [n:127.0.0.1:57477__je c:collection1   x:collection1] o.a.s.c.c.ZkStateReader path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 873402 INFO  (coreLoadExecutor-1002-thread-1-processing-n:127.0.0.1:57477__je) [n:127.0.0.1:57477__je c:collection1   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/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_952A6D9CE6F86E0A-001/shard-1-001/cores/collection1/'
 

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

]   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> 4705059 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[952A6D9CE6F86E0A]-worker) [n:127.0.0.1:38745_ c:addReplicaColl s:shard1 r:core_node7 x:propertyDotName] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> Jul 17, 2015 1:42:06 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=PostingsFormat(name=Asserting), range_facet_l_dv=PostingsFormat(name=LuceneVarGapDocFreqInterval), _version_=PostingsFormat(name=Asserting), multiDefault=FSTOrd50, a_t=FSTOrd50, intDefault=PostingsFormat(name=Asserting), a_i1=FSTOrd50, id=PostingsFormat(name=LuceneVarGapDocFreqInterval), range_facet_i_dv=FSTOrd50, text=Lucene50(blocksize=128), range_facet_l=FSTOrd50, timestamp=FSTOrd50}, docValues:{range_facet_l_dv=DocValuesFormat(name=Lucene50), range_facet_i_dv=DocValuesFormat(name=Memory), timestamp=DocValuesFormat(name=Memory)}, sim=RandomSimilarityProvider(queryNorm=true,coord=crazy): {}, locale=ar_KW, timezone=Etc/GMT+12
   [junit4]   2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.8.0_45 (64-bit)/cpus=4,threads=2,free=155122584,total=533725184
   [junit4]   2> NOTE: All tests run in this JVM: [SoftAutoCommitTest, RecoveryAfterSoftCommitTest, HdfsBasicDistributedZkTest, UUIDUpdateProcessorFallbackTest, CloudMLTQParserTest, PathHierarchyTokenizerFactoryTest, TestMaxScoreQueryParser, SortByFunctionTest, TestSolr4Spatial2, TestSolrQueryParser, DirectUpdateHandlerOptimizeTest, TestManagedResource, DirectSolrSpellCheckerTest, TestShardHandlerFactory, TestSurroundQueryParser, LoggingHandlerTest, TestFastOutputStream, IgnoreCommitOptimizeUpdateProcessorFactoryTest, SOLR749Test, ZkStateWriterTest, AtomicUpdatesTest, RecoveryZkTest, TestJsonRequest, SimpleFacetsTest, TestExpandComponent, TestJettySolrRunner, DistributedSuggestComponentTest, TestInitParams, TestCursorMarkWithoutUniqueKey, TestCryptoKeys, SearchHandlerTest, TestCSVResponseWriter, TestTrie, TestFieldTypeResource, TestStressRecovery, DistanceUnitsTest, TestExactSharedStatsCache, TestExtendedDismaxParser, TestPartialUpdateDeduplication, CollectionTooManyReplicasTest, SolrCoreCheckLockOnStartupTest, SampleTest, HardAutoCommitTest, SynonymTokenizerTest, TestSearcherReuse, DOMUtilTest, TestReplicationHandlerBackup, TestGroupingSearch, TestPseudoReturnFields, TestNoOpRegenerator, TestLuceneMatchVersion, DistributedQueryElevationComponentTest, OverseerTest, TestSolrJ, TestImplicitCoreProperties, BadIndexSchemaTest, TestExactStatsCache, MoreLikeThisHandlerTest, CircularListTest, OpenExchangeRatesOrgProviderTest, SpellCheckCollatorTest, TestSort, TestSchemaSimilarityResource, RemoteQueryErrorTest, RegexBoostProcessorTest, TestDefaultSimilarityFactory, AsyncMigrateRouteKeyTest, XsltUpdateRequestHandlerTest, TestSQLHandler, TestObjectReleaseTracker, ActionThrottleTest, HdfsNNFailoverTest, HdfsRecoverLeaseTest, DocValuesTest, TestPivotHelperCode, QueryEqualityTest, TestCollectionAPI, TestRebalanceLeaders, SharedFSAutoReplicaFailoverTest, SpatialRPTFieldTypeTest, TestHdfsUpdateLog, BinaryUpdateRequestHandlerTest, TestXIncludeConfig, BasicDistributedZk2Test, UnloadDistributedZkTest, LeaderElectionIntegrationTest, ShardRoutingTest, BasicZkTest, FullSolrCloudDistribCmdsTest, ClusterStateUpdateTest, TestRandomFaceting, LeaderElectionTest, ZkSolrClientTest, TestZkChroot, TestRandomDVFaceting, ShardRoutingCustomTest, AutoCommitTest, TestDistributedGrouping, TestRecovery, TestHashPartitioner, DistributedSpellCheckComponentTest, TermVectorComponentDistributedTest, TestRealTimeGet, TestStressReorder, TestReload, TestSolr4Spatial, StatsComponentTest, PeerSyncTest, ConvertedLegacyTest, TestFunctionQuery, TestLazyCores, DirectUpdateHandlerTest, OverseerCollectionProcessorTest, CurrencyFieldXmlFileTest, SolrIndexSplitterTest, SimplePostToolTest, AnalysisAfterCoreReloadTest, SignatureUpdateProcessorFactoryTest, CoreAdminHandlerTest, SolrRequestParserTest, SuggesterTest, TestStressLucene, PolyFieldTest, TestUpdate, FieldMutatingUpdateProcessorTest, TestAtomicUpdateErrorCases, DefaultValueUpdateProcessorTest, TestRemoteStreaming, DocValuesMultiTest, TestSolrDeletionPolicy1, SolrInfoMBeanTest, CacheHeaderTest, DisMaxRequestHandlerTest, TestQueryUtils, StandardRequestHandlerTest, TestReversedWildcardFilterFactory, DocumentAnalysisRequestHandlerTest, TestQueryTypes, PrimitiveFieldTypeTest, TestOmitPositions, FileBasedSpellCheckerTest, TermsComponentTest, XmlUpdateRequestHandlerTest, RequestHandlersTest, DocumentBuilderTest, TermVectorComponentTest, TestIndexingPerformance, RequiredFieldsTest, FieldAnalysisRequestHandlerTest, FastVectorHighlighterTest, IndexSchemaTest, TestJmxIntegration, TestCollationField, ReturnFieldsTest, QueryParsingTest, TestAnalyzedSuggestions, UniqFieldsUpdateProcessorFactoryTest, CSVRequestHandlerTest, TestBinaryResponseWriter, TestLFUCache, HighlighterConfigTest, TestQuerySenderListener, TestQuerySenderNoQuery, CopyFieldTest, ResponseLogComponentTest, TestMergePolicyConfig, TestDocSet, TestSearchPerf, NumericFieldsTest, TestConfig, OutputWriterTest, TestSolrCoreProperties, DirectSolrConnectionTest, NotRequiredUniqueKeyTest, SpellPossibilityIteratorTest, TestCharFilters, TestCodecSupport, TestSweetSpotSimilarityFactory, TestPerFieldSimilarity, TestLMDirichletSimilarityFactory, TestBM25SimilarityFactory, TestFastWriter, TestJmxMonitoredMap, TimeZoneUtilsTest, ResourceLoaderTest, PluginInfoTest, TestFastLRUCache, ChaosMonkeyNothingIsSafeTest, TestSystemIdResolver, PrimUtilsTest, TestSuggestSpellingConverter, DateFieldTest, RAMDirectoryFactoryTest, TestUtils, SliceStateTest, SystemInfoHandlerTest, UUIDFieldTest, FileUtilsTest, DistributedMLTComponentTest, TestRTGBase, CursorPagingTest, DistributedIntervalFacetingTest, SolrTestCaseJ4Test, TestSimpleTrackingShardHandler, AssignTest, CdcrVersionReplicationTest, CloudExitableDirectoryReaderTest, CollectionStateFormat2Test, CustomCollectionTest, DeleteReplicaTest, HttpPartitionTest, LeaderInitiatedRecoveryOnCommitTest, MultiThreadedOCPTest, TestCloudPivotFacet, TestSolrCloudWithKerberosAlt, HdfsChaosMonkeySafeLeaderTest, HdfsCollectionsAPIDistributedZkTest]
   [junit4] Completed [517/517] on J2 in 1398.18s, 1 test, 1 error <<< FAILURES!

[...truncated 12 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: 517 suites, 2032 tests, 2 errors, 20 ignored (10 assumptions)

Total time: 177 minutes 54 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
No prior successful build to compare, so performing full copy of artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message