lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@thetaphi.de>
Subject [JENKINS] Lucene-Solr-trunk-Linux (32bit/jdk1.8.0-ea-b79) - Build # 4591 - Failure!
Date Wed, 06 Mar 2013 07:54:25 GMT
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/4591/
Java: 32bit/jdk1.8.0-ea-b79 -server -XX:+UseSerialGC

3 tests failed.
REGRESSION:  org.apache.solr.cloud.BasicDistributedZk2Test.testDistribSearch

Error Message:
Server at http://127.0.0.1:34658/onenodecollectioncore returned non ok status:404, message:Can not find: /onenodecollectioncore/update

Stack Trace:
org.apache.solr.common.SolrException: Server at http://127.0.0.1:34658/onenodecollectioncore returned non ok status:404, message:Can not find: /onenodecollectioncore/update
	at __randomizedtesting.SeedInfo.seed([CBA80CAD2837B9FB:4A4E82B55F68D9C7]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:375)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:181)
	at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:117)
	at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:116)
	at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:102)
	at org.apache.solr.cloud.BasicDistributedZk2Test.testNodeWithoutCollectionForwarding(BasicDistributedZk2Test.java:200)
	at org.apache.solr.cloud.BasicDistributedZk2Test.doTest(BasicDistributedZk2Test.java:89)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:806)
	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:487)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:722)


REGRESSION:  org.apache.solr.rest.TestFieldCollectionResource.testJsonGetAllFields

Error Message:
mismatch: 'custstopfilt'!='stopfilt' @ fields/[0]/name

Stack Trace:
java.lang.RuntimeException: mismatch: 'custstopfilt'!='stopfilt' @ fields/[0]/name
	at __randomizedtesting.SeedInfo.seed([CBA80CAD2837B9FB:42A87E218884F5DD]:0)
	at org.apache.solr.util.RestTestBase.assertJQ(RestTestBase.java:242)
	at org.apache.solr.util.RestTestBase.assertJQ(RestTestBase.java:186)
	at org.apache.solr.rest.TestFieldCollectionResource.testJsonGetAllFields(TestFieldCollectionResource.java:50)
	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:487)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:722)


REGRESSION:  org.apache.solr.rest.TestFieldCollectionResource.testGetAllFields

Error Message:
Exception during query

Stack Trace:
java.lang.RuntimeException: Exception during query
	at __randomizedtesting.SeedInfo.seed([CBA80CAD2837B9FB:27064669D2C44063]:0)
	at org.apache.solr.util.RestTestBase.assertQ(RestTestBase.java:143)
	at org.apache.solr.rest.TestFieldCollectionResource.testGetAllFields(TestFieldCollectionResource.java:26)
	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:487)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:722)
Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=(/response/arr[@name='fields']/lst/str[@name='name'])[1] = 'custstopfilt'
	xml response was: <?xml version="1.0" encoding="UTF-8"?>
<response>

<lst name="responseHeader">
  <int name="status">0</int>
  <int name="QTime">1</int>
</lst>
<arr name="fields">
  <lst>
    <str name="name">stopfilt</str>
    <str name="type">stopfilt</str>
    <bool name="stored">true</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">subject</str>
    <str name="type">text</str>
    <bool name="stored">true</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">keywordtok</str>
    <str name="type">keywordtok</str>
    <bool name="stored">true</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">bindsto</str>
    <str name="type">boolean</str>
    <bool name="stored">true</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">iind</str>
    <str name="type">int</str>
    <bool name="stored">false</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">custengporterfilt</str>
    <str name="type">custengporterfilt</str>
    <bool name="stored">true</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">test_offtv</str>
    <str name="type">text</str>
    <bool name="termVectors">true</bool>
    <bool name="termOffsets">true</bool>
  </lst>
  <lst>
    <str name="name">weight</str>
    <str name="type">float</str>
    <bool name="stored">true</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">pointD</str>
    <str name="type">xyd</str>
    <bool name="stored">true</bool>
    <bool name="multiValued">false</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">uniq</str>
    <str name="type">string</str>
    <bool name="stored">true</bool>
    <bool name="multiValued">true</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">sindsto</str>
    <str name="type">string</str>
    <bool name="stored">true</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">lowerfilt</str>
    <str name="type">lowerfilt</str>
    <bool name="stored">true</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">point</str>
    <str name="type">xy</str>
    <bool name="stored">true</bool>
    <bool name="multiValued">false</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">point_hash</str>
    <str name="type">geohash</str>
    <bool name="stored">true</bool>
    <bool name="multiValued">false</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">wdf_preserve</str>
    <str name="type">wdf_preserve</str>
    <bool name="stored">true</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">standardfilt</str>
    <str name="type">standardfilt</str>
    <bool name="stored">true</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">shouldbeunindexed</str>
    <str name="type">unstored</str>
    <bool name="stored">true</bool>
    <bool name="indexed">false</bool>
  </lst>
  <lst>
    <str name="name">shouldbeunstored</str>
    <str name="type">unstored</str>
  </lst>
  <lst>
    <str name="name">test_hlt</str>
    <str name="type">highlittext</str>
    <bool name="compressed">true</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">standardtok</str>
    <str name="type">standardtok</str>
    <bool name="stored">true</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">text_np</str>
    <str name="type">text_np</str>
    <bool name="stored">false</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">uniq2</str>
    <str name="type">string</str>
    <bool name="stored">true</bool>
    <bool name="multiValued">true</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">sind</str>
    <str name="type">string</str>
    <bool name="stored">false</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">test_postv</str>
    <str name="type">text</str>
    <bool name="termVectors">true</bool>
    <bool name="termPositions">true</bool>
  </lst>
  <lst>
    <str name="name">signatureField</str>
    <str name="type">string</str>
    <bool name="stored">false</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">store</str>
    <str name="type">location</str>
    <bool name="stored">true</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">test_hlt_off</str>
    <str name="type">highlittext</str>
    <bool name="compressed">false</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">bsto</str>
    <str name="type">boolean</str>
    <bool name="stored">true</bool>
    <bool name="indexed">false</bool>
  </lst>
  <lst>
    <str name="name">isto</str>
    <str name="type">int</str>
    <bool name="stored">true</bool>
    <bool name="indexed">false</bool>
  </lst>
  <lst>
    <str name="name">timestamp</str>
    <str name="type">date</str>
    <bool name="stored">true</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">title</str>
    <str name="type">nametext</str>
    <bool name="stored">true</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">engporterfilt</str>
    <str name="type">engporterfilt</str>
    <bool name="stored">true</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">lengthfilt</str>
    <str name="type">lengthfilt</str>
    <bool name="stored">true</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">sku1</str>
    <str name="type">skutype1</str>
    <bool name="stored">true</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">nouniq</str>
    <str name="type">string</str>
    <bool name="stored">true</bool>
    <bool name="multiValued">true</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">bind</str>
    <str name="type">boolean</str>
    <bool name="stored">false</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">wdf_nocase</str>
    <str name="type">wdf_nocase</str>
    <bool name="stored">true</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">title_stemmed</str>
    <str name="type">text</str>
    <bool name="stored">false</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">text</str>
    <str name="type">text</str>
    <bool name="stored">false</bool>
    <bool name="multiValued">true</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">bday</str>
    <str name="type">date</str>
    <bool name="stored">true</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">numericsubword</str>
    <str name="type">numericsubword</str>
    <bool name="stored">true</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">HTMLwhitetok</str>
    <str name="type">HTMLwhitetok</str>
    <bool name="stored">true</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">uuid</str>
    <str name="type">uuid</str>
    <bool name="stored">true</bool>
  </lst>
  <lst>
    <str name="name">whitetok</str>
    <str name="type">whitetok</str>
    <bool name="stored">true</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">copyfield_source</str>
    <str name="type">string</str>
    <bool name="stored">true</bool>
    <bool name="multiValued">true</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">title_lettertok</str>
    <str name="type">lettertok</str>
    <bool name="stored">false</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">textgap</str>
    <str name="type">textgap</str>
    <bool name="stored">true</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">subword</str>
    <str name="type">subword</str>
    <bool name="stored">true</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">porterfilt</str>
    <str name="type">porterfilt</str>
    <bool name="stored">true</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">protectedsubword</str>
    <str name="type">protectedsubword</str>
    <bool name="stored">true</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">test_notv</str>
    <str name="type">text</str>
    <bool name="termVectors">false</bool>
  </lst>
  <lst>
    <str name="name">uniq3</str>
    <str name="type">string</str>
    <bool name="stored">true</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">custstopfilt</str>
    <str name="type">custstopfilt</str>
    <bool name="stored">true</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">test_basictv</str>
    <str name="type">text</str>
    <bool name="termVectors">true</bool>
  </lst>
  <lst>
    <str name="name">name</str>
    <str name="type">nametext</str>
    <bool name="stored">true</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">nullfirst</str>
    <str name="type">string</str>
    <bool name="stored">true</bool>
    <bool name="sortMissingFirst">true</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">_version_</str>
    <str name="type">long</str>
    <bool name="stored">true</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">patternreplacefilt</str>
    <str name="type">patternreplacefilt</str>
    <bool name="stored">true</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">syn</str>
    <str name="type">syn</str>
    <bool name="stored">true</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">id</str>
    <str name="type">string</str>
    <bool name="stored">true</bool>
    <bool name="required">true</bool>
    <bool name="multiValued">false</bool>
    <bool name="indexed">true</bool>
    <bool name="uniqueKey">true</bool>
  </lst>
  <lst>
    <str name="name">numberpartfail</str>
    <str name="type">failtype1</str>
    <bool name="stored">true</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">dedup</str>
    <str name="type">dedup</str>
    <bool name="stored">true</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">lowertok</str>
    <str name="type">lowertok</str>
    <bool name="stored">true</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">shouldbestored</str>
    <str name="type">unstored</str>
    <bool name="stored">true</bool>
  </lst>
  <lst>
    <str name="name">standardtokfilt</str>
    <str name="type">standardtokfilt</str>
    <bool name="stored">true</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">sku2</str>
    <str name="type">skutype2</str>
    <bool name="stored">true</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">teststop</str>
    <str name="type">teststop</str>
    <bool name="stored">true</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">lettertok</str>
    <str name="type">lettertok</str>
    <bool name="stored">true</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">ssto</str>
    <str name="type">string</str>
    <bool name="stored">true</bool>
    <bool name="indexed">false</bool>
  </lst>
  <lst>
    <str name="name">HTMLstandardtok</str>
    <str name="type">HTMLstandardtok</str>
    <bool name="stored">true</bool>
    <bool name="indexed">true</bool>
  </lst>
  <lst>
    <str name="name">test_posofftv</str>
    <str name="type">text</str>
    <bool name="termVectors">true</bool>
    <bool name="termOffsets">true</bool>
    <bool name="termPositions">true</bool>
  </lst>
