lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Jenkins Server <jenk...@builds.apache.org>
Subject [JENKINS] Lucene-Solr-NightlyTests-5.5 - Build # 7 - Still Failing
Date Sat, 20 Feb 2016 01:52:50 GMT
Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-5.5/7/

2 tests failed.
FAILED:  org.apache.solr.spelling.SpellCheckCollatorTest.testEstimatedHitCounts

Error Message:
Exception during query

Stack Trace:
java.lang.RuntimeException: Exception during query
	at __randomizedtesting.SeedInfo.seed([DE25A916905B004D:EF9E17233564109D]:0)
	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:754)
	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:721)
	at org.apache.solr.spelling.SpellCheckCollatorTest.testEstimatedHitCounts(SpellCheckCollatorTest.java:561)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java: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)
Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=//lst[@name='spellcheck']/lst[@name='collations']/lst[@name='collation']/int[@name='hits' and 6 <= . and . <= 10]
	xml response was: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader"><int name="status">0</int><int name="QTime">3</int></lst><result name="response" numFound="0" start="0"></result><lst name="spellcheck"><lst name="suggestions"><lst name="everother"><int name="numFound">1</int><int name="startOffset">9</int><int name="endOffset">18</int><arr name="suggestion"><str>everyother</str></arr></lst></lst><lst name="collations"><lst name="collation"><str name="collationQuery">teststop:everyother</str><int name="hits">14</int><lst name="misspellingsAndCorrections"><str name="everother">everyother</str></lst></lst></lst></lst>
</response>

	request was:spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:everother&spellcheck.collateMaxCollectDocs=5
	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:747)
	... 41 more


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

