lucene-dev mailing list archives

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

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

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

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=53068, 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:55130/vr_y/to: collection already exists: awholynewstresscollection_collection2_2
	at __randomizedtesting.SeedInfo.seed([17816CE72CFFC24D]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226)
	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:376)
	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:328)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1098)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:869)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:805)
	at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220)
	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1572)
	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1593)
	at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:887)


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

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

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




Build Log:
[...truncated 11019 lines...]
   [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
   [junit4]   2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_17816CE72CFFC24D-001/init-core-data-001
   [junit4]   2> 1073979 INFO  (SUITE-CollectionsAPIDistributedZkTest-seed#[17816CE72CFFC24D]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
   [junit4]   2> 1073979 INFO  (SUITE-CollectionsAPIDistributedZkTest-seed#[17816CE72CFFC24D]-worker) [    ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /vr_y/to
   [junit4]   2> 1073985 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1073985 INFO  (Thread-46219) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1073986 INFO  (Thread-46219) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 1074086 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.ZkTestServer start zk server on port:49013
   [junit4]   2> 1074086 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1074087 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1074090 INFO  (zkCallback-1914-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@66103eca name:ZooKeeperConnection Watcher:127.0.0.1:49013 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1074091 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1074091 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1074091 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 1074095 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1074096 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1074097 INFO  (zkCallback-1915-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@28cd8b53 name:ZooKeeperConnection Watcher:127.0.0.1:49013/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1074098 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1074098 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1074098 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1
   [junit4]   2> 1074101 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
   [junit4]   2> 1074103 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
   [junit4]   2> 1074105 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
   [junit4]   2> 1074108 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
   [junit4]   2> 1074108 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 1074112 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
   [junit4]   2> 1074112 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
   [junit4]   2> 1074116 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 1074117 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 1074119 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
   [junit4]   2> 1074119 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
   [junit4]   2> 1074121 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
   [junit4]   2> 1074121 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
   [junit4]   2> 1074123 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
   [junit4]   2> 1074123 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
   [junit4]   2> 1074125 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
   [junit4]   2> 1074125 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
   [junit4]   2> 1074127 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
   [junit4]   2> 1074128 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
   [junit4]   2> 1074130 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 1074130 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 1074132 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
   [junit4]   2> 1074132 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
   [junit4]   2> 1074134 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
   [junit4]   2> 1074134 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
   [junit4]   2> 1074234 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_17816CE72CFFC24D-001/control-001/cores/collection1
   [junit4]   2> 1074236 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.e.j.s.Server jetty-9.2.11.v20150529
   [junit4]   2> 1074238 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6cbdae43{/vr_y/to,null,AVAILABLE}
   [junit4]   2> 1074239 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.e.j.s.ServerConnector Started ServerConnector@f059451{HTTP/1.1}{127.0.0.1:36927}
   [junit4]   2> 1074240 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.e.j.s.Server Started @1077273ms
   [junit4]   2> 1074240 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_17816CE72CFFC24D-001/tempDir-001/control/data, hostContext=/vr_y/to, hostPort=36927, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_17816CE72CFFC24D-001/control-001/cores}
   [junit4]   2> 1074240 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@7b3cb2c6
   [junit4]   2> 1074240 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_17816CE72CFFC24D-001/control-001/'
   [junit4]   2> 1074261 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1074262 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1074263 INFO  (zkCallback-1916-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@22152051 name:ZooKeeperConnection Watcher:127.0.0.1:49013/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1074263 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1074264 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1074266 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
   [junit4]   2> 1074266 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_17816CE72CFFC24D-001/control-001/solr.xml
   [junit4]   2> 1074283 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_17816CE72CFFC24D-001/control-001/cores
   [junit4]   2> 1074283 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.CoreContainer New CoreContainer 1602662422
   [junit4]   2> 1074283 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_17816CE72CFFC24D-001/control-001/]
   [junit4]   2> 1074283 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_17816CE72CFFC24D-001/control-001/lib
   [junit4]   2> 1074283 WARN  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_17816CE72CFFC24D-001/control-001/lib).
   [junit4]   2> 1074294 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] 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> 1074295 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 1074296 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1074296 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1074296 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49013/solr
   [junit4]   2> 1074296 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 1074297 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1074297 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1074299 INFO  (zkCallback-1918-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6ff82639 name:ZooKeeperConnection Watcher:127.0.0.1:49013 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1074299 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1074300 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1074302 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1074304 INFO  (zkCallback-1919-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@824d359 name:ZooKeeperConnection Watcher:127.0.0.1:49013/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1074305 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1074306 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
   [junit4]   2> 1074310 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
   [junit4]   2> 1074313 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
   [junit4]   2> 1074316 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
   [junit4]   2> 1074320 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
   [junit4]   2> 1074326 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 1074335 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 1074338 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 1074345 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 1074349 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:36927_vr_y%2Fto
   [junit4]   2> 1074350 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:36927_vr_y%2Fto
   [junit4]   2> 1074352 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
   [junit4]   2> 1074355 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
   [junit4]   2> 1074357 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1074358 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95190315775885316-127.0.0.1:36927_vr_y%2Fto-n_0000000000
   [junit4]   2> 1074360 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:36927_vr_y%2Fto
   [junit4]   2> 1074360 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
   [junit4]   2> 1074362 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.Overseer Overseer (id=95190315775885316-127.0.0.1:36927_vr_y%2Fto-n_0000000000) starting
   [junit4]   2> 1074365 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
   [junit4]   2> 1074374 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000
   [junit4]   2> 1074375 INFO  (OverseerCollectionProcessor-95190315775885316-127.0.0.1:36927_vr_y%2Fto-n_0000000000) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.OverseerCollectionProcessor Process current queue of collection creations
   [junit4]   2> 1074375 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 1074376 INFO  (OverseerStateUpdate-95190315775885316-127.0.0.1:36927_vr_y%2Fto-n_0000000000) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.Overseer Starting to work on the main queue
   [junit4]   2> 1074409 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 1074409 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 1074410 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_17816CE72CFFC24D-001/control-001/cores
   [junit4]   2> 1074411 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.SolrCore Created CoreDescriptor: {dataDir=data/, loadOnStartup=true, configSetProperties=configsetprops.json, collection=control_collection, shard=, schema=schema.xml, name=collection1, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_17816CE72CFFC24D-001/control-001/cores/collection1, transient=false, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_17816CE72CFFC24D-001/control-001/cores/collection1/, coreNodeName=, config=solrconfig.xml}
   [junit4]   2> 1074412 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_17816CE72CFFC24D-001/control-001/cores/collection1/
   [junit4]   2> 1074412 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.CoresLocator Found 1 core definitions
   [junit4]   2> 1074414 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection   x:collection1] o.a.s.c.ZkController publishing state=down
   [junit4]   2> 1074414 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection   x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
   [junit4]   2> 1074414 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1
   [junit4]   2> 1074415 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 1074416 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection   x:collection1] o.a.s.c.ZkController look for our core node name
   [junit4]   2> 1074416 INFO  (zkCallback-1919-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 1074417 INFO  (OverseerStateUpdate-95190315775885316-127.0.0.1:36927_vr_y%2Fto-n_0000000000) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "operation":"state",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "base_url":"http://127.0.0.1:36927/vr_y/to",
   [junit4]   2>   "node_name":"127.0.0.1:36927_vr_y%2Fto",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "roles":null} current state version: 0
   [junit4]   2> 1074417 INFO  (OverseerStateUpdate-95190315775885316-127.0.0.1:36927_vr_y%2Fto-n_0000000000) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
   [junit4]   2>   "operation":"state",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "base_url":"http://127.0.0.1:36927/vr_y/to",
   [junit4]   2>   "node_name":"127.0.0.1:36927_vr_y%2Fto",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "roles":null}
   [junit4]   2> 1074417 INFO  (OverseerStateUpdate-95190315775885316-127.0.0.1:36927_vr_y%2Fto-n_0000000000) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection
   [junit4]   2> 1074418 INFO  (OverseerStateUpdate-95190315775885316-127.0.0.1:36927_vr_y%2Fto-n_0000000000) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
   [junit4]   2> 1074419 INFO  (zkCallback-1919-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto    ] 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> 1074423 INFO  (zkCallback-1919-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 1
   [junit4]   2> 1075416 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection   x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
   [junit4]   2> 1075416 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection   x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection
   [junit4]   2> 1075417 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection   x:collection1] o.a.s.c.ZkController Collection zkNode exists
   [junit4]   2> 1075417 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection
   [junit4]   2> 1075418 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 1075418 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection   x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_17816CE72CFFC24D-001/control-001/cores/collection1/'
   [junit4]   2> 1075458 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection   x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 
   [junit4]   2> 1075471 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection   x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 1075494 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection   x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.3.2
   [junit4]   2> 1075527 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection   x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1075528 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection   x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml
   [junit4]   2> 1075538 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection   x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1075839 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 1075849 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection   x:collection1] o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 1075852 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection   x:collection1] o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 1075883 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection   x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1075888 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection   x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1075892 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1075894 WARN  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 1075894 WARN  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 1075894 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1075897 WARN  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 1075897 WARN  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 1075899 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection   x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1
   [junit4]   2> 1075899 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection   x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection
   [junit4]   2> 1075900 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 1075900 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_17816CE72CFFC24D-001/control-001/cores/collection1/], dataDir=[null]
   [junit4]   2> 1075900 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto 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@2f75a5a7
   [junit4]   2> 1075901 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_17816CE72CFFC24D-001/control-001/cores/collection1/data
   [junit4]   2> 1075901 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_17816CE72CFFC24D-001/control-001/cores/collection1/data/index/
   [junit4]   2> 1075901 WARN  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_17816CE72CFFC24D-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index...
   [junit4]   2> 1075902 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_17816CE72CFFC24D-001/control-001/cores/collection1/data/index
   [junit4]   2> 1075902 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1858036093, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
   [junit4]   2> 1075903 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@546cc409 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@30ba562c),segFN=segments_1,generation=1}
   [junit4]   2> 1075903 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 1075904 INFO  (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_17816CE72CFFC24D-001/control-001/cores/collection1/data/
   [junit4]   2> 1075905 WARN  (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_17816CE72CFFC24D-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories.
   [junit4]   2> 1075909 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 1075909 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 1075909 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 1075909 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1075909 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1075910 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto 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> 1075910 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto 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> 1075910 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto 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> 1075912 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto 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> 1075915 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1075916 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1075918 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1075919 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1075923 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /get,/admin/ping,/config,/admin/luke,/admin/segments,/admin/threads,/admin/file,/admin/system,standard,/admin/properties,/admin/logging,/update,/replication,/schema,/update/csv,/admin/plugins,/admin/mbeans,/update/json,/update/json/docs
   [junit4]   2> 1075925 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto 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> 1075925 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto 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> 1075925 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto 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> 1075926 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
   [junit4]   2> 1075926 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
   [junit4]   2> 1075927 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=21, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3085963382633903]
   [junit4]   2> 1075978 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@546cc409 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@30ba562c),segFN=segments_1,generation=1}
   [junit4]   2> 1075978 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 1075978 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@4f60a274[collection1] main
   [junit4]   2> 1075978 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto 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> 1075983 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto 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> 1075983 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto 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> 1075984 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 1075984 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
   [junit4]   2> 1075984 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto 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> 1075985 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto 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> 1075985 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto 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> 1075985 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 1075986 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1075986 INFO  (searcherExecutor-3108-thread-1-processing-r:core_node1 n:127.0.0.1:36927_vr_y%2Fto x:collection1 c:control_collection s:shard1) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4f60a274[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1075987 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto 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> 1075987 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto 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> 1075987 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto 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> 1075987 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto 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 1523045054510268416
   [junit4]   2> 1075989 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 2 ms to seed version buckets with highest version 1523045054510268416
   [junit4]   2> 1075989 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1
   [junit4]   2> 1075991 INFO  (coreLoadExecutor-3107-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 1075991 INFO  (coreZkRegister-3101-thread-1-processing-r:core_node1 n:127.0.0.1:36927_vr_y%2Fto x:collection1 c:control_collection s:shard1) [n:127.0.0.1:36927_vr_y%2Fto 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:36927/vr_y/to collection:control_collection shard:shard1
   [junit4]   2> 1075994 INFO  (coreZkRegister-3101-thread-1-processing-r:core_node1 n:127.0.0.1:36927_vr_y%2Fto x:collection1 c:control_collection s:shard1) [n:127.0.0.1:36927_vr_y%2Fto 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> 1076008 INFO  (coreZkRegister-3101-thread-1-processing-r:core_node1 n:127.0.0.1:36927_vr_y%2Fto x:collection1 c:control_collection s:shard1) [n:127.0.0.1:36927_vr_y%2Fto 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/95190315775885316-core_node1-n_0000000000
   [junit4]   2> 1076009 INFO  (coreZkRegister-3101-thread-1-processing-r:core_node1 n:127.0.0.1:36927_vr_y%2Fto x:collection1 c:control_collection s:shard1) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1
   [junit4]   2> 1076011 INFO  (zkCallback-1919-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 1076012 INFO  (coreZkRegister-3101-thread-1-processing-r:core_node1 n:127.0.0.1:36927_vr_y%2Fto x:collection1 c:control_collection s:shard1) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 1076012 INFO  (coreZkRegister-3101-thread-1-processing-r:core_node1 n:127.0.0.1:36927_vr_y%2Fto x:collection1 c:control_collection s:shard1) [n:127.0.0.1:36927_vr_y%2Fto 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> 1076012 INFO  (coreZkRegister-3101-thread-1-processing-r:core_node1 n:127.0.0.1:36927_vr_y%2Fto x:collection1 c:control_collection s:shard1) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:36927/vr_y/to/collection1/
   [junit4]   2> 1076012 INFO  (coreZkRegister-3101-thread-1-processing-r:core_node1 n:127.0.0.1:36927_vr_y%2Fto x:collection1 c:control_collection s:shard1) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 1076012 INFO  (OverseerStateUpdate-95190315775885316-127.0.0.1:36927_vr_y%2Fto-n_0000000000) [n:127.0.0.1:36927_vr_y%2Fto    ] 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> 1076012 INFO  (coreZkRegister-3101-thread-1-processing-r:core_node1 n:127.0.0.1:36927_vr_y%2Fto x:collection1 c:control_collection s:shard1) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:36927/vr_y/to/collection1/ has no replicas
   [junit4]   2> 1076013 INFO  (coreZkRegister-3101-thread-1-processing-r:core_node1 n:127.0.0.1:36927_vr_y%2Fto x:collection1 c:control_collection s:shard1) [n:127.0.0.1:36927_vr_y%2Fto 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:36927/vr_y/to/collection1/ shard1
   [junit4]   2> 1076013 INFO  (coreZkRegister-3101-thread-1-processing-r:core_node1 n:127.0.0.1:36927_vr_y%2Fto x:collection1 c:control_collection s:shard1) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1
   [junit4]   2> 1076014 INFO  (zkCallback-1919-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto    ] 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> 1076016 INFO  (zkCallback-1919-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 2
   [junit4]   2> 1076019 INFO  (zkCallback-1919-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 1076021 INFO  (OverseerStateUpdate-95190315775885316-127.0.0.1:36927_vr_y%2Fto-n_0000000000) [n:127.0.0.1:36927_vr_y%2Fto    ] 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:36927/vr_y/to",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "state":"active"} current state version: 2
   [junit4]   2> 1076125 INFO  (zkCallback-1919-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto    ] 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> 1076128 INFO  (zkCallback-1919-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 3
   [junit4]   2> 1076171 INFO  (coreZkRegister-3101-thread-1-processing-r:core_node1 n:127.0.0.1:36927_vr_y%2Fto x:collection1 c:control_collection s:shard1) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:36927/vr_y/to/collection1/ and leader is http://127.0.0.1:36927/vr_y/to/collection1/
   [junit4]   2> 1076172 INFO  (coreZkRegister-3101-thread-1-processing-r:core_node1 n:127.0.0.1:36927_vr_y%2Fto x:collection1 c:control_collection s:shard1) [n:127.0.0.1:36927_vr_y%2Fto 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:36927/vr_y/to
   [junit4]   2> 1076172 INFO  (coreZkRegister-3101-thread-1-processing-r:core_node1 n:127.0.0.1:36927_vr_y%2Fto x:collection1 c:control_collection s:shard1) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 1076172 INFO  (coreZkRegister-3101-thread-1-processing-r:core_node1 n:127.0.0.1:36927_vr_y%2Fto x:collection1 c:control_collection s:shard1) [n:127.0.0.1:36927_vr_y%2Fto c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active
   [junit4]   2> 1076172 INFO  (coreZkRegister-3101-thread-1-processing-r:core_node1 n:127.0.0.1:36927_vr_y%2Fto x:collection1 c:control_collection s:shard1) [n:127.0.0.1:36927_vr_y%2Fto 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> 1076174 INFO  (zkCallback-1919-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 1076175 INFO  (OverseerStateUpdate-95190315775885316-127.0.0.1:36927_vr_y%2Fto-n_0000000000) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "operation":"state",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "base_url":"http://127.0.0.1:36927/vr_y/to",
   [junit4]   2>   "node_name":"127.0.0.1:36927_vr_y%2Fto",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "core_node_name":"core_node1"} current state version: 3
   [junit4]   2> 1076175 INFO  (OverseerStateUpdate-95190315775885316-127.0.0.1:36927_vr_y%2Fto-n_0000000000) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
   [junit4]   2>   "operation":"state",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "base_url":"http://127.0.0.1:36927/vr_y/to",
   [junit4]   2>   "node_name":"127.0.0.1:36927_vr_y%2Fto",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "core_node_name":"core_node1"}
   [junit4]   2> 1076279 INFO  (zkCallback-1919-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto    ] 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> 1076282 INFO  (zkCallback-1919-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 4
   [junit4]   2> 1076417 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1076417 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1076419 INFO  (zkCallback-1921-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7de925ce name:ZooKeeperConnection Watcher:127.0.0.1:49013/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1076419 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1076419 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1076419 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 1076423 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
   [junit4]   2> 1076424 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2
   [junit4]   2> 1076424 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1076424 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1076425 INFO  (zkCallback-1922-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7570a5b2 name:ZooKeeperConnection Watcher:127.0.0.1:49013/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1076426 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1076426 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1076427 INFO  (zkCallback-1919-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 1076429 INFO  (OverseerStateUpdate-95190315775885316-127.0.0.1:36927_vr_y%2Fto-n_0000000000) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "operation":"create",
   [junit4]   2>   "name":"collection1",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "stateFormat":"2"} current state version: 4
   [junit4]   2> 1076429 INFO  (OverseerStateUpdate-95190315775885316-127.0.0.1:36927_vr_y%2Fto-n_0000000000) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1
   [junit4]   2> 1076532 INFO  (OverseerStateUpdate-95190315775885316-127.0.0.1:36927_vr_y%2Fto-n_0000000000) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/collection1/state.json
   [junit4]   2> 1076534 INFO  (zkCallback-1919-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto    ] 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> 1076534 INFO  (zkCallback-1921-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> 1076537 INFO  (zkCallback-1921-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 5
   [junit4]   2> 1076538 INFO  (zkCallback-1919-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 5
   [junit4]   2> 1076552 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_17816CE72CFFC24D-001/shard-1-001/cores/collection1
   [junit4]   2> 1076553 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_17816CE72CFFC24D-001/shard-1-001
   [junit4]   2> 1076553 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.e.j.s.Server jetty-9.2.11.v20150529
   [junit4]   2> 1076556 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@596f08e4{/vr_y/to,null,AVAILABLE}
   [junit4]   2> 1076556 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.e.j.s.ServerConnector Started ServerConnector@13e69692{HTTP/1.1}{127.0.0.1:55773}
   [junit4]   2> 1076557 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.e.j.s.Server Started @1079590ms
   [junit4]   2> 1076557 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_17816CE72CFFC24D-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/vr_y/to, hostPort=55773, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_17816CE72CFFC24D-001/shard-1-001/cores}
   [junit4]   2> 1076558 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@7b3cb2c6
   [junit4]   2> 1076558 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_17816CE72CFFC24D-001/shard-1-001/'
   [junit4]   2> 1076583 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1076584 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1076586 INFO  (zkCallback-1923-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7049ac06 name:ZooKeeperConnection Watcher:127.0.0.1:49013/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1076586 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1076587 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1076589 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
   [junit4]   2> 1076589 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_17816CE72CFFC24D-001/shard-1-001/solr.xml
   [junit4]   2> 1076612 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_17816CE72CFFC24D-001/shard-1-001/cores
   [junit4]   2> 1076612 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.CoreContainer New CoreContainer 637697789
   [junit4]   2> 1076612 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_17816CE72CFFC24D-001/shard-1-001/]
   [junit4]   2> 1076612 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_17816CE72CFFC24D-001/shard-1-001/lib
   [junit4]   2> 1076613 WARN  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_17816CE72CFFC24D-001/shard-1-001/lib).
   [junit4]   2> 1076625 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] 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> 1076627 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 1076628 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1076629 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1076629 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49013/solr
   [junit4]   2> 1076629 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 1076629 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1076630 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1076632 INFO  (zkCallback-1925-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7bff9056 name:ZooKeeperConnection Watcher:127.0.0.1:49013 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1076632 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1076633 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1076636 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [n:127.0.0.1:55773_vr_y%2Fto    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1076638 INFO  (zkCallback-1926-thread-1-processing-n:127.0.0.1:55773_vr_y%2Fto) [n:127.0.0.1:55773_vr_y%2Fto    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3a30c836 name:ZooKeeperConnection Watcher:127.0.0.1:49013/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1076638 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [n:127.0.0.1:55773_vr_y%2Fto    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1076645 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [n:127.0.0.1:55773_vr_y%2Fto    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 1077653 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [n:127.0.0.1:55773_vr_y%2Fto    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55773_vr_y%2Fto
   [junit4]   2> 1077654 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [n:127.0.0.1:55773_vr_y%2Fto    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:55773_vr_y%2Fto
   [junit4]   2> 1077658 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [n:127.0.0.1:55773_vr_y%2Fto    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1077659 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [n:127.0.0.1:55773_vr_y%2Fto    ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95190315775885321-127.0.0.1:55773_vr_y%2Fto-n_0000000001
   [junit4]   2> 1077660 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [n:127.0.0.1:55773_vr_y%2Fto    ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/95190315775885316-127.0.0.1:36927_vr_y%2Fto-n_0000000000 to know if I could be the leader
   [junit4]   2> 1077674 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [n:127.0.0.1:55773_vr_y%2Fto    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 1077674 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [n:127.0.0.1:55773_vr_y%2Fto    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 1077676 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [n:127.0.0.1:55773_vr_y%2Fto    ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_17816CE72CFFC24D-001/shard-1-001/cores
   [junit4]   2> 1077676 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [n:127.0.0.1:55773_vr_y%2Fto    ] o.a.s.c.SolrCore Created CoreDescriptor: {coreNodeName=, transient=false, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_17816CE72CFFC24D-001/shard-1-001/cores/collection1/, schema=schema.xml, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_17816CE72CFFC24D-001/shard-1-001/cores/collection1, name=collection1, config=solrconfig.xml, collection=collection1, dataDir=data/, shard=, loadOnStartup=true, configSetProperties=configsetprops.json}
   [junit4]   2> 1077677 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [n:127.0.0.1:55773_vr_y%2Fto    ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_17816CE72CFFC24D-001/shard-1-001/cores/collection1/
   [junit4]   2> 1077677 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [n:127.0.0.1:55773_vr_y%2Fto    ] o.a.s.c.CoresLocator Found 1 core definitions
   [junit4]   2> 1077681 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [n:127.0.0.1:55773_vr_y%2Fto    ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1
   [junit4]   2> 1077681 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[17816CE72CFFC24D]) [n:127.0.0.1:55773_vr_y%2Fto    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 1077681 INFO  (coreLoadExecutor-3118-thread-1-processing-n:127.0.0.1:55773_vr_y%2Fto) [n:127.0.0.1:55773_vr_y%2Fto c:collection1   x:collection1] o.a.s.c.ZkController publishing state=down
   [junit4]   2> 1077682 INFO  (coreLoadExecutor-3118-thread-1-processing-n:127.0.0.1:55773_vr_y%2Fto) [n:127.0.0.1:55773_vr_y%2Fto c:collection1   x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
   [junit4]   2> 1077684 INFO  (zkCallback-1919-thread-1-processing-n:127.0.0.1:36927_vr_y%2Fto) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 1077685 INFO  (OverseerStateUpdate-95190315775885316-127.0.0.1:36927_vr_y%2Fto-n_0000000000) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "operation":"state",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "base_url":"http://127.0.0.1:55773/vr_y/to",
   [junit4]   2>   "node_name":"127.0.0.1:55773_vr_y%2Fto",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "collection":"collection1",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "roles":null} current state version: 5
   [junit4]   2> 1077685 INFO  (coreLoadExecutor-3118-thread-1-processing-n:127.0.0.1:55773_vr_y%2Fto) [n:127.0.0.1:55773_vr_y%2Fto c:collection1   x:collection1] o.a.s.c.ZkController Registering watch for external collection collection1
   [junit4]   2> 1077686 INFO  (OverseerStateUpdate-95190315775885316-127.0.0.1:36927_vr_y%2Fto-n_0000000000) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
   [junit4]   2>   "operation":"state",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "base_url":"http://127.0.0.1:55773/vr_y/to",
   [junit4]   2>   "node_name":"127.0.0.1:55773_vr_y%2Fto",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "collection":"collection1",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "roles":null}
   [junit4]   2> 1077686 INFO  (coreLoadExecutor-3118-thread-1-processing-n:127.0.0.1:55773_vr_y%2Fto) [n:127.0.0.1:55773_vr_y%2Fto c:collection1   x:collection1] o.a.s.c.c.ZkStateReader addZkWatch collection1
   [junit4]   2> 1077686 INFO  (OverseerStateUpdate-95190315775885316-127.0.0.1:36927_vr_y%2Fto-n_0000000000) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.o.ReplicaMutator Collection already exists with numShards=2
   [junit4]   2> 1077687 INFO  (OverseerStateUpdate-95190315775885316-127.0.0.1:36927_vr_y%2Fto-n_0000000000) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2
   [junit4]   2> 1077688 INFO  (coreLoadExecutor-3118-thread-1-processing-n:127.0.0.1:55773_vr_y%2Fto) [n:127.0.0.1:55773_vr_y%2Fto c:collection1   x:collection1] o.a.s.c.c.ZkStateReader Updating collection state at /collections/collection1/state.json from ZooKeeper... 
   [junit4]   2> 1077690 INFO  (coreLoadExecutor-3118-thread-1-processing-n:127.0.0.1:55773_vr_y%2Fto) [n:127.0.0.1:55773_vr_y%2Fto c:collection1   x:collection1] o.a.s.c.c.ZkStateReader Updating data for collection1 to ver 0 
   [junit4]   2> 1077690 INFO  (coreLoadExecutor-3118-thread-1-processing-n:127.0.0.1:55773_vr_y%2Fto) [n:127.0.0.1:55773_vr_y%2Fto c:collection1   x:collection1] o.a.s.c.ZkController look for our core node name
   [junit4]   2> 1077790 INFO  (OverseerStateUpdate-95190315775885316-127.0.0.1:36927_vr_y%2Fto-n_0000000000) [n:127.0.0.1:36927_vr_y%2Fto    ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/collection1/state.json version: 0
   [junit4]   2> 1077791 INFO  (zkCallback-1926-thread-1-processing-n:127.0.0.1:55773_vr_y%2Fto) [n:127.0.0.1:55773_vr_y%2Fto    ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json for collection collection1 has occurred - updating... (live nodes size: 2)
   [junit4]   2> 1077791 INFO  (zkCallback-1926-thread-1-processing-n:127.0.0.1:55773_vr_y%2Fto) [n:127.0.0.1:55773_vr_y%2Fto    ] o.a.s.c.c.ZkStateReader Updating data for collection1 to ver 1 
   [junit4]   2> 1078690 INFO  (coreLoadExecutor-3118-thread-1-processing-n:127.0.0.1:55773_vr_y%2Fto) [n:127.0.0.1:55773_vr_y%2Fto c:collection1   x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
   [junit4]   2> 1078690 INFO  (coreLoadExecutor-3118-thread-1-processing-n:127.0.0.1:55773_vr_y%2Fto) [n:127.0.0.1:55773_vr_y%2Fto c:collection1   x:collection1] o.a.s.c.ZkController Check for collection zkNode:collection1
   [junit4]   2> 1078691 INFO  (coreLoadExecutor-3118-thread-1-processing-n:127.0.0.1:55773_vr_y%2Fto) [n:127.0.0.1:55773_vr_y%2Fto c:collection1   x:collection1] o.a.s.c.ZkController Collection zkNode exists
   [junit4]   2> 1078691 INFO  (coreLoadExecutor-3118-thread-1-processing-n:127.0.0.1:55773_vr_y%2Fto) [n:127.0.0.1:55773_vr_y%2Fto c:collection1   x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/collection1
   [junit4]   2> 1078692 INFO  (coreLoadExecutor-3118-thread-1-processing-n:127.0.0.1:55773_vr_y%2Fto) [n:127.0.0.1:55773_vr_y%2Fto c:collection1   x:collection1] o.a.s.c.c.ZkStateReader path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 1078692 INFO  (coreLoadExecutor-3118-thread-1-processing-n:127.0.0.1:55773_vr_y%2Fto) [n:127.0.0.1:55773_vr_y%2Fto c:collection1   x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_17816CE72CFFC24D-001/shard-1-001/cores/collection1/'
   [junit4]   2> 1078710 INFO  (coreLoadExecutor-3118-thread-1-processing-n:127.0.0.1:55773_vr_y%2Fto) [n:127.0.0.1:55773_vr_y%2Fto c:collection1   x:collection1] o.a.s.c.Config loaded config so

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

(MetricsSourceAdapter.java:212)
   [junit4]   2> 	at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.stopSources(MetricsSystemImpl.java:461)
   [junit4]   2> 	at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.stop(MetricsSystemImpl.java:212)
   [junit4]   2> 	at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.shutdown(MetricsSystemImpl.java:592)
   [junit4]   2> 	at org.apache.hadoop.metrics2.lib.DefaultMetricsSystem.shutdownInstance(DefaultMetricsSystem.java:72)
   [junit4]   2> 	at org.apache.hadoop.metrics2.lib.DefaultMetricsSystem.shutdown(DefaultMetricsSystem.java:68)
   [junit4]   2> 	at org.apache.hadoop.hdfs.server.namenode.metrics.NameNodeMetrics.shutdown(NameNodeMetrics.java:145)
   [junit4]   2> 	at org.apache.hadoop.hdfs.server.namenode.NameNode.stop(NameNode.java:822)
   [junit4]   2> 	at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:1720)
   [junit4]   2> 	at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:1699)
   [junit4]   2> 	at org.apache.solr.cloud.hdfs.HdfsTestUtil.teardownClass(HdfsTestUtil.java:197)
   [junit4]   2> 	at org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.teardownClass(HdfsCollectionsAPIDistributedZkTest.java:50)
   [junit4]   2> 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   [junit4]   2> 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
   [junit4]   2> 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]   2> 	at java.lang.reflect.Method.invoke(Method.java:606)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:799)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
   [junit4]   2> 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> Caused by: java.lang.NullPointerException
   [junit4]   2> 	at org.apache.hadoop.hdfs.server.blockmanagement.BlocksMap.size(BlocksMap.java:198)
   [junit4]   2> 	at org.apache.hadoop.hdfs.server.blockmanagement.BlockManager.getTotalBlocks(BlockManager.java:3291)
   [junit4]   2> 	at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.getBlocksTotal(FSNamesystem.java:6223)
   [junit4]   2> 	... 53 more
   [junit4]   2> 5202056 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[17816CE72CFFC24D]-worker) [n:127.0.0.1:39353_ c:addReplicaColl s:shard1 r:core_node7 x:propertyDotName] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> Jan 11, 2016 6:17:57 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 2 leaked thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene53): {_version_=BlockTreeOrds(blocksize=128), a_i1=PostingsFormat(name=Asserting), a_t=PostingsFormat(name=Asserting), text=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), range_facet_l=PostingsFormat(name=Asserting), other_tl1=BlockTreeOrds(blocksize=128), intDefault=BlockTreeOrds(blocksize=128), range_facet_i_dv=PostingsFormat(name=Asserting), multiDefault=PostingsFormat(name=Asserting), timestamp=PostingsFormat(name=Asserting), range_facet_l_dv=FST50, id=FST50}, docValues:{range_facet_i_dv=DocValuesFormat(name=Memory), timestamp=DocValuesFormat(name=Memory), range_facet_l_dv=DocValuesFormat(name=Direct)}, sim=RandomSimilarityProvider(queryNorm=false,coord=no): {}, locale=fr_CA, timezone=Canada/Atlantic
   [junit4]   2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.7.0_80 (64-bit)/cpus=4,threads=1,free=196609024,total=533725184
   [junit4]   2> NOTE: All tests run in this JVM: [MoreLikeThisHandlerTest, TestGroupingSearch, TestCustomSort, TestCollapseQParserPlugin, SoftAutoCommitTest, SynonymTokenizerTest, TestSolrQueryParserResource, DocValuesTest, AddSchemaFieldsUpdateProcessorFactoryTest, TestNonDefinedSimilarityFactory, TestPKIAuthenticationPlugin, PolyFieldTest, TestCursorMarkWithoutUniqueKey, SOLR749Test, HdfsRecoverLeaseTest, TestSolrCLIRunExample, TestDistributedStatsComponentCardinality, TestQueryTypes, TestTolerantSearch, TestDefaultSimilarityFactory, HardAutoCommitTest, SolrCloudExampleTest, TestQuerySenderNoQuery, PathHierarchyTokenizerFactoryTest, TermVectorComponentDistributedTest, TestHdfsUpdateLog, CoreAdminRequestStatusTest, HdfsUnloadDistributedZkTest, XsltUpdateRequestHandlerTest, TestManagedResource, TestHighFrequencyDictionaryFactory, TestExtendedDismaxParser, LeaderElectionTest, TestSurroundQueryParser, TestManagedSchemaDynamicFieldResource, TestSort, TestRawResponseWriter, RankQueryTest, CleanupOldIndexTest, TestReload, QueryEqualityTest, SampleTest, CoreMergeIndexesAdminHandlerTest, TestRemoveLastDynamicCopyField, SolrCoreCheckLockOnStartupTest, SortByFunctionTest, TestBM25SimilarityFactory, TestLuceneMatchVersion, HdfsChaosMonkeySafeLeaderTest, OutputWriterTest, TestPartialUpdateDeduplication, DirectSolrSpellCheckerTest, TestExactStatsCache, SearchHandlerTest, TestUniqueKeyFieldResource, DistributedFacetPivotWhiteBoxTest, TestQueryUtils, UnloadDistributedZkTest, TestCollationFieldDocValues, TestClassNameShortening, TestBinaryResponseWriter, TestSolrConfigHandler, PrimUtilsTest, TestPerFieldSimilarity, TestSolrJ, DistributedQueryComponentCustomSortTest, ResponseLogComponentTest, TestCryptoKeys, QueryParsingTest, CloudExitableDirectoryReaderTest, TestDistributedMissingSort, StatsComponentTest, FileBasedSpellCheckerTest, TestCSVResponseWriter, TestDocSet, MultiThreadedOCPTest, TestXIncludeConfig, SolrXmlInZkTest, TestRTimer, TestSearcherReuse, QueryResultKeyTest, HLLUtilTest, DOMUtilTest, CoreAdminCreateDiscoverTest, TestUtils, TestCloudInspectUtil, OpenCloseCoreStressTest, ZkSolrClientTest, TestZkChroot, ShardRoutingCustomTest, TestDistributedSearch, NumericFieldsTest, TestElisionMultitermQuery, ExternalFileFieldSortTest, NotRequiredUniqueKeyTest, TestPhraseSuggestions, SpellPossibilityIteratorTest, TestCodecSupport, EchoParamsTest, TestSweetSpotSimilarityFactory, TimeZoneUtilsTest, TestFastOutputStream, ScriptEngineTest, OpenExchangeRatesOrgProviderTest, PluginInfoTest, DateMathParserTest, ChaosMonkeyNothingIsSafeTest, TestEmbeddedSolrServerConstructors, TestJettySolrRunner, AssignTest, CollectionReloadTest, CollectionsAPIAsyncDistributedZkTest, ConcurrentDeleteAndCreateCollectionTest, DeleteLastCustomShardedReplicaTest, DeleteReplicaTest, DistribDocExpirationUpdateProcessorTest, LeaderFailoverAfterPartitionTest, OverseerRolesTest, ReplicationFactorTest, SSLMigrationTest, ShardSplitTest, HdfsCollectionsAPIDistributedZkTest]
   [junit4] Completed [533/533] on J2 in 2158.92s, 1 test, 1 error <<< FAILURES!

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

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



Mime
View raw message