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 # 934 - Still Failing
Date Sun, 14 Feb 2016 21:33:46 GMT
Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/934/

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

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

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=813, 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:50510: collection already exists: awholynewstresscollection_collection2_6
	at __randomizedtesting.SeedInfo.seed([59CB1503372ACE0]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:577)
	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:1219)
	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1593)
	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1614)
	at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:966)


FAILED:  org.apache.solr.update.DirectUpdateHandlerTest.testExpungeDeletes

Error Message:
expected:<5> but was:<4>

Stack Trace:
java.lang.AssertionError: expected:<5> but was:<4>
	at __randomizedtesting.SeedInfo.seed([59CB1503372ACE0:29E5F5D546CB6445]: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.junit.Assert.assertEquals(Assert.java:456)
	at org.apache.solr.update.DirectUpdateHandlerTest.testExpungeDeletes(DirectUpdateHandlerTest.java:299)
	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:1764)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	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:880)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
	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:45)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
	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:53)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
	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)




Build Log:
[...truncated 11031 lines...]
   [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
   [junit4]   2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_59CB1503372ACE0-001/init-core-data-001
   [junit4]   2> 18608 INFO  (SUITE-CollectionsAPIDistributedZkTest-seed#[59CB1503372ACE0]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
   [junit4]   2> 18608 INFO  (SUITE-CollectionsAPIDistributedZkTest-seed#[59CB1503372ACE0]-worker) [    ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
   [junit4]   2> 18628 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 18632 INFO  (Thread-97) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 18633 INFO  (Thread-97) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 18731 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.ZkTestServer start zk server on port:57012
   [junit4]   2> 18750 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 18850 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 18867 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] o.a.z.s.NIOServerCnxn Exception causing close of session 0x0 due to java.io.IOException: ZooKeeperServer not running
   [junit4]   2> 20462 INFO  (zkCallback-24-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3f20b176 name:ZooKeeperConnection Watcher:127.0.0.1:57012 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 20463 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 20463 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 20465 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 20503 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 20504 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] o.a.z.s.NIOServerCnxn caught end of stream exception
   [junit4]   2> EndOfStreamException: Unable to read additional data from client sessionid 0x152e16ca8d90000, likely client has closed socket
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 20506 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 20515 INFO  (zkCallback-25-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@9049296 name:ZooKeeperConnection Watcher:127.0.0.1:57012/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 20515 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 20516 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 20516 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1
   [junit4]   2> 20528 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
   [junit4]   2> 20534 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
   [junit4]   2> 20537 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
   [junit4]   2> 20542 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] 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> 20543 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 20550 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] 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> 20550 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
   [junit4]   2> 20554 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] 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> 20554 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 20560 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] 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> 20560 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
   [junit4]   2> 20565 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] 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> 20565 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
   [junit4]   2> 20571 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] 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> 20571 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
   [junit4]   2> 20575 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] 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> 20576 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
   [junit4]   2> 20581 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] 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> 20582 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
   [junit4]   2> 20586 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] 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> 20587 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 20593 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] 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> 20593 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
   [junit4]   2> 20599 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] 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> 20599 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
   [junit4]   2> 20606 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 20607 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 20610 INFO  (zkCallback-26-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5245922c name:ZooKeeperConnection Watcher:127.0.0.1:57012 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 20610 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 20610 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 20611 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 20620 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 20624 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 20630 INFO  (zkCallback-27-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@29017e7d name:ZooKeeperConnection Watcher:127.0.0.1:57012/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 20630 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 20630 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 20631 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf2/solrconfig.xml
   [junit4]   2> 20631 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.xml
   [junit4]   2> 20643 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf2/schema.xml
   [junit4]   2> 20643 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/schema.xml
   [junit4]   2> 20650 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf2/enumsConfig.xml
   [junit4]   2> 20650 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/enumsConfig.xml
   [junit4]   2> 20655 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf2/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 20655 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 20662 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf2/stopwords.txt
   [junit4]   2> 20662 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/stopwords.txt
   [junit4]   2> 20666 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf2/protwords.txt
   [junit4]   2> 20666 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/protwords.txt
   [junit4]   2> 20670 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf2/currency.xml
   [junit4]   2> 20670 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/currency.xml
   [junit4]   2> 20674 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf2/open-exchange-rates.json
   [junit4]   2> 20674 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/open-exchange-rates.json
   [junit4]   2> 20678 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf2/mapping-ISOLatin1Accent.txt
   [junit4]   2> 20678 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/mapping-ISOLatin1Accent.txt
   [junit4]   2> 20682 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf2/old_synonyms.txt
   [junit4]   2> 20682 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/old_synonyms.txt
   [junit4]   2> 20685 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf2/synonyms.txt
   [junit4]   2> 20685 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/synonyms.txt
   [junit4]   2> 20689 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/elevate.xml to /configs/conf2/elevate.xml
   [junit4]   2> 20689 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/elevate.xml
   [junit4]   2> 20805 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_59CB1503372ACE0-001/control-001/cores/collection1
   [junit4]   2> 20808 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.e.j.s.Server jetty-9.2.13.v20150730
   [junit4]   2> 20810 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@cc0fa58{/,null,AVAILABLE}
   [junit4]   2> 20812 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.e.j.s.ServerConnector Started ServerConnector@52df766d{HTTP/1.1}{127.0.0.1:46702}
   [junit4]   2> 20812 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.e.j.s.Server Started @24495ms
   [junit4]   2> 20812 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_59CB1503372ACE0-001/tempDir-001/control/data, hostContext=/, hostPort=46702, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_59CB1503372ACE0-001/control-001/cores}
   [junit4]   2> 20812 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a
   [junit4]   2> 20812 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_59CB1503372ACE0-001/control-001'
   [junit4]   2> 20813 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 20813 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 20813 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 20818 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 20825 INFO  (zkCallback-28-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7c418dc8 name:ZooKeeperConnection Watcher:127.0.0.1:57012/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 20825 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 20825 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 20831 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
   [junit4]   2> 20831 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_59CB1503372ACE0-001/control-001/solr.xml
   [junit4]   2> 20840 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_59CB1503372ACE0-001/control-001/cores
   [junit4]   2> 20840 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.CoreContainer New CoreContainer 364362857
   [junit4]   2> 20840 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_59CB1503372ACE0-001/control-001]
   [junit4]   2> 20841 WARN  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_59CB1503372ACE0-001/control-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_59CB1503372ACE0-001/control-001/lib
   [junit4]   2> 20841 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] 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> 20843 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 20843 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 20843 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 20843 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:57012/solr
   [junit4]   2> 20860 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 20860 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 20861 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 20865 INFO  (zkCallback-31-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@efcb13b name:ZooKeeperConnection Watcher:127.0.0.1:57012 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 20865 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 20866 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 20873 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [n:127.0.0.1:46702_    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 20879 INFO  (zkCallback-32-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@31a962fb name:ZooKeeperConnection Watcher:127.0.0.1:57012/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 20879 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [n:127.0.0.1:46702_    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 20890 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [n:127.0.0.1:46702_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
   [junit4]   2> 20903 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [n:127.0.0.1:46702_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
   [junit4]   2> 20915 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [n:127.0.0.1:46702_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
   [junit4]   2> 20922 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [n:127.0.0.1:46702_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
   [junit4]   2> 20930 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [n:127.0.0.1:46702_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
   [junit4]   2> 20945 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [n:127.0.0.1:46702_    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 20950 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [n:127.0.0.1:46702_    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 20956 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [n:127.0.0.1:46702_    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 20960 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [n:127.0.0.1:46702_    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 20961 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [n:127.0.0.1:46702_    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46702_
   [junit4]   2> 20965 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [n:127.0.0.1:46702_    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:46702_
   [junit4]   2> 20975 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [n:127.0.0.1:46702_    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
   [junit4]   2> 20978 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [n:127.0.0.1:46702_    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
   [junit4]   2> 20982 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [n:127.0.0.1:46702_    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 20984 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [n:127.0.0.1:46702_    ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95386398933712902-127.0.0.1:46702_-n_0000000000
   [junit4]   2> 20989 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [n:127.0.0.1:46702_    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:46702_
   [junit4]   2> 20990 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [n:127.0.0.1:46702_    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
   [junit4]   2> 20992 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [n:127.0.0.1:46702_    ] o.a.s.c.Overseer Overseer (id=95386398933712902-127.0.0.1:46702_-n_0000000000) starting
   [junit4]   2> 21003 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [n:127.0.0.1:46702_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
   [junit4]   2> 21046 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [n:127.0.0.1:46702_    ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000
   [junit4]   2> 21050 INFO  (OverseerCollectionConfigSetProcessor-95386398933712902-127.0.0.1:46702_-n_0000000000) [n:127.0.0.1:46702_    ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations
   [junit4]   2> 21050 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [n:127.0.0.1:46702_    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 21058 INFO  (OverseerStateUpdate-95386398933712902-127.0.0.1:46702_-n_0000000000) [n:127.0.0.1:46702_    ] o.a.s.c.Overseer Starting to work on the main queue
   [junit4]   2> 21166 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [n:127.0.0.1:46702_    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 21166 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [n:127.0.0.1:46702_    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 21168 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [n:127.0.0.1:46702_    ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_59CB1503372ACE0-001/control-001/cores
   [junit4]   2> 21172 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [n:127.0.0.1:46702_    ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=control_collection, coreNodeName=, dataDir=data/, shard=}
   [junit4]   2> 21172 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [n:127.0.0.1:46702_    ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_59CB1503372ACE0-001/control-001/cores/collection1
   [junit4]   2> 21172 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [n:127.0.0.1:46702_    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
   [junit4]   2> 21178 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [n:127.0.0.1:46702_    ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1
   [junit4]   2> 21178 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [n:127.0.0.1:46702_    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 21179 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection   x:collection1] o.a.s.c.ZkController publishing state=down
   [junit4]   2> 21179 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection   x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
   [junit4]   2> 21189 INFO  (OverseerStateUpdate-95386398933712902-127.0.0.1:46702_-n_0000000000) [n:127.0.0.1:46702_    ] 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:46702",
   [junit4]   2>   "node_name":"127.0.0.1:46702_",
   [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> 21194 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection   x:collection1] o.a.s.c.ZkController look for our core node name
   [junit4]   2> 21201 INFO  (OverseerStateUpdate-95386398933712902-127.0.0.1:46702_-n_0000000000) [n:127.0.0.1:46702_    ] 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:46702",
   [junit4]   2>   "node_name":"127.0.0.1:46702_",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 21204 INFO  (OverseerStateUpdate-95386398933712902-127.0.0.1:46702_-n_0000000000) [n:127.0.0.1:46702_    ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection
   [junit4]   2> 21215 INFO  (OverseerStateUpdate-95386398933712902-127.0.0.1:46702_-n_0000000000) [n:127.0.0.1:46702_    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
   [junit4]   2> 21221 INFO  (zkCallback-32-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_    ] 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> 22195 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection   x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
   [junit4]   2> 22195 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection   x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection
   [junit4]   2> 22196 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection   x:collection1] o.a.s.c.ZkController Collection zkNode exists
   [junit4]   2> 22196 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection]
   [junit4]   2> 22198 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper
   [junit4]   2> 22198 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection   x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_59CB1503372ACE0-001/control-001/cores/collection1'
   [junit4]   2> 22198 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection   x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 22198 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection   x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 22201 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection   x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 
   [junit4]   2> 22214 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection   x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 22219 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection   x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
   [junit4]   2> 22246 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection   x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 22259 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection   x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 22424 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 22441 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection   x:collection1] o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 22442 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection   x:collection1] o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 22480 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection   x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 22484 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection   x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 22487 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 22491 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 22496 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection   x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1
   [junit4]   2> 22496 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection   x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection
   [junit4]   2> 22496 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 22497 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_59CB1503372ACE0-001/control-001/cores/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_59CB1503372ACE0-001/control-001/cores/collection1/data/]
   [junit4]   2> 22497 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ 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@7633bc35
   [junit4]   2> 22506 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_59CB1503372ACE0-001/control-001/cores/collection1/data
   [junit4]   2> 22506 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_59CB1503372ACE0-001/control-001/cores/collection1/data/index/
   [junit4]   2> 22506 WARN  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_59CB1503372ACE0-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index...
   [junit4]   2> 22507 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_59CB1503372ACE0-001/control-001/cores/collection1/data/index
   [junit4]   2> 22508 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ 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=38, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=90.91015625, floorSegmentMB=0.8583984375, forceMergeDeletesPctAllowed=13.163093030912039, segmentsPerTier=5.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 22520 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(NIOFSDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_59CB1503372ACE0-001/index-NIOFSDirectory-001 lockFactory=org.apache.lucene.store.NativeFSLockFactory@50962eab),segFN=segments_1,generation=1}
   [junit4]   2> 22520 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 22528 INFO  (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:46702_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_59CB1503372ACE0-001/control-001/cores/collection1/data/
   [junit4]   2> 22528 WARN  (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:46702_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_59CB1503372ACE0-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories.
   [junit4]   2> 22529 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 22545 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 22545 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 22545 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 22545 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 22545 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ 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> 22545 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ 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> 22546 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ 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> 22546 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ 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> 22546 WARN  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ 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> 22549 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 22554 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 22558 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 22561 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 22576 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/dump,/update/csv,/sql,/update/json/docs,/admin/luke,/export,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/stream,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
   [junit4]   2> 22577 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ 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> 22582 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ 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> 22582 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ 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> 22584 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 22584 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 22586 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ 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=23, maxMergeAtOnceExplicit=34, maxMergedSegmentMB=9.677734375, floorSegmentMB=1.166015625, forceMergeDeletesPctAllowed=16.086761724317427, segmentsPerTier=22.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 22594 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(NIOFSDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_59CB1503372ACE0-001/index-NIOFSDirectory-001 lockFactory=org.apache.lucene.store.NativeFSLockFactory@50962eab),segFN=segments_1,generation=1}
   [junit4]   2> 22594 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 22594 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@387abaad[collection1] main]
   [junit4]   2> 22595 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ 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> 22598 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ 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> 22598 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ 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> 22602 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 22602 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
   [junit4]   2> 22603 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ 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> 22603 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ 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> 22603 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ 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> 22604 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 22604 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 22615 INFO  (searcherExecutor-125-thread-1-processing-n:127.0.0.1:46702_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46702_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@387abaad[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 22616 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ 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> 22616 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ 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> 22616 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ 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> 22616 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ 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 1526182387033047040
   [junit4]   2> 22617 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with highest version 1526182387033047040
   [junit4]   2> 22619 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1
   [junit4]   2> 22621 INFO  (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 22622 INFO  (coreZkRegister-118-thread-1-processing-n:127.0.0.1:46702_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46702_ 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:46702 collection:control_collection shard:shard1
   [junit4]   2> 22635 INFO  (coreZkRegister-118-thread-1-processing-n:127.0.0.1:46702_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46702_ 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> 22647 INFO  (coreZkRegister-118-thread-1-processing-n:127.0.0.1:46702_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46702_ 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/95386398933712902-core_node1-n_0000000000
   [junit4]   2> 22648 INFO  (coreZkRegister-118-thread-1-processing-n:127.0.0.1:46702_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46702_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard=shard1 and weAreReplacement=false and leaderVoteWait=180000
   [junit4]   2> 22653 INFO  (OverseerStateUpdate-95386398933712902-127.0.0.1:46702_-n_0000000000) [n:127.0.0.1:46702_    ] 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> 22669 INFO  (coreZkRegister-118-thread-1-processing-n:127.0.0.1:46702_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46702_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 22669 INFO  (coreZkRegister-118-thread-1-processing-n:127.0.0.1:46702_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46702_ 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> 22669 INFO  (coreZkRegister-118-thread-1-processing-n:127.0.0.1:46702_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46702_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:46702/collection1/
   [junit4]   2> 22669 INFO  (coreZkRegister-118-thread-1-processing-n:127.0.0.1:46702_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46702_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 22669 INFO  (coreZkRegister-118-thread-1-processing-n:127.0.0.1:46702_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46702_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:46702/collection1/ has no replicas
   [junit4]   2> 22680 INFO  (coreZkRegister-118-thread-1-processing-n:127.0.0.1:46702_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46702_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1
   [junit4]   2> 22689 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 22693 INFO  (coreZkRegister-118-thread-1-processing-n:127.0.0.1:46702_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46702_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node
   [junit4]   2> 22700 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 22720 INFO  (zkCallback-34-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6f0d7b10 name:ZooKeeperConnection Watcher:127.0.0.1:57012/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 22720 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 22720 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 22720 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 22727 INFO  (coreZkRegister-118-thread-1-processing-n:127.0.0.1:46702_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46702_ 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:46702/collection1/ shard1
   [junit4]   2> 22727 INFO  (OverseerStateUpdate-95386398933712902-127.0.0.1:46702_-n_0000000000) [n:127.0.0.1:46702_    ] 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:46702",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "state":"active"} current state version: 1
   [junit4]   2> 22729 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
   [junit4]   2> 22729 INFO  (zkCallback-32-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_    ] 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> 22732 INFO  (zkCallback-34-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> 22779 INFO  (coreZkRegister-118-thread-1-processing-n:127.0.0.1:46702_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46702_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:46702/collection1/ and leader is http://127.0.0.1:46702/collection1/
   [junit4]   2> 22779 INFO  (coreZkRegister-118-thread-1-processing-n:127.0.0.1:46702_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46702_ 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:46702
   [junit4]   2> 22780 INFO  (coreZkRegister-118-thread-1-processing-n:127.0.0.1:46702_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46702_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 22780 INFO  (coreZkRegister-118-thread-1-processing-n:127.0.0.1:46702_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46702_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active
   [junit4]   2> 22780 INFO  (coreZkRegister-118-thread-1-processing-n:127.0.0.1:46702_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46702_ 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> 22783 INFO  (OverseerStateUpdate-95386398933712902-127.0.0.1:46702_-n_0000000000) [n:127.0.0.1:46702_    ] 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:46702",
   [junit4]   2>   "node_name":"127.0.0.1:46702_",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"} current state version: 2
   [junit4]   2> 22784 INFO  (OverseerStateUpdate-95386398933712902-127.0.0.1:46702_-n_0000000000) [n:127.0.0.1:46702_    ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "core_node_name":"core_node1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:46702",
   [junit4]   2>   "node_name":"127.0.0.1:46702_",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 22846 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_59CB1503372ACE0-001/shard-1-001/cores/collection1
   [junit4]   2> 22851 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_59CB1503372ACE0-001/shard-1-001
   [junit4]   2> 22852 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.e.j.s.Server jetty-9.2.13.v20150730
   [junit4]   2> 22857 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@66fbd515{/,null,AVAILABLE}
   [junit4]   2> 22858 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.e.j.s.ServerConnector Started ServerConnector@66cfc1d{HTTP/1.1}{127.0.0.1:56763}
   [junit4]   2> 22858 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.e.j.s.Server Started @26541ms
   [junit4]   2> 22858 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_59CB1503372ACE0-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/, hostPort=56763, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_59CB1503372ACE0-001/shard-1-001/cores}
   [junit4]   2> 22858 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a
   [junit4]   2> 22858 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_59CB1503372ACE0-001/shard-1-001'
   [junit4]   2> 22859 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 22859 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 22859 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 22860 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 22863 INFO  (zkCallback-35-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5af66e07 name:ZooKeeperConnection Watcher:127.0.0.1:57012/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 22863 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 22864 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 22872 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
   [junit4]   2> 22872 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_59CB1503372ACE0-001/shard-1-001/solr.xml
   [junit4]   2> 22880 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_59CB1503372ACE0-001/shard-1-001/cores
   [junit4]   2> 22880 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.CoreContainer New CoreContainer 637151620
   [junit4]   2> 22880 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_59CB1503372ACE0-001/shard-1-001]
   [junit4]   2> 22880 WARN  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_59CB1503372ACE0-001/shard-1-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_59CB1503372ACE0-001/shard-1-001/lib
   [junit4]   2> 22880 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] 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> 22882 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 22882 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 22882 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 22882 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:57012/solr
   [junit4]   2> 22882 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 22882 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 22883 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 22888 INFO  (zkCallback-38-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@30a895e9 name:ZooKeeperConnection Watcher:127.0.0.1:57012 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 22888 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 22889 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 22890 INFO  (zkCallback-34-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> 22890 INFO  (zkCallback-32-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_    ] 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> 22898 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [n:127.0.0.1:56763_    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 22902 INFO  (zkCallback-39-thread-1-processing-n:127.0.0.1:56763_) [n:127.0.0.1:56763_    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7e8d9527 name:ZooKeeperConnection Watcher:127.0.0.1:57012/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 22902 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [n:127.0.0.1:56763_    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 22913 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [n:127.0.0.1:56763_    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 23920 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [n:127.0.0.1:56763_    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56763_
   [junit4]   2> 23921 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [n:127.0.0.1:56763_    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:56763_
   [junit4]   2> 23923 INFO  (zkCallback-32-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_    ] 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> 23923 INFO  (zkCallback-39-thread-1-processing-n:127.0.0.1:56763_) [n:127.0.0.1:56763_    ] 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> 23924 INFO  (zkCallback-34-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> 23925 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [n:127.0.0.1:56763_    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 23925 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [n:127.0.0.1:56763_    ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95386398933712906-127.0.0.1:56763_-n_0000000001
   [junit4]   2> 23928 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [n:127.0.0.1:56763_    ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/95386398933712902-127.0.0.1:46702_-n_0000000000 to know if I could be the leader
   [junit4]   2> 23951 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [n:127.0.0.1:56763_    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 23953 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [n:127.0.0.1:56763_    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 23954 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [n:127.0.0.1:56763_    ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_59CB1503372ACE0-001/shard-1-001/cores
   [junit4]   2> 23955 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [n:127.0.0.1:56763_    ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=collection1, coreNodeName=, dataDir=data/, shard=}
   [junit4]   2> 23956 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [n:127.0.0.1:56763_    ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_59CB1503372ACE0-001/shard-1-001/cores/collection1
   [junit4]   2> 23956 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [n:127.0.0.1:56763_    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
   [junit4]   2> 23982 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [n:127.0.0.1:56763_    ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1
   [junit4]   2> 23982 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[59CB1503372ACE0]) [n:127.0.0.1:56763_    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 23982 INFO  (coreLoadExecutor-135-thread-1-processing-n:127.0.0.1:56763_) [n:127.0.0.1:56763_ c:collection1   x:collection1] o.a.s.c.ZkController publishing state=down
   [junit4]   2> 23982 INFO  (coreLoadExecutor-135-thread-1-processing-n:127.0.0.1:56763_) [n:127.0.0.1:56763_ c:collection1   x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
   [junit4]   2> 23989 INFO  (OverseerStateUpdate-95386398933712902-127.0.0.1:46702_-n_0000000000) [n:127.0.0.1:46702_    ] 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:56763",
   [junit4]   2>   "node_name":"127.0.0.1:56763_",
   [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> 23990 INFO  (OverseerStateUpdate-95386398933712902-127.0.0.1:46702_-n_0000000000) [n:127.0.0.1:46702_    ] 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:56763",
   [junit4]   2>   "node_name":"127.0.0.1:56763_",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"collection1",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 23990 INFO  (OverseerStateUpdate-95386398933712902-127.0.0.1:46702_-n_0000000000) [n:127.0.0.1:46702_    ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1
   [junit4]   2> 23990 INFO  (OverseerStateUpdate-95386398933712902-127.0.0.1:46702_-n_0000000000) [n:127.0.0.1:46702_    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2
   [junit4]   2> 23993 INFO  (coreLoadExecutor-135-thread-1-processing-n:127.0.0.1:56763_) [n:127.0.0.1:56763_ c:collection1   x:collection1] o.a.s.c.ZkController look for our core node name
   [junit4]   2> 24102 INFO  (zkCallback-34-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: [2])
   [junit4]   2> 24102 INFO  (zkCallback-39-thread-1-processing-n:127.0.0.1:56763_) [n:127.0.0.1:56763_    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [2])
   [junit4]   2> 24103 INFO  (zkCallback-32-thread-1-processing-n:127.0.0.1:46702_) [n:127.0.0.1:46702_    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [2])
   [junit4]   2> 25000 INFO  (coreLoadExecutor-135-thread-1-processing-n:127.0.0.1:56763_) [n:127.0.0.1:56763_ c:collection1   x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
   [junit4]   2> 25000 INFO  (coreLoadExecutor-135-thread-1-processing-n:127.0.0.1:56763_) [n:127.0.0.1:56763_ c:collection1   x:collection1] o.a.s.c.ZkController Check for collection zkNode:collection1
   [junit4]   2> 25001 INFO  (coreLoadExecutor-135-thread-1-processing-n:127.0.0.1:56763_) [n:127.0.0.1:56763_ c:collection1   x:collection1] o.a.s.c.ZkController Collection zkNode exists
   [junit4]   2> 25001 INF

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

eHandlerTest.testDeleteRollback-seed#[59CB1503372ACE0]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1247988 INFO  (TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[59CB1503372ACE0]) [    ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 	commit{dir=RawDirectoryWrapper(SimpleFSDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_59CB1503372ACE0-001/init-core-data-001/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5debacd6),segFN=segments_i,generation=18}
   [junit4]   2> 	commit{dir=RawDirectoryWrapper(SimpleFSDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_59CB1503372ACE0-001/init-core-data-001/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5debacd6),segFN=segments_j,generation=19}
   [junit4]   2> 1247988 INFO  (TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[59CB1503372ACE0]) [    ] o.a.s.c.SolrDeletionPolicy newest commit generation = 19
   [junit4]   2> 1247990 INFO  (TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[59CB1503372ACE0]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@657a605[collection1] main]
   [junit4]   2> 1247990 INFO  (TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[59CB1503372ACE0]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1247993 INFO  (searcherExecutor-3750-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@657a605[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_a(6.0.0):c2) Uninverting(_b(6.0.0):c1)))}
   [junit4]   2> 1247993 INFO  (TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[59CB1503372ACE0]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 16
   [junit4]   2> 1247994 INFO  (TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[59CB1503372ACE0]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:ZZZ&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1247994 INFO  (TEST-DirectUpdateHandlerTest.testDeleteRollback-seed#[59CB1503372ACE0]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testDeleteRollback
   [junit4]   2> 1247995 INFO  (SUITE-DirectUpdateHandlerTest-seed#[59CB1503372ACE0]-worker) [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 1247995 INFO  (SUITE-DirectUpdateHandlerTest-seed#[59CB1503372ACE0]-worker) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=917979863
   [junit4]   2> 1247995 INFO  (coreCloseExecutor-3754-thread-1) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@76dafe34
   [junit4]   2> 1247995 INFO  (coreCloseExecutor-3754-thread-1) [    x:collection1] o.a.s.u.DirectUpdateHandler2 closing DirectUpdateHandler2{commits=2,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=1,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=3,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
   [junit4]   2> 1247995 INFO  (coreCloseExecutor-3754-thread-1) [    x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
   [junit4]   2> 1247995 INFO  (coreCloseExecutor-3754-thread-1) [    x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 1247995 INFO  (coreCloseExecutor-3754-thread-1) [    x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
   [junit4]   2> 1247996 INFO  (coreCloseExecutor-3754-thread-1) [    x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
   [junit4]   2> 1248006 INFO  (coreCloseExecutor-3754-thread-1) [    x:collection1] o.a.s.c.CachingDirectoryFactory Closing MockFSDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 1248007 INFO  (coreCloseExecutor-3754-thread-1) [    x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_59CB1503372ACE0-001/init-core-data-001 [CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_59CB1503372ACE0-001/init-core-data-001;done=false>>]
   [junit4]   2> 1248007 INFO  (coreCloseExecutor-3754-thread-1) [    x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_59CB1503372ACE0-001/init-core-data-001
   [junit4]   2> 1248007 INFO  (coreCloseExecutor-3754-thread-1) [    x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_59CB1503372ACE0-001/init-core-data-001/index [CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_59CB1503372ACE0-001/init-core-data-001/index;done=false>>]
   [junit4]   2> 1248007 INFO  (coreCloseExecutor-3754-thread-1) [    x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_59CB1503372ACE0-001/init-core-data-001/index
   [junit4]   2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.update.DirectUpdateHandlerTest_59CB1503372ACE0-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene60): {id=PostingsFormat(name=LuceneVarGapDocFreqInterval)}, docValues:{}, sim=ClassicSimilarity, locale=en-ZA, timezone=America/Cayenne
   [junit4]   2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.8.0_66 (64-bit)/cpus=4,threads=1,free=189273048,total=529006592
   [junit4]   2> NOTE: All tests run in this JVM: [TestElisionMultitermQuery, DistributedQueryComponentOptimizationTest, AnalyticsMergeStrategyTest, DocValuesMissingTest, TestDynamicLoading, SpatialHeatmapFacetsTest, BaseCdcrDistributedZkTest, LeaderElectionTest, TestFuzzyAnalyzedSuggestions, TestConfigSetsAPIExclusivity, TestJsonFacets, FullHLLTest, BadCopyFieldTest, TestDistribDocBasedVersion, TestStressRecovery, SampleTest, DocValuesMultiTest, TestFieldCollectionResource, TestTolerantSearch, TestSha256AuthenticationProvider, PreAnalyzedUpdateProcessorTest, VMParamsZkACLAndCredentialsProvidersTest, StressHdfsTest, XmlUpdateRequestHandlerTest, MergeStrategyTest, TestDistributedMissingSort, SliceStateTest, ClusterStateTest, TestSmileRequest, NoCacheHeaderTest, DistributedQueueTest, DistribCursorPagingTest, LukeRequestHandlerTest, TestJettySolrRunner, FieldMutatingUpdateProcessorTest, DeleteReplicaTest, TestWriterPerf, TestDistributedStatsComponentCardinality, PluginInfoTest, CleanupOldIndexTest, TestSchemaResource, TestLRUStatsCache, SegmentsInfoRequestHandlerTest, TestChildDocTransformer, TestSolrConfigHandlerConcurrent, StatsComponentTest, SearchHandlerTest, DistanceUnitsTest, SharedFSAutoReplicaFailoverUtilsTest, TestFilteredDocIdSet, TestManagedSchemaFieldTypeResource, CircularListTest, TestFreeTextSuggestions, ZkStateWriterTest, CdcrUpdateLogTest, HdfsChaosMonkeyNothingIsSafeTest, TestAnalyzeInfixSuggestions, TestExceedMaxTermLength, CoreMergeIndexesAdminHandlerTest, SolrCoreCheckLockOnStartupTest, TermVectorComponentDistributedTest, TestRequestForwarding, TestReloadDeadlock, TestBulkSchemaAPI, HdfsNNFailoverTest, SuggesterWFSTTest, TestSearchPerf, BinaryUpdateRequestHandlerTest, HardAutoCommitTest, TestInitParams, RecoveryZkTest, RegexBoostProcessorTest, TestManagedResource, DistributedFacetPivotSmallTest, TestReload, DistributedFacetPivotLargeTest, TestSuggestSpellingConverter, DebugComponentTest, BasicDistributedZk2Test, SmileWriterTest, TestTrackingShardHandlerFactory, TestSort, TestJmxMonitoredMap, ScriptEngineTest, LeaderInitiatedRecoveryOnShardRestartTest, DirectUpdateHandlerTest]
   [junit4] Completed [176/579 (2!)] on J2 in 14.31s, 7 tests, 1 failure <<< FAILURES!

[...truncated 1326 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:747: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:684: 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:524: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1457: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1014: There were test failures: 579 suites (8 ignored), 2331 tests, 1 error, 1 failure, 34 ignored (18 assumptions) [seed: 59CB1503372ACE0]

Total time: 216 minutes 38 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