Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/835/

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

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

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught 
exception in thread: Thread[id=520, name=collection2, state=RUNNABLE, 
group=TGRP-CollectionsAPIDistributedZkTest]
Caused by: 
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error 
from server at http://127.0.0.1:36735: Could not find collection : 
awholynewstresscollection_collection2_3
        at __randomizedtesting.SeedInfo.seed([7321E9508C7EB203]:0)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:575)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230)
        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372)
        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1099)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:870)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806)
        at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220)
        at 
org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:895)


FAILED:  org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.test

Error Message:
Captured an uncaught exception in thread: Thread[id=63230, name=Thread-55338, 
state=RUNNABLE, group=TGRP-FullSolrCloudDistribCmdsTest]

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught 
exception in thread: Thread[id=63230, name=Thread-55338, state=RUNNABLE, 
group=TGRP-FullSolrCloudDistribCmdsTest]
Caused by: java.lang.RuntimeException: 
org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting 
response from server at: http://127.0.0.1:40002/collection1
        at __randomizedtesting.SeedInfo.seed([7321E9508C7EB203]:0)
        at 
org.apache.solr.cloud.FullSolrCloudDistribCmdsTest$1IndexThread.run(FullSolrCloudDistribCmdsTest.java:645)
Caused by: org.apache.solr.client.solrj.SolrServerException: Timeout occured 
while waiting response from server at: http://127.0.0.1:40002/collection1
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:587)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230)
        at 
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:150)
        at 
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:167)
        at 
org.apache.solr.cloud.FullSolrCloudDistribCmdsTest$1IndexThread.run(FullSolrCloudDistribCmdsTest.java:643)
Caused by: java.net.SocketTimeoutException: Read timed out
        at java.net.SocketInputStream.socketRead0(Native Method)
        at java.net.SocketInputStream.socketRead(SocketInputStream.java:116)
        at java.net.SocketInputStream.read(SocketInputStream.java:170)
        at java.net.SocketInputStream.read(SocketInputStream.java:141)
        at 
org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160)
        at 
org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:84)
        at 
org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273)
        at 
org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:140)
        at 
org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57)
        at 
org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:261)
        at 
org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283)
        at 
org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251)
        at 
org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197)
        at 
org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:272)
        at 
org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:124)
        at 
org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:685)
        at 
org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:487)
        at 
org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:882)
        at 
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
        at 
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:107)
        at 
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:480)
        ... 5 more


FAILED:  
org.apache.solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR

Error Message:
Captured an uncaught exception in thread: Thread[id=56037, 
name=coreZkRegister-2723-thread-1, state=RUNNABLE, 
group=TGRP-LeaderInitiatedRecoveryOnShardRestartTest]

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught 
exception in thread: Thread[id=56037, name=coreZkRegister-2723-thread-1, 
state=RUNNABLE, group=TGRP-LeaderInitiatedRecoveryOnShardRestartTest]
Caused by: java.lang.AssertionError
        at __randomizedtesting.SeedInfo.seed([7321E9508C7EB203]:0)
        at 
org.apache.solr.cloud.ZkController.updateLeaderInitiatedRecoveryState(ZkController.java:2126)
        at 
org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:433)
        at 
org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:197)
        at 
org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:157)
        at 
org.apache.solr.cloud.LeaderElector.joinElection(LeaderElector.java:346)
        at 
org.apache.solr.cloud.ZkController.joinElection(ZkController.java:1113)
        at org.apache.solr.cloud.ZkController.register(ZkController.java:926)
        at org.apache.solr.cloud.ZkController.register(ZkController.java:881)
        at org.apache.solr.core.ZkContainer$2.run(ZkContainer.java:183)
        at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$1.run(ExecutorUtil.java:231)
        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
        at java.lang.Thread.run(Thread.java:745)