Error Message:
Error from server at http://127.0.0.1:43486: Could not load collection from ZK: nodes_used_collection

Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:43486: Could not load collection from ZK: nodes_used_collection
	at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:576)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:240)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:229)
	at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
	at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.makeRequest(CollectionsAPIDistributedZkTest.java:375)
	at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testErrorHandling(CollectionsAPIDistributedZkTest.java:491)
	at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test(CollectionsAPIDistributedZkTest.java:162)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java: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 org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:964)
	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:939)
	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 11723 lines...]
   [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
   [junit4]   2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_DE25A916905B004D-001/init-core-data-001
   [junit4]   2> 1657484 INFO  (SUITE-CollectionsAPIDistributedZkTest-seed#[DE25A916905B004D]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
   [junit4]   2> 1657484 INFO  (SUITE-CollectionsAPIDistributedZkTest-seed#[DE25A916905B004D]-worker) [    ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
   [junit4]   2> 1657489 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1657489 INFO  (Thread-4428) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1657489 INFO  (Thread-4428) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 1657589 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.ZkTestServer start zk server on port:46879
   [junit4]   2> 1657590 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1657590 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1657592 INFO  (zkCallback-1221-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@646930f4 name:ZooKeeperConnection Watcher:127.0.0.1:46879 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1657592 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1657593 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1657593 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 1657596 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1657596 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1657597 INFO  (zkCallback-1222-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@37450b21 name:ZooKeeperConnection Watcher:127.0.0.1:46879/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1657597 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1657598 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1657598 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1
   [junit4]   2> 1657600 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
   [junit4]   2> 1657601 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
   [junit4]   2> 1657602 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
   [junit4]   2> 1657604 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
   [junit4]   2> 1657604 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 1657607 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
   [junit4]   2> 1657607 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
   [junit4]   2> 1657609 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 1657609 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 1657611 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
   [junit4]   2> 1657611 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
   [junit4]   2> 1657612 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
   [junit4]   2> 1657612 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
   [junit4]   2> 1657614 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
   [junit4]   2> 1657614 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
   [junit4]   2> 1657616 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
   [junit4]   2> 1657616 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
   [junit4]   2> 1657617 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
   [junit4]   2> 1657617 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
   [junit4]   2> 1657619 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 1657619 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 1657621 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
   [junit4]   2> 1657621 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
   [junit4]   2> 1657622 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
   [junit4]   2> 1657622 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
   [junit4]   2> 1657625 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1657625 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1657627 INFO  (zkCallback-1223-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@134e37ae name:ZooKeeperConnection Watcher:127.0.0.1:46879 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1657627 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1657627 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1657627 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 1657631 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1657631 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1657632 INFO  (zkCallback-1224-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5337b802 name:ZooKeeperConnection Watcher:127.0.0.1:46879/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1657632 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1657633 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1657633 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf2/solrconfig.xml
   [junit4]   2> 1657633 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.xml
   [junit4]   2> 1657635 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf2/schema.xml
   [junit4]   2> 1657635 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/schema.xml
   [junit4]   2> 1657637 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf2/enumsConfig.xml
   [junit4]   2> 1657638 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/enumsConfig.xml
   [junit4]   2> 1657639 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf2/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 1657639 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 1657641 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf2/stopwords.txt
   [junit4]   2> 1657641 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/stopwords.txt
   [junit4]   2> 1657642 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf2/protwords.txt
   [junit4]   2> 1657643 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/protwords.txt
   [junit4]   2> 1657644 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf2/currency.xml
   [junit4]   2> 1657644 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/currency.xml
   [junit4]   2> 1657646 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf2/open-exchange-rates.json
   [junit4]   2> 1657646 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/open-exchange-rates.json
   [junit4]   2> 1657647 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf2/mapping-ISOLatin1Accent.txt
   [junit4]   2> 1657647 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/mapping-ISOLatin1Accent.txt
   [junit4]   2> 1657649 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf2/old_synonyms.txt
   [junit4]   2> 1657649 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/old_synonyms.txt
   [junit4]   2> 1657651 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf2/synonyms.txt
   [junit4]   2> 1657651 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/synonyms.txt
   [junit4]   2> 1657652 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/elevate.xml to /configs/conf2/elevate.xml
   [junit4]   2> 1657653 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/elevate.xml
   [junit4]   2> 1657742 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_DE25A916905B004D-001/control-001/cores/collection1
   [junit4]   2> 1657743 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.e.j.s.Server jetty-9.2.13.v20150730
   [junit4]   2> 1657745 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@603939aa{/,null,AVAILABLE}
   [junit4]   2> 1657748 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.e.j.s.ServerConnector Started ServerConnector@6db4003a{HTTP/1.1}{127.0.0.1:58915}
   [junit4]   2> 1657748 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.e.j.s.Server Started @1660480ms
   [junit4]   2> 1657748 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=58915, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_DE25A916905B004D-001/control-001/cores, solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_DE25A916905B004D-001/tempDir-001/control/data}
   [junit4]   2> 1657748 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@7b3cb2c6
   [junit4]   2> 1657748 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_DE25A916905B004D-001/control-001'
   [junit4]   2> 1657748 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1657748 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 1657749 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1657749 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1657751 INFO  (zkCallback-1225-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@eeb48bf name:ZooKeeperConnection Watcher:127.0.0.1:46879/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1657751 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1657751 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1657752 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
   [junit4]   2> 1657752 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_DE25A916905B004D-001/control-001/solr.xml
   [junit4]   2> 1657768 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_DE25A916905B004D-001/control-001/cores
   [junit4]   2> 1657768 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.CoreContainer New CoreContainer 537503754
   [junit4]   2> 1657768 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_DE25A916905B004D-001/control-001]
   [junit4]   2> 1657769 WARN  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_DE25A916905B004D-001/control-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_DE25A916905B004D-001/control-001/lib
   [junit4]   2> 1657769 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] 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> 1657771 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 1657771 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1657771 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1657771 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46879/solr
   [junit4]   2> 1657771 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 1657772 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1657772 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1657773 INFO  (zkCallback-1228-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@37d8dc47 name:ZooKeeperConnection Watcher:127.0.0.1:46879 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1657774 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1657774 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1657778 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [n:127.0.0.1:58915_    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1657779 INFO  (zkCallback-1229-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4faa2707 name:ZooKeeperConnection Watcher:127.0.0.1:46879/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1657779 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [n:127.0.0.1:58915_    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1657780 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [n:127.0.0.1:58915_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
   [junit4]   2> 1657782 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [n:127.0.0.1:58915_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
   [junit4]   2> 1657785 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [n:127.0.0.1:58915_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
   [junit4]   2> 1657787 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [n:127.0.0.1:58915_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
   [junit4]   2> 1657789 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [n:127.0.0.1:58915_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
   [junit4]   2> 1657791 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [n:127.0.0.1:58915_    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 1657793 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [n:127.0.0.1:58915_    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 1657794 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [n:127.0.0.1:58915_    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 1657795 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [n:127.0.0.1:58915_    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 1657796 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [n:127.0.0.1:58915_    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58915_
   [junit4]   2> 1657796 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [n:127.0.0.1:58915_    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:58915_
   [junit4]   2> 1657798 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [n:127.0.0.1:58915_    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
   [junit4]   2> 1657799 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [n:127.0.0.1:58915_    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
   [junit4]   2> 1657800 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [n:127.0.0.1:58915_    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1657801 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [n:127.0.0.1:58915_    ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95415855368962054-127.0.0.1:58915_-n_0000000000
   [junit4]   2> 1657801 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [n:127.0.0.1:58915_    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:58915_
   [junit4]   2> 1657801 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [n:127.0.0.1:58915_    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
   [junit4]   2> 1657802 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [n:127.0.0.1:58915_    ] o.a.s.c.Overseer Overseer (id=95415855368962054-127.0.0.1:58915_-n_0000000000) starting
   [junit4]   2> 1657804 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [n:127.0.0.1:58915_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
   [junit4]   2> 1657809 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [n:127.0.0.1:58915_    ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000
   [junit4]   2> 1657810 INFO  (OverseerCollectionConfigSetProcessor-95415855368962054-127.0.0.1:58915_-n_0000000000) [n:127.0.0.1:58915_    ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations
   [junit4]   2> 1657810 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [n:127.0.0.1:58915_    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 1657810 INFO  (OverseerStateUpdate-95415855368962054-127.0.0.1:58915_-n_0000000000) [n:127.0.0.1:58915_    ] o.a.s.c.Overseer Starting to work on the main queue
   [junit4]   2> 1657823 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [n:127.0.0.1:58915_    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 1657823 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [n:127.0.0.1:58915_    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 1657824 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [n:127.0.0.1:58915_    ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_DE25A916905B004D-001/control-001/cores
   [junit4]   2> 1657825 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [n:127.0.0.1:58915_    ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {transient=false, name=collection1, shard=, configSetProperties=configsetprops.json, collection=control_collection, schema=schema.xml, loadOnStartup=true, coreNodeName=, config=solrconfig.xml, dataDir=data/}
   [junit4]   2> 1657825 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [n:127.0.0.1:58915_    ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_DE25A916905B004D-001/control-001/cores/collection1
   [junit4]   2> 1657825 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [n:127.0.0.1:58915_    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
   [junit4]   2> 1657826 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [n:127.0.0.1:58915_    ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1
   [junit4]   2> 1657826 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection   x:collection1] o.a.s.c.ZkController publishing state=down
   [junit4]   2> 1657826 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [n:127.0.0.1:58915_    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 1657826 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection   x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
   [junit4]   2> 1657828 INFO  (OverseerStateUpdate-95415855368962054-127.0.0.1:58915_-n_0000000000) [n:127.0.0.1:58915_    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "node_name":"127.0.0.1:58915_",
   [junit4]   2>   "base_url":"http://127.0.0.1:58915",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "operation":"state",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "roles":null} current state version: 0
   [junit4]   2> 1657828 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection   x:collection1] o.a.s.c.ZkController look for our core node name
   [junit4]   2> 1657828 INFO  (OverseerStateUpdate-95415855368962054-127.0.0.1:58915_-n_0000000000) [n:127.0.0.1:58915_    ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "node_name":"127.0.0.1:58915_",
   [junit4]   2>   "base_url":"http://127.0.0.1:58915",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "operation":"state",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "roles":null}
   [junit4]   2> 1657829 INFO  (OverseerStateUpdate-95415855368962054-127.0.0.1:58915_-n_0000000000) [n:127.0.0.1:58915_    ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection
   [junit4]   2> 1657829 INFO  (OverseerStateUpdate-95415855368962054-127.0.0.1:58915_-n_0000000000) [n:127.0.0.1:58915_    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
   [junit4]   2> 1657831 INFO  (zkCallback-1229-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_    ] 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> 1658828 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection   x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
   [junit4]   2> 1658828 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection   x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection
   [junit4]   2> 1658829 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection   x:collection1] o.a.s.c.ZkController Collection zkNode exists
   [junit4]   2> 1658829 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection]
   [junit4]   2> 1658830 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper
   [junit4]   2> 1658830 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection   x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_DE25A916905B004D-001/control-001/cores/collection1'
   [junit4]   2> 1658830 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection   x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1658831 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection   x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 1658832 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection   x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 
   [junit4]   2> 1658848 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection   x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 1658864 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection   x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0
   [junit4]   2> 1658898 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection   x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1658907 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection   x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1659174 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 1659182 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection   x:collection1] o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 1659184 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection   x:collection1] o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 1659193 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection   x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1659196 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection   x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1659199 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1659200 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1659201 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection   x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1
   [junit4]   2> 1659201 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection   x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection
   [junit4]   2> 1659201 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 1659202 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_DE25A916905B004D-001/control-001/cores/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_DE25A916905B004D-001/control-001/cores/collection1/data/]
   [junit4]   2> 1659202 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ 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@5879fba9
   [junit4]   2> 1659202 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_DE25A916905B004D-001/control-001/cores/collection1/data
   [junit4]   2> 1659202 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_DE25A916905B004D-001/control-001/cores/collection1/data/index/
   [junit4]   2> 1659203 WARN  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_DE25A916905B004D-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index...
   [junit4]   2> 1659203 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_DE25A916905B004D-001/control-001/cores/collection1/data/index
   [junit4]   2> 1659203 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ 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=19, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=49.150390625, floorSegmentMB=1.671875, forceMergeDeletesPctAllowed=17.81684355776071, segmentsPerTier=39.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6530421930898627
   [junit4]   2> 1659208 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ 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(RAMDirectory@1d39dc2d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@41a61d58),segFN=segments_1,generation=1}
   [junit4]   2> 1659208 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 1659208 INFO  (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:58915_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_DE25A916905B004D-001/control-001/cores/collection1/data/
   [junit4]   2> 1659208 WARN  (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:58915_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_DE25A916905B004D-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories.
   [junit4]   2> 1659212 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 1659212 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 1659212 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 1659212 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1659213 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1659213 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ 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> 1659213 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ 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> 1659213 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ 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> 1659213 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ 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> 1659213 WARN  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ 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> 1659216 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1659217 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1659219 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1659220 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1659224 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/file,/admin/logging,/schema,/admin/threads,/replication,/dump,/update/csv,/update/json/docs,standard,/config,/admin/plugins,/update,/admin/system,/admin/properties,/admin/ping,/update/json,/admin/segments,/get,/admin/mbeans,/admin/luke
   [junit4]   2> 1659225 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ 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> 1659225 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ 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> 1659225 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ 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> 1659226 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1659226 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1659227 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=29, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
   [junit4]   2> 1659227 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ 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(RAMDirectory@1d39dc2d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@41a61d58),segFN=segments_1,generation=1}
   [junit4]   2> 1659227 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 1659227 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@10ad8458[collection1] main
   [junit4]   2> 1659227 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ 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> 1659228 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ 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> 1659228 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ 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> 1659229 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 1659229 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
   [junit4]   2> 1659229 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ 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> 1659229 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ 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> 1659229 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ 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> 1659230 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 1659230 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1659231 INFO  (searcherExecutor-3090-thread-1-processing-c:control_collection n:127.0.0.1:58915_ s:shard1 r:core_node1 x:collection1) [n:127.0.0.1:58915_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@10ad8458[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1659231 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ 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> 1659231 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ 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> 1659231 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ 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> 1659231 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ 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 1526653687727915008
   [junit4]   2> 1659233 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 2.0ms to seed version buckets with highest version 1526653687727915008
   [junit4]   2> 1659233 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1
   [junit4]   2> 1659234 INFO  (coreLoadExecutor-3089-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 1659234 INFO  (coreZkRegister-3083-thread-1-processing-c:control_collection n:127.0.0.1:58915_ s:shard1 r:core_node1 x:collection1) [n:127.0.0.1:58915_ 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:58915 collection:control_collection shard:shard1
   [junit4]   2> 1659235 INFO  (coreZkRegister-3083-thread-1-processing-c:control_collection n:127.0.0.1:58915_ s:shard1 r:core_node1 x:collection1) [n:127.0.0.1:58915_ 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> 1659239 INFO  (coreZkRegister-3083-thread-1-processing-c:control_collection n:127.0.0.1:58915_ s:shard1 r:core_node1 x:collection1) [n:127.0.0.1:58915_ 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/95415855368962054-core_node1-n_0000000000
   [junit4]   2> 1659240 INFO  (coreZkRegister-3083-thread-1-processing-c:control_collection n:127.0.0.1:58915_ s:shard1 r:core_node1 x:collection1) [n:127.0.0.1:58915_ 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> 1659241 INFO  (coreZkRegister-3083-thread-1-processing-c:control_collection n:127.0.0.1:58915_ s:shard1 r:core_node1 x:collection1) [n:127.0.0.1:58915_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 1659241 INFO  (coreZkRegister-3083-thread-1-processing-c:control_collection n:127.0.0.1:58915_ s:shard1 r:core_node1 x:collection1) [n:127.0.0.1:58915_ 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> 1659241 INFO  (coreZkRegister-3083-thread-1-processing-c:control_collection n:127.0.0.1:58915_ s:shard1 r:core_node1 x:collection1) [n:127.0.0.1:58915_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:58915/collection1/
   [junit4]   2> 1659242 INFO  (coreZkRegister-3083-thread-1-processing-c:control_collection n:127.0.0.1:58915_ s:shard1 r:core_node1 x:collection1) [n:127.0.0.1:58915_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 1659242 INFO  (coreZkRegister-3083-thread-1-processing-c:control_collection n:127.0.0.1:58915_ s:shard1 r:core_node1 x:collection1) [n:127.0.0.1:58915_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:58915/collection1/ has no replicas
   [junit4]   2> 1659242 INFO  (OverseerStateUpdate-95415855368962054-127.0.0.1:58915_-n_0000000000) [n:127.0.0.1:58915_    ] 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> 1659242 INFO  (coreZkRegister-3083-thread-1-processing-c:control_collection n:127.0.0.1:58915_ s:shard1 r:core_node1 x:collection1) [n:127.0.0.1:58915_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1
   [junit4]   2> 1659245 INFO  (coreZkRegister-3083-thread-1-processing-c:control_collection n:127.0.0.1:58915_ s:shard1 r:core_node1 x:collection1) [n:127.0.0.1:58915_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node
   [junit4]   2> 1659247 INFO  (coreZkRegister-3083-thread-1-processing-c:control_collection n:127.0.0.1:58915_ s:shard1 r:core_node1 x:collection1) [n:127.0.0.1:58915_ 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:58915/collection1/ shard1
   [junit4]   2> 1659248 INFO  (OverseerStateUpdate-95415855368962054-127.0.0.1:58915_-n_0000000000) [n:127.0.0.1:58915_    ] 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:58915",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "state":"active"} current state version: 1
   [junit4]   2> 1659328 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1659329 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1659330 INFO  (zkCallback-1231-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@24c36791 name:ZooKeeperConnection Watcher:127.0.0.1:46879/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1659330 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1659330 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1659330 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 1659332 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
   [junit4]   2> 1659333 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2
   [junit4]   2> 1659333 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1659333 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1659334 INFO  (zkCallback-1232-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@68b20c5e name:ZooKeeperConnection Watcher:127.0.0.1:46879/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1659334 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1659334 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1659337 INFO  (OverseerStateUpdate-95415855368962054-127.0.0.1:58915_-n_0000000000) [n:127.0.0.1:58915_    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "operation":"create",
   [junit4]   2>   "name":"collection1",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "stateFormat":"2"} current state version: 1
   [junit4]   2> 1659337 INFO  (OverseerStateUpdate-95415855368962054-127.0.0.1:58915_-n_0000000000) [n:127.0.0.1:58915_    ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1
   [junit4]   2> 1659338 INFO  (zkCallback-1229-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_    ] 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> 1659339 INFO  (zkCallback-1231-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> 1659348 INFO  (coreZkRegister-3083-thread-1-processing-c:control_collection n:127.0.0.1:58915_ s:shard1 r:core_node1 x:collection1) [n:127.0.0.1:58915_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:58915/collection1/ and leader is http://127.0.0.1:58915/collection1/
   [junit4]   2> 1659348 INFO  (coreZkRegister-3083-thread-1-processing-c:control_collection n:127.0.0.1:58915_ s:shard1 r:core_node1 x:collection1) [n:127.0.0.1:58915_ 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:58915
   [junit4]   2> 1659348 INFO  (coreZkRegister-3083-thread-1-processing-c:control_collection n:127.0.0.1:58915_ s:shard1 r:core_node1 x:collection1) [n:127.0.0.1:58915_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 1659348 INFO  (coreZkRegister-3083-thread-1-processing-c:control_collection n:127.0.0.1:58915_ s:shard1 r:core_node1 x:collection1) [n:127.0.0.1:58915_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active
   [junit4]   2> 1659348 INFO  (coreZkRegister-3083-thread-1-processing-c:control_collection n:127.0.0.1:58915_ s:shard1 r:core_node1 x:collection1) [n:127.0.0.1:58915_ 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> 1659350 INFO  (OverseerStateUpdate-95415855368962054-127.0.0.1:58915_-n_0000000000) [n:127.0.0.1:58915_    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "node_name":"127.0.0.1:58915_",
   [junit4]   2>   "base_url":"http://127.0.0.1:58915",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "operation":"state",
   [junit4]   2>   "core_node_name":"core_node1",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "roles":null} current state version: 2
   [junit4]   2> 1659350 INFO  (OverseerStateUpdate-95415855368962054-127.0.0.1:58915_-n_0000000000) [n:127.0.0.1:58915_    ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "node_name":"127.0.0.1:58915_",
   [junit4]   2>   "base_url":"http://127.0.0.1:58915",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "operation":"state",
   [junit4]   2>   "core_node_name":"core_node1",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "roles":null}
   [junit4]   2> 1659351 INFO  (OverseerStateUpdate-95415855368962054-127.0.0.1:58915_-n_0000000000) [n:127.0.0.1:58915_    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/collection1/state.json
   [junit4]   2> 1659433 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_DE25A916905B004D-001/shard-1-001/cores/collection1
   [junit4]   2> 1659434 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_DE25A916905B004D-001/shard-1-001
   [junit4]   2> 1659435 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.e.j.s.Server jetty-9.2.13.v20150730
   [junit4]   2> 1659437 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@74117c78{/,null,AVAILABLE}
   [junit4]   2> 1659437 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.e.j.s.ServerConnector Started ServerConnector@73eeccce{HTTP/1.1}{127.0.0.1:43486}
   [junit4]   2> 1659438 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.e.j.s.Server Started @1662169ms
   [junit4]   2> 1659438 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solrconfig=solrconfig.xml, hostPort=43486, solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_DE25A916905B004D-001/tempDir-001/jetty1, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_DE25A916905B004D-001/shard-1-001/cores, hostContext=/}
   [junit4]   2> 1659438 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@7b3cb2c6
   [junit4]   2> 1659438 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_DE25A916905B004D-001/shard-1-001'
   [junit4]   2> 1659438 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1659438 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 1659438 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1659439 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1659440 INFO  (zkCallback-1233-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1d99ecdb name:ZooKeeperConnection Watcher:127.0.0.1:46879/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1659440 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1659441 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1659442 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
   [junit4]   2> 1659442 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_DE25A916905B004D-001/shard-1-001/solr.xml
   [junit4]   2> 1659455 INFO  (zkCallback-1231-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> 1659455 INFO  (zkCallback-1229-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_    ] 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> 1659458 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_DE25A916905B004D-001/shard-1-001/cores
   [junit4]   2> 1659458 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.CoreContainer New CoreContainer 1608971815
   [junit4]   2> 1659458 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_DE25A916905B004D-001/shard-1-001]
   [junit4]   2> 1659458 WARN  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_DE25A916905B004D-001/shard-1-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_DE25A916905B004D-001/shard-1-001/lib
   [junit4]   2> 1659458 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] 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> 1659460 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 1659460 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1659460 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1659460 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46879/solr
   [junit4]   2> 1659460 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 1659461 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1659461 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1659462 INFO  (zkCallback-1236-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3cf84bfa name:ZooKeeperConnection Watcher:127.0.0.1:46879 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1659463 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1659463 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1659465 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [n:127.0.0.1:43486_    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1659466 INFO  (zkCallback-1237-thread-1-processing-n:127.0.0.1:43486_) [n:127.0.0.1:43486_    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@48b4fd25 name:ZooKeeperConnection Watcher:127.0.0.1:46879/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1659466 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [n:127.0.0.1:43486_    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1659472 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [n:127.0.0.1:43486_    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 1660480 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [n:127.0.0.1:43486_    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:43486_
   [junit4]   2> 1660481 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [n:127.0.0.1:43486_    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:43486_
   [junit4]   2> 1660482 INFO  (zkCallback-1229-thread-1-processing-n:127.0.0.1:58915_) [n:127.0.0.1:58915_    ] 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> 1660483 INFO  (zkCallback-1231-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> 1660483 INFO  (zkCallback-1237-thread-1-processing-n:127.0.0.1:43486_) [n:127.0.0.1:43486_    ] 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> 1660483 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [n:127.0.0.1:43486_    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1660484 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [n:127.0.0.1:43486_    ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95415855368962059-127.0.0.1:43486_-n_0000000001
   [junit4]   2> 1660485 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[DE25A916905B004D]) [n:127.0.0.1:43486_    ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/9541585

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

    > </response>
   [junit4]    > 	request was:spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:everother&spellcheck.collateMaxCollectDocs=5
   [junit4]    > 	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:747)
   [junit4]    > 	... 41 more
   [junit4]   2> 2803434 INFO  (TEST-SpellCheckCollatorTest.testZeroTries-seed#[DE25A916905B004D]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testZeroTries
   [junit4]   2> 2803458 INFO  (TEST-SpellCheckCollatorTest.testZeroTries-seed#[DE25A916905B004D]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testZeroTries
   [junit4]   2> 2803464 INFO  (TEST-SpellCheckCollatorTest.testCollationWithRangeQuery-seed#[DE25A916905B004D]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testCollationWithRangeQuery
   [junit4]   1> id:[1 TO 10] AND lowerfilt:love
   [junit4]   2> 2803489 INFO  (TEST-SpellCheckCollatorTest.testCollationWithRangeQuery-seed#[DE25A916905B004D]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testCollationWithRangeQuery
   [junit4]   2> 2803507 INFO  (TEST-SpellCheckCollatorTest.testCollateWithMultipleRequestHandlers-seed#[DE25A916905B004D]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testCollateWithMultipleRequestHandlers
   [junit4]   2> 2803536 INFO  (TEST-SpellCheckCollatorTest.testCollateWithMultipleRequestHandlers-seed#[DE25A916905B004D]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testCollateWithMultipleRequestHandlers
   [junit4]   2> 2803537 INFO  (SUITE-SpellCheckCollatorTest-seed#[DE25A916905B004D]-worker) [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 2803537 INFO  (SUITE-SpellCheckCollatorTest-seed#[DE25A916905B004D]-worker) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1923431575
   [junit4]   2> 2803537 INFO  (coreCloseExecutor-5270-thread-1) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@2752332c
   [junit4]   2> 2803537 INFO  (coreCloseExecutor-5270-thread-1) [    x:collection1] o.a.s.u.DirectUpdateHandler2 closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=17,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
   [junit4]   2> 2803538 INFO  (coreCloseExecutor-5270-thread-1) [    x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
   [junit4]   2> 2803538 INFO  (coreCloseExecutor-5270-thread-1) [    x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 2803538 INFO  (coreCloseExecutor-5270-thread-1) [    x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
   [junit4]   2> 2803539 INFO  (coreCloseExecutor-5270-thread-1) [    x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
   [junit4]   2> 2803539 INFO  (coreCloseExecutor-5270-thread-1) [    x:collection1] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 2803539 INFO  (coreCloseExecutor-5270-thread-1) [    x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.spelling.SpellCheckCollatorTest_DE25A916905B004D-001/init-core-data-001 [CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.spelling.SpellCheckCollatorTest_DE25A916905B004D-001/init-core-data-001;done=false>>]
   [junit4]   2> 2803539 INFO  (coreCloseExecutor-5270-thread-1) [    x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.spelling.SpellCheckCollatorTest_DE25A916905B004D-001/init-core-data-001
   [junit4]   2> 2803539 INFO  (coreCloseExecutor-5270-thread-1) [    x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.spelling.SpellCheckCollatorTest_DE25A916905B004D-001/init-core-data-001/index [CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.spelling.SpellCheckCollatorTest_DE25A916905B004D-001/init-core-data-001/index;done=false>>]
   [junit4]   2> 2803539 INFO  (coreCloseExecutor-5270-thread-1) [    x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.spelling.SpellCheckCollatorTest_DE25A916905B004D-001/init-core-data-001/index
   [junit4]   2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.spelling.SpellCheckCollatorTest_DE25A916905B004D-001
   [junit4]   2> NOTE: test params are: codec=Lucene54, sim=RandomSimilarity(queryNorm=true,coord=crazy): {}, locale=is, timezone=Pacific/Pohnpei
   [junit4]   2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.7.0_80 (64-bit)/cpus=4,threads=1,free=283740208,total=530579456
   [junit4]   2> NOTE: All tests run in this JVM: [TestRemoteStreaming, SignatureUpdateProcessorFactoryTest, IndexSchemaTest, QueryElevationComponentTest, TestSolrDynamicMBean, BasicDistributedZkTest, OverseerRolesTest, TestArbitraryIndexDir, TestUniqueKeyFieldResource, RestartWhileUpdatingTest, BadCopyFieldTest, CloudMLTQParserTest, CacheHeaderTest, URLClassifyProcessorTest, ParsingFieldUpdateProcessorsTest, TestDistributedSearch, TestComponentsName, AsyncCallRequestStatusResponseTest, DistributedSpellCheckComponentTest, TestDocBasedVersionConstraints, SpatialHeatmapFacetsTest, TestManagedSchemaFieldTypeResource, UUIDFieldTest, IndexSchemaRuntimeFieldTest, TestLuceneMatchVersion, HdfsBasicDistributedZk2Test, UUIDUpdateProcessorFallbackTest, TestRawTransformer, ConcurrentDeleteAndCreateCollectionTest, TestRandomDVFaceting, SystemInfoHandlerTest, TestFaceting, TestPseudoReturnFields, OutOfBoxZkACLAndCredentialsProvidersTest, TestHashQParserPlugin, TestAnalyzedSuggestions, TestJoin, TestMiniSolrCloudClusterBase, TestDistributedStatsComponentCardinality, CopyFieldTest, PingRequestHandlerTest, TestMergePolicyConfig, PreAnalyzedFieldTest, TestReplicationHandler, TestConfigOverlay, TestReloadAndDeleteDocs, HdfsRecoverLeaseTest, TestLeaderElectionZkExpiry, SynonymTokenizerTest, CursorPagingTest, TestRandomRequestDistribution, AnalyticsMergeStrategyTest, TestAddFieldRealTimeGet, DocumentAnalysisRequestHandlerTest, ExternalFileFieldSortTest, CleanupOldIndexTest, TestLMDirichletSimilarityFactory, TestCloudPivotFacet, BasicZkTest, EchoParamsTest, TestFieldTypeResource, DistributedQueryComponentOptimizationTest, SegmentsInfoRequestHandlerTest, RulesTest, TestSimpleQParserPlugin, TestRTGBase, TestBulkSchemaAPI, PolyFieldTest, TestDistribDocBasedVersion, StandardRequestHandlerTest, DateFieldTest, BlockJoinFacetDistribTest, VersionInfoTest, TestHighFrequencyDictionaryFactory, LeaderElectionIntegrationTest, ResourceLoaderTest, JSONWriterTest, TestExactSharedStatsCache, TestSerializedLuceneMatchVersion, XsltUpdateRequestHandlerTest, TestRandomFaceting, TestInfoStreamLogging, TestRandomCollapseQParserPlugin, SmileWriterTest, TestPKIAuthenticationPlugin, TestStressLucene, DefaultValueUpdateProcessorTest, TestSolr4Spatial, DistribCursorPagingTest, TestFieldCollectionResource, TestDefaultSearchFieldResource, TestLRUCache, PrimUtilsTest, TestCoreContainer, SolrCoreTest, SpellCheckComponentTest, PeerSyncTest, BadIndexSchemaTest, TestSort, BasicFunctionalityTest, TestBadConfig, TestIndexSearcher, SoftAutoCommitTest, SpellCheckCollatorTest]
   [junit4] Completed [288/566 (2!)] on J0 in 2.09s, 10 tests, 1 error <<< FAILURES!

[...truncated 863 lines...]
   [junit4] JVM J1: stdout was not empty, see: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/temp/junit4-J1-20160220_003909_968.sysout
   [junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
   [junit4] java.lang.OutOfMemoryError: GC overhead limit exceeded
   [junit4] Dumping heap to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/heapdumps/java_pid14644.hprof ...
   [junit4] Heap dump file created [613070642 bytes in 6.468 secs]
   [junit4] <<< JVM J1: EOF ----

[...truncated 14 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/build.xml:757: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/build.xml:694: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/build.xml:59: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build.xml:233: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/common-build.xml:534: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/lucene/common-build.xml:1477: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/lucene/common-build.xml:1033: There were test failures: 566 suites (7 ignored), 2275 tests, 2 errors, 34 ignored (18 assumptions) [seed: DE25A916905B004D]

Total time: 213 minutes 30 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