lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Jenkins Server <jenk...@builds.apache.org>
Subject [JENKINS] Lucene-Solr-NightlyTests-trunk - Build # 854 - Still Failing
Date Mon, 16 Nov 2015 12:39:59 GMT
Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/854/

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

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

Stack Trace:
java.lang.AssertionError: commitWithin did not work on node: http://127.0.0.1:33340/collection1 expected:<68> but was:<67>
	at __randomizedtesting.SeedInfo.seed([5BDA0A180335E023:D38E35C2ADC98DDB]: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:333)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:497)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1660)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:866)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:902)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:916)
	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:367)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:875)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:777)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:811)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:822)
	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:40)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	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:367)
	at java.lang.Thread.run(Thread.java:745)


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

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

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=9457, name=collection2, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest]
Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:51613/rt/g: collection already exists: awholynewstresscollection_collection2_0
	at __randomizedtesting.SeedInfo.seed([5BDA0A180335E023]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:575)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230)
	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372)
	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1099)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:870)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806)
	at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220)
	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1573)
	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1594)
	at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:876)




Build Log:
[...truncated 9989 lines...]
   [junit4] Suite: org.apache.solr.cloud.BasicDistributedZkTest
   [junit4]   2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5BDA0A180335E023-001/init-core-data-001
   [junit4]   2> 460518 INFO  (SUITE-BasicDistributedZkTest-seed#[5BDA0A180335E023]-worker) [    ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
   [junit4]   2> 460527 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 460533 INFO  (Thread-12841) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 460534 INFO  (Thread-12841) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 460633 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.ZkTestServer start zk server on port:54364
   [junit4]   2> 460634 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 460657 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 460665 INFO  (zkCallback-256-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6c1f3770 name:ZooKeeperConnection Watcher:127.0.0.1:54364 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 460666 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 460666 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 460666 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 460669 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 460677 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 460688 INFO  (zkCallback-257-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@c530e8 name:ZooKeeperConnection Watcher:127.0.0.1:54364/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 460688 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 460689 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 460689 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1
   [junit4]   2> 460692 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
   [junit4]   2> 460693 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
   [junit4]   2> 460694 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
   [junit4]   2> 460696 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
   [junit4]   2> 460696 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 460698 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
   [junit4]   2> 460698 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
   [junit4]   2> 460699 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 460699 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 460700 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
   [junit4]   2> 460700 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
   [junit4]   2> 460701 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
   [junit4]   2> 460702 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
   [junit4]   2> 460703 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
   [junit4]   2> 460703 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
   [junit4]   2> 460704 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
   [junit4]   2> 460705 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
   [junit4]   2> 460706 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
   [junit4]   2> 460706 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
   [junit4]   2> 460708 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 460708 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 460709 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
   [junit4]   2> 460709 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
   [junit4]   2> 460711 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
   [junit4]   2> 460711 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
   [junit4]   2> 461214 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5BDA0A180335E023-001/control-001/cores/collection1
   [junit4]   2> 461219 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.e.j.s.Server jetty-9.2.13.v20150730
   [junit4]   2> 461238 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@35ff7283{/,null,AVAILABLE}
   [junit4]   2> 461238 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.e.j.s.ServerConnector Started ServerConnector@41837789{HTTP/1.1}{127.0.0.1:46417}
   [junit4]   2> 461238 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.e.j.s.Server Started @464124ms
   [junit4]   2> 461238 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5BDA0A180335E023-001/tempDir-001/control/data, hostContext=/, hostPort=46417, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5BDA0A180335E023-001/control-001/cores}
   [junit4]   2> 461239 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a
   [junit4]   2> 461239 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5BDA0A180335E023-001/control-001/'
   [junit4]   2> 461239 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 461239 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 461258 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 461262 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 461277 INFO  (zkCallback-258-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3453b184 name:ZooKeeperConnection Watcher:127.0.0.1:54364/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 461278 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 461278 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 461279 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
   [junit4]   2> 461285 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5BDA0A180335E023-001/control-001/solr.xml
   [junit4]   2> 461293 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5BDA0A180335E023-001/control-001/cores
   [junit4]   2> 461293 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.CoreContainer New CoreContainer 148941025
   [junit4]   2> 461293 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5BDA0A180335E023-001/control-001/]
   [junit4]   2> 461293 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5BDA0A180335E023-001/control-001/lib
   [junit4]   2> 461293 WARN  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5BDA0A180335E023-001/control-001/lib).
   [junit4]   2> 461301 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] 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> 461315 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 461326 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 461326 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 461327 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54364/solr
   [junit4]   2> 461327 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 461327 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 461329 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 461341 INFO  (zkCallback-260-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@30e2b862 name:ZooKeeperConnection Watcher:127.0.0.1:54364 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 461352 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 461355 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 461363 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [n:127.0.0.1:46417_    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 461366 INFO  (zkCallback-261-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@56a8e209 name:ZooKeeperConnection Watcher:127.0.0.1:54364/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 461366 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [n:127.0.0.1:46417_    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 461367 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [n:127.0.0.1:46417_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
   [junit4]   2> 461370 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [n:127.0.0.1:46417_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
   [junit4]   2> 461373 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [n:127.0.0.1:46417_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
   [junit4]   2> 461376 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [n:127.0.0.1:46417_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
   [junit4]   2> 461379 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [n:127.0.0.1:46417_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
   [junit4]   2> 461383 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [n:127.0.0.1:46417_    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 461387 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [n:127.0.0.1:46417_    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 461388 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [n:127.0.0.1:46417_    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 461389 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [n:127.0.0.1:46417_    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 461390 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [n:127.0.0.1:46417_    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46417_
   [junit4]   2> 461391 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [n:127.0.0.1:46417_    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:46417_
   [junit4]   2> 461392 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [n:127.0.0.1:46417_    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
   [junit4]   2> 461393 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [n:127.0.0.1:46417_    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
   [junit4]   2> 461394 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [n:127.0.0.1:46417_    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 461395 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [n:127.0.0.1:46417_    ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94874741134655492-127.0.0.1:46417_-n_0000000000
   [junit4]   2> 461395 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [n:127.0.0.1:46417_    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:46417_
   [junit4]   2> 461395 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [n:127.0.0.1:46417_    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
   [junit4]   2> 461398 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [n:127.0.0.1:46417_    ] o.a.s.c.Overseer Overseer (id=94874741134655492-127.0.0.1:46417_-n_0000000000) starting
   [junit4]   2> 461402 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [n:127.0.0.1:46417_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
   [junit4]   2> 461422 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [n:127.0.0.1:46417_    ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000
   [junit4]   2> 461428 INFO  (OverseerCollectionConfigSetProcessor-94874741134655492-127.0.0.1:46417_-n_0000000000) [n:127.0.0.1:46417_    ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations
   [junit4]   2> 461428 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [n:127.0.0.1:46417_    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 461428 INFO  (OverseerStateUpdate-94874741134655492-127.0.0.1:46417_-n_0000000000) [n:127.0.0.1:46417_    ] o.a.s.c.Overseer Starting to work on the main queue
   [junit4]   2> 461448 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [n:127.0.0.1:46417_    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 461448 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [n:127.0.0.1:46417_    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 461449 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [n:127.0.0.1:46417_    ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5BDA0A180335E023-001/control-001/cores
   [junit4]   2> 461450 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [n:127.0.0.1:46417_    ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5BDA0A180335E023-001/control-001/cores/collection1, collection=control_collection, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5BDA0A180335E023-001/control-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=}
   [junit4]   2> 461450 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [n:127.0.0.1:46417_    ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5BDA0A180335E023-001/control-001/cores/collection1/
   [junit4]   2> 461450 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [n:127.0.0.1:46417_    ] o.a.s.c.CoresLocator Found 1 core definitions
   [junit4]   2> 461452 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection   x:collection1] o.a.s.c.ZkController publishing state=down
   [junit4]   2> 461452 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection   x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
   [junit4]   2> 461453 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [n:127.0.0.1:46417_    ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0
   [junit4]   2> 461453 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [n:127.0.0.1:46417_    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 461454 INFO  (OverseerStateUpdate-94874741134655492-127.0.0.1:46417_-n_0000000000) [n:127.0.0.1:46417_    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:46417",
   [junit4]   2>   "node_name":"127.0.0.1:46417_",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"} current state version: 0
   [junit4]   2> 461455 INFO  (OverseerStateUpdate-94874741134655492-127.0.0.1:46417_-n_0000000000) [n:127.0.0.1:46417_    ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:46417",
   [junit4]   2>   "node_name":"127.0.0.1:46417_",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 461455 INFO  (OverseerStateUpdate-94874741134655492-127.0.0.1:46417_-n_0000000000) [n:127.0.0.1:46417_    ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection
   [junit4]   2> 461456 INFO  (OverseerStateUpdate-94874741134655492-127.0.0.1:46417_-n_0000000000) [n:127.0.0.1:46417_    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
   [junit4]   2> 461457 INFO  (zkCallback-261-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_    ] 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> 461460 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection   x:collection1] o.a.s.c.ZkController look for our core node name
   [junit4]   2> 461460 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection   x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
   [junit4]   2> 461460 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection   x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection
   [junit4]   2> 461460 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection   x:collection1] o.a.s.c.ZkController Collection zkNode exists
   [junit4]   2> 461460 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection
   [junit4]   2> 461461 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 461461 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection   x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5BDA0A180335E023-001/control-001/cores/collection1/'
   [junit4]   2> 461462 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection   x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 461462 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection   x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 461476 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection   x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 
   [junit4]   2> 461481 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection   x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 461485 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection   x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
   [junit4]   2> 461493 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection   x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 461494 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection   x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml
   [junit4]   2> 461499 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection   x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 461626 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 461632 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection   x:collection1] o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 461633 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection   x:collection1] o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 461642 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection   x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 461644 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection   x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 461646 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 461647 WARN  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 461647 WARN  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 461647 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 461647 WARN  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 461647 WARN  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 461648 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection   x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0
   [junit4]   2> 461648 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection   x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection
   [junit4]   2> 461652 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 461652 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5BDA0A180335E023-001/control-001/cores/collection1/], dataDir=[null]
   [junit4]   2> 461652 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ 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@23c1ba3d
   [junit4]   2> 461653 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5BDA0A180335E023-001/control-001/cores/collection1/data
   [junit4]   2> 461653 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5BDA0A180335E023-001/control-001/cores/collection1/data/index/
   [junit4]   2> 461653 WARN  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5BDA0A180335E023-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index...
   [junit4]   2> 461653 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5BDA0A180335E023-001/control-001/cores/collection1/data/index
   [junit4]   2> 461654 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=19, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
   [junit4]   2> 461668 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ 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(MMapDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5BDA0A180335E023-001/index-MMapDirectory-001 lockFactory=org.apache.lucene.store.NativeFSLockFactory@82ba7d5),segFN=segments_1,generation=1}
   [junit4]   2> 461668 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 461690 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 461690 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 461690 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 461690 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 461690 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 461690 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ 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> 461690 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ 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> 461691 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ 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> 461691 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ 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> 461691 WARN  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}}
   [junit4]   2> 461692 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 461694 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 461694 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 461709 INFO  (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:46417_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5BDA0A180335E023-001/control-001/cores/collection1/data/
   [junit4]   2> 461710 WARN  (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:46417_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5BDA0A180335E023-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories.
   [junit4]   2> 461710 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 461714 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/dump,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
   [junit4]   2> 461715 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ 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> 461730 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ 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> 461730 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ 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> 461731 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
   [junit4]   2> 461731 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
   [junit4]   2> 461731 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ 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=26, maxMergeAtOnceExplicit=31, maxMergedSegmentMB=9.5048828125, floorSegmentMB=1.0068359375, forceMergeDeletesPctAllowed=17.992572174167172, segmentsPerTier=15.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 461741 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ 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(MMapDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5BDA0A180335E023-001/index-MMapDirectory-001 lockFactory=org.apache.lucene.store.NativeFSLockFactory@82ba7d5),segFN=segments_1,generation=1}
   [junit4]   2> 461741 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 461742 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@57b5d41d[collection1] main
   [junit4]   2> 461742 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ 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> 461743 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ 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> 461743 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ 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> 461743 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 461743 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
   [junit4]   2> 461743 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ 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> 461744 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ 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> 461744 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ 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> 461744 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 461744 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 461745 INFO  (searcherExecutor-1145-thread-1-processing-n:127.0.0.1:46417_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46417_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@57b5d41d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 461745 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ 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> 461745 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ 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> 461745 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ 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> 461745 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ 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 1517995859415924736
   [junit4]   2> 461747 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 2.0ms to seed version buckets with highest version 1517995859415924736
   [junit4]   2> 461747 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1
   [junit4]   2> 461748 INFO  (coreLoadExecutor-1144-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 461757 INFO  (coreZkRegister-1138-thread-1-processing-n:127.0.0.1:46417_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46417_ 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:46417 collection:control_collection shard:shard1
   [junit4]   2> 461763 INFO  (coreZkRegister-1138-thread-1-processing-n:127.0.0.1:46417_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46417_ 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> 461769 INFO  (coreZkRegister-1138-thread-1-processing-n:127.0.0.1:46417_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46417_ 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/94874741134655492-core_node1-n_0000000000
   [junit4]   2> 461770 INFO  (coreZkRegister-1138-thread-1-processing-n:127.0.0.1:46417_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46417_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1
   [junit4]   2> 461771 INFO  (OverseerStateUpdate-94874741134655492-127.0.0.1:46417_-n_0000000000) [n:127.0.0.1:46417_    ] 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> 461773 INFO  (coreZkRegister-1138-thread-1-processing-n:127.0.0.1:46417_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46417_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 461773 INFO  (coreZkRegister-1138-thread-1-processing-n:127.0.0.1:46417_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46417_ 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> 461773 INFO  (coreZkRegister-1138-thread-1-processing-n:127.0.0.1:46417_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46417_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:46417/collection1/
   [junit4]   2> 461773 INFO  (coreZkRegister-1138-thread-1-processing-n:127.0.0.1:46417_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46417_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 461773 INFO  (coreZkRegister-1138-thread-1-processing-n:127.0.0.1:46417_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46417_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:46417/collection1/ has no replicas
   [junit4]   2> 461774 INFO  (coreZkRegister-1138-thread-1-processing-n:127.0.0.1:46417_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46417_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1
   [junit4]   2> 461775 INFO  (coreZkRegister-1138-thread-1-processing-n:127.0.0.1:46417_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46417_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node
   [junit4]   2> 461777 INFO  (OverseerStateUpdate-94874741134655492-127.0.0.1:46417_-n_0000000000) [n:127.0.0.1:46417_    ] 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:46417",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "state":"active"} current state version: 1
   [junit4]   2> 461778 INFO  (coreZkRegister-1138-thread-1-processing-n:127.0.0.1:46417_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46417_ 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:46417/collection1/ shard1
   [junit4]   2> 461879 INFO  (zkCallback-261-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_    ] 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> 461929 INFO  (coreZkRegister-1138-thread-1-processing-n:127.0.0.1:46417_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46417_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:46417/collection1/ and leader is http://127.0.0.1:46417/collection1/
   [junit4]   2> 461929 INFO  (coreZkRegister-1138-thread-1-processing-n:127.0.0.1:46417_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46417_ 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:46417
   [junit4]   2> 461929 INFO  (coreZkRegister-1138-thread-1-processing-n:127.0.0.1:46417_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46417_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 461929 INFO  (coreZkRegister-1138-thread-1-processing-n:127.0.0.1:46417_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46417_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active
   [junit4]   2> 461929 INFO  (coreZkRegister-1138-thread-1-processing-n:127.0.0.1:46417_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46417_ 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> 461931 INFO  (OverseerStateUpdate-94874741134655492-127.0.0.1:46417_-n_0000000000) [n:127.0.0.1:46417_    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "core_node_name":"core_node1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:46417",
   [junit4]   2>   "node_name":"127.0.0.1:46417_",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"} current state version: 2
   [junit4]   2> 461931 INFO  (OverseerStateUpdate-94874741134655492-127.0.0.1:46417_-n_0000000000) [n:127.0.0.1:46417_    ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "core_node_name":"core_node1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:46417",
   [junit4]   2>   "node_name":"127.0.0.1:46417_",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 461955 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 461955 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 461962 INFO  (zkCallback-263-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@16700a0c name:ZooKeeperConnection Watcher:127.0.0.1:54364/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 461963 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 461963 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 461963 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 461967 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
   [junit4]   2> 461967 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2
   [junit4]   2> 461967 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 461968 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 461977 INFO  (zkCallback-264-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@66c239af name:ZooKeeperConnection Watcher:127.0.0.1:54364/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 461977 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 461977 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 461980 INFO  (OverseerStateUpdate-94874741134655492-127.0.0.1:46417_-n_0000000000) [n:127.0.0.1:46417_    ] 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: 2
   [junit4]   2> 461980 INFO  (OverseerStateUpdate-94874741134655492-127.0.0.1:46417_-n_0000000000) [n:127.0.0.1:46417_    ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1
   [junit4]   2> 461980 INFO  (zkCallback-261-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_    ] 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> 461983 INFO  (zkCallback-263-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> 462103 INFO  (OverseerStateUpdate-94874741134655492-127.0.0.1:46417_-n_0000000000) [n:127.0.0.1:46417_    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/collection1/state.json
   [junit4]   2> 462508 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5BDA0A180335E023-001/shard-1-001/cores/collection1
   [junit4]   2> 462520 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5BDA0A180335E023-001/shard-1-001
   [junit4]   2> 462521 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.e.j.s.Server jetty-9.2.13.v20150730
   [junit4]   2> 462526 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6f8aa9e{/,null,AVAILABLE}
   [junit4]   2> 462526 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.e.j.s.ServerConnector Started ServerConnector@6f51d122{HTTP/1.1}{127.0.0.1:38919}
   [junit4]   2> 462526 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.e.j.s.Server Started @465412ms
   [junit4]   2> 462526 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5BDA0A180335E023-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/, hostPort=38919, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5BDA0A180335E023-001/shard-1-001/cores}
   [junit4]   2> 462530 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a
   [junit4]   2> 462530 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5BDA0A180335E023-001/shard-1-001/'
   [junit4]   2> 462530 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 462530 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 462547 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 462548 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 462555 INFO  (zkCallback-265-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@78fc4845 name:ZooKeeperConnection Watcher:127.0.0.1:54364/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 462555 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 462555 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 462561 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
   [junit4]   2> 462561 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5BDA0A180335E023-001/shard-1-001/solr.xml
   [junit4]   2> 462569 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5BDA0A180335E023-001/shard-1-001/cores
   [junit4]   2> 462569 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.CoreContainer New CoreContainer 415898173
   [junit4]   2> 462569 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5BDA0A180335E023-001/shard-1-001/]
   [junit4]   2> 462569 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5BDA0A180335E023-001/shard-1-001/lib
   [junit4]   2> 462570 WARN  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5BDA0A180335E023-001/shard-1-001/lib).
   [junit4]   2> 462587 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] 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> 462604 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 462604 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 462604 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 462605 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54364/solr
   [junit4]   2> 462605 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 462605 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 462617 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 462631 INFO  (zkCallback-267-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@53318ddf name:ZooKeeperConnection Watcher:127.0.0.1:54364 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 462633 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 462634 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 462637 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [n:127.0.0.1:38919_    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 462659 INFO  (zkCallback-268-thread-1-processing-n:127.0.0.1:38919_) [n:127.0.0.1:38919_    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@360ee680 name:ZooKeeperConnection Watcher:127.0.0.1:54364/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 462659 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [n:127.0.0.1:38919_    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 462665 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [n:127.0.0.1:38919_    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 463671 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [n:127.0.0.1:38919_    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38919_
   [junit4]   2> 463672 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [n:127.0.0.1:38919_    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:38919_
   [junit4]   2> 463672 INFO  (zkCallback-263-thread-1) [    ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1)
   [junit4]   2> 463672 INFO  (zkCallback-261-thread-1-processing-n:127.0.0.1:46417_) [n:127.0.0.1:46417_    ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1)
   [junit4]   2> 463673 INFO  (zkCallback-268-thread-1-processing-n:127.0.0.1:38919_) [n:127.0.0.1:38919_    ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1)
   [junit4]   2> 463676 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [n:127.0.0.1:38919_    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 463676 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [n:127.0.0.1:38919_    ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94874741134655497-127.0.0.1:38919_-n_0000000001
   [junit4]   2> 463677 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [n:127.0.0.1:38919_    ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/94874741134655492-127.0.0.1:46417_-n_0000000000 to know if I could be the leader
   [junit4]   2> 463687 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [n:127.0.0.1:38919_    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 463687 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [n:127.0.0.1:38919_    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 463688 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [n:127.0.0.1:38919_    ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5BDA0A180335E023-001/shard-1-001/cores
   [junit4]   2> 463689 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [n:127.0.0.1:38919_    ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5BDA0A180335E023-001/shard-1-001/cores/collection1, collection=collection1, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5BDA0A180335E023-001/shard-1-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=}
   [junit4]   2> 463689 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [n:127.0.0.1:38919_    ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5BDA0A180335E023-001/shard-1-001/cores/collection1/
   [junit4]   2> 463689 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [n:127.0.0.1:38919_    ] o.a.s.c.CoresLocator Found 1 core definitions
   [junit4]   2> 463691 INFO  (coreLoadExecutor-1155-thread-1-processing-n:127.0.0.1:38919_) [n:127.0.0.1:38919_ c:collection1   x:collection1] o.a.s.c.ZkController publishing state=down
   [junit4]   2> 463691 INFO  (coreLoadExecutor-1155-thread-1-processing-n:127.0.0.1:38919_) [n:127.0.0.1:38919_ c:collection1   x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
   [junit4]   2> 463693 INFO  (coreLoadExecutor-1155-thread-1-processing-n:127.0.0.1:38919_) [n:127.0.0.1:38919_ c:collection1   x:collection1] o.a.s.c.ZkController Registering watch for collection collection1
   [junit4]   2> 463693 INFO  (coreLoadExecutor-1155-thread-1-processing-n:127.0.0.1:38919_) [n:127.0.0.1:38919_ c:collection1   x:collection1] o.a.s.c.c.ZkStateReader addZkWatch collection1
   [junit4]   2> 463693 INFO  (coreLoadExecutor-1155-thread-1-processing-n:127.0.0.1:38919_) [n:127.0.0.1:38919_ c:collection1   x:collection1] o.a.s.c.c.ZkStateReader Add data for collection1 ver 0 
   [junit4]   2> 463693 INFO  (coreLoadExecutor-1155-thread-1-processing-n:127.0.0.1:38919_) [n:127.0.0.1:38919_ c:collection1   x:collection1] o.a.s.c.ZkController look for our core node name
   [junit4]   2> 463694 INFO  (OverseerStateUpdate-94874741134655492-127.0.0.1:46417_-n_0000000000) [n:127.0.0.1:46417_    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:38919",
   [junit4]   2>   "node_name":"127.0.0.1:38919_",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"collection1",
   [junit4]   2>   "operation":"state"} current state version: 3
   [junit4]   2> 463696 INFO  (OverseerStateUpdate-94874741134655492-127.0.0.1:46417_-n_0000000000) [n:127.0.0.1:46417_    ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:38919",
   [junit4]   2>   "node_name":"127.0.0.1:38919_",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"collection1",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 463696 INFO  (OverseerStateUpdate-94874741134655492-127.0.0.1:46417_-n_0000000000) [n:127.0.0.1:46417_    ] o.a.s.c.o.ReplicaMutator Collection already exists with numShards=2
   [junit4]   2> 463696 INFO  (OverseerStateUpdate-94874741134655492-127.0.0.1:46417_-n_0000000000) [n:127.0.0.1:46417_    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2
   [junit4]   2> 463698 INFO  (OverseerStateUpdate-94874741134655492-127.0.0.1:46417_-n_0000000000) [n:127.0.0.1:46417_    ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/collection1/state.json version: 0
   [junit4]   2> 463698 INFO  (zkCallback-268-thread-1-processing-n:127.0.0.1:38919_) [n:127.0.0.1:38919_    ] 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> 463699 INFO  (zkCallback-268-thread-1-processing-n:127.0.0.1:38919_) [n:127.0.0.1:38919_    ] o.a.s.c.c.ZkStateReader Updating data for collection1 from 0 to 1 
   [junit4]   2> 463711 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [n:127.0.0.1:38919_    ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0
   [junit4]   2> 463711 INFO  (TEST-BasicDistributedZkTest.test-seed#[5BDA0A180335E023]) [n:127.0.0.1:38919_    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 464693 INFO  (coreLoadExecutor-1155-thread-1-processing-n:127.0.0.1:38919_) [n:127.0.0.1:38919_ c:collection1   x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
   [junit4]   2> 464693 INFO  (coreLoadExecutor-1155-thread-1-processing-n:127.0.0.1:38919_) [n:127.0.0.1:38919_ c:collection1   x:collection1] o.a.s.c.ZkController Check for collection zkNode:collection1
   [junit4]   2> 464694 INFO  (coreLoadExecutor-1155-thread-1-processing-n:127.0.0.1:38919_) [n:127.0.0.1:38919_ c:collection1   x:collection1] o.a.s.c.ZkController Collection zkNode exists
   [junit4]   2> 464694 INFO  (coreLoadExecutor-1155-thread-1-processing-n:127.0.0.1:38919_) [n:127.0.0.1:38919_ c:collection1   x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/collection1
   [junit4]   2> 464694 INFO  (coreLoadExecutor-1155-thread-1-processing-n:127.0.0.1:38919_) [n:127.0.0.1:38919_ c:collection1   x:collection1] o.a.s.c.c.ZkStateReader path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 464695 INFO  (coreLoadExecutor-1155-thread-1-processing-n:127.0.0.1:38919_) [n:127.0.0.1:38919_ c:collection1   x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.BasicDistributedZkTest_5BDA0A180335E023-001/shard-1-001/cores/collection1/'
   [junit4]   2> 464695 INFO  (coreLoadExecutor-1155-thread-1-processing-n:127.0.0.1:38919_) [n:127.0.0.1:38919_ c:collection1   x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 464695 INFO  (coreLoadExecutor-1155-thread-1-processing-n:127.0.0.1:38919_) [n:127.0.0.1:38919_ c:collection1   x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 464723 INFO  (coreLoadExecutor-1155-thread-1-processing-n:127.0.0.1:38919_) [n:127.0.0.1:38919_ c:collection1   x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 
   [junit4]   2> 464728 INFO  (coreLoadExecutor-1155-thread-1-processing-n:127.0.0.1:38919_) [n:127.0.0.1:38919_ c:collection1   x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 464731 INFO  (coreLoadExecutor-1155-thread-1-processing-n:127.0.0.1:38919_) [n:127.0.0.1:38919_ c:collection1   x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
   [junit4]   2> 464742 INFO  (coreLoadExecutor-1155-thread-1-processing-n:127.0.0.1:38919_) [n:127.0.0.1:38919_ c

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

ollectionsAPIDistributedZkTest]
   [junit4]    > Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:51613/rt/g: collection already exists: awholynewstresscollection_collection5_0
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([5BDA0A180335E023]:0)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:575)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1099)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:870)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806)
   [junit4]    > 	at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220)
   [junit4]    > 	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1573)
   [junit4]    > 	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1594)
   [junit4]    > 	at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:876)Throwable #5: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=9455, name=collection0, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest]
   [junit4]    > Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:51613/rt/g: collection already exists: awholynewstresscollection_collection0_0
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([5BDA0A180335E023]:0)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:575)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1099)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:870)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806)
   [junit4]    > 	at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220)
   [junit4]    > 	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1573)
   [junit4]    > 	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1594)
   [junit4]    > 	at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:876)Throwable #6: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=9456, name=collection1, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest]
   [junit4]    > Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:51613/rt/g: Could not find collection : awholynewstresscollection_collection1_0
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([5BDA0A180335E023]:0)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:575)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1099)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:870)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806)
   [junit4]    > 	at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220)
   [junit4]    > 	at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:883)
   [junit4]   2> 1473001 INFO  (SUITE-CollectionsAPIDistributedZkTest-seed#[5BDA0A180335E023]-worker) [n:127.0.0.1:51814_rt%2Fg c:addReplicaColl s:shard2 r:core_node6 x:addReplicaColl_shard2_replica3] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene60): {other_tl1=PostingsFormat(name=MockRandom), range_facet_l_dv=PostingsFormat(name=LuceneFixedGap), _version_=PostingsFormat(name=MockRandom), multiDefault=PostingsFormat(name=Direct), a_t=PostingsFormat(name=MockRandom), intDefault=PostingsFormat(name=MockRandom), a_i1=PostingsFormat(name=MockRandom), id=PostingsFormat(name=LuceneFixedGap), range_facet_i_dv=PostingsFormat(name=MockRandom), text=PostingsFormat(name=Asserting), range_facet_l=PostingsFormat(name=MockRandom), timestamp=PostingsFormat(name=MockRandom)}, docValues:{range_facet_l_dv=DocValuesFormat(name=Lucene54), range_facet_i_dv=DocValuesFormat(name=Direct), timestamp=DocValuesFormat(name=Direct)}, sim=RandomSimilarityProvider(queryNorm=false,coord=no): {}, locale=ro, timezone=Africa/Libreville
   [junit4]   2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.8.0_66 (64-bit)/cpus=4,threads=1,free=90138976,total=512229376
   [junit4]   2> NOTE: All tests run in this JVM: [TestJmxMonitoredMap, CollectionTooManyReplicasTest, TestRemoveLastDynamicCopyField, TestSolrQueryParserResource, TestMacros, TestWriterPerf, TestSortByMinMaxFunction, SSLMigrationTest, HardAutoCommitTest, TestFileDictionaryLookup, TestStressUserVersions, TestCoreDiscovery, TestManagedSchema, LeaderElectionTest, TestObjectReleaseTracker, TestStressReorder, TestHighFrequencyDictionaryFactory, UnloadDistributedZkTest, SuggesterTSTTest, TestPostingsSolrHighlighter, TestSolrDeletionPolicy1, PreAnalyzedUpdateProcessorTest, TestUtils, TestRebalanceLeaders, TestRTimerTree, XmlUpdateRequestHandlerTest, TestFilteredDocIdSet, FieldAnalysisRequestHandlerTest, CustomCollectionTest, SuggesterWFSTTest, TestRequestStatusCollectionAPI, TermVectorComponentDistributedTest, TestReplicationHandlerBackup, TestCollapseQParserPlugin, TestCryptoKeys, HighlighterConfigTest, TestSchemaNameResource, TestJsonFacets, ScriptEngineTest, TestRecovery, CoreAdminRequestStatusTest, DirectUpdateHandlerTest, ExplicitHLLTest, TestSchemaSimilarityResource, UpdateParamsTest, TestSuggestSpellingConverter, PrimitiveFieldTypeTest, RuleEngineTest, OverseerTest, TestZkChroot, SpatialRPTFieldTypeTest, SuggesterTest, TestSolrQueryParser, BasicDistributedZk2Test, QueryParsingTest, DateMathParserTest, DirectUpdateHandlerOptimizeTest, CoreAdminHandlerTest, TestDistribDocBasedVersion, CircularListTest, HdfsNNFailoverTest, TestConfigSetsAPI, DistributedExpandComponentTest, FullSolrCloudDistribCmdsTest, TestSolrIndexConfig, TestRuleBasedAuthorizationPlugin, LukeRequestHandlerTest, FacetPivotSmallTest, NoCacheHeaderTest, BadIndexSchemaTest, TestCrossCoreJoin, CurrencyFieldXmlFileTest, SimpleFacetsTest, TestQueryWrapperFilter, TestCloudPivotFacet, ClusterStateTest, RequestHandlersTest, VMParamsZkACLAndCredentialsProvidersTest, StatsComponentTest, DocExpirationUpdateProcessorFactoryTest, TestSolr4Spatial2, TestSchemaManager, TestReloadAndDeleteDocs, TestCharFilters, DistribDocExpirationUpdateProcessorTest, HdfsRecoveryZkTest, DistributedQueueTest, TestReload, TestNoOpRegenerator, CollectionsAPIDistributedZkTest]
   [junit4] Completed [263/558] on J2 in 246.61s, 1 test, 1 error <<< FAILURES!

[...truncated 986 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:782: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:719: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:59: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build.xml:233: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/common-build.xml:516: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1432: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:987: There were test failures: 558 suites (8 ignored), 2214 tests, 1 error, 1 failure, 32 ignored (18 assumptions) [seed: 5BDA0A180335E023]

Total time: 211 minutes 26 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