Build Log:
[...truncated 9862 lines...]
   [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
   [junit4]   2> Creating dataDir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7321E9508C7EB203-001/init-core-data-001
   [junit4]   2> 8353 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[7321E9508C7EB203]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
   [junit4]   2> 8354 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[7321E9508C7EB203]-worker) [    ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
   [junit4]   2> 8372 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 8412 INFO  (Thread-10) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 8412 INFO  (Thread-10) [    ] o.a.s.c.ZkTestServer Starting 
server
   [junit4]   2> 8604 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.ZkTestServer start zk server on port:40824
   [junit4]   2> 8620 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 8740 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 8778 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn Exception causing close of session 0x0 due to 
java.io.IOException: ZooKeeperServer not running
   [junit4]   2> 10035 INFO  (zkCallback-4-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@7ce5001a 
name:ZooKeeperConnection Watcher:127.0.0.1:40824 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 10039 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 10040 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 10043 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 10072 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn caught end of stream exception
   [junit4]   2> EndOfStreamException: Unable to read additional data from 
client sessionid 0x150ae3627e00000, likely client has closed socket
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 10075 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 10077 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 10081 INFO  (zkCallback-5-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@5f905472 
name:ZooKeeperConnection Watcher:127.0.0.1:40824/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 10081 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 10082 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 10086 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /collections/collection1
   [junit4]   2> 10091 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
   [junit4]   2> 10095 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
   [junit4]   2> 10098 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
   [junit4]   2> 10102 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
 to /configs/conf1/solrconfig.xml
   [junit4]   2> 10111 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 10119 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml
 to /configs/conf1/schema.xml
   [junit4]   2> 10119 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
   [junit4]   2> 10123 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
 to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 10123 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.SolrZkClient makePath: 
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 10128 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
 to /configs/conf1/stopwords.txt
   [junit4]   2> 10128 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
   [junit4]   2> 10132 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt
 to /configs/conf1/protwords.txt
   [junit4]   2> 10132 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
   [junit4]   2> 10136 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml
 to /configs/conf1/currency.xml
   [junit4]   2> 10136 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
   [junit4]   2> 10139 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
 to /configs/conf1/enumsConfig.xml
   [junit4]   2> 10139 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
   [junit4]   2> 10151 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
 to /configs/conf1/open-exchange-rates.json
   [junit4]   2> 10151 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
   [junit4]   2> 10155 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
 to /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 10155 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 10158 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
 to /configs/conf1/old_synonyms.txt
   [junit4]   2> 10158 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
   [junit4]   2> 10161 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
 to /configs/conf1/synonyms.txt
   [junit4]   2> 10162 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
   [junit4]   2> 10168 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 10176 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 10186 INFO  (zkCallback-6-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@68460e67 
name:ZooKeeperConnection Watcher:127.0.0.1:40824 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 10186 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 10187 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 10187 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 10200 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 10208 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 10211 INFO  (zkCallback-7-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@490459c5 
name:ZooKeeperConnection Watcher:127.0.0.1:40824/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 10211 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 10211 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 10212 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
 to /configs/conf2/solrconfig.xml
   [junit4]   2> 10212 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.xml
   [junit4]   2> 10217 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml
 to /configs/conf2/schema.xml
   [junit4]   2> 10217 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/schema.xml
   [junit4]   2> 10220 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
 to /configs/conf2/enumsConfig.xml
   [junit4]   2> 10220 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/enumsConfig.xml
   [junit4]   2> 10224 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
 to /configs/conf2/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 10224 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.SolrZkClient makePath: 
/configs/conf2/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 10227 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
 to /configs/conf2/stopwords.txt
   [junit4]   2> 10227 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/stopwords.txt
   [junit4]   2> 10230 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt
 to /configs/conf2/protwords.txt
   [junit4]   2> 10230 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/protwords.txt
   [junit4]   2> 10233 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml
 to /configs/conf2/currency.xml
   [junit4]   2> 10234 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/currency.xml
   [junit4]   2> 10237 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
 to /configs/conf2/open-exchange-rates.json
   [junit4]   2> 10237 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/open-exchange-rates.json
   [junit4]   2> 10250 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
 to /configs/conf2/mapping-ISOLatin1Accent.txt
   [junit4]   2> 10250 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/mapping-ISOLatin1Accent.txt
   [junit4]   2> 10254 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
 to /configs/conf2/old_synonyms.txt
   [junit4]   2> 10254 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/old_synonyms.txt
   [junit4]   2> 10258 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
 to /configs/conf2/synonyms.txt
   [junit4]   2> 10258 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/synonyms.txt
   [junit4]   2> 10263 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/elevate.xml
 to /configs/conf2/elevate.xml
   [junit4]   2> 10263 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/elevate.xml
   [junit4]   2> 10656 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7321E9508C7EB203-001/control-001/cores/collection1
   [junit4]   2> 10788 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.e.j.u.log Logging initialized @14303ms
   [junit4]   2> 10944 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.e.j.s.Server jetty-9.2.13.v20150730
   [junit4]   2> 11131 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@42198797{/,null,AVAILABLE}
   [junit4]   2> 11147 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.e.j.s.ServerConnector Started 
ServerConnector@7e0d2ec0{HTTP/1.1}{127.0.0.1:50831}
   [junit4]   2> 11147 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.e.j.s.Server Started @14667ms
   [junit4]   2> 11148 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7321E9508C7EB203-001/tempDir-001/control/data,
 hostContext=/, hostPort=50831, 
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7321E9508C7EB203-001/control-001/cores}
   [junit4]   2> 11158 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): 
sun.misc.Launcher$AppClassLoader@4e0e2f2a
   [junit4]   2> 11159 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7321E9508C7EB203-001/control-001/'
   [junit4]   2> 11159 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 11160 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find 
system property or JNDI)
   [junit4]   2> 11189 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 11190 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 11198 INFO  (zkCallback-8-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@74fd902e 
name:ZooKeeperConnection Watcher:127.0.0.1:40824/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 11198 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 11199 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 11202 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in 
ZooKeeper)
   [junit4]   2> 11205 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7321E9508C7EB203-001/control-001/solr.xml
   [junit4]   2> 11220 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.CoresLocator Config-defined core root directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7321E9508C7EB203-001/control-001/cores
   [junit4]   2> 11221 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.CoreContainer New CoreContainer 1426537180
   [junit4]   2> 11221 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.CoreContainer Loading cores into CoreContainer 
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7321E9508C7EB203-001/control-001/]
   [junit4]   2> 11221 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.CoreContainer loading shared library: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7321E9508C7EB203-001/control-001/lib
   [junit4]   2> 11221 WARN  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to 
classloader: lib (resolved as: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7321E9508C7EB203-001/control-001/lib).
   [junit4]   2> 11241 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
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> 11242 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 11243 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 11243 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.l.LogWatcher Registering Log Listener [Log4j 
(org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 11243 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40824/solr
   [junit4]   2> 11258 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 11258 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 11258 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 11261 INFO  (zkCallback-10-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@42d4b294 
name:ZooKeeperConnection Watcher:127.0.0.1:40824 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 11262 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 11262 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 11271 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) 
[n:127.0.0.1:50831_    ] o.a.s.c.c.ConnectionManager Waiting for client to 
connect to ZooKeeper
   [junit4]   2> 11274 INFO  
(zkCallback-11-thread-1-processing-n:127.0.0.1:50831_) [n:127.0.0.1:50831_    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@19e4700 name:ZooKeeperConnection 
Watcher:127.0.0.1:40824/solr got event WatchedEvent state:SyncConnected 
type:None path:null path:null type:None
   [junit4]   2> 11274 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) 
[n:127.0.0.1:50831_    ] o.a.s.c.c.ConnectionManager Client is connected to 
ZooKeeper
   [junit4]   2> 11282 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) 
[n:127.0.0.1:50831_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
   [junit4]   2> 11293 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) 
[n:127.0.0.1:50831_    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer/collection-queue-work
   [junit4]   2> 11301 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) 
[n:127.0.0.1:50831_    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer/collection-map-running
   [junit4]   2> 11307 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) 
[n:127.0.0.1:50831_    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer/collection-map-completed
   [junit4]   2> 11311 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) 
[n:127.0.0.1:50831_    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer/collection-map-failure
   [junit4]   2> 11327 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) 
[n:127.0.0.1:50831_    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 11331 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) 
[n:127.0.0.1:50831_    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 11333 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) 
[n:127.0.0.1:50831_    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 11335 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) 
[n:127.0.0.1:50831_    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 11337 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) 
[n:127.0.0.1:50831_    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:50831_
   [junit4]   2> 11339 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) 
[n:127.0.0.1:50831_    ] o.a.s.c.c.SolrZkClient makePath: 
/live_nodes/127.0.0.1:50831_
   [junit4]   2> 11345 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) 
[n:127.0.0.1:50831_    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
   [junit4]   2> 11347 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) 
[n:127.0.0.1:50831_    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer_elect/election
   [junit4]   2> 11350 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) 
[n:127.0.0.1:50831_    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 11351 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) 
[n:127.0.0.1:50831_    ] o.a.s.c.LeaderElector Joined leadership election with 
path: /overseer_elect/election/94767139795238918-127.0.0.1:50831_-n_0000000000
   [junit4]   2> 11356 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) 
