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 # 16 - Still Failing
Date Thu, 21 Jan 2016 13:10:27 GMT
Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-5.3/16/

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

Error Message:
commitWithin did not work on node: http://127.0.0.1:55165/h/w/collection1 expected:<68> but was:<67>

Stack Trace:
java.lang.AssertionError: commitWithin did not work on node: http://127.0.0.1:55165/h/w/collection1 expected:<68> but was:<67>
	at __randomizedtesting.SeedInfo.seed([69E3401A7537C499:E1B77FC0DBCBA961]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.junit.Assert.failNotEquals(Assert.java:647)
	at org.junit.Assert.assertEquals(Assert.java:128)
	at org.junit.Assert.assertEquals(Assert.java:472)
	at org.apache.solr.cloud.BasicDistributedZkTest.test(BasicDistributedZkTest.java:332)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886)
	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:963)
	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:938)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
	at java.lang.Thread.run(Thread.java:745)


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

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

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=28395, name=collection1, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest]
Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:33655/os_/zv: Could not find collection : awholynewstresscollection_collection1_0
	at __randomizedtesting.SeedInfo.seed([69E3401A7537C499]: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 10780 lines...]
   [junit4] Suite: org.apache.solr.cloud.BasicDistributedZkTest
   [junit4]   2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_69E3401A7537C499-001/init-core-data-001
   [junit4]   2> 1274949 INFO  (SUITE-BasicDistributedZkTest-seed#[69E3401A7537C499]-worker) [    ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /h/w
   [junit4]   2> 1274954 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1274955 INFO  (Thread-29037) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1274955 INFO  (Thread-29037) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 1275055 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.ZkTestServer start zk server on port:48576
   [junit4]   2> 1275055 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1275056 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1275058 INFO  (zkCallback-631-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6ff0a37f name:ZooKeeperConnection Watcher:127.0.0.1:48576 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1275059 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1275059 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1275059 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 1275062 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] o.a.z.s.NIOServerCnxn caught end of stream exception
   [junit4]   2> EndOfStreamException: Unable to read additional data from client sessionid 0x15264078cd30000, likely client has closed socket
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 1275062 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1275066 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1275067 INFO  (zkCallback-632-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7a5eb517 name:ZooKeeperConnection Watcher:127.0.0.1:48576/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1275067 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1275068 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1275068 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1
   [junit4]   2> 1275070 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
   [junit4]   2> 1275072 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
   [junit4]   2> 1275073 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
   [junit4]   2> 1275075 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] 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> 1275076 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 1275079 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] 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> 1275079 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
   [junit4]   2> 1275084 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] 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> 1275085 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 1275086 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] 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> 1275086 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
   [junit4]   2> 1275090 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] 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> 1275090 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
   [junit4]   2> 1275093 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] 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> 1275093 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
   [junit4]   2> 1275095 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] 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> 1275096 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
   [junit4]   2> 1275098 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] 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> 1275098 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
   [junit4]   2> 1275100 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] 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> 1275100 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 1275101 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] 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> 1275102 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
   [junit4]   2> 1275103 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] 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> 1275103 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
   [junit4]   2> 1275198 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_69E3401A7537C499-001/control-001/cores/collection1
   [junit4]   2> 1275199 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.e.j.s.Server jetty-9.2.11.v20150529
   [junit4]   2> 1275201 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@44549cfd{/h/w,null,AVAILABLE}
   [junit4]   2> 1275204 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.e.j.s.ServerConnector Started ServerConnector@13b40cba{HTTP/1.1}{127.0.0.1:53326}
   [junit4]   2> 1275204 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.e.j.s.Server Started @1278323ms
   [junit4]   2> 1275204 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_69E3401A7537C499-001/control-001/cores, hostContext=/h/w, solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_69E3401A7537C499-001/tempDir-001/control/data, hostPort=53326}
   [junit4]   2> 1275205 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@7b3cb2c6
   [junit4]   2> 1275205 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_69E3401A7537C499-001/control-001/'
   [junit4]   2> 1275223 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1275223 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1275224 INFO  (zkCallback-633-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@13b54b49 name:ZooKeeperConnection Watcher:127.0.0.1:48576/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1275225 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1275225 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1275227 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
   [junit4]   2> 1275227 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_69E3401A7537C499-001/control-001/solr.xml
   [junit4]   2> 1275241 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] 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/J2/temp/solr.cloud.BasicDistributedZkTest_69E3401A7537C499-001/control-001/cores
   [junit4]   2> 1275241 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.CoreContainer New CoreContainer 710264024
   [junit4]   2> 1275241 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] 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/J2/temp/solr.cloud.BasicDistributedZkTest_69E3401A7537C499-001/control-001/]
   [junit4]   2> 1275241 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_69E3401A7537C499-001/control-001/lib
   [junit4]   2> 1275241 WARN  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] 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/J2/temp/solr.cloud.BasicDistributedZkTest_69E3401A7537C499-001/control-001/lib).
   [junit4]   2> 1275249 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] 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> 1275250 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 1275251 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1275251 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1275251 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:48576/solr
   [junit4]   2> 1275251 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 1275252 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1275252 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1275253 INFO  (zkCallback-635-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@71323a5d name:ZooKeeperConnection Watcher:127.0.0.1:48576 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1275254 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1275255 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1275257 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1275258 INFO  (zkCallback-636-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@769c0c3a name:ZooKeeperConnection Watcher:127.0.0.1:48576/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1275258 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1275259 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
   [junit4]   2> 1275261 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
   [junit4]   2> 1275262 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
   [junit4]   2> 1275264 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
   [junit4]   2> 1275266 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
   [junit4]   2> 1275268 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 1275270 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 1275271 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 1275272 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 1275273 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53326_h%2Fw
   [junit4]   2> 1275274 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:53326_h%2Fw
   [junit4]   2> 1275275 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
   [junit4]   2> 1275277 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
   [junit4]   2> 1275278 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1275278 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95248525718388740-127.0.0.1:53326_h%2Fw-n_0000000000
   [junit4]   2> 1275279 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:53326_h%2Fw
   [junit4]   2> 1275279 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
   [junit4]   2> 1275280 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.Overseer Overseer (id=95248525718388740-127.0.0.1:53326_h%2Fw-n_0000000000) starting
   [junit4]   2> 1275282 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
   [junit4]   2> 1275288 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000
   [junit4]   2> 1275288 INFO  (OverseerCollectionProcessor-95248525718388740-127.0.0.1:53326_h%2Fw-n_0000000000) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.OverseerCollectionProcessor Process current queue of collection creations
   [junit4]   2> 1275288 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 1275288 INFO  (OverseerStateUpdate-95248525718388740-127.0.0.1:53326_h%2Fw-n_0000000000) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.Overseer Starting to work on the main queue
   [junit4]   2> 1275308 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 1275308 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 1275309 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [n:127.0.0.1:53326_h%2Fw    ] 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/J2/temp/solr.cloud.BasicDistributedZkTest_69E3401A7537C499-001/control-001/cores
   [junit4]   2> 1275310 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.SolrCore Created CoreDescriptor: {configSetProperties=configsetprops.json, loadOnStartup=true, coreNodeName=, transient=false, collection=control_collection, config=solrconfig.xml, schema=schema.xml, name=collection1, shard=, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_69E3401A7537C499-001/control-001/cores/collection1/, dataDir=data/, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_69E3401A7537C499-001/control-001/cores/collection1}
   [junit4]   2> 1275310 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_69E3401A7537C499-001/control-001/cores/collection1/
   [junit4]   2> 1275310 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.CoresLocator Found 1 core definitions
   [junit4]   2> 1275311 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection   x:collection1] o.a.s.c.ZkController publishing state=down
   [junit4]   2> 1275311 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection   x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
   [junit4]   2> 1275312 INFO  (zkCallback-636-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 1275312 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection   x:collection1] o.a.s.c.ZkController look for our core node name
   [junit4]   2> 1275313 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2
   [junit4]   2> 1275313 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 1275314 INFO  (OverseerStateUpdate-95248525718388740-127.0.0.1:53326_h%2Fw-n_0000000000) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "base_url":"http://127.0.0.1:53326/h/w",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "state":"down",
   [junit4]   2>   "node_name":"127.0.0.1:53326_h%2Fw",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "roles":null} current state version: 0
   [junit4]   2> 1275315 INFO  (OverseerStateUpdate-95248525718388740-127.0.0.1:53326_h%2Fw-n_0000000000) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "base_url":"http://127.0.0.1:53326/h/w",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "state":"down",
   [junit4]   2>   "node_name":"127.0.0.1:53326_h%2Fw",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "roles":null}
   [junit4]   2> 1275315 INFO  (OverseerStateUpdate-95248525718388740-127.0.0.1:53326_h%2Fw-n_0000000000) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection
   [junit4]   2> 1275315 INFO  (OverseerStateUpdate-95248525718388740-127.0.0.1:53326_h%2Fw-n_0000000000) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
   [junit4]   2> 1275319 INFO  (zkCallback-636-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw    ] 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> 1275320 INFO  (zkCallback-636-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 1
   [junit4]   2> 1276313 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection   x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
   [junit4]   2> 1276313 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection   x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection
   [junit4]   2> 1276313 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection   x:collection1] o.a.s.c.ZkController Collection zkNode exists
   [junit4]   2> 1276314 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection
   [junit4]   2> 1276314 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 1276314 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw 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/J2/temp/solr.cloud.BasicDistributedZkTest_69E3401A7537C499-001/control-001/cores/collection1/'
   [junit4]   2> 1276346 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection   x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 
   [junit4]   2> 1276351 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection   x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 1276365 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection   x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.3.2
   [junit4]   2> 1276393 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection   x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1276394 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection   x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml
   [junit4]   2> 1276400 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection   x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1276655 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 1276664 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection   x:collection1] o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 1276665 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection   x:collection1] o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 1276685 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection   x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1276689 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection   x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1276692 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1276702 WARN  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 1276702 WARN  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 1276702 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1276703 WARN  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 1276703 WARN  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 1276704 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw 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/J2
   [junit4]   2> 1276704 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection   x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection
   [junit4]   2> 1276704 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 1276705 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw 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/J2/temp/solr.cloud.BasicDistributedZkTest_69E3401A7537C499-001/control-001/cores/collection1/], dataDir=[null]
   [junit4]   2> 1276705 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw 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@3342ac03
   [junit4]   2> 1276705 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw 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/J2/temp/solr.cloud.BasicDistributedZkTest_69E3401A7537C499-001/control-001/cores/collection1/data
   [junit4]   2> 1276706 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw 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/J2/temp/solr.cloud.BasicDistributedZkTest_69E3401A7537C499-001/control-001/cores/collection1/data/index/
   [junit4]   2> 1276706 WARN  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw 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/J2/temp/solr.cloud.BasicDistributedZkTest_69E3401A7537C499-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index...
   [junit4]   2> 1276706 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw 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/J2/temp/solr.cloud.BasicDistributedZkTest_69E3401A7537C499-001/control-001/cores/collection1/data/index
   [junit4]   2> 1276706 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=40, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=37.8212890625, floorSegmentMB=0.5068359375, forceMergeDeletesPctAllowed=29.01626376066678, segmentsPerTier=21.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1276707 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw 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@cf67132 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7819044),segFN=segments_1,generation=1}
   [junit4]   2> 1276707 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 1276708 INFO  (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:53326_h%2Fw 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/J2/temp/solr.cloud.BasicDistributedZkTest_69E3401A7537C499-001/control-001/cores/collection1/data/
   [junit4]   2> 1276708 WARN  (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:53326_h%2Fw 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/J2/temp/solr.cloud.BasicDistributedZkTest_69E3401A7537C499-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories.
   [junit4]   2> 1276713 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 1276713 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 1276713 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 1276713 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1276714 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1276714 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw 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> 1276714 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw 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> 1276714 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw 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> 1276714 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw 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> 1276717 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1276719 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1276720 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1276721 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1276725 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /replication,/admin/system,/config,/update/json,/admin/ping,/update/json/docs,/admin/file,/admin/plugins,/schema,standard,/admin/properties,/admin/mbeans,/get,/admin/threads,/admin/logging,/admin/luke,/update/csv,/update,/admin/segments
   [junit4]   2> 1276727 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw 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> 1276751 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw 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> 1276751 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw 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> 1276752 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
   [junit4]   2> 1276752 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
   [junit4]   2> 1276761 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=10, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.14449078796270892]
   [junit4]   2> 1276761 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw 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@cf67132 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7819044),segFN=segments_1,generation=1}
   [junit4]   2> 1276761 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 1276761 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@5d97ee4b[collection1] main
   [junit4]   2> 1276762 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw 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> 1276765 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw 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> 1276765 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw 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> 1276766 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 1276766 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
   [junit4]   2> 1276766 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw 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> 1276767 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw 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> 1276768 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw 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> 1276768 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 1276768 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1276768 INFO  (searcherExecutor-3466-thread-1-processing-x:collection1 c:control_collection r:core_node1 s:shard1 n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5d97ee4b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1276769 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw 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> 1276769 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw 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> 1276769 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw 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> 1276769 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw 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 1523976413395288064
   [junit4]   2> 1276771 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw 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 1523976413395288064
   [junit4]   2> 1276771 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1
   [junit4]   2> 1276774 INFO  (coreLoadExecutor-3465-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 1276774 INFO  (coreZkRegister-3459-thread-1-processing-x:collection1 c:control_collection r:core_node1 s:shard1 n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw 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:53326/h/w collection:control_collection shard:shard1
   [junit4]   2> 1276787 INFO  (coreZkRegister-3459-thread-1-processing-x:collection1 c:control_collection r:core_node1 s:shard1 n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw 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> 1276790 INFO  (coreZkRegister-3459-thread-1-processing-x:collection1 c:control_collection r:core_node1 s:shard1 n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw 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/95248525718388740-core_node1-n_0000000000
   [junit4]   2> 1276791 INFO  (coreZkRegister-3459-thread-1-processing-x:collection1 c:control_collection r:core_node1 s:shard1 n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1
   [junit4]   2> 1276793 INFO  (zkCallback-636-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 1276800 INFO  (coreZkRegister-3459-thread-1-processing-x:collection1 c:control_collection r:core_node1 s:shard1 n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 1276800 INFO  (coreZkRegister-3459-thread-1-processing-x:collection1 c:control_collection r:core_node1 s:shard1 n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw 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> 1276800 INFO  (OverseerStateUpdate-95248525718388740-127.0.0.1:53326_h%2Fw-n_0000000000) [n:127.0.0.1:53326_h%2Fw    ] 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> 1276800 INFO  (coreZkRegister-3459-thread-1-processing-x:collection1 c:control_collection r:core_node1 s:shard1 n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:53326/h/w/collection1/
   [junit4]   2> 1276800 INFO  (coreZkRegister-3459-thread-1-processing-x:collection1 c:control_collection r:core_node1 s:shard1 n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 1276801 INFO  (coreZkRegister-3459-thread-1-processing-x:collection1 c:control_collection r:core_node1 s:shard1 n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:53326/h/w/collection1/ has no replicas
   [junit4]   2> 1276801 INFO  (coreZkRegister-3459-thread-1-processing-x:collection1 c:control_collection r:core_node1 s:shard1 n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw 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:53326/h/w/collection1/ shard1
   [junit4]   2> 1276801 INFO  (coreZkRegister-3459-thread-1-processing-x:collection1 c:control_collection r:core_node1 s:shard1 n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1
   [junit4]   2> 1276805 INFO  (zkCallback-636-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 1276806 INFO  (OverseerStateUpdate-95248525718388740-127.0.0.1:53326_h%2Fw-n_0000000000) [n:127.0.0.1:53326_h%2Fw    ] 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:53326/h/w",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "state":"active"} current state version: 1
   [junit4]   2> 1276814 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1276831 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1276843 INFO  (zkCallback-638-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6a992925 name:ZooKeeperConnection Watcher:127.0.0.1:48576/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1276843 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1276843 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1276843 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 1276845 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
   [junit4]   2> 1276846 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2
   [junit4]   2> 1276846 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1276846 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1276847 INFO  (zkCallback-639-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@52696f21 name:ZooKeeperConnection Watcher:127.0.0.1:48576/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1276848 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1276848 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1276851 INFO  (zkCallback-636-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 1276855 INFO  (OverseerStateUpdate-95248525718388740-127.0.0.1:53326_h%2Fw-n_0000000000) [n:127.0.0.1:53326_h%2Fw    ] 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: 1
   [junit4]   2> 1276855 INFO  (OverseerStateUpdate-95248525718388740-127.0.0.1:53326_h%2Fw-n_0000000000) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1
   [junit4]   2> 1276856 INFO  (zkCallback-636-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw    ] 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> 1276856 INFO  (zkCallback-638-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> 1276858 INFO  (zkCallback-636-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 2
   [junit4]   2> 1276859 INFO  (zkCallback-638-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 2
   [junit4]   2> 1276906 INFO  (coreZkRegister-3459-thread-1-processing-x:collection1 c:control_collection r:core_node1 s:shard1 n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:53326/h/w/collection1/ and leader is http://127.0.0.1:53326/h/w/collection1/
   [junit4]   2> 1276906 INFO  (coreZkRegister-3459-thread-1-processing-x:collection1 c:control_collection r:core_node1 s:shard1 n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw 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:53326/h/w
   [junit4]   2> 1276906 INFO  (coreZkRegister-3459-thread-1-processing-x:collection1 c:control_collection r:core_node1 s:shard1 n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 1276906 INFO  (coreZkRegister-3459-thread-1-processing-x:collection1 c:control_collection r:core_node1 s:shard1 n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active
   [junit4]   2> 1276907 INFO  (coreZkRegister-3459-thread-1-processing-x:collection1 c:control_collection r:core_node1 s:shard1 n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw 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> 1276908 INFO  (zkCallback-636-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 1276909 INFO  (OverseerStateUpdate-95248525718388740-127.0.0.1:53326_h%2Fw-n_0000000000) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "base_url":"http://127.0.0.1:53326/h/w",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state",
   [junit4]   2>   "core_node_name":"core_node1",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "node_name":"127.0.0.1:53326_h%2Fw",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "roles":null} current state version: 2
   [junit4]   2> 1276910 INFO  (OverseerStateUpdate-95248525718388740-127.0.0.1:53326_h%2Fw-n_0000000000) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "base_url":"http://127.0.0.1:53326/h/w",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state",
   [junit4]   2>   "core_node_name":"core_node1",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "node_name":"127.0.0.1:53326_h%2Fw",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "roles":null}
   [junit4]   2> 1276911 INFO  (OverseerStateUpdate-95248525718388740-127.0.0.1:53326_h%2Fw-n_0000000000) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/collection1/state.json
   [junit4]   2> 1276912 INFO  (zkCallback-636-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw    ] 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> 1276912 INFO  (zkCallback-638-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> 1276913 INFO  (zkCallback-638-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 3
   [junit4]   2> 1276914 INFO  (zkCallback-636-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 3
   [junit4]   2> 1276936 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_69E3401A7537C499-001/shard-1-001/cores/collection1
   [junit4]   2> 1276936 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] 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/J2/temp/solr.cloud.BasicDistributedZkTest_69E3401A7537C499-001/shard-1-001
   [junit4]   2> 1276937 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.e.j.s.Server jetty-9.2.11.v20150529
   [junit4]   2> 1276939 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5086f5b6{/h/w,null,AVAILABLE}
   [junit4]   2> 1276939 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.e.j.s.ServerConnector Started ServerConnector@337431c4{HTTP/1.1}{127.0.0.1:55165}
   [junit4]   2> 1276940 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.e.j.s.Server Started @1280058ms
   [junit4]   2> 1276940 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solrconfig=solrconfig.xml, hostPort=55165, solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_69E3401A7537C499-001/tempDir-001/jetty1, hostContext=/h/w, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_69E3401A7537C499-001/shard-1-001/cores}
   [junit4]   2> 1276940 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@7b3cb2c6
   [junit4]   2> 1276940 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_69E3401A7537C499-001/shard-1-001/'
   [junit4]   2> 1276958 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1276958 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1276960 INFO  (zkCallback-640-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7a986dec name:ZooKeeperConnection Watcher:127.0.0.1:48576/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1276960 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1276960 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1276961 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
   [junit4]   2> 1276962 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_69E3401A7537C499-001/shard-1-001/solr.xml
   [junit4]   2> 1276975 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] 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/J2/temp/solr.cloud.BasicDistributedZkTest_69E3401A7537C499-001/shard-1-001/cores
   [junit4]   2> 1276976 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.CoreContainer New CoreContainer 1337070747
   [junit4]   2> 1276976 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] 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/J2/temp/solr.cloud.BasicDistributedZkTest_69E3401A7537C499-001/shard-1-001/]
   [junit4]   2> 1276976 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_69E3401A7537C499-001/shard-1-001/lib
   [junit4]   2> 1276976 WARN  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] 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/J2/temp/solr.cloud.BasicDistributedZkTest_69E3401A7537C499-001/shard-1-001/lib).
   [junit4]   2> 1276984 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] 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> 1276985 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 1276985 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1276985 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1276986 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:48576/solr
   [junit4]   2> 1276986 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 1276986 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1276987 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1276988 INFO  (zkCallback-642-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@204083e2 name:ZooKeeperConnection Watcher:127.0.0.1:48576 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1276989 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1276989 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1276991 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [n:127.0.0.1:55165_h%2Fw    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1276992 INFO  (zkCallback-643-thread-1-processing-n:127.0.0.1:55165_h%2Fw) [n:127.0.0.1:55165_h%2Fw    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@adac72a name:ZooKeeperConnection Watcher:127.0.0.1:48576/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1276992 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [n:127.0.0.1:55165_h%2Fw    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1276996 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [n:127.0.0.1:55165_h%2Fw    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 1277016 INFO  (zkCallback-638-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> 1277016 INFO  (zkCallback-643-thread-1-processing-n:127.0.0.1:55165_h%2Fw) [n:127.0.0.1:55165_h%2Fw    ] 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> 1277016 INFO  (zkCallback-636-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw    ] 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> 1277018 INFO  (zkCallback-638-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 4
   [junit4]   2> 1277019 INFO  (zkCallback-636-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 4
   [junit4]   2> 1277020 INFO  (zkCallback-643-thread-1-processing-n:127.0.0.1:55165_h%2Fw) [n:127.0.0.1:55165_h%2Fw    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 4
   [junit4]   2> 1278002 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [n:127.0.0.1:55165_h%2Fw    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55165_h%2Fw
   [junit4]   2> 1278003 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [n:127.0.0.1:55165_h%2Fw    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:55165_h%2Fw
   [junit4]   2> 1278005 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [n:127.0.0.1:55165_h%2Fw    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1278005 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [n:127.0.0.1:55165_h%2Fw    ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95248525718388745-127.0.0.1:55165_h%2Fw-n_0000000001
   [junit4]   2> 1278006 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [n:127.0.0.1:55165_h%2Fw    ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/95248525718388740-127.0.0.1:53326_h%2Fw-n_0000000000 to know if I could be the leader
   [junit4]   2> 1278025 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [n:127.0.0.1:55165_h%2Fw    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 1278025 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [n:127.0.0.1:55165_h%2Fw    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 1278026 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [n:127.0.0.1:55165_h%2Fw    ] 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/J2/temp/solr.cloud.BasicDistributedZkTest_69E3401A7537C499-001/shard-1-001/cores
   [junit4]   2> 1278026 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [n:127.0.0.1:55165_h%2Fw    ] o.a.s.c.SolrCore Created CoreDescriptor: {shard=, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_69E3401A7537C499-001/shard-1-001/cores/collection1/, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_69E3401A7537C499-001/shard-1-001/cores/collection1, dataDir=data/, loadOnStartup=true, config=solrconfig.xml, schema=schema.xml, coreNodeName=, transient=false, configSetProperties=configsetprops.json, collection=collection1, name=collection1}
   [junit4]   2> 1278027 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [n:127.0.0.1:55165_h%2Fw    ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.BasicDistributedZkTest_69E3401A7537C499-001/shard-1-001/cores/collection1/
   [junit4]   2> 1278027 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [n:127.0.0.1:55165_h%2Fw    ] o.a.s.c.CoresLocator Found 1 core definitions
   [junit4]   2> 1278029 INFO  (coreLoadExecutor-3476-thread-1-processing-n:127.0.0.1:55165_h%2Fw) [n:127.0.0.1:55165_h%2Fw c:collection1   x:collection1] o.a.s.c.ZkController publishing state=down
   [junit4]   2> 1278029 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [n:127.0.0.1:55165_h%2Fw    ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2
   [junit4]   2> 1278029 INFO  (TEST-BasicDistributedZkTest.test-seed#[69E3401A7537C499]) [n:127.0.0.1:55165_h%2Fw    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 1278029 INFO  (coreLoadExecutor-3476-thread-1-processing-n:127.0.0.1:55165_h%2Fw) [n:127.0.0.1:55165_h%2Fw c:collection1   x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
   [junit4]   2> 1278031 INFO  (zkCallback-636-thread-1-processing-n:127.0.0.1:53326_h%2Fw) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 1278032 INFO  (OverseerStateUpdate-95248525718388740-127.0.0.1:53326_h%2Fw-n_0000000000) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "base_url":"http://127.0.0.1:55165/h/w",
   [junit4]   2>   "collection":"collection1",
   [junit4]   2>   "operation":"state",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "state":"down",
   [junit4]   2>   "node_name":"127.0.0.1:55165_h%2Fw",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "roles":null} current state version: 4
   [junit4]   2> 1278032 INFO  (coreLoadExecutor-3476-thread-1-processing-n:127.0.0.1:55165_h%2Fw) [n:127.0.0.1:55165_h%2Fw c:collection1   x:collection1] o.a.s.c.ZkController Registering watch for external collection collection1
   [junit4]   2> 1278032 INFO  (OverseerStateUpdate-95248525718388740-127.0.0.1:53326_h%2Fw-n_0000000000) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "base_url":"http://127.0.0.1:55165/h/w",
   [junit4]   2>   "collection":"collection1",
   [junit4]   2>   "operation":"state",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "state":"down",
   [junit4]   2>   "node_name":"127.0.0.1:55165_h%2Fw",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "roles":null}
   [junit4]   2> 1278032 INFO  (coreLoadExecutor-3476-thread-1-processing-n:127.0.0.1:55165_h%2Fw) [n:127.0.0.1:55165_h%2Fw c:collection1   x:collection1] o.a.s.c.c.ZkStateReader addZkWatch collection1
   [junit4]   2> 1278032 INFO  (OverseerStateUpdate-95248525718388740-127.0.0.1:53326_h%2Fw-n_0000000000) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.o.ReplicaMutator Collection already exists with numShards=2
   [junit4]   2> 1278033 INFO  (OverseerStateUpdate-95248525718388740-127.0.0.1:53326_h%2Fw-n_0000000000) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2
   [junit4]   2> 1278033 INFO  (coreLoadExecutor-3476-thread-1-processing-n:127.0.0.1:55165_h%2Fw) [n:127.0.0.1:55165_h%2Fw c:collection1   x:collection1] o.a.s.c.c.ZkStateReader Updating collection state at /collections/collection1/state.json from ZooKeeper... 
   [junit4]   2> 1278034 INFO  (coreLoadExecutor-3476-thread-1-processing-n:127.0.0.1:55165_h%2Fw) [n:127.0.0.1:55165_h%2Fw c:collection1   x:collection1] o.a.s.c.c.ZkStateReader Updating data for collection1 to ver 0 
   [junit4]   2> 1278034 INFO  (coreLoadExecutor-3476-thread-1-processing-n:127.0.0.1:55165_h%2Fw) [n:127.0.0.1:55165_h%2Fw c:collection1   x:collection1] o.a.s.c.ZkController look for our core node name
   [junit4]   2> 1278138 INFO  (OverseerStateUpdate-95248525718388740-127.0.0.1:53326_h%2Fw-n_0000000000) [n:127.0.0.1:53326_h%2Fw    ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/collection1/state.json version: 0
   [junit4]   2> 1278143 INFO  (zkCallback-643-thread-1-processing-n:127.0.0.1:55165_h%2Fw) [n:127.0.0.1:55165_h%2Fw    ] 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> 1278144 INFO  (zkCallback-643-thread-1-processing-n:127.0.0.1:55165_h%2Fw) [n:127.0.0.1:55165_h%2Fw    ] o.a.s.c.c.ZkStateReader Updating data for collection1 to ver 1 
   [junit4]   2> 1279034 INFO  (coreLoadExecutor-3476-thread-1-processing-n:127.0.0.1:55165_h%2Fw) [n:127.0.0.1:55165_h%2Fw c:collection1   x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
   [junit4]   2> 1279034 INFO  (coreLoadExecutor-3476-thread-1-processing-n:127.0.0.1:55165_h%2Fw) [n:127.0.0.1:55165_h%2Fw c:collection1   x:collection1] o.a.s.c.ZkController Check for collection zkNode:collection1
   [junit4]   2> 1279035 INFO  (coreLoadExecutor-3476-thread-1-processing-n:127.0.0.1:55165_h%2Fw) [n:127.0.0.1:55165_h%2Fw c:collection1   x:collection1] o.a.s.c.ZkController Collection zkNode exists
   [junit4]   2> 1279035 INFO  (coreLoadExecutor-3476-thread-1-processing-n:127.0.0.1:55165_h%2Fw) [n:127.0.0.1:55165_h%2Fw c:collection1   x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/collection1
   [junit4]   2> 1279038 INFO  (coreLoadExecutor-3476-thread-1-processing-n:127.0.0.1:55165_h%2Fw) [n:127.0.0.1:55165_h%2Fw c:collection1   x:collection1] o.a.s.c.c.ZkStateReader path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 1279038 INFO  (coreLoadExecutor-3476-thread-1-processing-n:127.0.0.1:55165_h%2Fw) [n:127.0.0.1:55165_h%2Fw 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/J2/temp/solr.cloud.BasicDistributedZkTest_69E3401A7537C499-001/shard-1-001/cores/collection1/'
   [junit4]   2> 1279068 INFO  (coreLoadExecutor-3476-thread-1-processing-n:127.0.0.1:55165_h%2Fw) [n:127.0.0.1:55165_h%2Fw c:collection1   x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 
   [junit4]   2> 1279080 INFO  (coreLoadExecutor-3476-thread-1-processing-n:127.0.0.1:55165_h%2Fw) [n:127.0.0.1:55165_h%2Fw c:collection1   x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 1279103 INFO  (coreLoadExecutor-3476-thread-1-processing-n:127.0.0.1:55165_h%2Fw) [n:127.0.0.1:55165_h%2Fw c:collection1   x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.3.2
   [junit4]   2> 1279139 INFO  (coreLoadExecutor-3476-thread-1-processing-n:127.0.0.1:55165_h%2Fw) [n:127.0.0.1:55165_h%2Fw c:collection1   x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1279145 INFO  (coreLoadExecutor-3476-thread-1-processing-n:127.0.0.1:55165_h%2Fw) [n:127.0.0.1:55165_h%2Fw c:collection1   x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml
   [junit4]   2> 1279189 INFO  (coreLoadExecutor-3476-thread-1-processing-n:127.0.0.1:55165_h%2Fw) [n:127.0.0.1:55165_h%2Fw c:collection1   x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1279710 INFO  (coreLoadExecutor-3476-thread-1-processing-n:127.0.0.1:55165_h%2Fw) [n:127.0.0.1:55165_h%2Fw c:collection1   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 1279721 INFO  (coreLoadExecutor-3476-thread-1-processing-n:127.0.0.1:55165_h%2Fw) [n:127.0.0.1:55165_h%2Fw c:collection1   x:collection1] o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 1279723 INFO  (coreLoadExecutor-3476-thread-1-processing-n:127.0.0.1:

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

tlyTests-5.3/solr/core/test-lib/apacheds-interceptors-authn-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-authz-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-changelog-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-collective-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-event-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-exception-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-journal-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-normalization-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-operational-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-referral-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-schema-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-subtree-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-trigger-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-jdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-jdbm1-2.0.0-M2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-kerberos-codec-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-ldif-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-mavibot-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-protocol-kerberos-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-protocol-ldap-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-protocol-shared-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-xdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/api-all-1.0.0-M20.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/bcprov-jdk15-1.45.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/cglib-nodep-2.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/commons-collections-3.2.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/commons-math3-3.4.1.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/easymock-3.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/ehcache-core-2.4.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/hadoop-common-2.6.0-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/hadoop-hdfs-2.6.0-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/hadoop-minikdc-2.6.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/jackson-annotations-2.5.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/jackson-databind-2.5.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/jcl-over-slf4j-1.7.7.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/jersey-core-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/jersey-server-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/jetty-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/jetty-util-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/mina-core-2.0.0-M5.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/objenesis-1.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/lucene/build/analysis/icu/lucene-analyzers-icu-5.3.2-SNAPSHOT.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/contrib/solr-analysis-extras/classes/java:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/contrib/analysis-extras/lib/icu4j-54.1.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-launcher.jar:/x1/jenkins/.ant/lib/ivy-2.3.0.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-antlr.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-regexp.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-resolver.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-commons-net.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jmf.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-oro.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-xalan2.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-commons-logging.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jsch.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-junit4.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-javamail.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jai.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jdepend.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-junit.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-bsf.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-swing.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-netrexx.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-bcel.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-testutil.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-log4j.jar:/x1/jenkins/jenkins-slave/tools/hudson.model.JDK/latest1.7/lib/tools.jar:/x1/jenkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.1.13.jar com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe -eventsfile /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/temp/junit4-J2-20160121_113124_267.events @/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/temp/junit4-J2-20160121_113124_267.suites
   [junit4] ERROR: JVM J2 ended with an exception: Quit event not received from the forked process? This may indicate JVM crash or runner bugs.
   [junit4] 	at com.carrotsearch.ant.tasks.junit4.JUnit4.executeSlave(JUnit4.java:1504)
   [junit4] 	at com.carrotsearch.ant.tasks.junit4.JUnit4.access$000(JUnit4.java:133)
   [junit4] 	at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:964)
   [junit4] 	at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:961)
   [junit4] 	at java.util.concurrent.FutureTask.run(FutureTask.java:262)
   [junit4] 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
   [junit4] 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
   [junit4] 	at java.lang.Thread.run(Thread.java:745)

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: At least one slave process threw an exception, first: Quit event not received from the forked process? This may indicate JVM crash or runner bugs.

Total time: 244 minutes 56 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