</arr>
</response>

	request was:/schema/fields?indent=on&wt=xml
	at org.apache.solr.util.RestTestBase.assertQ(RestTestBase.java:136)
	... 41 more




Build Log:
[...truncated 9214 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.BasicDistributedZk2Test
[junit4:junit4]   2> 0 T2091 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4:junit4]   2> 2 T2091 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4:junit4]   2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-BasicDistributedZk2Test-1362556036520
[junit4:junit4]   2> 3 T2091 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 3 T2092 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4:junit4]   2> 4 T2092 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4]   2> 4 T2092 oazs.ZooKeeperServer.setMinSessionTimeout minSessionTimeout set to -1
[junit4:junit4]   2> 4 T2092 oazs.ZooKeeperServer.setMaxSessionTimeout maxSessionTimeout set to -1
[junit4:junit4]   2> 5 T2092 oazs.NIOServerCnxnFactory.configure binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 5 T2092 oazsp.FileTxnSnapLog.save Snapshotting: 0x0 to /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/zookeeper/server1/data/version-2/snapshot.0
[junit4:junit4]   2> 103 T2091 oasc.ZkTestServer.run start zk server on port:37280
[junit4:junit4]   2> 104 T2091 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:37280 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@148d6a0
[junit4:junit4]   2> 105 T2091 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 105 T2097 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:37280. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 105 T2097 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:37280, initiating session
[junit4:junit4]   2> 105 T2093 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:45871
[junit4:junit4]   2> 106 T2093 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:45871
[junit4:junit4]   2> 106 T2095 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 396 T2095 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d3eab25ab0000 with negotiated timeout 10000 for client /127.0.0.1:45871
[junit4:junit4]   2> 396 T2097 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:37280, sessionid = 0x13d3eab25ab0000, negotiated timeout = 10000
[junit4:junit4]   2> 397 T2098 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@148d6a0 name:ZooKeeperConnection Watcher:127.0.0.1:37280 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 397 T2091 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 398 T2091 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 402 T2096 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d3eab25ab0000
[junit4:junit4]   2> 403 T2098 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 403 T2093 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:45871 which had sessionid 0x13d3eab25ab0000
[junit4:junit4]   2> 403 T2091 oaz.ZooKeeper.close Session: 0x13d3eab25ab0000 closed
[junit4:junit4]   2> 404 T2091 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:37280/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@18f34b9
[junit4:junit4]   2> 405 T2099 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:37280. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 405 T2091 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 406 T2099 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:37280, initiating session
[junit4:junit4]   2> 406 T2093 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:45872
[junit4:junit4]   2> 406 T2093 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:45872
[junit4:junit4]   2> 408 T2095 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d3eab25ab0001 with negotiated timeout 10000 for client /127.0.0.1:45872
[junit4:junit4]   2> 408 T2099 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:37280, sessionid = 0x13d3eab25ab0001, negotiated timeout = 10000
[junit4:junit4]   2> 408 T2100 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@18f34b9 name:ZooKeeperConnection Watcher:127.0.0.1:37280/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 409 T2091 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 409 T2091 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 413 T2091 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 417 T2091 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 420 T2091 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 424 T2091 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 425 T2091 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 431 T2091 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4:junit4]   2> 432 T2091 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 436 T2091 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4:junit4]   2> 437 T2091 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 440 T2091 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4:junit4]   2> 441 T2091 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 445 T2091 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4:junit4]   2> 445 T2091 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 452 T2091 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 453 T2091 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 457 T2091 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 457 T2091 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 461 T2091 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 462 T2091 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 466 T2091 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4:junit4]   2> 467 T2091 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 471 T2096 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d3eab25ab0001
[junit4:junit4]   2> 471 T2100 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 471 T2093 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:45872 which had sessionid 0x13d3eab25ab0001
[junit4:junit4]   2> 471 T2091 oaz.ZooKeeper.close Session: 0x13d3eab25ab0001 closed
[junit4:junit4]   2> 545 T2091 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 550 T2091 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:48303
[junit4:junit4]   2> 550 T2091 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 551 T2091 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 551 T2091 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1362556036989
[junit4:junit4]   2> 551 T2091 oasc.CoreContainer$Initializer.initialize looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1362556036989/solr.xml
[junit4:junit4]   2> 552 T2091 oasc.CoreContainer.<init> New CoreContainer 23504717
[junit4:junit4]   2> 552 T2091 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1362556036989/'
[junit4:junit4]   2> 553 T2091 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1362556036989/'
[junit4:junit4]   2> 570 T2091 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 570 T2091 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 570 T2091 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 571 T2091 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 571 T2091 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 571 T2091 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 572 T2091 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 572 T2091 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 572 T2091 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 573 T2091 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 579 T2091 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 588 T2091 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:37280/solr
[junit4:junit4]   2> 588 T2091 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 589 T2091 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:37280 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@c78cf0
[junit4:junit4]   2> 589 T2091 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 590 T2110 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:37280. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 590 T2110 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:37280, initiating session
[junit4:junit4]   2> 590 T2093 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:45873
[junit4:junit4]   2> 590 T2093 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:45873
[junit4:junit4]   2> 591 T2095 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d3eab25ab0002 with negotiated timeout 20000 for client /127.0.0.1:45873
[junit4:junit4]   2> 591 T2110 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:37280, sessionid = 0x13d3eab25ab0002, negotiated timeout = 20000
[junit4:junit4]   2> 591 T2111 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@c78cf0 name:ZooKeeperConnection Watcher:127.0.0.1:37280 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 592 T2091 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 592 T2096 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d3eab25ab0002
[junit4:junit4]   2> 593 T2111 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 593 T2093 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:45873 which had sessionid 0x13d3eab25ab0002
[junit4:junit4]   2> 593 T2091 oaz.ZooKeeper.close Session: 0x13d3eab25ab0002 closed
[junit4:junit4]   2> 593 T2091 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 596 T2091 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:37280/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@17fa497
[junit4:junit4]   2> 596 T2112 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:37280. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 596 T2091 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 597 T2112 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:37280, initiating session
[junit4:junit4]   2> 597 T2093 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:45874
[junit4:junit4]   2> 597 T2093 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:45874
[junit4:junit4]   2> 598 T2095 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d3eab25ab0003 with negotiated timeout 20000 for client /127.0.0.1:45874
[junit4:junit4]   2> 598 T2112 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:37280, sessionid = 0x13d3eab25ab0003, negotiated timeout = 20000
[junit4:junit4]   2> 598 T2113 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@17fa497 name:ZooKeeperConnection Watcher:127.0.0.1:37280/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 598 T2091 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 599 T2096 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d3eab25ab0003 type:create cxid:0x2 zxid:0x1a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 600 T2091 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 601 T2091 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:48303_
[junit4:junit4]   2> 602 T2096 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d3eab25ab0003 type:delete cxid:0x8 zxid:0x1c txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:48303_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:48303_
[junit4:junit4]   2> 602 T2091 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:48303_
[junit4:junit4]   2> 604 T2091 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 609 T2096 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d3eab25ab0003 type:delete cxid:0x16 zxid:0x21 txntype:-1 reqpath:n/a Error Path:/solr/overseer_elect/leader Error:KeeperErrorCode = NoNode for /solr/overseer_elect/leader
[junit4:junit4]   2> 609 T2091 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 611 T2091 oasc.Overseer.start Overseer (id=89296472409571331-127.0.0.1:48303_-n_0000000000) starting
[junit4:junit4]   2> 611 T2096 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d3eab25ab0003 type:create cxid:0x1b zxid:0x23 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 612 T2096 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d3eab25ab0003 type:create cxid:0x1c zxid:0x24 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 612 T2096 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d3eab25ab0003 type:create cxid:0x1d zxid:0x25 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 613 T2096 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d3eab25ab0003 type:create cxid:0x1e zxid:0x26 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 613 T2115 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 614 T2091 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 615 T2091 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 632 T2114 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 634 T2116 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1362556036989/collection1
[junit4:junit4]   2> 634 T2116 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 634 T2116 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 635 T2116 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 635 T2116 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1362556036989/collection1/'
[junit4:junit4]   2> 636 T2116 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1362556036989/collection1/lib/README' to classloader
[junit4:junit4]   2> 636 T2116 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1362556036989/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 659 T2116 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 687 T2116 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 688 T2116 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 692 T2116 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 1049 T2116 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 1054 T2116 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 1056 T2116 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 1066 T2116 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1068 T2116 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1070 T2116 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1071 T2116 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 1072 T2116 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 1072 T2116 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1073 T2116 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 1073 T2116 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 1073 T2116 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1362556036989/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/control/data/
[junit4:junit4]   2> 1074 T2116 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2a2776
[junit4:junit4]   2> 1074 T2116 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 1074 T2116 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/control/data forceNew:false
[junit4:junit4]   2> 1074 T2116 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/control/data
[junit4:junit4]   2> 1075 T2116 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/control/data/index/
[junit4:junit4]   2> 1075 T2116 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/control/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 1075 T2116 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/control/data/index forceNew:false
[junit4:junit4]   2> 1078 T2116 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/index6135461365tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@a10a7c),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 1078 T2116 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 1078 T2116 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/control/data/index
[junit4:junit4]   2> 1079 T2116 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 1080 T2116 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 1080 T2116 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 1080 T2116 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 1081 T2116 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 1081 T2116 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 1081 T2116 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 1081 T2116 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 1081 T2116 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 1084 T2116 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 1085 T2116 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/control/data
[junit4:junit4]   2> 1086 T2116 oass.SolrIndexSearcher.<init> Opening Searcher@6abc8c main
[junit4:junit4]   2> 1086 T2116 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/control/data/tlog
[junit4:junit4]   2> 1086 T2116 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 1087 T2116 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 1089 T2116 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/control/data
[junit4:junit4]   2> 1089 T2117 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6abc8c main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 1090 T2116 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 1090 T2116 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 1090 T2096 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d3eab25ab0003 type:create cxid:0x50 zxid:0x29 txntype:-1 reqpath:n/a Error Path:/solr/overseer/queue Error:KeeperErrorCode = NoNode for /solr/overseer/queue
[junit4:junit4]   2> 2135 T2114 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2135 T2114 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:48303",
[junit4:junit4]   2> 	  "core_node_name":"1",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "collection":"control_collection",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:48303_",
[junit4:junit4]   2> 	  "operation":"state"}
[junit4:junit4]   2> 2136 T2114 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with numShards 1
[junit4:junit4]   2> 2136 T2114 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 2136 T2096 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d3eab25ab0003 type:create cxid:0x58 zxid:0x2c txntype:-1 reqpath:n/a Error Path:/solr/overseer/queue-work Error:KeeperErrorCode = NoNode for /solr/overseer/queue-work
[junit4:junit4]   2> 2141 T2113 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4:junit4]   2> 3092 T2116 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 3093 T2116 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:48303 collection:control_collection shard:shard1
[junit4:junit4]   2> 3093 T2116 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4:junit4]   2> 3098 T2096 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d3eab25ab0003 type:delete cxid:0x72 zxid:0x36 txntype:-1 reqpath:n/a Error Path:/solr/collections/control_collection/leaders Error:KeeperErrorCode = NoNode for /solr/collections/control_collection/leaders
[junit4:junit4]   2> 3098 T2116 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 3098 T2096 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d3eab25ab0003 type:create cxid:0x73 zxid:0x37 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3100 T2116 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 3100 T2116 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 3100 T2116 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:48303/collection1/
[junit4:junit4]   2> 3100 T2116 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 3100 T2116 oasc.SyncStrategy.syncToMe http://127.0.0.1:48303/collection1/ has no replicas
[junit4:junit4]   2> 3101 T2116 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:48303/collection1/
[junit4:junit4]   2> 3101 T2116 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4:junit4]   2> 3103 T2096 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d3eab25ab0003 type:create cxid:0x7d zxid:0x3b txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3644 T2114 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3651 T2113 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4:junit4]   2> 3655 T2116 oasc.ZkController.register We are http://127.0.0.1:48303/collection1/ and leader is http://127.0.0.1:48303/collection1/
[junit4:junit4]   2> 3655 T2116 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:48303
[junit4:junit4]   2> 3656 T2116 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 3656 T2116 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 3656 T2116 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 3658 T2116 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3659 T2091 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 3659 T2091 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 3660 T2091 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 3663 T2091 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 3663 T2091 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:37280/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@64befa
[junit4:junit4]   2> 3664 T2091 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3664 T2119 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:37280. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 3664 T2119 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:37280, initiating session
[junit4:junit4]   2> 3664 T2093 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:45904
[junit4:junit4]   2> 3665 T2093 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:45904
[junit4:junit4]   2> 3665 T2095 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d3eab25ab0004 with negotiated timeout 10000 for client /127.0.0.1:45904
[junit4:junit4]   2> 3665 T2119 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:37280, sessionid = 0x13d3eab25ab0004, negotiated timeout = 10000
[junit4:junit4]   2> 3666 T2120 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@64befa name:ZooKeeperConnection Watcher:127.0.0.1:37280/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 3666 T2091 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 3667 T2091 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 3668 T2091 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4]   2> 3726 T2091 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 3728 T2091 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:37226
[junit4:junit4]   2> 3728 T2091 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 3728 T2091 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3729 T2091 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1362556040185
[junit4:junit4]   2> 3729 T2091 oasc.CoreContainer$Initializer.initialize looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1362556040185/solr.xml
[junit4:junit4]   2> 3729 T2091 oasc.CoreContainer.<init> New CoreContainer 12228094
[junit4:junit4]   2> 3730 T2091 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1362556040185/'
[junit4:junit4]   2> 3730 T2091 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1362556040185/'
[junit4:junit4]   2> 3751 T2091 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 3752 T2091 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 3752 T2091 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 3753 T2091 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 3753 T2091 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 3754 T2091 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 3754 T2091 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 3755 T2091 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 3755 T2091 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 3755 T2091 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 3764 T2091 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 3778 T2091 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:37280/solr
[junit4:junit4]   2> 3779 T2091 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 3779 T2091 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:37280 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@c79c6
[junit4:junit4]   2> 3780 T2130 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:37280. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 3781 T2091 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3782 T2093 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:45918
[junit4:junit4]   2> 3782 T2130 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:37280, initiating session
[junit4:junit4]   2> 3782 T2093 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:45918
[junit4:junit4]   2> 3783 T2095 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d3eab25ab0005 with negotiated timeout 20000 for client /127.0.0.1:45918
[junit4:junit4]   2> 3783 T2130 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:37280, sessionid = 0x13d3eab25ab0005, negotiated timeout = 20000
[junit4:junit4]   2> 3784 T2131 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@c79c6 name:ZooKeeperConnection Watcher:127.0.0.1:37280 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 3784 T2091 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 3785 T2096 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d3eab25ab0005
[junit4:junit4]   2> 3786 T2093 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:45918 which had sessionid 0x13d3eab25ab0005
[junit4:junit4]   2> 3786 T2131 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 3786 T2091 oaz.ZooKeeper.close Session: 0x13d3eab25ab0005 closed
[junit4:junit4]   2> 3786 T2091 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 3788 T2091 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:37280/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@e3154
[junit4:junit4]   2> 3789 T2132 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:37280. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 3789 T2091 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3790 T2132 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:37280, initiating session
[junit4:junit4]   2> 3790 T2093 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:45919
[junit4:junit4]   2> 3790 T2093 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:45919
[junit4:junit4]   2> 3791 T2095 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d3eab25ab0006 with negotiated timeout 20000 for client /127.0.0.1:45919
[junit4:junit4]   2> 3791 T2132 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:37280, sessionid = 0x13d3eab25ab0006, negotiated timeout = 20000
[junit4:junit4]   2> 3791 T2133 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@e3154 name:ZooKeeperConnection Watcher:127.0.0.1:37280/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 3791 T2091 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 3792 T2096 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d3eab25ab0006 type:create cxid:0x1 zxid:0x49 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3792 T2096 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d3eab25ab0006 type:create cxid:0x2 zxid:0x4a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3793 T2091 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 4795 T2091 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37226_
[junit4:junit4]   2> 4796 T2096 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d3eab25ab0006 type:delete cxid:0x9 zxid:0x4b txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:37226_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:37226_
[junit4:junit4]   2> 4797 T2091 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:37226_
[junit4:junit4]   2> 4804 T2120 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 4804 T2113 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 4804 T2113 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 4804 T2133 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 4809 T2134 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1362556040185/collection1
[junit4:junit4]   2> 4809 T2134 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 4813 T2134 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 4813 T2134 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 4820 T2134 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1362556040185/collection1/'
[junit4:junit4]   2> 4821 T2134 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1362556040185/collection1/lib/README' to classloader
[junit4:junit4]   2> 4821 T2134 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1362556040185/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 4866 T2134 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 4912 T2134 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 4913 T2134 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 4917 T2134 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 5155 T2114 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5156 T2114 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:48303",
[junit4:junit4]   2> 	  "core_node_name":"1",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "collection":"control_collection",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:48303_",
[junit4:junit4]   2> 	  "operation":"state"}
[junit4:junit4]   2> 5159 T2133 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 5159 T2113 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 5159 T2120 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 5362 T2134 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 5383 T2134 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 5386 T2134 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 5409 T2134 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 5421 T2134 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 5424 T2134 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 5428 T2134 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 5429 T2134 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 5429 T2134 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 5431 T2134 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 5431 T2134 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 5431 T2134 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1362556040185/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty1/
[junit4:junit4]   2> 5432 T2134 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2a2776
[junit4:junit4]   2> 5432 T2134 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 5432 T2134 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty1 forceNew:false
[junit4:junit4]   2> 5433 T2134 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty1
[junit4:junit4]   2> 5433 T2134 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty1/index/
[junit4:junit4]   2> 5433 T2134 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty1/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 5434 T2134 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty1/index forceNew:false
[junit4:junit4]   2> 5438 T2134 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/index6135461366tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@ef79b7),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 5438 T2134 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 5438 T2134 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty1/index
[junit4:junit4]   2> 5440 T2134 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 5440 T2134 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 5440 T2134 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 5441 T2134 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 5441 T2134 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 5442 T2134 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 5442 T2134 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 5442 T2134 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 5443 T2134 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 5447 T2134 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 5454 T2134 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty1
[junit4:junit4]   2> 5460 T2134 oass.SolrIndexSearcher.<init> Opening Searcher@87703d main
[junit4:junit4]   2> 5461 T2134 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty1/tlog
[junit4:junit4]   2> 5461 T2134 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 5461 T2134 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 5464 T2135 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@87703d main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 5465 T2134 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 5466 T2134 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 6663 T2114 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6664 T2114 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:37226",
[junit4:junit4]   2> 	  "core_node_name":"2",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:37226_",
[junit4:junit4]   2> 	  "operation":"state"}
[junit4:junit4]   2> 6664 T2114 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with numShards 2
[junit4:junit4]   2> 6664 T2114 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 6716 T2133 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 6716 T2113 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 6716 T2120 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 7467 T2134 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 7467 T2134 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:37226 collection:collection1 shard:shard1
[junit4:junit4]   2> 7468 T2134 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 7472 T2096 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d3eab25ab0006 type:delete cxid:0x46 zxid:0x5b txntype:-1 reqpath:n/a Error Path:/solr/collections/collection1/leaders Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders
[junit4:junit4]   2> 7473 T2134 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 7473 T2096 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d3eab25ab0006 type:create cxid:0x47 zxid:0x5c txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 7474 T2134 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 7475 T2134 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 7475 T2134 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:37226/collection1/
[junit4:junit4]   2> 7475 T2134 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 7475 T2134 oasc.SyncStrategy.syncToMe http://127.0.0.1:37226/collection1/ has no replicas
[junit4:junit4]   2> 7475 T2134 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:37226/collection1/
[junit4:junit4]   2> 7476 T2134 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 7478 T2096 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d3eab25ab0006 type:create cxid:0x51 zxid:0x60 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8221 T2114 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8231 T2113 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 8231 T2133 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 8231 T2120 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 8281 T2134 oasc.ZkController.register We are http://127.0.0.1:37226/collection1/ and leader is http://127.0.0.1:37226/collection1/
[junit4:junit4]   2> 8281 T2134 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:37226
[junit4:junit4]   2> 8282 T2134 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 8282 T2134 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 8282 T2134 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 8284 T2134 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8285 T2091 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 8285 T2091 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 8285 T2091 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 8341 T2091 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 8343 T2091 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:34658
[junit4:junit4]   2> 8343 T2091 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 8344 T2091 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 8344 T2091 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1362556044805
[junit4:junit4]   2> 8344 T2091 oasc.CoreContainer$Initializer.initialize looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1362556044805/solr.xml
[junit4:junit4]   2> 8345 T2091 oasc.CoreContainer.<init> New CoreContainer 10249191
[junit4:junit4]   2> 8345 T2091 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1362556044805/'
[junit4:junit4]   2> 8345 T2091 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1362556044805/'
[junit4:junit4]   2> 8365 T2091 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 8365 T2091 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 8366 T2091 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 8366 T2091 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 8367 T2091 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 8367 T2091 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 8367 T2091 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 8368 T2091 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 8368 T2091 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 8368 T2091 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 8374 T2091 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 8382 T2091 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:37280/solr
[junit4:junit4]   2> 8383 T2091 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 8383 T2091 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:37280 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@182839
[junit4:junit4]   2> 8384 T2091 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 8384 T2146 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:37280. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 8385 T2146 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:37280, initiating session
[junit4:junit4]   2> 8385 T2093 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:45936
[junit4:junit4]   2> 8385 T2093 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:45936
[junit4:junit4]   2> 8386 T2095 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d3eab25ab0007 with negotiated timeout 20000 for client /127.0.0.1:45936
[junit4:junit4]   2> 8386 T2146 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:37280, sessionid = 0x13d3eab25ab0007, negotiated timeout = 20000
[junit4:junit4]   2> 8386 T2147 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@182839 name:ZooKeeperConnection Watcher:127.0.0.1:37280 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 8386 T2091 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 8387 T2096 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d3eab25ab0007
[junit4:junit4]   2> 8387 T2147 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 8387 T2093 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:45936 which had sessionid 0x13d3eab25ab0007
[junit4:junit4]   2> 8387 T2091 oaz.ZooKeeper.close Session: 0x13d3eab25ab0007 closed
[junit4:junit4]   2> 8388 T2091 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 8390 T2091 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:37280/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@618c10
[junit4:junit4]   2> 8391 T2148 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:37280. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 8390 T2091 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 8391 T2148 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:37280, initiating session
[junit4:junit4]   2> 8391 T2093 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:45937
[junit4:junit4]   2> 8391 T2093 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:45937
[junit4:junit4]   2> 8392 T2095 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d3eab25ab0008 with negotiated timeout 20000 for client /127.0.0.1:45937
[junit4:junit4]   2> 8392 T2148 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:37280, sessionid = 0x13d3eab25ab0008, negotiated timeout = 20000
[junit4:junit4]   2> 8392 T2149 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@618c10 name:ZooKeeperConnection Watcher:127.0.0.1:37280/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 8392 T2091 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 8393 T2096 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d3eab25ab0008 type:create cxid:0x1 zxid:0x6d txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8393 T2096 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d3eab25ab0008 type:create cxid:0x2 zxid:0x6e txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8395 T2091 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 9397 T2091 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34658_
[junit4:junit4]   2> 9397 T2096 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d3eab25ab0008 type:delete cxid:0x9 zxid:0x6f txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:34658_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:34658_
[junit4:junit4]   2> 9398 T2091 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:34658_
[junit4:junit4]   2> 9399 T2120 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 9400 T2113 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 9400 T2149 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 9400 T2133 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 9400 T2113 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 9400 T2133 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 9401 T2120 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 9404 T2150 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1362556044805/collection1
[junit4:junit4]   2> 9405 T2150 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 9405 T2150 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 9405 T2150 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 9406 T2150 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1362556044805/collection1/'
[junit4:junit4]   2> 9407 T2150 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1362556044805/collection1/lib/README' to classloader
[junit4:junit4]   2> 9407 T2150 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1362556044805/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 9430 T2150 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 9469 T2150 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 9470 T2150 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 9473 T2150 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 9737 T2114 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9738 T2114 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:37226",
[junit4:junit4]   2> 	  "core_node_name":"2",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:37226_",
[junit4:junit4]   2> 	  "operation":"state"}
[junit4:junit4]   2> 9741 T2133 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 9741 T2113 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 9741 T2149 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 9741 T2120 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 9805 T2150 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 9811 T2150 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 9814 T2150 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 9822 T2150 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 9824 T2150 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 9827 T2150 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 9827 T2150 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 9828 T2150 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 9828 T2150 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 9828 T2150 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 9828 T2150 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 9829 T2150 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1362556044805/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty2/
[junit4:junit4]   2> 9829 T2150 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2a2776
[junit4:junit4]   2> 9829 T2150 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 9830 T2150 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty2 forceNew:false
[junit4:junit4]   2> 9830 T2150 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty2
[junit4:junit4]   2> 9830 T2150 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty2/index/
[junit4:junit4]   2> 9830 T2150 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty2/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 9831 T2150 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty2/index forceNew:false
[junit4:junit4]   2> 9833 T2150 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/index6135461367tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@861474),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 9833 T2150 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 9834 T2150 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty2/index
[junit4:junit4]   2> 9835 T2150 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 9835 T2150 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 9835 T2150 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 9836 T2150 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 9836 T2150 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 9836 T2150 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 9837 T2150 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 9837 T2150 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 9837 T2150 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 9839 T2150 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 9841 T2150 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty2
[junit4:junit4]   2> 9842 T2150 oass.SolrIndexSearcher.<init> Opening Searcher@1aa5113 main
[junit4:junit4]   2> 9842 T2150 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty2/tlog
[junit4:junit4]   2> 9842 T2150 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 9843 T2150 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 9845 T2151 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1aa5113 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 9848 T2150 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 9848 T2150 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 11246 T2114 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 11246 T2114 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:34658",
[junit4:junit4]   2> 	  "core_node_name":"3",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:34658_",
[junit4:junit4]   2> 	  "operation":"state"}
[junit4:junit4]   2> 11247 T2114 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4:junit4]   2> 11247 T2114 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4:junit4]   2> 11265 T2113 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 11265 T2149 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 11265 T2120 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 11265 T2133 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 11850 T2150 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 11850 T2150 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:34658 collection:collection1 shard:shard2
[junit4:junit4]   2> 11851 T2150 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4:junit4]   2> 11858 T2096 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d3eab25ab0008 type:delete cxid:0x45 zxid:0x7e txntype:-1 reqpath:n/a Error Path:/solr/collections/collection1/leaders/shard2 Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders/shard2
[junit4:junit4]   2> 11859 T2150 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 11859 T2096 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d3eab25ab0008 type:create cxid:0x46 zxid:0x7f txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 11861 T2150 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 11861 T2150 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 11861 T2150 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:34658/collection1/
[junit4:junit4]   2> 11861 T2150 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 11861 T2150 oasc.SyncStrategy.syncToMe http://127.0.0.1:34658/collection1/ has no replicas
[junit4:junit4]   2> 11862 T2150 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:34658/collection1/
[junit4:junit4]   2> 11862 T2150 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4:junit4]   2> 11876 T2096 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d3eab25ab0008 type:create cxid:0x4f zxid:0x82 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 12770 T2114 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 12777 T2120 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 12777 T2149 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 12777 T2113 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 12777 T2133 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 12779 T2150 oasc.ZkController.register We are http://127.0.0.1:34658/collection1/ and leader is http://127.0.0.1:34658/collection1/
[junit4:junit4]   2> 12779 T2150 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:34658
[junit4:junit4]   2> 12779 T2150 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 12780 T2150 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 12780 T2150 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 12781 T2150 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 12781 T2091 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 12782 T2091 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 12782 T2091 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 12843 T2091 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 12845 T2091 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:32811
[junit4:junit4]   2> 12845 T2091 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 12846 T2091 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 12846 T2091 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1362556049302
[junit4:junit4]   2> 12847 T2091 oasc.CoreContainer$Initializer.initialize looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1362556049302/solr.xml
[junit4:junit4]   2> 12847 T2091 oasc.CoreContainer.<init> New CoreContainer 21606304
[junit4:junit4]   2> 12847 T2091 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1362556049302/'
[junit4:junit4]   2> 12848 T2091 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1362556049302/'
[junit4:junit4]   2> 12864 T2091 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 12865 T2091 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 12865 T2091 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 12865 T2091 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 12866 T2091 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 12866 T2091 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 12866 T2091 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 12867 T2091 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 12867 T2091 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 12867 T2091 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 12873 T2091 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 12882 T2091 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:37280/solr
[junit4:junit4]   2> 12882 T2091 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 12883 T2091 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:37280 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@17e01f
[junit4:junit4]   2> 12884 T2162 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:37280. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 12884 T2091 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 12884 T2162 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:37280, initiating session
[junit4:junit4]   2> 12884 T2093 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:45941
[junit4:junit4]   2> 12884 T2093 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:45941
[junit4:junit4]   2> 12885 T2095 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d3eab25ab0009 with negotiated timeout 20000 for client /127.0.0.1:45941
[junit4:junit4]   2> 12885 T2162 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:37280, sessionid = 0x13d3eab25ab0009, negotiated timeout = 20000
[junit4:junit4]   2> 12885 T2163 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@17e01f name:ZooKeeperConnection Watcher:127.0.0.1:37280 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 12885 T2091 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 12886 T2096 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d3eab25ab0009
[junit4:junit4]   2> 12887 T2163 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 12887 T2093 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:45941 which had sessionid 0x13d3eab25ab0009
[junit4:junit4]   2> 12887 T2091 oaz.ZooKeeper.close Session: 0x13d3eab25ab0009 closed
[junit4:junit4]   2> 12887 T2091 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 12889 T2091 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:37280/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@14f7914
[junit4:junit4]   2> 12890 T2164 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:37280. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 12890 T2091 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 12890 T2164 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:37280, initiating session
[junit4:junit4]   2> 12891 T2093 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:45942
[junit4:junit4]   2> 12891 T2093 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:45942
[junit4:junit4]   2> 12891 T2095 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d3eab25ab000a with negotiated timeout 20000 for client /127.0.0.1:45942
[junit4:junit4]   2> 12891 T2164 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:37280, sessionid = 0x13d3eab25ab000a, negotiated timeout = 20000
[junit4:junit4]   2> 12892 T2165 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@14f7914 name:ZooKeeperConnection Watcher:127.0.0.1:37280/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 12892 T2091 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 12892 T2096 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d3eab25ab000a type:create cxid:0x1 zxid:0x8f txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 12893 T2096 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d3eab25ab000a type:create cxid:0x2 zxid:0x90 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 12894 T2091 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 13896 T2091 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:32811_
[junit4:junit4]   2> 13897 T2096 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d3eab25ab000a type:delete cxid:0x9 zxid:0x91 txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:32811_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:32811_
[junit4:junit4]   2> 13897 T2091 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:32811_
[junit4:junit4]   2> 13900 T2120 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 13900 T2133 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 13900 T2113 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 13900 T2149 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 13900 T2113 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 13900 T2165 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 13900 T2149 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 13901 T2120 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 13901 T2133 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 13905 T2166 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1362556049302/collection1
[junit4:junit4]   2> 13905 T2166 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 13906 T2166 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 13906 T2166 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 13907 T2166 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1362556049302/collection1/'
[junit4:junit4]   2> 13907 T2166 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1362556049302/collection1/lib/README' to classloader
[junit4:junit4]   2> 13907 T2166 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1362556049302/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 13928 T2166 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 13965 T2166 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 13966 T2166 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 13970 T2166 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 14275 T2166 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 14279 T2166 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 14282 T2114 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 14282 T2166 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 14282 T2114 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:34658",
[junit4:junit4]   2> 	  "core_node_name":"3",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "shard":"shard2",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:34658_",
[junit4:junit4]   2> 	  "operation":"state"}
[junit4:junit4]   2> 14285 T2113 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 14285 T2149 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 14285 T2165 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 14285 T2120 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 14286 T2133 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 14292 T2166 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 14295 T2166 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 14297 T2166 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 14298 T2166 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 14298 T2166 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 14299 T2166 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 14299 T2166 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 14299 T2166 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 14300 T2166 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1362556049302/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty3/
[junit4:junit4]   2> 14300 T2166 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2a2776
[junit4:junit4]   2> 14300 T2166 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 14301 T2166 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty3 forceNew:false
[junit4:junit4]   2> 14301 T2166 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty3
[junit4:junit4]   2> 14301 T2166 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty3/index/
[junit4:junit4]   2> 14301 T2166 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty3/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 14302 T2166 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty3/index forceNew:false
[junit4:junit4]   2> 14305 T2166 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/index6135461368tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@15eb3a7),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 14305 T2166 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 14305 T2166 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty3/index
[junit4:junit4]   2> 14306 T2166 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 14307 T2166 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 14307 T2166 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 14307 T2166 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 14308 T2166 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 14308 T2166 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 14308 T2166 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 14308 T2166 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 14309 T2166 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 14311 T2166 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 14313 T2166 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty3
[junit4:junit4]   2> 14313 T2166 oass.SolrIndexSearcher.<init> Opening Searcher@1206b71 main
[junit4:junit4]   2> 14313 T2166 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty3/tlog
[junit4:junit4]   2> 14314 T2166 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 14314 T2166 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 14317 T2167 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1206b71 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 14318 T2166 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 14318 T2166 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 15789 T2114 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 15789 T2114 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:32811",
[junit4:junit4]   2> 	  "core_node_name":"4",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:32811_",
[junit4:junit4]   2> 	  "operation":"state"}
[junit4:junit4]   2> 15790 T2114 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4:junit4]   2> 15790 T2114 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 15793 T2113 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 15793 T2149 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 15793 T2165 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 15793 T2133 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 15793 T2120 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 16320 T2166 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 16320 T2166 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:32811 collection:collection1 shard:shard1
[junit4:junit4]   2> 16325 T2166 oasc.ZkController.register We are http://127.0.0.1:32811/collection1/ and leader is http://127.0.0.1:37226/collection1/
[junit4:junit4]   2> 16325 T2166 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:32811
[junit4:junit4]   2> 16326 T2166 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 16326 T2166 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C121 name=collection1 org.apache.solr.core.SolrCore@3ce8dd url=http://127.0.0.1:32811/collection1 node=127.0.0.1:32811_ C121_STATE=coll:collection1 core:collection1 props:{core=collection1, state=down, base_url=http://127.0.0.1:32811, collection=collection1, node_name=127.0.0.1:32811_}
[junit4:junit4]   2> 16326 T2168 C121 P32811 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 16327 T2168 C121 P32811 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 16328 T2168 C121 P32811 oasc.ZkController.publish publishing core=collection1 state=recovering
[junit4:junit4]   2> 16328 T2166 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 16328 T2168 C121 P32811 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 16329 T2168 C121 P32811 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 16329 T2091 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 16330 T2091 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 16331 T2091 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 16335 T2091 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 16336 T2091 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 16339 T2126 oasc.CoreContainer.create Creating SolrCore 'onenodecollectioncore' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1362556040185/onenodecollectioncore
[junit4:junit4]   2> 16340 T2126 oasc.ZkController.createCollectionZkNode Check for collection zkNode:onenodecollection
[junit4:junit4]   2> 16340 T2126 oasc.ZkController.createCollectionZkNode Creating collection in ZooKeeper:onenodecollection
[junit4:junit4]   2> 16341 T2126 oasc.ZkController.getConfName Looking for collection configName
[junit4:junit4]   2> 16342 T2126 oasc.ZkController.getConfName Only one config set found in zk - using it:conf1
[junit4:junit4]   2> 16342 T2126 oascc.SolrZkClient.makePath makePath: /collections/onenodecollection
[junit4:junit4]   2> 16345 T2126 oasc.ZkController.readConfigName Load collection config from:/collections/onenodecollection
[junit4:junit4]   2> 16346 T2126 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1362556040185/onenodecollectioncore/'
[junit4:junit4]   2> 16386 T2126 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 16415 T2126 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 16416 T2126 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 16419 T2126 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 16736 T2126 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 16741 T2126 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 16743 T2126 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 16753 T2126 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 16755 T2126 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 16758 T2126 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 16758 T2126 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 16759 T2126 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 16759 T2126 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 16760 T2126 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 16760 T2126 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 16760 T2126 oasc.SolrCore.<init> [onenodecollectioncore] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1362556040185/onenodecollectioncore/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-BasicDistributedZk2Test-1362556036520/onenodecollection/
[junit4:junit4]   2> 16760 T2126 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2a2776
[junit4:junit4]   2> 16761 T2126 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 16761 T2126 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-BasicDistributedZk2Test-1362556036520/onenodecollection forceNew:false
[junit4:junit4]   2> 16762 T2126 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-BasicDistributedZk2Test-1362556036520/onenodecollection
[junit4:junit4]   2> 16762 T2126 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-BasicDistributedZk2Test-1362556036520/onenodecollection/index/
[junit4:junit4]   2> 16762 T2126 oasc.SolrCore.initIndex WARNING [onenodecollectioncore] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-BasicDistributedZk2Test-1362556036520/onenodecollection/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 16763 T2126 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-BasicDistributedZk2Test-1362556036520/onenodecollection/index forceNew:false
[junit4:junit4]   2> 16766 T2126 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/index6135461369tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@18dfb1c),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 16766 T2126 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 16766 T2126 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-BasicDistributedZk2Test-1362556036520/onenodecollection/index
[junit4:junit4]   2> 16767 T2126 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 16768 T2126 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 16768 T2126 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 16769 T2126 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 16769 T2126 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 16769 T2126 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 16769 T2126 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 16770 T2126 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 16770 T2126 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 16772 T2126 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 16774 T2126 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-BasicDistributedZk2Test-1362556036520/onenodecollection
[junit4:junit4]   2> 16775 T2126 oass.SolrIndexSearcher.<init> Opening Searcher@218178 main
[junit4:junit4]   2> 16775 T2126 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-BasicDistributedZk2Test-1362556036520/onenodecollection/tlog
[junit4:junit4]   2> 16776 T2126 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 16776 T2126 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 16778 T2126 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-BasicDistributedZk2Test-1362556036520/onenodecollection
[junit4:junit4]   2> 16779 T2170 oasc.SolrCore.registerSearcher [onenodecollectioncore] Registered new searcher Searcher@218178 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 16780 T2126 oasc.ZkController.publish publishing core=onenodecollectioncore state=down
[junit4:junit4]   2> 17299 T2114 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 17300 T2114 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "state":"recovering",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:32811",
[junit4:junit4]   2> 	  "core_node_name":"4",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:32811_",
[junit4:junit4]   2> 	  "operation":"state"}
[junit4:junit4]   2> 17305 T2114 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "core":"onenodecollectioncore",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "roles":"none",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:37226",
[junit4:junit4]   2> 	  "core_node_name":null,
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "collection":"onenodecollection",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:37226_",
[junit4:junit4]   2> 	  "operation":"state"}
[junit4:junit4]   2> 17305 T2114 oasc.Overseer$ClusterStateUpdater.createCollection Create collection onenodecollection with numShards 1
[junit4:junit4]   2> 17306 T2114 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 17312 T2113 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 17312 T2149 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 17312 T2165 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 17313 T2133 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 17313 T2120 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 17335 T2127 oass.SolrDispatchFilter.handleAdminRequest /admin/cores {core=collection1&version=2&checkLive=true&coreNodeName=4&state=recovering&nodeName=127.0.0.1:32811_&onlyIfLeader=true&wt=javabin&action=PREPRECOVERY} status=0 QTime=1000 
[junit4:junit4]   2> 17781 T2126 oasc.CoreContainer.registerCore registering core: onenodecollectioncore
[junit4:junit4]   2> 17781 T2126 oasc.ZkController.register Register replica - core:onenodecollectioncore address:http://127.0.0.1:37226 collection:onenodecollection shard:shard1
[junit4:junit4]   2> 17782 T2126 oascc.SolrZkClient.makePath makePath: /collections/onenodecollection/leader_elect/shard1/election
[junit4:junit4]   2> 17787 T2096 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d3eab25ab0006 type:delete cxid:0x9f zxid:0xac txntype:-1 reqpath:n/a Error Path:/solr/collections/onenodecollection/leaders Error:KeeperErrorCode = NoNode for /solr/collections/onenodecollection/leaders
[junit4:junit4]   2> 17788 T2126 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 17788 T2096 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d3eab25ab0006 type:create cxid:0xa0 zxid:0xad txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 17789 T2126 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 17790 T2126 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 17790 T2126 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:37226/onenodecollectioncore/
[junit4:junit4]   2> 17790 T2126 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 17790 T2126 oasc.SyncStrategy.syncToMe http://127.0.0.1:37226/onenodecollectioncore/ has no replicas
[junit4:junit4]   2> 17791 T2126 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:37226/onenodecollectioncore/
[junit4:junit4]   2> 17791 T2126 oascc.SolrZkClient.makePath makePath: /collections/onenodecollection/leaders/shard1
[junit4:junit4]   2> 17794 T2096 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d3eab25ab0006 type:create cxid:0xaa zxid:0xb1 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 18820 T2114 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 18828 T2120 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 18828 T2133 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 18828 T2149 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 18828 T2165 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 18828 T2113 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 18847 T2126 oasc.ZkController.register We are http://127.0.0.1:37226/onenodecollectioncore/ and leader is http://127.0.0.1:37226/onenodecollectioncore/
[junit4:junit4]   2> 18848 T2126 oasc.ZkController.register No LogReplay needed for core=onenodecollectioncore baseURL=http://127.0.0.1:37226
[junit4:junit4]   2> 18848 T2126 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 18848 T2126 oasc.ZkController.publish publishing core=onenodecollectioncore state=active
[junit4:junit4]   2> 18849 T2126 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 18850 T2126 oass.SolrDispatchFilter.handleAdminRequest /admin/cores {dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-BasicDistributedZk2Test-1362556036520/onenodecollection&version=2&roles=none&numShards=1&wt=javabin&collection=onenodecollection&name=onenodecollectioncore&action=CREATE} status=0 QTime=2511 
[junit4:junit4]   2> 18850 T2091 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Wait for recoveries to finish - collection: onenodecollection failOnTimeout:true timeout (sec):230
[junit4:junit4]   2> 18851 T2091 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2>  C121_STATE=coll:collection1 core:collection1 props:{core=collection1, state=recovering, base_url=http://127.0.0.1:32811, shard=shard1, collection=collection1, node_name=127.0.0.1:32811_}
[junit4:junit4]   2> 19336 T2168 C121 P32811 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:37226/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 19337 T2168 C121 P32811 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:32811 START replicas=[http://127.0.0.1:37226/collection1/] nUpdates=100
[junit4:junit4]   2> 19337 T2168 C121 P32811 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 19337 T2168 C121 P32811 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 19337 T2168 C121 P32811 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 19337 T2168 C121 P32811 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 19337 T2168 C121 P32811 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 19337 T2168 C121 P32811 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:37226/collection1/. core=collection1
[junit4:junit4]   2> 19338 T2168 C121 P32811 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C122 name=collection1 org.apache.solr.core.SolrCore@4cbcb6 url=http://127.0.0.1:37226/collection1 node=127.0.0.1:37226_ C122_STATE=coll:collection1 core:collection1 props:{core=collection1, state=active, base_url=http://127.0.0.1:37226, shard=shard1, collection=collection1, node_name=127.0.0.1:37226_, leader=true}
[junit4:junit4]   2> 19341 T2126 C122 P37226 REQ /get {getVersions=100&version=2&distrib=false&qt=/get&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 19342 T2127 C122 P37226 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 19343 T2127 C122 P37226 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty1
[junit4:junit4]   2> 19344 T2127 C122 P37226 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/index6135461366tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@ef79b7),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 19345 T2127 C122 P37226 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 19355 T2127 C122 P37226 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/index6135461366tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@ef79b7),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/index6135461366tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@ef79b7),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 19355 T2127 C122 P37226 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 19355 T2127 C122 P37226 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty1
[junit4:junit4]   2> 19356 T2127 C122 P37226 oass.SolrIndexSearcher.<init> Opening Searcher@18da6c realtime
[junit4:junit4]   2> 19356 T2127 C122 P37226 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 19356 T2127 C122 P37226 /update {version=2&commit_end_point=true&softCommit=false&commit=true&wt=javabin&waitSearcher=true&openSearcher=false} {commit=} 0 14
[junit4:junit4]   2> 19357 T2168 C121 P32811 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 19357 T2168 C121 P32811 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 19360 T2128 C122 P37226 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 19360 T2128 C122 P37226 REQ /replication {version=2&command=indexversion&qt=/replication&wt=javabin} status=0 QTime=3 
[junit4:junit4]   2> 19360 T2168 C121 P32811 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4:junit4]   2> 19360 T2168 C121 P32811 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4:junit4]   2> 19360 T2168 C121 P32811 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4]   2> 19362 T2126 C122 P37226 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty1
[junit4:junit4]   2> 19362 T2126 C122 P37226 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty1/index
[junit4:junit4]   2> 19363 T2126 C122 P37226 REQ /replication {version=2&command=filelist&qt=/replication&wt=javabin&generation=2} status=0 QTime=1 
[junit4:junit4]   2> 19363 T2168 C121 P32811 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 1
[junit4:junit4]   2> 19364 T2168 C121 P32811 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty3/index.20130306084735880 forceNew:false
[junit4:junit4]   2> 19364 T2168 C121 P32811 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty3
[junit4:junit4]   2> 19364 T2168 C121 P32811 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory@1d184da lockFactory=org.apache.lucene.store.NativeFSLockFactory@99704c) fullCopy=false
[junit4:junit4]   2> 19366 T2127 C122 P37226 REQ /replication {command=filecontent&qt=/replication&file=segments_2&wt=filestream&checksum=true&generation=2} status=0 QTime=0 
[junit4:junit4]   2> 19367 T2168 C121 P32811 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty3/index
[junit4:junit4]   2> 19367 T2168 C121 P32811 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4]   2> 19367 T2168 C121 P32811 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty3
[junit4:junit4]   2> 19368 T2168 C121 P32811 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty3
[junit4:junit4]   2> 19368 T2168 C121 P32811 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4]   2> 19368 T2168 C121 P32811 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4]   2> 19368 T2168 C121 P32811 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty3
[junit4:junit4]   2> 19369 T2168 C121 P32811 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/index6135461368tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@15eb3a7),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/index6135461368tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@15eb3a7),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 19370 T2168 C121 P32811 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 19370 T2168 C121 P32811 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4]   2> 19370 T2168 C121 P32811 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty3
[junit4:junit4]   2> 19370 T2168 C121 P32811 oass.SolrIndexSearcher.<init> Opening Searcher@ee5394 main
[junit4:junit4]   2> 19371 T2167 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@ee5394 main{StandardDirectoryReader(segments_2:1:nrt)}
[junit4:junit4]   2> 19371 T2167 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty3/index
[junit4:junit4]   2> 19371 T2168 C121 P32811 oash.SnapPuller.fetchLatestIndex removing temporary index download directory files MockDirWrapper(org.apache.lucene.store.RAMDirectory@1d184da lockFactory=org.apache.lucene.store.NativeFSLockFactory@99704c)
[junit4:junit4]   2> 19371 T2168 C121 P32811 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty3/index.20130306084735880
[junit4:junit4]   2> 19371 T2168 C121 P32811 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty3/index
[junit4:junit4]   2> 19372 T2168 C121 P32811 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 19372 T2168 C121 P32811 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 19372 T2168 C121 P32811 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 19372 T2168 C121 P32811 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 19373 T2168 C121 P32811 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 19852 T2091 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 20332 T2114 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 20333 T2114 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "core":"onenodecollectioncore",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "roles":"none",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:37226",
[junit4:junit4]   2> 	  "core_node_name":null,
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "collection":"onenodecollection",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:37226_",
[junit4:junit4]   2> 	  "operation":"state"}
[junit4:junit4]   2> 20335 T2114 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:32811",
[junit4:junit4]   2> 	  "core_node_name":"4",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:32811_",
[junit4:junit4]   2> 	  "operation":"state"}
[junit4:junit4]   2> 20355 T2133 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 20355 T2165 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 20355 T2149 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 20355 T2120 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 20355 T2113 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 20855 T2091 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 20856 T2091 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Recoveries finished - collection: onenodecollection
[junit4:junit4]   2> 20856 T2091 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 20864 T2091 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:37280 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@f9856d
[junit4:junit4]   2> 20865 T2091 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 20865 T2173 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:37280. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 20865 T2173 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:37280, initiating session
[junit4:junit4]   2> 20865 T2093 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:45952
[junit4:junit4]   2> 20866 T2093 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:45952
[junit4:junit4]   2> 20866 T2095 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d3eab25ab000b with negotiated timeout 10000 for client /127.0.0.1:45952
[junit4:junit4]   2> 20866 T2173 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:37280, sessionid = 0x13d3eab25ab000b, negotiated timeout = 10000
[junit4:junit4]   2> 20866 T2174 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@f9856d name:ZooKeeperConnection Watcher:127.0.0.1:37280 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 20867 T2091 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 20891 T2096 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d3eab25ab000b
[junit4:junit4]   2> 20891 T2174 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 20891 T2093 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:45952 which had sessionid 0x13d3eab25ab000b
[junit4:junit4]   2> 20891 T2091 oaz.ZooKeeper.close Session: 0x13d3eab25ab000b closed
[junit4:junit4]   2> 20892 T2096 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d3eab25ab0004
[junit4:junit4]   2> 20892 T2093 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:45904 which had sessionid 0x13d3eab25ab0004
[junit4:junit4]   2> 20892 T2120 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 20892 T2091 oaz.ZooKeeper.close Session: 0x13d3eab25ab0004 closed
[junit4:junit4]   2> 20894 T2091 oasc.ChaosMonkey.monkeyLog monkey: stop shard! 48303
[junit4:junit4]   2> 20894 T2091 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=23504717
[junit4:junit4]   2> 21861 T2114 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 21862 T2114 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:48303",
[junit4:junit4]   2> 	  "core_node_name":"1",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "collection":"control_collection",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:48303_",
[junit4:junit4]   2> 	  "operation":"state"}
[junit4:junit4]   2> 21865 T2133 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 21865 T2165 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 21865 T2113 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 21865 T2149 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 22895 T2091 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 22896 T2091 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 22900 T2091 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@19b3a58
[junit4:junit4]   2> 22903 T2091 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty3/index
[junit4:junit4]   2> 22904 T2091 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty3
[junit4:junit4]   2> 22905 T2091 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> 22906 T2091 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4:junit4]   2> 22906 T2091 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 22907 T2091 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4:junit4]   2> 22907 T2091 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 22908 T2091 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/control/data/index
[junit4:junit4]   2> 22909 T2091 oasc.CachingDirectoryFactory.close Closing directory when closing factory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/control/data
[junit4:junit4]   2> 22910 T2091 oasc.CachingDirectoryFactory.closeDirectory Closing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/control/data
[junit4:junit4]   2> 22910 T2091 oasc.CachingDirectoryFactory.close Closing directory when closing factory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/control/data/index
[junit4:junit4]   2> 22911 T2091 oasc.CachingDirectoryFactory.closeDirectory Closing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/control/data/index
[junit4:junit4]   2> 22911 T2114 oasc.Overseer$ClusterStateUpdater.amILeader According to ZK I (id=89296472409571331-127.0.0.1:48303_-n_0000000000) am no longer a leader.
[junit4:junit4]   2> 22913 T2096 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d3eab25ab0003
[junit4:junit4]   2> 22914 T2113 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 22914 T2165 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 22914 T2113 oascc.ZkStateReader$2.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 22914 T2091 oaz.ZooKeeper.close Session: 0x13d3eab25ab0003 closed
[junit4:junit4]   2> 22914 T2093 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:45874 which had sessionid 0x13d3eab25ab0003
[junit4:junit4]   2> 22914 T2113 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 22915 T2113 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 22915 T2133 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 22915 T2149 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 22915 T2133 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 22915 T2149 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 22916 T2165 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 22917 T2096 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d3eab25ab0006 type:delete cxid:0xba zxid:0xcd txntype:-1 reqpath:n/a Error Path:/solr/overseer_elect/leader Error:KeeperErrorCode = NoNode for /solr/overseer_elect/leader
[junit4:junit4]   2> 22917 T2133 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 22918 T2133 oasc.Overseer.start Overseer (id=89296472409571334-127.0.0.1:37226_-n_0000000001) starting
[junit4:junit4]   2> 22919 T2096 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d3eab25ab0006 type:create cxid:0xbf zxid:0xcf txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 22919 T2096 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d3eab25ab0006 type:create cxid:0xc0 zxid:0xd0 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 22920 T2096 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d3eab25ab0006 type:create cxid:0xc1 zxid:0xd1 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 22920 T2096 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d3eab25ab0006 type:create cxid:0xc2 zxid:0xd2 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 22921 T2176 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 22922 T2175 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 22923 T2175 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 22923 T2175 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:48303",
[junit4:junit4]   2> 	  "core_node_name":"1",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "collection":"control_collection",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:48303_",
[junit4:junit4]   2> 	  "operation":"state"}
[junit4:junit4]   2> 22926 T2133 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 22926 T2165 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 22926 T2149 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 22935 T2091 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/,null}
[junit4:junit4]   2> 22987 T2091 oasc.ChaosMonkey.monkeyLog monkey: stop shard! 37226
[junit4:junit4]   2> 22988 T2091 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=12228094
[junit4:junit4]   2> 24429 T2175 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 24430 T2175 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4:junit4]   2> 	  "core":"onenodecollectioncore",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "roles":"none",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:37226",
[junit4:junit4]   2> 	  "core_node_name":"127.0.0.1:37226__onenodecollectioncore",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "collection":"onenodecollection",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:37226_",
[junit4:junit4]   2> 	  "operation":"state"}
[junit4:junit4]   2> 24432 T2175 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:37226",
[junit4:junit4]   2> 	  "core_node_name":"2",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:37226_",
[junit4:junit4]   2> 	  "operation":"state"}
[junit4:junit4]   2> 24435 T2133 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 24435 T2149 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 24435 T2165 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 25992 T2091 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 25993 T2091 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 25995 T2091 oasc.ZkController.publish publishing core=onenodecollectioncore state=down
[junit4:junit4]   2> 25997 T2091 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@4cbcb6
[junit4:junit4]   2> 25999 T2091 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty3/index
[junit4:junit4]   2> 25999 T2091 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty3
[junit4:junit4]   2> 26000 T2091 oasu.DirectUpdateHandler2.close 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=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> 26000 T2091 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4:junit4]   2> 26000 T2091 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 26000 T2091 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4:junit4]   2>  C122_STATE=coll:collection1 core:collection1 props:{core=collection1, state=down, base_url=http://127.0.0.1:37226, shard=shard1, collection=collection1, node_name=127.0.0.1:37226_, leader=true}
[junit4:junit4]   2> 26001 T2091 C122 P37226 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty1/index
[junit4:junit4]   2> 26002 T2091 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 26002 T2091 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty1/index
[junit4:junit4]   2> 26003 T2091 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty1/index
[junit4:junit4]   2> 26003 T2091 oasc.CachingDirectoryFactory.close Closing directory when closing factory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty1/index
[junit4:junit4]   2> 26003 T2091 oasc.CachingDirectoryFactory.closeDirectory Closing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty1/index
[junit4:junit4]   2> 26003 T2091 oasc.CachingDirectoryFactory.close Closing directory when closing factory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty1
[junit4:junit4]   2> 26004 T2091 oasc.CachingDirectoryFactory.closeDirectory Closing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362556036520/jetty1
[junit4:junit4]   2> 26004 T2091 oasc.SolrCore.close [onenodecollectioncore]  CLOSING SolrCore org.apache.solr.core.SolrCore@140226
[junit4:junit4]   2> 26007 T2091 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> 26007 T2091 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4:junit4]   2> 26007 T2091 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 26007 T2091 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4:junit4]   2> 26008 T2091 oasc.SolrCore.closeSearcher [onenodecollectioncore] Closing main searcher on request.
[junit4:junit4]   2> 26008 T2091 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-BasicDistributedZk2Test-1362556036520/onenodecollection/index
[junit4:junit4]   2> 26009 T2091 oasc.CachingDirectoryFactory.close Closing directory when closing factory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-BasicDistributedZk2Test-1362556036520/onenodecollection/index
[junit4:junit4]   2> 26009 T2091 oasc.CachingDirectoryFactory.closeDirectory Closing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-BasicDistributedZk2Test-1362556036520/onenodecollection/index
[junit4:junit4]   2> 26009 T2091 oasc.CachingDirectoryFactory.close Closing directory when closing factory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-BasicDistributedZk2Test-1362556036520/onenodecollection
[junit4:junit4]   2> 26009 T2091 oasc.CachingDirectoryFactory.closeDirectory Closing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-BasicDistributedZk2Test-1362556036520/onenodecollection
[junit4:junit4]   2> 26010 T2175 oasc.Overseer$ClusterStateUpdater.amILeader According to ZK I (id=89296472409571334-127.0.0.1:37226_-n_0000000001) am no longer a leader.
[junit4:junit4]   2> 26010 T2096 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d3eab25ab0006
[junit4:junit4]   2> 26012 T2093 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:45919 which had sessionid 0x13d3eab25ab0006
[junit4:junit4]   2> 26012 T2091 oaz.ZooKeeper.close Session: 0x13d3eab25ab0006 closed
[junit4:junit4]   2> 26013 T2165 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 26013 T2096 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d3eab25ab0008 type:delete cxid:0x6e zxid:0xe3 txntype:-1 reqpath:n/a Error Path:/solr/overseer_elect/leader Error:KeeperErrorCode = NoNode for /solr/overseer_elect/leader
[junit4:junit4]   2> 26013 T2165 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 26013 T2149 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 26014 T2096 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d3eab25ab000a type:delete cxid:0x56 zxid:0xe5 txntype:-1 reqpath:n/a Error Path:/solr/collections/collection1/leaders/shard1 Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders/shard1
[junit4:junit4]   2> 26015 T2165 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 26015 T2149 oasc.Overseer.start Overseer (id=89296472409571336-127.0.0.1:34658_-n_0000000002) starting
[junit4:junit4]   2> 26015 T2096 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d3eab25ab000a type:create cxid:0x57 zxid:0xe6 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 26015 T2096 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d3eab25ab0008 type:create cxid:0x73 zxid:0xe7 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 26016 T2096 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d3eab25ab0008 type:create cxid:0x74 zxid:0xe9 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 26016 T2165 oasc.ShardLeaderElectionContext.shouldIBeLeader Checking if I should try and be the leader.
[junit4:junit4]   2> 26016 T2165 oasc.ShardLeaderElectionContext.shouldIBeLeader My last published State was Active, it's okay to be the leader.
[junit4:junit4]   2> 26016 T2165 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 26017 T2096 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d3eab25ab0008 type:create cxid:0x75 zxid:0xea txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 26017 T2165 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:32811/collection1/
[junit4:junit4]   2> 26017 T2165 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 26017 T2165 oasc.SyncStrategy.syncToMe http://127.0.0.1:32811/collection1/ has no replicas
[junit4:junit4]   2> 26017 T2165 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:32811/collection1/
[junit4:junit4]   2> 26017 T2096 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d3eab25ab0008 type:create cxid:0x76 zxid:0xeb txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 26017 T2165 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 26018 T2178 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 26018 T2149 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 26019 T2149 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 26019 T2177 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 26019 T2096 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d3eab25ab000a type:create cxid:0x5f zxid:0xed txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists fo

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