[n:127.0.0.1:50831_    ] o.a.s.c.OverseerElectionContext I am going to be the 
leader 127.0.0.1:50831_
   [junit4]   2> 11356 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) 
[n:127.0.0.1:50831_    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
   [junit4]   2> 11358 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) 
[n:127.0.0.1:50831_    ] o.a.s.c.Overseer Overseer 
(id=94767139795238918-127.0.0.1:50831_-n_0000000000) starting
   [junit4]   2> 11365 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) 
[n:127.0.0.1:50831_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
   [junit4]   2> 11394 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) 
[n:127.0.0.1:50831_    ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting 
OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 
autoReplicaFailoverWaitAfterExpiration=10000 
autoReplicaFailoverBadNodeExpiration=60000
   [junit4]   2> 11398 INFO  
(OverseerCollectionConfigSetProcessor-94767139795238918-127.0.0.1:50831_-n_0000000000)
 [n:127.0.0.1:50831_    ] o.a.s.c.OverseerTaskProcessor Process current queue 
of overseer operations
   [junit4]   2> 11398 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) 
[n:127.0.0.1:50831_    ] o.a.s.c.c.ZkStateReader Updating cluster state from 
ZooKeeper... 
   [junit4]   2> 11408 INFO  
(OverseerStateUpdate-94767139795238918-127.0.0.1:50831_-n_0000000000) 
[n:127.0.0.1:50831_    ] o.a.s.c.Overseer Starting to work on the main queue
   [junit4]   2> 11492 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) 
[n:127.0.0.1:50831_    ] o.a.s.c.CoreContainer Security conf doesn't exist. 
Skipping setup for authorization module.
   [junit4]   2> 11492 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) 
[n:127.0.0.1:50831_    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 11494 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) 
[n:127.0.0.1:50831_    ] o.a.s.c.CoresLocator Looking for core definitions 
underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7321E9508C7EB203-001/control-001/cores
   [junit4]   2> 11497 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) 
[n:127.0.0.1:50831_    ] o.a.s.c.SolrCore Created CoreDescriptor: 
{name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, 
loadOnStartup=true, configSetProperties=configsetprops.json, 
instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7321E9508C7EB203-001/control-001/cores/collection1,
 collection=control_collection, 
absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7321E9508C7EB203-001/control-001/cores/collection1/,
 coreNodeName=, dataDir=data/, shard=}
   [junit4]   2> 11498 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) 
