lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Jenkins Server <jenk...@builds.apache.org>
Subject [JENKINS] Lucene-Solr-NightlyTests-6.x - Build # 56 - Still Failing
Date Wed, 04 May 2016 21:08:35 GMT
Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-6.x/56/

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

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

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=17253, name=collection1, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest]
Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:39126: collection already exists: awholynewstresscollection_collection1_0
	at __randomizedtesting.SeedInfo.seed([358C62A1A273FF2D]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:590)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:259)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:404)
	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:357)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1192)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:962)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:898)
	at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1595)
	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1616)
	at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:987)




Build Log:
[...truncated 11942 lines...]
   [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
   [junit4]   2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_358C62A1A273FF2D-001/init-core-data-001
   [junit4]   2> 2210492 INFO  (SUITE-CollectionsAPIDistributedZkTest-seed#[358C62A1A273FF2D]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true)
   [junit4]   2> 2210492 INFO  (SUITE-CollectionsAPIDistributedZkTest-seed#[358C62A1A273FF2D]-worker) [    ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
   [junit4]   2> 2210494 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 2210494 INFO  (Thread-6400) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 2210494 INFO  (Thread-6400) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 2210594 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.ZkTestServer start zk server on port:57646
   [junit4]   2> 2210594 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2210594 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2210596 INFO  (zkCallback-2424-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6d7a83b4 name:ZooKeeperConnection Watcher:127.0.0.1:57646 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2210596 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2210596 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2210596 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 2210597 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2210598 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2210598 INFO  (zkCallback-2425-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@46c47bd5 name:ZooKeeperConnection Watcher:127.0.0.1:57646/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2210598 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2210598 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2210598 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1
   [junit4]   2> 2210600 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
   [junit4]   2> 2210600 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
   [junit4]   2> 2210601 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
   [junit4]   2> 2210602 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
   [junit4]   2> 2210602 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 2210605 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
   [junit4]   2> 2210605 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
   [junit4]   2> 2210606 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 2210606 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 2210609 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
   [junit4]   2> 2210609 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
   [junit4]   2> 2210610 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
   [junit4]   2> 2210610 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
   [junit4]   2> 2210611 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
   [junit4]   2> 2210611 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
   [junit4]   2> 2210612 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
   [junit4]   2> 2210612 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
   [junit4]   2> 2210613 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
   [junit4]   2> 2210613 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
   [junit4]   2> 2210615 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 2210615 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 2210616 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
   [junit4]   2> 2210616 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
   [junit4]   2> 2210617 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
   [junit4]   2> 2210617 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
   [junit4]   2> 2210620 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2210621 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2210622 INFO  (zkCallback-2426-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4e49abd5 name:ZooKeeperConnection Watcher:127.0.0.1:57646 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2210622 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2210623 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2210623 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 2210624 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2210624 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2210625 INFO  (zkCallback-2427-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5adfb46a name:ZooKeeperConnection Watcher:127.0.0.1:57646/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2210625 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2210625 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2210625 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf2/solrconfig.xml
   [junit4]   2> 2210625 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.xml
   [junit4]   2> 2210627 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf2/schema.xml
   [junit4]   2> 2210627 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/schema.xml
   [junit4]   2> 2210628 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf2/enumsConfig.xml
   [junit4]   2> 2210628 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/enumsConfig.xml
   [junit4]   2> 2210630 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf2/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 2210630 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 2210631 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf2/stopwords.txt
   [junit4]   2> 2210631 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/stopwords.txt
   [junit4]   2> 2210634 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf2/protwords.txt
   [junit4]   2> 2210634 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/protwords.txt
   [junit4]   2> 2210635 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf2/currency.xml
   [junit4]   2> 2210635 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/currency.xml
   [junit4]   2> 2210638 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf2/open-exchange-rates.json
   [junit4]   2> 2210638 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/open-exchange-rates.json
   [junit4]   2> 2210640 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf2/mapping-ISOLatin1Accent.txt
   [junit4]   2> 2210640 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/mapping-ISOLatin1Accent.txt
   [junit4]   2> 2210641 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf2/old_synonyms.txt
   [junit4]   2> 2210641 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/old_synonyms.txt
   [junit4]   2> 2210642 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf2/synonyms.txt
   [junit4]   2> 2210642 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/synonyms.txt
   [junit4]   2> 2210644 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/elevate.xml to /configs/conf2/elevate.xml
   [junit4]   2> 2210644 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/elevate.xml
   [junit4]   2> 2210704 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_358C62A1A273FF2D-001/control-001/cores/collection1
   [junit4]   2> 2210705 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.e.j.s.Server jetty-9.3.8.v20160314
   [junit4]   2> 2210706 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2c116764{/,null,AVAILABLE}
   [junit4]   2> 2210708 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.e.j.s.ServerConnector Started ServerConnector@6ecdc687{HTTP/1.1,[http/1.1]}{127.0.0.1:59752}
   [junit4]   2> 2210708 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.e.j.s.Server Started @2212974ms
   [junit4]   2> 2210708 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_358C62A1A273FF2D-001/tempDir-001/control/data, hostContext=/, hostPort=59752, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_358C62A1A273FF2D-001/control-001/cores}
   [junit4]   2> 2210708 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93
   [junit4]   2> 2210708 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_358C62A1A273FF2D-001/control-001'
   [junit4]   2> 2210708 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 2210708 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 2210708 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2210709 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2210709 INFO  (zkCallback-2428-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1a550be5 name:ZooKeeperConnection Watcher:127.0.0.1:57646/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2210709 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2210710 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2210710 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
   [junit4]   2> 2210710 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_358C62A1A273FF2D-001/control-001/solr.xml
   [junit4]   2> 2210714 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_358C62A1A273FF2D-001/control-001/cores
   [junit4]   2> 2210714 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.CoreContainer New CoreContainer 1685680505
   [junit4]   2> 2210714 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_358C62A1A273FF2D-001/control-001]
   [junit4]   2> 2210714 WARN  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_358C62A1A273FF2D-001/control-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_358C62A1A273FF2D-001/control-001/lib
   [junit4]   2> 2210714 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] 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> 2210715 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 2210715 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 2210716 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 2210716 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:57646/solr
   [junit4]   2> 2210716 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 2210716 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2210716 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2210719 INFO  (zkCallback-2431-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@10890172 name:ZooKeeperConnection Watcher:127.0.0.1:57646 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2210719 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2210719 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2210720 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:59752_    ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$27/1828077232@4777a1d9
   [junit4]   2> 2210721 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:59752_    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2210722 INFO  (zkCallback-2432-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5333b36b name:ZooKeeperConnection Watcher:127.0.0.1:57646/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2210722 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:59752_    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2210723 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:59752_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
   [junit4]   2> 2210724 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:59752_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
   [junit4]   2> 2210726 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:59752_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
   [junit4]   2> 2210727 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:59752_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
   [junit4]   2> 2210728 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:59752_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
   [junit4]   2> 2210731 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:59752_    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 2210732 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:59752_    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 2210733 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:59752_    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 2210735 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:59752_    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 2210736 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:59752_    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 2210737 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:59752_    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 2210739 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:59752_    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
   [junit4]   2> 2210740 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:59752_    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
   [junit4]   2> 2210741 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:59752_    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2210741 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:59752_    ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95839401293905926-127.0.0.1:59752_-n_0000000000
   [junit4]   2> 2210741 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:59752_    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:59752_
   [junit4]   2> 2210741 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:59752_    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
   [junit4]   2> 2210742 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:59752_    ] o.a.s.c.Overseer Overseer (id=95839401293905926-127.0.0.1:59752_-n_0000000000) starting
   [junit4]   2> 2210743 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:59752_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
   [junit4]   2> 2210747 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:59752_    ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000
   [junit4]   2> 2210747 INFO  (OverseerCollectionConfigSetProcessor-95839401293905926-127.0.0.1:59752_-n_0000000000) [n:127.0.0.1:59752_    ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations
   [junit4]   2> 2210747 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:59752_    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59752_
   [junit4]   2> 2210748 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:59752_    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:59752_
   [junit4]   2> 2210748 INFO  (OverseerStateUpdate-95839401293905926-127.0.0.1:59752_-n_0000000000) [n:127.0.0.1:59752_    ] o.a.s.c.Overseer Starting to work on the main queue
   [junit4]   2> 2210750 INFO  (zkCallback-2432-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_    ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [0])
   [junit4]   2> 2210757 INFO  (OverseerStateUpdate-95839401293905926-127.0.0.1:59752_-n_0000000000) [n:127.0.0.1:59752_    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 2210780 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:59752_    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 2210780 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:59752_    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 2210780 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:59752_    ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_358C62A1A273FF2D-001/control-001/cores
   [junit4]   2> 2210781 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:59752_    ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=control_collection, coreNodeName=, dataDir=data/, shard=}
   [junit4]   2> 2210781 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:59752_    ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_358C62A1A273FF2D-001/control-001/cores/collection1
   [junit4]   2> 2210781 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:59752_    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
   [junit4]   2> 2210782 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:59752_    ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2
   [junit4]   2> 2210782 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:59752_    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 2210784 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection   x:collection1] o.a.s.c.ZkController publishing state=down
   [junit4]   2> 2210784 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection   x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
   [junit4]   2> 2210785 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection   x:collection1] o.a.s.c.ZkController Collection control_collection not visible yet, but flagging it so a watch is registered when it becomes visible
   [junit4]   2> 2210785 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader addZkWatch [control_collection]
   [junit4]   2> 2210785 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader Deleting data for [control_collection]
   [junit4]   2> 2210785 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection   x:collection1] o.a.s.c.ZkController look for our core node name
   [junit4]   2> 2210787 INFO  (OverseerStateUpdate-95839401293905926-127.0.0.1:59752_-n_0000000000) [n:127.0.0.1:59752_    ] 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:59752",
   [junit4]   2>   "node_name":"127.0.0.1:59752_",
   [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> 2210787 INFO  (OverseerStateUpdate-95839401293905926-127.0.0.1:59752_-n_0000000000) [n:127.0.0.1:59752_    ] 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:59752",
   [junit4]   2>   "node_name":"127.0.0.1:59752_",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 2210788 INFO  (OverseerStateUpdate-95839401293905926-127.0.0.1:59752_-n_0000000000) [n:127.0.0.1:59752_    ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection
   [junit4]   2> 2210788 INFO  (OverseerStateUpdate-95839401293905926-127.0.0.1:59752_-n_0000000000) [n:127.0.0.1:59752_    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
   [junit4]   2> 2210790 INFO  (zkCallback-2432-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_    ] 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> 2211787 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection   x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
   [junit4]   2> 2211787 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection   x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection
   [junit4]   2> 2211787 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection   x:collection1] o.a.s.c.ZkController Collection zkNode exists
   [junit4]   2> 2211787 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection]
   [junit4]   2> 2211787 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper
   [junit4]   2> 2211788 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection   x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_358C62A1A273FF2D-001/control-001/cores/collection1'
   [junit4]   2> 2211788 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection   x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 2211788 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection   x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 2211789 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection   x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 
   [junit4]   2> 2211792 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection   x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 2211796 WARN  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection   x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 2211797 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection   x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.1.0
   [junit4]   2> 2211812 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection   x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 2211817 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection   x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 2211879 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 2211882 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection   x:collection1] o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 2211890 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection   x:collection1] o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 2211898 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection   x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 2211900 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection   x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 2211902 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 2211903 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 2211904 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection   x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2
   [junit4]   2> 2211904 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection   x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection
   [junit4]   2> 2211904 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 2211905 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_358C62A1A273FF2D-001/control-001/cores/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_358C62A1A273FF2D-001/control-001/cores/collection1/data/]
   [junit4]   2> 2211905 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ 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@4ad80101
   [junit4]   2> 2211905 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ 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-6.x/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_358C62A1A273FF2D-001/control-001/cores/collection1/data
   [junit4]   2> 2211905 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ 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-6.x/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_358C62A1A273FF2D-001/control-001/cores/collection1/data/index/
   [junit4]   2> 2211905 WARN  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ 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-6.x/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_358C62A1A273FF2D-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index...
   [junit4]   2> 2211906 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ 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-6.x/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_358C62A1A273FF2D-001/control-001/cores/collection1/data/index
   [junit4]   2> 2211906 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=36, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4045144742146216]
   [junit4]   2> 2211906 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@934c492 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@685c588),segFN=segments_1,generation=1}
   [junit4]   2> 2211906 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 2211907 INFO  (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:59752_ 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-6.x/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_358C62A1A273FF2D-001/control-001/cores/collection1/data/
   [junit4]   2> 2211907 WARN  (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:59752_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_358C62A1A273FF2D-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories.
   [junit4]   2> 2211910 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 2211910 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 2211910 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 2211910 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 2211910 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 2211910 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ 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> 2211910 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ 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> 2211910 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ 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> 2211910 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ 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> 2211910 WARN  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}}
   [junit4]   2> 2211912 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2211912 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2211913 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2211914 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2211916 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/dump,/update/csv,/sql,/update/json/docs,/admin/luke,/export,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/stream,/schema,/admin/plugins,/admin/logging,/update/json,/admin/ping,/admin/threads,/update,/admin/file
   [junit4]   2> 2211916 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ 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> 2211917 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ 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> 2211917 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ 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> 2211918 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2211918 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2211918 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=42, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=61.1064453125, floorSegmentMB=0.953125, forceMergeDeletesPctAllowed=29.561958091259484, segmentsPerTier=49.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 2211918 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@934c492 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@685c588),segFN=segments_1,generation=1}
   [junit4]   2> 2211918 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 2211918 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6bbff668[collection1] main]
   [junit4]   2> 2211919 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ 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> 2211919 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ 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> 2211919 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ 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> 2211920 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 2211920 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
   [junit4]   2> 2211920 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ 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> 2211920 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ 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> 2211920 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ 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> 2211920 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 2211920 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2211921 INFO  (searcherExecutor-6017-thread-1-processing-n:127.0.0.1:59752_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59752_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6bbff668[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2211921 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ 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> 2211921 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ 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> 2211921 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ 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> 2211921 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ 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 1533430422197764096
   [junit4]   2> 2211924 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 3.0ms to seed version buckets with highest version 1533430422197764096
   [junit4]   2> 2211924 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1
   [junit4]   2> 2211925 INFO  (coreLoadExecutor-6016-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 2211925 INFO  (coreZkRegister-6010-thread-1-processing-n:127.0.0.1:59752_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59752_ 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:59752 collection:control_collection shard:shard1
   [junit4]   2> 2211926 INFO  (coreZkRegister-6010-thread-1-processing-n:127.0.0.1:59752_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59752_ 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> 2211928 INFO  (coreZkRegister-6010-thread-1-processing-n:127.0.0.1:59752_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59752_ 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/95839401293905926-core_node1-n_0000000000
   [junit4]   2> 2211928 INFO  (coreZkRegister-6010-thread-1-processing-n:127.0.0.1:59752_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59752_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard=shard1 and weAreReplacement=false and leaderVoteWait=180000
   [junit4]   2> 2211929 INFO  (coreZkRegister-6010-thread-1-processing-n:127.0.0.1:59752_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59752_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 2211929 INFO  (coreZkRegister-6010-thread-1-processing-n:127.0.0.1:59752_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59752_ 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> 2211929 INFO  (coreZkRegister-6010-thread-1-processing-n:127.0.0.1:59752_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59752_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:59752/collection1/
   [junit4]   2> 2211929 INFO  (coreZkRegister-6010-thread-1-processing-n:127.0.0.1:59752_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59752_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 2211929 INFO  (coreZkRegister-6010-thread-1-processing-n:127.0.0.1:59752_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59752_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:59752/collection1/ has no replicas
   [junit4]   2> 2211929 INFO  (OverseerStateUpdate-95839401293905926-127.0.0.1:59752_-n_0000000000) [n:127.0.0.1:59752_    ] 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> 2211930 INFO  (coreZkRegister-6010-thread-1-processing-n:127.0.0.1:59752_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59752_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1
   [junit4]   2> 2211931 INFO  (coreZkRegister-6010-thread-1-processing-n:127.0.0.1:59752_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59752_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/control_collection/leaders/shard1/leader after winning as /collections/control_collection/leader_elect/shard1/election/95839401293905926-core_node1-n_0000000000
   [junit4]   2> 2211934 INFO  (coreZkRegister-6010-thread-1-processing-n:127.0.0.1:59752_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59752_ 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:59752/collection1/ shard1
   [junit4]   2> 2211934 INFO  (OverseerStateUpdate-95839401293905926-127.0.0.1:59752_-n_0000000000) [n:127.0.0.1:59752_    ] 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:59752",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "state":"active"} current state version: 1
   [junit4]   2> 2212036 INFO  (zkCallback-2432-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_    ] 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> 2212085 INFO  (coreZkRegister-6010-thread-1-processing-n:127.0.0.1:59752_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59752_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:59752/collection1/ and leader is http://127.0.0.1:59752/collection1/
   [junit4]   2> 2212085 INFO  (coreZkRegister-6010-thread-1-processing-n:127.0.0.1:59752_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59752_ 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:59752
   [junit4]   2> 2212085 INFO  (coreZkRegister-6010-thread-1-processing-n:127.0.0.1:59752_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59752_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 2212085 INFO  (coreZkRegister-6010-thread-1-processing-n:127.0.0.1:59752_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59752_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active
   [junit4]   2> 2212085 INFO  (coreZkRegister-6010-thread-1-processing-n:127.0.0.1:59752_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59752_ 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> 2212086 INFO  (OverseerStateUpdate-95839401293905926-127.0.0.1:59752_-n_0000000000) [n:127.0.0.1:59752_    ] 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:59752",
   [junit4]   2>   "node_name":"127.0.0.1:59752_",
   [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> 2212087 INFO  (OverseerStateUpdate-95839401293905926-127.0.0.1:59752_-n_0000000000) [n:127.0.0.1:59752_    ] 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:59752",
   [junit4]   2>   "node_name":"127.0.0.1:59752_",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 2212188 INFO  (zkCallback-2432-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_    ] 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> 2212284 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2212284 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2212285 INFO  (zkCallback-2434-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@408451f4 name:ZooKeeperConnection Watcher:127.0.0.1:57646/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2212285 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2212285 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2212285 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 2212290 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 2212290 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
   [junit4]   2> 2212346 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_358C62A1A273FF2D-001/shard-1-001/cores/collection1
   [junit4]   2> 2212347 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_358C62A1A273FF2D-001/shard-1-001
   [junit4]   2> 2212347 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.e.j.s.Server jetty-9.3.8.v20160314
   [junit4]   2> 2212348 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@ac7d460{/,null,AVAILABLE}
   [junit4]   2> 2212349 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.e.j.s.ServerConnector Started ServerConnector@18a78c88{HTTP/1.1,[http/1.1]}{127.0.0.1:46267}
   [junit4]   2> 2212349 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.e.j.s.Server Started @2214615ms
   [junit4]   2> 2212349 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_358C62A1A273FF2D-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/, hostPort=46267, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_358C62A1A273FF2D-001/shard-1-001/cores}
   [junit4]   2> 2212349 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93
   [junit4]   2> 2212349 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_358C62A1A273FF2D-001/shard-1-001'
   [junit4]   2> 2212350 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 2212350 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 2212350 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2212350 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2212351 INFO  (zkCallback-2435-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@974b456 name:ZooKeeperConnection Watcher:127.0.0.1:57646/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2212351 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2212351 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2212355 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
   [junit4]   2> 2212355 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_358C62A1A273FF2D-001/shard-1-001/solr.xml
   [junit4]   2> 2212358 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_358C62A1A273FF2D-001/shard-1-001/cores
   [junit4]   2> 2212358 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.CoreContainer New CoreContainer 351112566
   [junit4]   2> 2212358 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_358C62A1A273FF2D-001/shard-1-001]
   [junit4]   2> 2212358 WARN  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_358C62A1A273FF2D-001/shard-1-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_358C62A1A273FF2D-001/shard-1-001/lib
   [junit4]   2> 2212359 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] 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> 2212359 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 2212360 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 2212360 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 2212360 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:57646/solr
   [junit4]   2> 2212360 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 2212360 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2212360 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2212361 INFO  (zkCallback-2438-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1ce2ddd6 name:ZooKeeperConnection Watcher:127.0.0.1:57646 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2212361 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2212361 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2212362 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:46267_    ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$27/1828077232@6a2831d8
   [junit4]   2> 2212362 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:46267_    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2212363 INFO  (zkCallback-2439-thread-1-processing-n:127.0.0.1:46267_) [n:127.0.0.1:46267_    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6095ac80 name:ZooKeeperConnection Watcher:127.0.0.1:57646/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2212363 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:46267_    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2212367 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:46267_    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 2212368 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:46267_    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 2212370 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:46267_    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2212370 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:46267_    ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95839401293905930-127.0.0.1:46267_-n_0000000001
   [junit4]   2> 2212374 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:46267_    ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/95839401293905926-127.0.0.1:59752_-n_0000000000 to know if I could be the leader
   [junit4]   2> 2212374 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:46267_    ] o.a.s.c.ZkController Publish node=127.0.0.1:46267_ as DOWN
   [junit4]   2> 2212376 INFO  (OverseerStateUpdate-95839401293905926-127.0.0.1:59752_-n_0000000000) [n:127.0.0.1:59752_    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "operation":"downnode",
   [junit4]   2>   "node_name":"127.0.0.1:46267_"} current state version: 3
   [junit4]   2> 2212376 INFO  (OverseerStateUpdate-95839401293905926-127.0.0.1:59752_-n_0000000000) [n:127.0.0.1:59752_    ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:46267_
   [junit4]   2> 2212478 INFO  (zkCallback-2434-thread-1) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [1])
   [junit4]   2> 2212479 INFO  (zkCallback-2432-thread-1-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_    ] 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> 2212479 INFO  (zkCallback-2439-thread-1-processing-n:127.0.0.1:46267_) [n:127.0.0.1:46267_    ] 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> 2213376 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:46267_    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46267_
   [junit4]   2> 2213377 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:46267_    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:46267_
   [junit4]   2> 2213379 INFO  (zkCallback-2439-thread-1-processing-n:127.0.0.1:46267_) [n:127.0.0.1:46267_    ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [1])
   [junit4]   2> 2213379 INFO  (zkCallback-2434-thread-1) [    ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [1])
   [junit4]   2> 2213379 INFO  (zkCallback-2432-thread-2-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_    ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [1])
   [junit4]   2> 2213379 INFO  (zkCallback-2434-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 2213379 INFO  (zkCallback-2439-thread-1-processing-n:127.0.0.1:46267_) [n:127.0.0.1:46267_    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 2213381 INFO  (zkCallback-2432-thread-2-processing-n:127.0.0.1:59752_) [n:127.0.0.1:59752_    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 2213387 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:46267_    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 2213387 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:46267_    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 2213387 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:46267_    ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_358C62A1A273FF2D-001/shard-1-001/cores
   [junit4]   2> 2213388 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:46267_    ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=collection1, coreNodeName=, dataDir=data/, shard=}
   [junit4]   2> 2213388 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:46267_    ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_358C62A1A273FF2D-001/shard-1-001/cores/collection1
   [junit4]   2> 2213388 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:46267_    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
   [junit4]   2> 2213389 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:46267_    ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2
   [junit4]   2> 2213389 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[358C62A1A273FF2D]) [n:127.0.0.1:46267_    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 2213389 INFO  (coreLoadExecutor-6027-thread-1-processing-n:127.0.0.1:46267_) [n:127.0.0.1:46267_ c:collection1   x:collection1] o.a.s.c.ZkController publishing state=down
   [junit4]   2> 2213390 INFO  (coreLoadExecutor-6027-thread-1-processing-n:127.0.0.1:46267_) [n:127.0.0.1:46267_ c:collection1   x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
   [junit4]   2> 2213390 INFO  (coreLoadExecutor-6027-thread-1-processing-n:127.0.0.1:46267_) [n:127.0.0.1:46267_ c:collection1   x:collection1] o.a.s.c.ZkController Collection collection1 not visible yet, but flagging it so a watch is registered when it becomes visible
   [junit4]   2> 2213390 INFO  (coreLoadExecutor-6027-thread-1-processing-n:127.0.0.1:46267_) [n:127.0.0.1:46267_ c:collection1   x:collection1] o.a.s.c.c.ZkStateReader addZkWatch [collection1]
   [junit4]   2> 2213390 INFO  (OverseerStateUpdate-95839401293905926-127.0.0.1:59752_-n_0000000000) [n:127.0.0.1:59752_    ] 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:46267",
   [junit4]   2>   "node_name":"127.0.0.1:46267_",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"collection1",
   [junit4]   2>   "operation":"state"} current state version: 4
   [junit4]   2> 2213390 INFO  (coreLoadExecutor-6027-thread-1-processing-n:127.0.0.1:46267_) [n:127.0.0.1:46267_ c:collection1   x:collection1] o.a.s.c.c.ZkStateReader Deleting data for [collection1]
   [junit4]   2> 2213390 INFO  (OverseerStateUpdate-95839401293905926-127.0.0.1:59752_-n_0000000000) [n:127.0.0.1:59752_    ] 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:46267",
   [junit4]   2>   "node_name":"127.0.0.1:46267_",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"collection1",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 2213391 INFO  (OverseerStateUpdate-95839401293905926-127.0.0.1:59752_-n_0000000000) [n:127.0.0.1:59752_    ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1
   [junit4]   2> 2213391 INFO  (OverseerStateUpdate-95839401293905926-127.0.0.1:59752_-n_0000000000) [n:127.0.0.1:59752_    ] o.a.s.c.o.ReplicaMutator Assigni

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

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

BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:747: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:684: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:59: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build.xml:233: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/common-build.xml:529: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/common-build.xml:1457: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/common-build.xml:1014: At least one slave process threw an exception, first: Quit event not received from the forked process? This may indicate JVM crash or runner bugs.

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




Mime
View raw message