st>
[junit4:junit4]    >   <lst>
[junit4:junit4]    >     <str name="name">standardtokfilt</str>
[junit4:junit4]    >     <str name="type">standardtokfilt</str>
[junit4:junit4]    >     <bool name="stored">true</bool>
[junit4:junit4]    >     <bool name="indexed">true</bool>
[junit4:junit4]    >   </lst>
[junit4:junit4]    >   <lst>
[junit4:junit4]    >     <str name="name">sku2</str>
[junit4:junit4]    >     <str name="type">skutype2</str>
[junit4:junit4]    >     <bool name="stored">true</bool>
[junit4:junit4]    >     <bool name="indexed">true</bool>
[junit4:junit4]    >   </lst>
[junit4:junit4]    >   <lst>
[junit4:junit4]    >     <str name="name">teststop</str>
[junit4:junit4]    >     <str name="type">teststop</str>
[junit4:junit4]    >     <bool name="stored">true</bool>
[junit4:junit4]    >     <bool name="indexed">true</bool>
[junit4:junit4]    >   </lst>
[junit4:junit4]    >   <lst>
[junit4:junit4]    >     <str name="name">lettertok</str>
[junit4:junit4]    >     <str name="type">lettertok</str>
[junit4:junit4]    >     <bool name="stored">true</bool>
[junit4:junit4]    >     <bool name="indexed">true</bool>
[junit4:junit4]    >   </lst>
[junit4:junit4]    >   <lst>
[junit4:junit4]    >     <str name="name">ssto</str>
[junit4:junit4]    >     <str name="type">string</str>
[junit4:junit4]    >     <bool name="stored">true</bool>
[junit4:junit4]    >     <bool name="indexed">false</bool>
[junit4:junit4]    >   </lst>
[junit4:junit4]    >   <lst>
[junit4:junit4]    >     <str name="name">HTMLstandardtok</str>
[junit4:junit4]    >     <str name="type">HTMLstandardtok</str>
[junit4:junit4]    >     <bool name="stored">true</bool>
[junit4:junit4]    >     <bool name="indexed">true</bool>
[junit4:junit4]    >   </lst>
[junit4:junit4]    >   <lst>
[junit4:junit4]    >     <str name="name">test_posofftv</str>
[junit4:junit4]    >     <str name="type">text</str>
[junit4:junit4]    >     <bool name="termVectors">true</bool>
[junit4:junit4]    >     <bool name="termOffsets">true</bool>
[junit4:junit4]    >     <bool name="termPositions">true</bool>
[junit4:junit4]    >   </lst>
[junit4:junit4]    > </arr>
[junit4:junit4]    > </response>
[junit4:junit4]    > 
[junit4:junit4]    > 	request was:/schema/fields?indent=on&wt=xml
[junit4:junit4]    > 	at org.apache.solr.util.RestTestBase.assertQ(RestTestBase.java:136)
[junit4:junit4]    > 	... 41 more
[junit4:junit4]   2> 790 T3130 oas.SolrTestCaseJ4.setUp ###Starting testGetTwoFields
[junit4:junit4]   2> 792 T3138 C245 oasr.BaseSchemaResource.handlePostExecution [collection1] webapp=/solr path=/schema/fields params={fl=id,_version_&indent=on&wt=xml} status=0 QTime=1 
[junit4:junit4]   2> 793 T3138 C245 orel.LogFilter.afterHandle 2013-03-06	01:52:52	127.0.0.1	-	127.0.0.1	51737	GET	/solr/schema/fields	indent=on&wt=xml&fl=id,_version_	200	-	0	1	http://127.0.0.1:51737	Java/1.8.0-ea	-
[junit4:junit4]   2> 797 T3130 oas.SolrTestCaseJ4.tearDown ###Ending testGetTwoFields
[junit4:junit4]   2> 809 T3130 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=13661436
[junit4:junit4]   2> 811 T3130 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@fef43c
[junit4:junit4]   2> 819 T3130 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> 820 T3130 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4:junit4]   2> 820 T3130 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 820 T3130 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4:junit4]   2> 821 T3130 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 821 T3130 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/solrtest-TestFieldCollectionResource-1362556372045/index
[junit4:junit4]   2> 821 T3130 oasc.CachingDirectoryFactory.close Closing directory when closing factory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/solrtest-TestFieldCollectionResource-1362556372045/index
[junit4:junit4]   2> 822 T3130 oasc.CachingDirectoryFactory.closeDirectory Closing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/solrtest-TestFieldCollectionResource-1362556372045/index
[junit4:junit4]   2> 822 T3130 oasc.CachingDirectoryFactory.close Closing directory when closing factory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/solrtest-TestFieldCollectionResource-1362556372045
[junit4:junit4]   2> 822 T3130 oasc.CachingDirectoryFactory.closeDirectory Closing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/solrtest-TestFieldCollectionResource-1362556372045
[junit4:junit4]   2> 824 T3130 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 876 T3130 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> NOTE: test params are: codec=Lucene40, sim=DefaultSimilarity, locale=uk_UA, timezone=America/Bahia_Banderas
[junit4:junit4]   2> NOTE: Linux 3.2.0-38-generic i386/Oracle Corporation 1.8.0-ea (32-bit)/cpus=8,threads=1,free=62028440,total=202674176
[junit4:junit4]   2> NOTE: All tests run in this JVM: [TestSweetSpotSimilarityFactory, TestPropInjectDefaults, PrimUtilsTest, OverseerCollectionProcessorTest, TestFieldTypeCollectionResource, QueryElevationComponentTest, TestFunctionQuery, TestRemoteStreaming, TestWriterPerf, TestPerFieldSimilarity, LeaderElectionTest, CoreAdminHandlerTest, QueryParsingTest, SolrCoreTest, TestValueSourceCache, SuggesterFSTTest, TestPhraseSuggestions, TestDynamicFieldCollectionResource, TestSearchPerf, StatelessScriptUpdateProcessorFactoryTest, TestFastLRUCache, DocumentBuilderTest, TestSort, PeerSyncTest, TestPluginEnable, TestCharFilters, TestJoin, TestSolrIndexConfig, TestReplicationHandler, FileBasedSpellCheckerTest, AnalysisAfterCoreReloadTest, SimpleFacetsTest, BasicFunctionalityTest, DocValuesTest, LukeRequestHandlerTest, TestGroupingSearch, TestRandomDVFaceting, ScriptEngineTest, SolrInfoMBeanTest, TestLFUCache, LoggingHandlerTest, PolyFieldTest, TestReversedWildcardFilterFactory, TestExtendedDismaxParser, TestFastWriter, MinimalSchemaTest, TestFieldResource, WordBreakSolrSpellCheckerTest, SpellingQueryConverterTest, ZkCLITest, RAMDirectoryFactoryTest, ChaosMonkeyNothingIsSafeTest, TermVectorComponentTest, SolrIndexSplitterTest, CacheHeaderTest, TestDocumentBuilder, ResourceLoaderTest, FieldMutatingUpdateProcessorTest, TestStressVersions, ZkControllerTest, SyncSliceTest, SynonymTokenizerTest, CircularListTest, TestRealTimeGet, SOLR749Test, BasicDistributedZkTest, BasicDistributedZk2Test, UnloadDistributedZkTest, RecoveryZkTest, FullSolrCloudDistribCmdsTest, TestZkChroot, AutoCommitTest, TestRecovery, DistributedSpellCheckComponentTest, TestMultiCoreConfBootstrap, TestReload, TestCoreContainer, TestFiltering, TestTrie, SchemaVersionSpecificBehaviorTest, TestPseudoReturnFields, TestWordDelimiterFilterFactory, SortByFunctionTest, DistanceFunctionTest, XsltUpdateRequestHandlerTest, TestSurroundQueryParser, TestQueryUtils, TestQueryTypes, TestOmitPositions, XmlUpdateRequestHandlerTest, RequiredFieldsTest, TestSolrQueryParser, FastVectorHighlighterTest, IndexSchemaTest, ReturnFieldsTest, TestCSVResponseWriter, JsonLoaderTest, TestPartialUpdateDeduplication, CSVRequestHandlerTest, TestBinaryResponseWriter, TestPropInject, IndexReaderFactoryTest, AlternateDirectoryTest, CopyFieldTest, BadComponentTest, TestMergePolicyConfig, TestSolrDeletionPolicy2, MultiTermTest, TestDocSet, TestElisionMultitermQuery, TestConfig, TestFuzzyAnalyzedSuggestions, ExternalFileFieldSortTest, TestSolrCoreProperties, TestPostingsSolrHighlighter, DirectSolrConnectionTest, TestLuceneMatchVersion, TestXIncludeConfig, TestNumberUtils, TestLMDirichletSimilarityFactory, TestLMJelinekMercerSimilarityFactory, TestIBSimilarityFactory, TestBM25SimilarityFactory, LegacyHTMLStripCharFilterTest, OpenExchangeRatesOrgProviderTest, PluginInfoTest, TestSolrXMLSerializer, PreAnalyzedFieldTest, TestSuggestSpellingConverter, DateFieldTest, DOMUtilTest, ClusterStateTest, TestLRUCache, ZkNodePropsTest, SliceStateTest, SystemInfoHandlerTest, UUIDFieldTest, TestRTGBase, TestDynamicFieldResource, TestFieldCollectionResource]
[junit4:junit4] Completed on J0 in 0.89s, 5 tests, 2 errors <<< FAILURES!

[...truncated 15 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:381: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:361: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:183: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:449: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1213: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:877: There were test failures: 268 suites, 1134 tests, 3 errors, 13 ignored (7 assumptions)

Total time: 45 minutes 23 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Description set: Java: 32bit/jdk1.8.0-ea-b79 -server -XX:+UseSerialGC
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message