[n:127.0.0.1:50831_    ] o.a.s.c.CoresLocator Found core collection1 in 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7321E9508C7EB203-001/control-001/cores/collection1/
   [junit4]   2> 11498 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) 
[n:127.0.0.1:50831_    ] o.a.s.c.CoresLocator Found 1 core definitions
   [junit4]   2> 11501 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection   x:collection1] o.a.s.c.ZkController 
publishing state=down
   [junit4]   2> 11501 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection   x:collection1] o.a.s.c.ZkController 
numShards not found on descriptor - reading it from system property
   [junit4]   2> 11502 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) 
[n:127.0.0.1:50831_    ] o.a.s.s.SolrDispatchFilter 
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1
   [junit4]   2> 11503 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) 
[n:127.0.0.1:50831_    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() 
done
   [junit4]   2> 11513 INFO  
(OverseerStateUpdate-94767139795238918-127.0.0.1:50831_-n_0000000000) 
[n:127.0.0.1:50831_    ] 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:50831";,
   [junit4]   2>   "node_name":"127.0.0.1:50831_",
   [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> 11515 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection   x:collection1] o.a.s.c.ZkController 
look for our core node name
   [junit4]   2> 11522 INFO  
(OverseerStateUpdate-94767139795238918-127.0.0.1:50831_-n_0000000000) 
[n:127.0.0.1:50831_    ] 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:50831";,
   [junit4]   2>   "node_name":"127.0.0.1:50831_",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 11524 INFO  
(OverseerStateUpdate-94767139795238918-127.0.0.1:50831_-n_0000000000) 
[n:127.0.0.1:50831_    ] o.a.s.c.o.ClusterStateMutator building a new cName: 
control_collection
   [junit4]   2> 11534 INFO  
(OverseerStateUpdate-94767139795238918-127.0.0.1:50831_-n_0000000000) 
[n:127.0.0.1:50831_    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard 
shard=shard1
   [junit4]   2> 11539 INFO  
(zkCallback-11-thread-1-processing-n:127.0.0.1:50831_) [n:127.0.0.1:50831_    ] 
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> 12516 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection   x:collection1] o.a.s.c.ZkController 
waiting to find shard id in clusterstate for collection1
   [junit4]   2> 12516 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection   x:collection1] o.a.s.c.ZkController 
Check for collection zkNode:control_collection
   [junit4]   2> 12517 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection   x:collection1] o.a.s.c.ZkController 
Collection zkNode exists
   [junit4]   2> 12517 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection   x:collection1] 
o.a.s.c.c.ZkStateReader Load collection config 
from:/collections/control_collection
   [junit4]   2> 12518 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection   x:collection1] 
o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 
specified config exists in ZooKeeper
   [junit4]   2> 12519 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection   x:collection1] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7321E9508C7EB203-001/control-001/cores/collection1/'
   [junit4]   2> 12519 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection   x:collection1] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 12519 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection   x:collection1] 
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find 
system property or JNDI)
   [junit4]   2> 12569 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection   x:collection1] o.a.s.c.Config loaded 
config solrconfig.xml with version 0 
   [junit4]   2> 12587 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection   x:collection1] o.a.s.c.SolrConfig 
current version of requestparams : -1
   [junit4]   2> 12593 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection   x:collection1] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 6.0.0
   [junit4]   2> 12610 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection   x:collection1] o.a.s.c.Config Loaded 
SolrConfig: solrconfig.xml
   [junit4]   2> 12614 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection   x:collection1] o.a.s.s.IndexSchema 
Reading Solr Schema from /configs/conf1/schema.xml
   [junit4]   2> 12640 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection   x:collection1] o.a.s.s.IndexSchema 
[collection1] Schema name=test
   [junit4]   2> 12787 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Initialized with 
rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 12799 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection   x:collection1] o.a.s.s.IndexSchema 
default search field in schema is text
   [junit4]   2> 12800 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection   x:collection1] o.a.s.s.IndexSchema 
unique key field: id
   [junit4]   2> 12822 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection   x:collection1] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 12825 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection   x:collection1] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 12828 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 12829 WARN  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 12829 WARN  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 12830 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 12831 WARN  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 12831 WARN  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 12832 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ 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-trunk/solr/build/solr-core/test/J1
   [junit4]   2> 12832 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection   x:collection1] o.a.s.c.CoreContainer 
Creating SolrCore 'collection1' using configuration from collection 
control_collection
   [junit4]   2> 12832 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 12833 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7321E9508C7EB203-001/control-001/cores/collection1/],
 dataDir=[null]
   [junit4]   2> 12835 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ 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@24b151ad
   [junit4]   2> 12840 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.CachingDirectoryFactory return new directory for 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7321E9508C7EB203-001/control-001/cores/collection1/data
   [junit4]   2> 12840 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.SolrCore New index directory detected: old=null 
new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7321E9508C7EB203-001/control-001/cores/collection1/data/index/
   [junit4]   2> 12840 WARN  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.SolrCore [collection1] Solr index directory 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7321E9508C7EB203-001/control-001/cores/collection1/data/index'
 doesn't exist. Creating new index...
   [junit4]   2> 12844 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.CachingDirectoryFactory return new directory for 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7321E9508C7EB203-001/control-001/cores/collection1/data/index
   [junit4]   2> 12844 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ 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=16, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=14.482421875, 
floorSegmentMB=1.0751953125, forceMergeDeletesPctAllowed=21.77378160478481, 
segmentsPerTier=29.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.30887223824920773
   [junit4]   2> 12855 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>        
commit{dir=MockDirectoryWrapper(NIOFSDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7321E9508C7EB203-001/index-NIOFSDirectory-001
 
lockFactory=org.apache.lucene.store.NativeFSLockFactory@3fac91f3),segFN=segments_1,generation=1}
   [junit4]   2> 12856 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 12860 INFO  
(OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:50831_ 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore 
Looking for old index directories to cleanup for core collection1 in 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7321E9508C7EB203-001/control-001/cores/collection1/data/
   [junit4]   2> 12861 WARN  
(OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:50831_ 
c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.DirectoryFactory 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7321E9508C7EB203-001/control-001/cores/collection1/data/
 does not point to a valid data directory; skipping clean-up of old index 
directories.
   [junit4]   2> 12876 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain 
"nodistrib"
   [junit4]   2> 12885 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain 
"dedupe"
   [junit4]   2> 12886 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 12886 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain 
"stored_sig"
   [junit4]   2> 12886 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 12886 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ 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> 12886 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ 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> 12887 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ 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> 12887 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ 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> 12890 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 12892 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 12893 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 12895 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 12905 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.RequestHandlers Registered paths: 
/admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
   [junit4]   2> 12906 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ 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> 12912 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ 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> 12912 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ 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> 12920 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.SolrCore Hard AutoCommit: disabled
   [junit4]   2> 12920 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.SolrCore Soft AutoCommit: disabled
   [junit4]   2> 12921 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.MockRandomMergePolicy: 
org.apache.lucene.index.MockRandomMergePolicy@15cd27dd
   [junit4]   2> 12925 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>        
commit{dir=MockDirectoryWrapper(NIOFSDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7321E9508C7EB203-001/index-NIOFSDirectory-001
 
lockFactory=org.apache.lucene.store.NativeFSLockFactory@3fac91f3),segFN=segments_1,generation=1}
   [junit4]   2> 12925 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 12926 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.s.SolrIndexSearcher Opening Searcher@5ab90b87[collection1] main
   [junit4]   2> 12926 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ 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> 12927 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ 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> 12927 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ 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> 12930 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf1
   [junit4]   2> 12930 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.r.RestManager Initializing RestManager with initArgs: {}
   [junit4]   2> 12930 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ 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> 12930 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ 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> 12931 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ 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> 12931 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 12931 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 12933 INFO  
(searcherExecutor-41-thread-1-processing-n:127.0.0.1:50831_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:50831_ 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore 
[collection1] Registered new searcher Searcher@5ab90b87[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 12934 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ 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> 12934 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ 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> 12934 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ 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> 12934 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ 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 1516274241300856832
   [junit4]   2> 12939 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.UpdateLog Took 5.0ms to seed version buckets with highest version 
1516274241300856832
   [junit4]   2> 12940 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.ZkController watch zkdir /configs/conf1
   [junit4]   2> 12942 INFO  
(coreLoadExecutor-40-thread-1-processing-n:127.0.0.1:50831_) 
[n:127.0.0.1:50831_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 12944 INFO  
(coreZkRegister-34-thread-1-processing-n:127.0.0.1:50831_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:50831_ 
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:50831 
collection:control_collection shard:shard1
   [junit4]   2> 12952 INFO  
(coreZkRegister-34-thread-1-processing-n:127.0.0.1:50831_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:50831_ 
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> 12958 INFO  
(coreZkRegister-34-thread-1-processing-n:127.0.0.1:50831_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:50831_ 
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/94767139795238918-core_node1-n_0000000000
   [junit4]   2> 12959 INFO  
(coreZkRegister-34-thread-1-processing-n:127.0.0.1:50831_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:50831_ 
c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1
   [junit4]   2> 12963 INFO  
(coreZkRegister-34-thread-1-processing-n:127.0.0.1:50831_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:50831_ 
c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 12963 INFO  
(coreZkRegister-34-thread-1-processing-n:127.0.0.1:50831_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:50831_ 
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> 12963 INFO  
(OverseerStateUpdate-94767139795238918-127.0.0.1:50831_-n_0000000000) 
[n:127.0.0.1:50831_    ] 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> 12964 INFO  
(coreZkRegister-34-thread-1-processing-n:127.0.0.1:50831_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:50831_ 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy 
Sync replicas to http://127.0.0.1:50831/collection1/
   [junit4]   2> 12964 INFO  
(coreZkRegister-34-thread-1-processing-n:127.0.0.1:50831_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:50831_ 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy 
Sync Success - now sync replicas to me
   [junit4]   2> 12964 INFO  
(coreZkRegister-34-thread-1-processing-n:127.0.0.1:50831_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:50831_ 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy 
http://127.0.0.1:50831/collection1/ has no replicas
   [junit4]   2> 12964 INFO  
(coreZkRegister-34-thread-1-processing-n:127.0.0.1:50831_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:50831_ 
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:50831/collection1/ shard1
   [junit4]   2> 12971 INFO  
(coreZkRegister-34-thread-1-processing-n:127.0.0.1:50831_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:50831_ 
c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1
   [junit4]   2> 12979 INFO  
(coreZkRegister-34-thread-1-processing-n:127.0.0.1:50831_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:50831_ 
c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node
   [junit4]   2> 12997 INFO  
(OverseerStateUpdate-94767139795238918-127.0.0.1:50831_-n_0000000000) 
[n:127.0.0.1:50831_    ] 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:50831";,
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "state":"active"} current state version: 1
   [junit4]   2> 13030 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 13031 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 13034 INFO  (zkCallback-13-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@3850ea7f 
name:ZooKeeperConnection Watcher:127.0.0.1:40824/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 13034 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 13034 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 13034 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 13040 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection 
loss:false
   [junit4]   2> 13102 INFO  (zkCallback-13-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> 13102 INFO  
(zkCallback-11-thread-1-processing-n:127.0.0.1:50831_) [n:127.0.0.1:50831_    ] 
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> 13150 INFO  
(coreZkRegister-34-thread-1-processing-n:127.0.0.1:50831_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:50831_ 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController 
We are http://127.0.0.1:50831/collection1/ and leader is 
http://127.0.0.1:50831/collection1/
   [junit4]   2> 13152 INFO  
(coreZkRegister-34-thread-1-processing-n:127.0.0.1:50831_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:50831_ 
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:50831
   [junit4]   2> 13152 INFO  
(coreZkRegister-34-thread-1-processing-n:127.0.0.1:50831_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:50831_ 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController 
I am the leader, no recovery necessary
   [junit4]   2> 13152 INFO  
(coreZkRegister-34-thread-1-processing-n:127.0.0.1:50831_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:50831_ 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController 
publishing state=active
   [junit4]   2> 13152 INFO  
(coreZkRegister-34-thread-1-processing-n:127.0.0.1:50831_ x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:50831_ 
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> 13156 INFO  
(OverseerStateUpdate-94767139795238918-127.0.0.1:50831_-n_0000000000) 
[n:127.0.0.1:50831_    ] 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:50831";,
   [junit4]   2>   "node_name":"127.0.0.1:50831_",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"} current state version: 2
   [junit4]   2> 13156 INFO  
(OverseerStateUpdate-94767139795238918-127.0.0.1:50831_-n_0000000000) 
[n:127.0.0.1:50831_    ] o.a.s.c.o.ReplicaMutator Update state numShards=2 
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:50831";,
   [junit4]   2>   "node_name":"127.0.0.1:50831_",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 13263 INFO  (zkCallback-13-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> 13263 INFO  
(zkCallback-11-thread-1-processing-n:127.0.0.1:50831_) [n:127.0.0.1:50831_    ] 
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> 13409 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7321E9508C7EB203-001/shard-1-001/cores/collection1
   [junit4]   2> 13410 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7321E9508C7EB203-001/shard-1-001
   [junit4]   2> 13416 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.e.j.s.Server jetty-9.2.13.v20150730
   [junit4]   2> 13437 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@f7cb12c{/,null,AVAILABLE}
   [junit4]   2> 13438 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.e.j.s.ServerConnector Started 
ServerConnector@223bfde6{HTTP/1.1}{127.0.0.1:36735}
   [junit4]   2> 13440 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.e.j.s.Server Started @16960ms
   [junit4]   2> 13440 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7321E9508C7EB203-001/tempDir-001/jetty1,
 solrconfig=solrconfig.xml, hostContext=/, hostPort=36735, 
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7321E9508C7EB203-001/shard-1-001/cores}
   [junit4]   2> 13441 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): 
sun.misc.Launcher$AppClassLoader@4e0e2f2a
   [junit4]   2> 13441 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7321E9508C7EB203-001/shard-1-001/'
   [junit4]   2> 13441 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 13441 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find 
system property or JNDI)
   [junit4]   2> 13461 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 13462 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 13463 INFO  (zkCallback-14-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@9900b5f name:ZooKeeperConnection 
Watcher:127.0.0.1:40824/solr got event WatchedEvent state:SyncConnected 
type:None path:null path:null type:None
   [junit4]   2> 13464 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 13464 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 13467 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn caught end of stream exception
   [junit4]   2> EndOfStreamException: Unable to read additional data from 
client sessionid 0x150ae3627e00008, likely client has closed socket
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 13467 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in 
ZooKeeper)
   [junit4]   2> 13468 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7321E9508C7EB203-001/shard-1-001/solr.xml
   [junit4]   2> 13484 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.CoresLocator Config-defined core root directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7321E9508C7EB203-001/shard-1-001/cores
   [junit4]   2> 13485 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.CoreContainer New CoreContainer 856006190
   [junit4]   2> 13485 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.CoreContainer Loading cores into CoreContainer 
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7321E9508C7EB203-001/shard-1-001/]
   [junit4]   2> 13485 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.CoreContainer loading shared library: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7321E9508C7EB203-001/shard-1-001/lib
   [junit4]   2> 13485 WARN  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to 
classloader: lib (resolved as: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7321E9508C7EB203-001/shard-1-001/lib).
   [junit4]   2> 13495 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
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> 13497 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 13497 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 13498 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.l.LogWatcher Registering Log Listener [Log4j 
(org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 13498 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40824/solr
   [junit4]   2> 13498 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 13498 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 13498 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 13501 INFO  (zkCallback-16-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@6dc4e491 
name:ZooKeeperConnection Watcher:127.0.0.1:40824 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 13501 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 13502 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 13507 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) 
[n:127.0.0.1:36735_    ] o.a.s.c.c.ConnectionManager Waiting for client to 
connect to ZooKeeper
   [junit4]   2> 13509 INFO  
(zkCallback-17-thread-1-processing-n:127.0.0.1:36735_) [n:127.0.0.1:36735_    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@10c8df1f 
name:ZooKeeperConnection Watcher:127.0.0.1:40824/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 13510 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) 
[n:127.0.0.1:36735_    ] o.a.s.c.c.ConnectionManager Client is connected to 
ZooKeeper
   [junit4]   2> 13520 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) 
[n:127.0.0.1:36735_    ] o.a.s.c.c.ZkStateReader Updating cluster state from 
ZooKeeper... 
   [junit4]   2> 14531 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) 
[n:127.0.0.1:36735_    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:36735_
   [junit4]   2> 14532 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) 
[n:127.0.0.1:36735_    ] o.a.s.c.c.SolrZkClient makePath: 
/live_nodes/127.0.0.1:36735_
   [junit4]   2> 14533 INFO  
(zkCallback-11-thread-1-processing-n:127.0.0.1:50831_) [n:127.0.0.1:50831_    ] 
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> 14533 INFO  (zkCallback-13-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> 14534 INFO  
(zkCallback-17-thread-1-processing-n:127.0.0.1:36735_) [n:127.0.0.1:36735_    ] 
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> 14536 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) 
[n:127.0.0.1:36735_    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 14537 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) 
[n:127.0.0.1:36735_    ] o.a.s.c.LeaderElector Joined leadership election with 
path: /overseer_elect/election/94767139795238922-127.0.0.1:36735_-n_0000000001
   [junit4]   2> 14539 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) 
[n:127.0.0.1:36735_    ] o.a.s.c.LeaderElector Watching path 
/overseer_elect/election/94767139795238918-127.0.0.1:50831_-n_0000000000 to 
know if I could be the leader
   [junit4]   2> 14566 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) 
[n:127.0.0.1:36735_    ] o.a.s.c.CoreContainer Security conf doesn't exist. 
Skipping setup for authorization module.
   [junit4]   2> 14566 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) 
[n:127.0.0.1:36735_    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 14567 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) 
[n:127.0.0.1:36735_    ] o.a.s.c.CoresLocator Looking for core definitions 
underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7321E9508C7EB203-001/shard-1-001/cores
   [junit4]   2> 14569 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[7321E9508C7EB203]) 
[n:127.0.0.1:36735_    ] o.a.s.c.SolrCore Created CoreDescriptor: 
{name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, 
loadOnStartup=true, configSetProperties=configsetprops.json, 
instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7321E9508C7EB203-001/shard-1-001/cores/collection1,
 collection=collection1, 
absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_7321E9508C7EB203-001/shard-1-001/cores/collection1/,
 coreNodeName=, 

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

.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:140)
   [junit4]    >        at 
org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57)
   [junit4]    >        at 
org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:261)
   [junit4]    >        at 
org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283)
   [junit4]    >        at 
org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251)
   [junit4]    >        at 
org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197)
   [junit4]    >        at 
org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:272)
   [junit4]    >        at 
org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:124)
   [junit4]    >        at 
org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:685)
   [junit4]    >        at 
org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:487)
   [junit4]    >        at 
org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:882)
   [junit4]    >        at 
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
   [junit4]    >        at 
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:107)
   [junit4]    >        at 
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:480)
   [junit4]    >        ... 5 moreThrowable #3: 
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught 
exception in thread: Thread[id=63231, name=Thread-55339, state=RUNNABLE, 
group=TGRP-FullSolrCloudDistribCmdsTest]
   [junit4]    > Caused by: java.lang.RuntimeException: 
org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting 
response from server at: http://127.0.0.1:40002/collection1
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([7321E9508C7EB203]:0)
   [junit4]    >        at 
org.apache.solr.cloud.FullSolrCloudDistribCmdsTest$1IndexThread.run(FullSolrCloudDistribCmdsTest.java:645)
   [junit4]    > Caused by: org.apache.solr.client.solrj.SolrServerException: 
Timeout occured while waiting response from server at: 
http://127.0.0.1:40002/collection1
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:587)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230)
   [junit4]    >        at 
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:150)
   [junit4]    >        at 
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:167)
   [junit4]    >        at 
org.apache.solr.cloud.FullSolrCloudDistribCmdsTest$1IndexThread.run(FullSolrCloudDistribCmdsTest.java:643)
   [junit4]    > Caused by: java.net.SocketTimeoutException: Read timed out
   [junit4]    >        at java.net.SocketInputStream.socketRead0(Native Method)
   [junit4]    >        at 
java.net.SocketInputStream.socketRead(SocketInputStream.java:116)
   [junit4]    >        at 
java.net.SocketInputStream.read(SocketInputStream.java:170)
   [junit4]    >        at 
java.net.SocketInputStream.read(SocketInputStream.java:141)
   [junit4]    >        at 
org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160)
   [junit4]    >        at 
org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:84)
   [junit4]    >        at 
org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273)
   [junit4]    >        at 
org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:140)
   [junit4]    >        at 
org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57)
   [junit4]    >        at 
org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:261)
   [junit4]    >        at 
org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283)
   [junit4]    >        at 
org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251)
   [junit4]    >        at 
org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197)
   [junit4]    >        at 
org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:272)
   [junit4]    >        at 
org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:124)
   [junit4]    >        at 
org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:685)
   [junit4]    >        at 
org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:487)
   [junit4]    >        at 
org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:882)
   [junit4]    >        at 
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
   [junit4]    >        at 
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:107)
   [junit4]    >        at 
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:480)
   [junit4]    >        ... 5 more
   [junit4]   2> 2598416 INFO  
(SUITE-FullSolrCloudDistribCmdsTest-seed#[7321E9508C7EB203]-worker) 
[n:127.0.0.1:41672_ c:collection2 s:shard2 r:core_node21 
x:collection2_shard2_replica1] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: test params are: codec=CheapBastard, 
sim=RandomSimilarityProvider(queryNorm=false,coord=no): {}, locale=fr_CA, 
timezone=Europe/Minsk
   [junit4]   2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 
1.8.0_45 (64-bit)/cpus=4,threads=1,free=179073136,total=511705088
   [junit4]   2> NOTE: All tests run in this JVM: [DistribCursorPagingTest, 
TestSolrCLIRunExample, TestReloadAndDeleteDocs, FacetPivotSmallTest, 
TestScoreJoinQPScore, AsyncCallRequestStatusResponseTest, 
SharedFSAutoReplicaFailoverTest, RecoveryZkTest, TestConfigSetsAPI, 
TestRawTransformer, UpdateParamsTest, TestQueryWrapperFilter, DeleteShardTest, 
TestExtendedDismaxParser, FieldMutatingUpdateProcessorTest, CdcrUpdateLogTest, 
ZkStateReaderTest, SuggesterWFSTTest, BadComponentTest, 
RAMDirectoryFactoryTest, OverseerTest, TestMiniSolrCloudCluster, 
BasicAuthIntegrationTest, CursorMarkTest, TestMaxScoreQueryParser, 
BJQParserTest, TestPostingsSolrHighlighter, TestBulkSchemaConcurrent, 
SecurityConfHandlerTest, TestPerFieldSimilarity, OverseerStatusTest, 
TestShortCircuitedRequests, TestFuzzyAnalyzedSuggestions, 
TermVectorComponentDistributedTest, TestCustomSort, DirectSolrConnectionTest, 
SimpleFacetsTest, TestManagedSchemaDynamicFieldResource, SimpleMLTQParserTest, 
TestAddFieldRealTimeGet, CloudExitableDirectoryReaderTest, 
DistributedFacetPivotSmallAdvancedTest, DebugComponentTest, 
TestDefaultSimilarityFactory, SharedFSAutoReplicaFailoverUtilsTest, 
CurrencyFieldXmlFileTest, TestScoreJoinQPNoScore, TestLRUStatsCache, 
DocValuesMultiTest, TestCoreDiscovery, ZkNodePropsTest, 
TestDistributedStatsComponentCardinality, TestFastLRUCache, 
TestOverriddenPrefixQueryForCustomFieldType, TestGroupingSearch, 
TestFieldResource, TestFieldTypeResource, TestMiniSolrCloudClusterBase, 
TestXIncludeConfig, TestSolrIndexConfig, DocValuesMissingTest, TestReload, 
SolrCoreCheckLockOnStartupTest, StatelessScriptUpdateProcessorFactoryTest, 
TestTrieFacet, DirectUpdateHandlerTest, BigEndianAscendingWordDeserializerTest, 
TestWriterPerf, XmlUpdateRequestHandlerTest, StatsComponentTest, 
DistributedDebugComponentTest, TestConfigSetsAPIExclusivity, 
HdfsBasicDistributedZkTest, TestReqParamsAPI, RequestHandlersTest, 
AnalyticsQueryTest, OverriddenZkACLAndCredentialsProvidersTest, 
MultiThreadedOCPTest, BasicDistributedZk2Test, TestSearcherReuse, 
TestJsonFacets, HdfsDirectoryFactoryTest, FieldAnalysisRequestHandlerTest, 
TestStressReorder, TestSmileRequest, HardAutoCommitTest, UUIDFieldTest, 
TestCharFilters, QueryResultKeyTest, BasicDistributedZkTest, 
ChaosMonkeySafeLeaderTest, UnloadDistributedZkTest, SyncSliceTest, 
LeaderElectionIntegrationTest, ShardRoutingTest, FullSolrCloudDistribCmdsTest]
   [junit4] Completed [280/553] on J2 in 349.90s, 1 test, 1 error <<< FAILURES!

[...truncated 892 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:782:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:719:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:59:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build.xml:233:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/common-build.xml:516:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1432:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:987:
 There were test failures: 553 suites (8 ignored), 2175 tests, 3 errors, 31 
ignored (18 assumptions) [seed: 7321E9508C7EB203]

Total time: 193 minutes 32 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


---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org

Reply via email to