You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2013/11/11 19:47:21 UTC
[JENKINS] Lucene-Solr-4.x-Windows (32bit/jdk1.6.0_45) - Build #
3377 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Windows/3377/
Java: 32bit/jdk1.6.0_45 -server -XX:+UseConcMarkSweepGC
2 tests failed.
REGRESSION: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testDistribSearch
Error Message:
collection already exists: awholynewcollection_1
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: collection already exists: awholynewcollection_1
at __randomizedtesting.SeedInfo.seed([9985B637BEC33328:1863382FC99C5314]:0)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:464)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:181)
at org.apache.solr.client.solrj.impl.LBHttpSolrServer.request(LBHttpSolrServer.java:268)
at org.apache.solr.client.solrj.impl.CloudSolrServer.request(CloudSolrServer.java:640)
at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1601)
at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1627)
at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testCollectionsAPI(CollectionsAPIDistributedZkTest.java:646)
at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.doTest(CollectionsAPIDistributedZkTest.java:197)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:835)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
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:662)
REGRESSION: org.apache.solr.core.TestNonNRTOpen.testReaderIsNotNRT
Error Message:
expected:<3> but was:<2>
Stack Trace:
java.lang.AssertionError: expected:<3> but was:<2>
at __randomizedtesting.SeedInfo.seed([9985B637BEC33328:2C03D7B0010281DC]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.junit.Assert.assertEquals(Assert.java:456)
at org.apache.solr.core.TestNonNRTOpen.assertNotNRT(TestNonNRTOpen.java:133)
at org.apache.solr.core.TestNonNRTOpen.testReaderIsNotNRT(TestNonNRTOpen.java:94)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
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:662)
Build Log:
[...truncated 9487 lines...]
[junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
[junit4] 2> 103476 T134 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4] 2> 103479 T134 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-CollectionsAPIDistributedZkTest-1384191946513
[junit4] 2> 103488 T134 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 2> 103494 T135 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 103794 T134 oasc.ZkTestServer.run start zk server on port:63466
[junit4] 2> 104191 T134 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 104329 T141 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1856ec1 name:ZooKeeperConnection Watcher:127.0.0.1:63466 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 104330 T134 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 104334 T134 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 104418 T134 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 104422 T143 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@875666 name:ZooKeeperConnection Watcher:127.0.0.1:63466/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 104422 T134 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 104427 T134 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 104439 T134 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 104456 T134 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 104465 T134 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 104479 T134 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 104480 T134 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 104499 T134 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\schema.xml to /configs/conf1/schema.xml
[junit4] 2> 104499 T134 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 104512 T134 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 104512 T134 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 104528 T134 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 104528 T134 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 104540 T134 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 104540 T134 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 104553 T134 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml
[junit4] 2> 104553 T134 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 104564 T134 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 104564 T134 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 104576 T134 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 104576 T134 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 104587 T134 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 104587 T134 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 104599 T134 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 104599 T134 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 104611 T134 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 104611 T134 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> Using new style solr.xml
[junit4] 2> 104627 T134 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 104632 T145 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@140b30d name:ZooKeeperConnection Watcher:127.0.0.1:63466 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 104632 T134 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 104634 T134 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 104655 T134 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 104660 T147 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@191c7b3 name:ZooKeeperConnection Watcher:127.0.0.1:63466/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 104660 T134 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 104662 T134 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\solrconfig.xml to /configs/conf2/solrconfig.xml
[junit4] 2> 104665 T134 oascc.SolrZkClient.makePath makePath: /configs/conf2/solrconfig.xml
[junit4] 2> 104681 T134 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\schema.xml to /configs/conf2/schema.xml
[junit4] 2> 104681 T134 oascc.SolrZkClient.makePath makePath: /configs/conf2/schema.xml
[junit4] 2> 104694 T134 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml to /configs/conf2/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 104694 T134 oascc.SolrZkClient.makePath makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 104705 T134 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\stopwords.txt to /configs/conf2/stopwords.txt
[junit4] 2> 104705 T134 oascc.SolrZkClient.makePath makePath: /configs/conf2/stopwords.txt
[junit4] 2> 104717 T134 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\protwords.txt to /configs/conf2/protwords.txt
[junit4] 2> 104717 T134 oascc.SolrZkClient.makePath makePath: /configs/conf2/protwords.txt
[junit4] 2> 104730 T134 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\currency.xml to /configs/conf2/currency.xml
[junit4] 2> 104730 T134 oascc.SolrZkClient.makePath makePath: /configs/conf2/currency.xml
[junit4] 2> 104740 T134 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf2/open-exchange-rates.json
[junit4] 2> 104740 T134 oascc.SolrZkClient.makePath makePath: /configs/conf2/open-exchange-rates.json
[junit4] 2> 104752 T134 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf2/mapping-ISOLatin1Accent.txt
[junit4] 2> 104752 T134 oascc.SolrZkClient.makePath makePath: /configs/conf2/mapping-ISOLatin1Accent.txt
[junit4] 2> 104764 T134 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf2/old_synonyms.txt
[junit4] 2> 104765 T134 oascc.SolrZkClient.makePath makePath: /configs/conf2/old_synonyms.txt
[junit4] 2> 104779 T134 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\synonyms.txt to /configs/conf2/synonyms.txt
[junit4] 2> 104779 T134 oascc.SolrZkClient.makePath makePath: /configs/conf2/synonyms.txt
[junit4] 2> 104791 T134 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\elevate.xml to /configs/conf2/elevate.xml
[junit4] 2> 104791 T134 oascc.SolrZkClient.makePath makePath: /configs/conf2/elevate.xml
[junit4] 2> 105696 T134 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 105935 T134 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:63479
[junit4] 2> 105983 T134 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 105983 T134 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 105984 T134 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1384191947838
[junit4] 2> 105984 T134 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1384191947838\'
[junit4] 2> 106025 T134 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1384191947838\solr.xml
[junit4] 2> 106103 T134 oasc.ConfigSolrXml.<init> Config-defined core root directory:
[junit4] 2> 106105 T134 oasc.CoreContainer.<init> New CoreContainer 12344036
[junit4] 2> 106105 T134 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1384191947838\]
[junit4] 2> 106109 T134 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 106109 T134 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 106109 T134 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 106109 T134 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 106111 T134 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 106112 T134 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 106112 T134 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 106112 T134 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 106112 T134 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 106120 T134 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 106122 T134 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 106122 T134 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:63466/solr
[junit4] 2> 106146 T134 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 106149 T134 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 106173 T159 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@af0c9a name:ZooKeeperConnection Watcher:127.0.0.1:63466 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 106174 T134 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 106199 T134 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 106207 T161 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@f6a6ae name:ZooKeeperConnection Watcher:127.0.0.1:63466/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 106208 T134 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 106220 T134 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 106245 T134 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 106268 T134 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 106275 T134 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:63479_
[junit4] 2> 106282 T134 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:63479_
[junit4] 2> 106296 T134 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 106335 T134 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 106344 T134 oasc.Overseer.start Overseer (id=90714403430989829-127.0.0.1:63479_-n_0000000000) starting
[junit4] 2> 106373 T134 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 106397 T163 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 106408 T134 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 106427 T134 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 106439 T134 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 106462 T162 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 106468 T134 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1384191947838
[junit4] 2> 106726 T134 oasc.CorePropertiesLocator.discoverUnder Found core conf in C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1384191947838\conf\
[junit4] 2> 106735 T134 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 106738 T134 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 106738 T134 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 106810 T134 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 106816 T166 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@14340c4 name:ZooKeeperConnection Watcher:127.0.0.1:63466/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 106817 T134 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 106821 T134 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 106832 T134 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 107309 T134 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 107314 T134 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:63491
[junit4] 2> 107315 T134 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 107315 T134 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 107315 T134 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1384191949864
[junit4] 2> 107315 T134 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1384191949864\'
[junit4] 2> 107348 T134 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1384191949864\solr.xml
[junit4] 2> 107434 T134 oasc.ConfigSolrXml.<init> Config-defined core root directory:
[junit4] 2> 107434 T134 oasc.CoreContainer.<init> New CoreContainer 22456210
[junit4] 2> 107435 T134 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1384191949864\]
[junit4] 2> 107437 T134 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 107437 T134 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 107438 T134 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 107438 T134 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 107438 T134 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 107439 T134 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 107439 T134 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 107439 T134 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 107440 T134 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 107443 T134 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 107444 T134 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 107444 T134 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:63466/solr
[junit4] 2> 107444 T134 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 107446 T134 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 107459 T177 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1385ce name:ZooKeeperConnection Watcher:127.0.0.1:63466 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 107460 T134 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 107467 T134 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 107471 T179 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@d4159f name:ZooKeeperConnection Watcher:127.0.0.1:63466/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 107471 T134 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 107485 T134 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 108486 T134 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:63491_
[junit4] 2> 108489 T134 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:63491_
[junit4] 2> 108506 T166 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 108507 T179 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 108508 T161 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 108526 T134 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1384191949864
[junit4] 2> 108779 T134 oasc.CorePropertiesLocator.discoverUnder Found core conf in C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1384191949864\conf\
[junit4] 2> 108794 T134 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 108794 T134 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 108794 T134 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 109312 T134 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 109316 T134 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:63500
[junit4] 2> 109317 T134 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 109317 T134 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 109317 T134 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1384191951829
[junit4] 2> 109317 T134 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1384191951829\'
[junit4] 2> 109356 T134 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1384191951829\solr.xml
[junit4] 2> 109445 T134 oasc.ConfigSolrXml.<init> Config-defined core root directory:
[junit4] 2> 109445 T134 oasc.CoreContainer.<init> New CoreContainer 28183810
[junit4] 2> 109445 T134 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1384191951829\]
[junit4] 2> 109449 T134 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 109449 T134 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 109449 T134 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 109449 T134 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 109449 T134 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 109449 T134 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 109450 T134 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 109450 T134 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 109450 T134 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 109454 T134 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 109454 T134 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 109457 T134 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:63466/solr
[junit4] 2> 109457 T134 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 109459 T134 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 109470 T191 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@baf4c name:ZooKeeperConnection Watcher:127.0.0.1:63466 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 109471 T134 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 109479 T134 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 109486 T193 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@135cf21 name:ZooKeeperConnection Watcher:127.0.0.1:63466/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 109486 T134 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 109502 T134 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 110503 T134 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:63500_
[junit4] 2> 110508 T134 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:63500_
[junit4] 2> 110521 T161 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 110522 T179 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 110523 T166 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 110526 T193 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 110543 T134 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1384191951829
[junit4] 2> 110812 T134 oasc.CorePropertiesLocator.discoverUnder Found core conf in C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1384191951829\conf\
[junit4] 2> 110819 T134 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 110822 T134 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 110822 T134 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 111336 T134 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 111342 T134 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:63509
[junit4] 2> 111342 T134 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 111342 T134 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 111342 T134 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1384191953858
[junit4] 2> 111342 T134 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1384191953858\'
[junit4] 2> 111374 T134 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1384191953858\solr.xml
[junit4] 2> 111455 T134 oasc.ConfigSolrXml.<init> Config-defined core root directory:
[junit4] 2> 111455 T134 oasc.CoreContainer.<init> New CoreContainer 27557506
[junit4] 2> 111458 T134 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1384191953858\]
[junit4] 2> 111460 T134 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 111460 T134 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 111460 T134 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 111460 T134 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 111460 T134 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 111462 T134 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 111462 T134 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 111462 T134 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 111462 T134 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 111472 T134 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 111472 T134 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 111472 T134 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:63466/solr
[junit4] 2> 111472 T134 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 111475 T134 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 111487 T205 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@39e02d name:ZooKeeperConnection Watcher:127.0.0.1:63466 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 111488 T134 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 111497 T134 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 111502 T207 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1a07061 name:ZooKeeperConnection Watcher:127.0.0.1:63466/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 111503 T134 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 111517 T134 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 112522 T134 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:63509_
[junit4] 2> 112527 T134 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:63509_
[junit4] 2> 112542 T161 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 112543 T166 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 112544 T207 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 112546 T179 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 112547 T193 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 112568 T134 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1384191953858
[junit4] 2> 112871 T134 oasc.CorePropertiesLocator.discoverUnder Found core conf in C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1384191953858\conf\
[junit4] 2> 112884 T134 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 112886 T134 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 112886 T134 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 113476 T134 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 113480 T134 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:63518
[junit4] 2> 113481 T134 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 113481 T134 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 113481 T134 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1384191955923
[junit4] 2> 113481 T134 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1384191955923\'
[junit4] 2> 113523 T134 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1384191955923\solr.xml
[junit4] 2> 113609 T134 oasc.ConfigSolrXml.<init> Config-defined core root directory:
[junit4] 2> 113609 T134 oasc.CoreContainer.<init> New CoreContainer 33524975
[junit4] 2> 113609 T134 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1384191955923\]
[junit4] 2> 113613 T134 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 113613 T134 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 113613 T134 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 113613 T134 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 113613 T134 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 113613 T134 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 113613 T134 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 113615 T134 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 113615 T134 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 113619 T134 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 113620 T134 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 113620 T134 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:63466/solr
[junit4] 2> 113620 T134 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 113625 T134 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 113637 T219 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7da1fc name:ZooKeeperConnection Watcher:127.0.0.1:63466 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 113638 T134 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 113649 T134 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 113652 T136 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x1424844f084000d, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:662)
[junit4] 2>
[junit4] 2> 113658 T221 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@33f652 name:ZooKeeperConnection Watcher:127.0.0.1:63466/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 113658 T134 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 113678 T134 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 114695 T134 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:63518_
[junit4] 2> 114698 T134 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:63518_
[junit4] 2> 114715 T207 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 114716 T193 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 114716 T166 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 114718 T179 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 114718 T221 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 114720 T161 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 114735 T134 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1384191955923
[junit4] 2> 115003 T134 oasc.CorePropertiesLocator.discoverUnder Found core conf in C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1384191955923\conf\
[junit4] 2> 115016 T134 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 115017 T134 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 115017 T134 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 116127 T170 oasha.CollectionsHandler.handleCreateAction Creating Collection : numShards=2&collection.configName=conf1&name=solrj_collection&replicationFactor=2&action=CREATE&router.field=myOwnField&wt=javabin&router.name=compositeId&version=2
[junit4] 2> 116133 T161 oasc.DistributedQueue$LatchChildWatcher.process Watcher fired on path: /overseer/collection-queue-work state: SyncConnected type NodeChildrenChanged
[junit4] 2> 116137 T161 oasc.DistributedQueue$LatchChildWatcher.process Watcher fired on path: /overseer/collection-queue-work state: SyncConnected type NodeChildrenChanged
[junit4] 2> 116142 T163 oasc.OverseerCollectionProcessor.run Overseer Collection Processor: Get the message id:/overseer/collection-queue-work/qn-0000000000 message:{
[junit4] 2> "operation":"createcollection",
[junit4] 2> "fromApi":"true",
[junit4] 2> "name":"solrj_collection",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "collection.configName":"conf1",
[junit4] 2> "numShards":"2",
[junit4] 2> "router.field":"myOwnField",
[junit4] 2> "router.name":"compositeId"}
[junit4] 2> 116145 T163 oasc.OverseerCollectionProcessor.processMessage WARN OverseerCollectionProcessor.processMessage : createcollection , {
[junit4] 2> "operation":"createcollection",
[junit4] 2> "fromApi":"true",
[junit4] 2> "name":"solrj_collection",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "collection.configName":"conf1",
[junit4] 2> "numShards":"2",
[junit4] 2> "router.field":"myOwnField",
[junit4] 2> "router.name":"compositeId"}
[junit4] 2> 116994 T162 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 117028 T162 oasc.Overseer$ClusterStateUpdater.buildCollection building a new collection: solrj_collection
[junit4] 2> 117028 T162 oasc.Overseer$ClusterStateUpdater.createCollection Create collection solrj_collection with shards [shard1, shard2]
[junit4] 2> 117076 T207 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 117077 T179 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 117078 T221 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 117078 T161 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 117079 T193 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 117080 T166 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 117189 T163 oasc.OverseerCollectionProcessor.createCollection going to create cores replicas shardNames [shard1, shard2] , repFactor : 2
[junit4] 2> 117189 T163 oasc.OverseerCollectionProcessor.createCollection Creating shard solrj_collection_shard1_replica1 as part of slice shard1 of collection solrj_collection on 127.0.0.1:63509_
[junit4] 2> 117207 T163 oasc.OverseerCollectionProcessor.createCollection Creating shard solrj_collection_shard1_replica2 as part of slice shard1 of collection solrj_collection on 127.0.0.1:63500_
[junit4] 2> 117209 T163 oasc.OverseerCollectionProcessor.createCollection Creating shard solrj_collection_shard2_replica1 as part of slice shard2 of collection solrj_collection on 127.0.0.1:63479_
[junit4] 2> 117210 T163 oasc.OverseerCollectionProcessor.createCollection Creating shard solrj_collection_shard2_replica2 as part of slice shard2 of collection solrj_collection on 127.0.0.1:63491_
[junit4] 2> 117281 T198 oasc.ZkController.publish publishing core=solrj_collection_shard1_replica1 state=down
[junit4] 2> 117281 T156 oasc.ZkController.publish publishing core=solrj_collection_shard2_replica1 state=down
[junit4] 2> 117282 T171 oasc.ZkController.publish publishing core=solrj_collection_shard2_replica2 state=down
[junit4] 2> 117280 T188 oasc.ZkController.publish publishing core=solrj_collection_shard1_replica2 state=down
[junit4] 2> 117288 T156 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 117291 T171 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 117292 T198 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 117296 T188 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 118603 T162 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 118606 T162 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:63479",
[junit4] 2> "core":"solrj_collection_shard2_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:63479_",
[junit4] 2> "shard":"shard2",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"solrj_collection",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":null}
[junit4] 2> 118626 T162 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:63491",
[junit4] 2> "core":"solrj_collection_shard2_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:63491_",
[junit4] 2> "shard":"shard2",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"solrj_collection",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":null}
[junit4] 2> 118644 T162 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:63509",
[junit4] 2> "core":"solrj_collection_shard1_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:63509_",
[junit4] 2> "shard":"shard1",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"solrj_collection",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":null}
[junit4] 2> 118663 T162 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:63500",
[junit4] 2> "core":"solrj_collection_shard1_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:63500_",
[junit4] 2> "shard":"shard1",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"solrj_collection",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":null}
[junit4] 2> 118681 T207 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 118683 T161 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 118683 T221 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 118684 T193 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 118689 T179 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 118697 T166 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 119289 T156 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for solrj_collection_shard2_replica1
[junit4] 2> 119291 T171 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for solrj_collection_shard2_replica2
[junit4] 2> 119291 T156 oasc.CoreContainer.create Creating SolrCore 'solrj_collection_shard2_replica1' using instanceDir: .\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1384191947838\solrj_collection_shard2_replica1
[junit4] 2> 119291 T156 oasc.ZkController.createCollectionZkNode Check for collection zkNode:solrj_collection
[junit4] 2> 119293 T171 oasc.CoreContainer.create Creating SolrCore 'solrj_collection_shard2_replica2' using instanceDir: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1384191949864\solrj_collection_shard2_replica2
[junit4] 2> 119293 T171 oasc.ZkController.createCollectionZkNode Check for collection zkNode:solrj_collection
[junit4] 2> 119294 T156 oasc.ZkController.createCollectionZkNode Creating collection in ZooKeeper:solrj_collection
[junit4] 2> 119294 T156 oascc.SolrZkClient.makePath makePath: /collections/solrj_collection
[junit4] 2> 119295 T171 oasc.ZkController.createCollectionZkNode Creating collection in ZooKeeper:solrj_collection
[junit4] 2> 119295 T171 oascc.SolrZkClient.makePath makePath: /collections/solrj_collection
[junit4] 2> 119297 T198 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for solrj_collection_shard1_replica1
[junit4] 2> 119299 T198 oasc.CoreContainer.create Creating SolrCore 'solrj_collection_shard1_replica1' using instanceDir: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1384191953858\solrj_collection_shard1_replica1
[junit4] 2> 119299 T198 oasc.ZkController.createCollectionZkNode Check for collection zkNode:solrj_collection
[junit4] 2> 119300 T188 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for solrj_collection_shard1_replica2
[junit4] 2> 119303 T188 oasc.CoreContainer.create Creating SolrCore 'solrj_collection_shard1_replica2' using instanceDir: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1384191951829\solrj_collection_shard1_replica2
[junit4] 2> 119304 T188 oasc.ZkController.createCollectionZkNode Check for collection zkNode:solrj_collection
[junit4] 2> 119305 T198 oasc.ZkController.createCollectionZkNode Creating collection in ZooKeeper:solrj_collection
[junit4] 2> 119305 T198 oascc.SolrZkClient.makePath makePath: /collections/solrj_collection
[junit4] 2> 119308 T156 oasc.ZkController.readConfigName Load collection config from:/collections/solrj_collection
[junit4] 2> 119308 T188 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 119308 T188 oasc.ZkController.readConfigName Load collection config from:/collections/solrj_collection
[junit4] 2> 119312 T171 oasc.ZkController.readConfigName Load collection config from:/collections/solrj_collection
[junit4] 2> 119317 T188 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1384191951829\solrj_collection_shard1_replica2\'
[junit4] 2> 119321 T156 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1384191947838\solrj_collection_shard2_replica1\'
[junit4] 2> 119325 T198 oasc.ZkController.readConfigName Load collection config from:/collections/solrj_collection
[junit4] 2> 119332 T171 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1384191949864\solrj_collection_shard2_replica2\'
[junit4] 2> 119343 T198 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1384191953858\solrj_collection_shard1_replica1\'
[junit4] 2> 119469 T156 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_46
[junit4] 2> 119473 T171 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_46
[junit4] 2> 119580 T171 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 119590 T156 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 119601 T188 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_46
[junit4] 2> 119631 T198 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_46
[junit4] 2> 119695 T188 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 119696 T171 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 119697 T156 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 119709 T156 oass.IndexSchema.readSchema [solrj_collection_shard2_replica1] Schema name=test
[junit4] 2> 119716 T171 oass.IndexSchema.readSchema [solrj_collection_shard2_replica2] Schema name=test
[junit4] 2> 119789 T198 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 119896 T188 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 119906 T188 oass.IndexSchema.readSchema [solrj_collection_shard1_replica2] Schema name=test
[junit4] 2> 120024 T198 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 120042 T198 oass.IndexSchema.readSchema [solrj_collection_shard1_replica1] Schema name=test
[junit4] 2> 121165 T171 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 121165 T171 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 121168 T171 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 121191 T171 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 121196 T171 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 121582 T156 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 121582 T156 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 121585 T156 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 121651 T156 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 121656 T156 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 121691 T188 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 121691 T188 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 121694 T188 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 121703 T188 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 121710 T188 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 121970 T171 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 121999 T171 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 122017 T171 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 122022 T171 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 122023 T171 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 122023 T171 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 122046 T171 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 122046 T171 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 122046 T171 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 122047 T171 oasc.SolrCore.<init> [solrj_collection_shard2_replica2] Opening new SolrCore at ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1384191949864\solrj_collection_shard2_replica2\, dataDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1384191949864\solrj_collection_shard2_replica2\data\
[junit4] 2> 122049 T171 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1d7e9cb
[junit4] 2> 122072 T171 oasc.CachingDirectoryFactory.get return new directory for ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1384191949864\solrj_collection_shard2_replica2\data\
[junit4] 2> 122072 T171 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1384191949864\solrj_collection_shard2_replica2\data\index/
[junit4] 2> 122072 T171 oasc.SolrCore.initIndex WARN [solrj_collection_shard2_replica2] Solr index directory '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1384191949864\solrj_collection_shard2_replica2\data\index' doesn't exist. Creating new index...
[junit4] 2> 122072 T171 oasc.CachingDirectoryFactory.get return new directory for ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1384191949864\solrj_collection_shard2_replica2\data\index
[junit4] 2> 122074 T171 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=38, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=13.9189453125, floorSegmentMB=1.5087890625, forceMergeDeletesPctAllowed=27.50717169871203, segmentsPerTier=38.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4146817188534314
[junit4] 2> 122074 T171 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@1b88d41 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ee8489),segFN=segments_1,generation=1}
[junit4] 2> 122074 T171 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 122085 T171 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 122128 T156 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 122138 T156 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 122150 T156 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 122155 T156 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 122156 T156 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 122156 T156 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 122161 T156 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 122161 T156 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 122161 T156 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 122163 T171 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 122163 T156 oasc.SolrCore.<init> [solrj_collection_shard2_replica1] Opening new SolrCore at .\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1384191947838\solrj_collection_shard2_replica1\, dataDir=.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1384191947838\solrj_collection_shard2_replica1\data\
[junit4] 2> 122164 T156 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1d7e9cb
[junit4] 2> 122164 T171 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 122166 T171 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 122172 T171 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 122172 T171 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 122174 T171 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 122174 T171 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 122175 T171 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 122187 T171 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 122188 T171 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 122188 T171 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 122188 T171 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 122192 T171 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 122193 T171 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 122199 T156 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1384191947838\solrj_collection_shard2_replica1\data\
[junit4] 2> 122199 T156 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1384191947838\solrj_collection_shard2_replica1\data\index/
[junit4] 2> 122200 T156 oasc.SolrCore.initIndex WARN [solrj_collection_shard2_replica1] Solr index directory '.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1384191947838\solrj_collection_shard2_replica1\data\index' doesn't exist. Creating new index...
[junit4] 2> 122203 T156 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1384191947838\solrj_collection_shard2_replica1\data\index
[junit4] 2> 122203 T156 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=38, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=13.9189453125, floorSegmentMB=1.5087890625, forceMergeDeletesPctAllowed=27.50717169871203, segmentsPerTier=38.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4146817188534314
[junit4] 2> 122209 T188 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 122210 T156 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@7aaba3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@aad926),segFN=segments_1,generation=1}
[junit4] 2> 122210 T156 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 122211 T171 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 122224 T171 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 122224 T171 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 122236 T156 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 122237 T156 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 122237 T156 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 122237 T156 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 122237 T156 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 122237 T156 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 122239 T156 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 122239 T156 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 122239 T156 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 122246 T156 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 122247 T156 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 122247 T156 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 122247 T156 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 122251 T156 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 122252 T156 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 122254 T156 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 122262 T171 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=14, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=85.1357421875, floorSegmentMB=1.13671875, forceMergeDeletesPctAllowed=21.131593701480288, segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2858624176875082
[junit4] 2> 122264 T171 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@1b88d41 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ee8489),segFN=segments_1,generation=1}
[junit4] 2> 122264 T171 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 122264 T171 oass.SolrIndexSearcher.<init> Opening Searcher@1907cd5 main
[junit4] 2> 122270 T156 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 122270 T156 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 122272 T156 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=14, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=85.1357421875, floorSegmentMB=1.13671875, forceMergeDeletesPctAllowed=21.131593701480288, segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2858624176875082
[junit4] 2> 122272 T156 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@7aaba3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@aad926),segFN=segments_1,generation=1}
[junit4] 2> 122272 T156 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 122272 T156 oass.SolrIndexSearcher.<init> Opening Searcher@7a051d main
[junit4] 2> 122275 T188 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 122289 T188 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 122300 T188 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 122306 T198 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 122310 T198 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 122311 T198 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 122320 T198 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 122327 T198 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 122338 T188 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 122339 T188 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 122382 T171 oasc.CoreContainer.registerCore registering core: solrj_collection_shard2_replica2
[junit4] 2> 122382 T171 oasc.ZkController.register Register replica - core:solrj_collection_shard2_replica2 address:http://127.0.0.1:63491 collection:solrj_collection shard:shard2
[junit4] 2> 122400 T156 oasc.CoreContainer.registerCore registering core: solrj_collection_shard2_replica1
[junit4] 2> 122400 T156 oasc.ZkController.register Register replica - core:solrj_collection_shard2_replica1 address:http://127.0.0.1:63479 collection:solrj_collection shard:shard2
[junit4] 2> 122404 T228 oasc.SolrCore.registerSearcher [solrj_collection_shard2_replica1] Registered new searcher Searcher@7a051d main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 122412 T227 oasc.SolrCore.registerSearcher [solrj_collection_shard2_replica2] Registered new searcher Searcher@1907cd5 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 122419 T156 oascc.SolrZkClient.makePath makePath: /collections/solrj_collection/leader_elect/shard2/election
[junit4] 2> 122420 T188 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 122421 T188 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 122421 T188 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 122422 T188 oasc.SolrCore.<init> [solrj_collection_shard1_replica2] Opening new SolrCore at ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1384191951829\solrj_collection_shard1_replica2\, dataDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1384191951829\solrj_collection_shard1_replica2\data\
[junit4] 2> 122422 T188 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1d7e9cb
[junit4] 2> 122424 T188 oasc.CachingDirectoryFactory.get return new directory for ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1384191951829\solrj_collection_shard1_replica2\data\
[junit4] 2> 122424 T188 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1384191951829\solrj_collection_shard1_replica2\data\index/
[junit4] 2> 122425 T188 oasc.SolrCore.initIndex WARN [solrj_collection_shard1_replica2] Solr index directory '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1384191951829\solrj_collection_shard1_replica2\data\index' doesn't exist. Creating new index...
[junit4] 2> 122425 T188 oasc.CachingDirectoryFactory.get return new directory for ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1384191951829\solrj_collection_shard1_replica2\data\index
[junit4] 2> 122426 T188 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=38, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=13.9189453125, floorSegmentMB=1.5087890625, forceMergeDeletesPctAllowed=27.50717169871203, segmentsPerTier=38.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4146817188534314
[junit4] 2> 122427 T188 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@e3e421 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7d7d9a),segFN=segments_1,generation=1}
[junit4] 2> 122427 T188 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 122435 T188 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 122435 T188 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 122436 T188 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 122436 T188 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 122436 T188 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 122438 T188 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 122438 T188 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 122439 T188 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 122439 T188 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 122440 T188 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 122442 T188 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 122443 T188 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 122443 T188 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4]
[...truncated too long message...]
t-TestNonNRTOpen-1384192679968\
[junit4] 2> 839304 T1835 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 839305 T1835 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestNonNRTOpen-1384192679968\index/
[junit4] 2> 839307 T1835 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 839308 T1835 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 839308 T1835 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 839319 T1835 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 839324 T1835 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 839324 T1835 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 839329 T1835 oass.SolrIndexSearcher.<init> Opening Searcher@cfedb4 main
[junit4] 2> 839330 T1835 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4] 2> 839330 T1835 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 839330 T1835 oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter... core=collection1
[junit4] 2> 839330 T1844 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@cfedb4 main{StandardDirectoryReader(segments_9:18 _5(4.6):c1 _6(4.6):c1)}
[junit4] 2> 839364 T1835 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384192679968\index,segFN=segments_9,generation=9}
[junit4] 2> commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384192679968\index,segFN=segments_a,generation=10}
[junit4] 2> 839364 T1835 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 10
[junit4] 2> 839376 T1835 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384192679968\index,segFN=segments_a,generation=10}
[junit4] 2> 839379 T1835 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 10
[junit4] 2> 839379 T1835 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 839383 T1835 oass.SolrIndexSearcher.<init> Opening Searcher@10f0e1d main
[junit4] 2> 839386 T1835 oasc.CoreContainer.registerCore replacing core: collection1
[junit4] 2> 839386 T1835 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@198fe78
[junit4] 2> 839386 T1835 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=1,adds=1,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=2,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 839387 T1835 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 839387 T1835 oas.SolrTestCaseJ4.tearDown ###Ending testReaderIsNotNRT
[junit4] 2> 839394 T1844 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@10f0e1d main{StandardDirectoryReader(segments_a:20 _5(4.6):c1 _6(4.6):c1 _7(4.6):c1)}
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestNonNRTOpen -Dtests.method=testReaderIsNotNRT -Dtests.seed=9985B637BEC33328 -Dtests.slow=true -Dtests.locale=fr_FR -Dtests.timezone=America/Montserrat -Dtests.file.encoding=Cp1252
[junit4] FAILURE 0.81s | TestNonNRTOpen.testReaderIsNotNRT <<<
[junit4] > Throwable #1: java.lang.AssertionError: expected:<3> but was:<2>
[junit4] > at __randomizedtesting.SeedInfo.seed([9985B637BEC33328:2C03D7B0010281DC]:0)
[junit4] > at org.apache.solr.core.TestNonNRTOpen.assertNotNRT(TestNonNRTOpen.java:133)
[junit4] > at org.apache.solr.core.TestNonNRTOpen.testReaderIsNotNRT(TestNonNRTOpen.java:94)
[junit4] > at java.lang.Thread.run(Thread.java:662)
[junit4] 2> 839407 T1835 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 839407 T1835 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=26889256
[junit4] 2> 839408 T1835 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1679bb1
[junit4] 2> 839408 T1835 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] 2> 839408 T1835 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 839409 T1835 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 839409 T1835 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 839410 T1835 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 839411 T1835 oasc.CachingDirectoryFactory.close Closing StandardDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 839411 T1835 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384192679968 [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384192679968;done=false>>]
[junit4] 2> 839412 T1835 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384192679968
[junit4] 2> 839412 T1835 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384192679968\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384192679968\index;done=false>>]
[junit4] 2> 839412 T1835 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestNonNRTOpen-1384192679968\index
[junit4] 2> NOTE: test params are: codec=Lucene40, sim=RandomSimilarityProvider(queryNorm=false,coord=yes): {}, locale=fr_FR, timezone=America/Montserrat
[junit4] 2> NOTE: Windows 7 6.1 x86/Sun Microsystems Inc. 1.6.0_45 (32-bit)/cpus=2,threads=1,free=32512992,total=179625984
[junit4] 2> NOTE: All tests run in this JVM: [TestSolrXMLSerializer, TestRTGBase, TestCoreContainer, TestCoreDiscovery, SignatureUpdateProcessorFactoryTest, TestSurroundQueryParser, TestRandomDVFaceting, CollectionsAPIDistributedZkTest, TestSchemaSimilarityResource, DefaultValueUpdateProcessorTest, TestHashPartitioner, AliasIntegrationTest, IndexBasedSpellCheckerTest, UniqFieldsUpdateProcessorFactoryTest, HdfsBasicDistributedZk2Test, TestDocBasedVersionConstraints, OverseerTest, TestBinaryResponseWriter, TestSolrXmlPersistor, MoreLikeThisHandlerTest, CachingDirectoryFactoryTest, SpellingQueryConverterTest, MultiTermTest, TestFaceting, TestTrie, TestDocSet, TestDynamicFieldResource, TestCollationKeyRangeQueries, RequestHandlersTest, SuggesterWFSTTest, TestFastLRUCache, XmlUpdateRequestHandlerTest, DocValuesMultiTest, TestDistribDocBasedVersion, TestSolr4Spatial, TestReversedWildcardFilterFactory, PathHierarchyTokenizerFactoryTest, BadIndexSchemaTest, DisMaxRequestHandlerTest, SOLR749Test, DocumentBuilderTest, CurrencyFieldXmlFileTest, NoCacheHeaderTest, TestLRUCache, DistributedQueryElevationComponentTest, TestFastWriter, TestFieldResource, DistributedSpellCheckComponentTest, CircularListTest, TestSolrXmlPersistence, UpdateRequestProcessorFactoryTest, TestManagedSchemaFieldResource, SolrIndexSplitterTest, SystemInfoHandlerTest, TermVectorComponentDistributedTest, TestRandomFaceting, TestShardHandlerFactory, TestNonNRTOpen]
[junit4] Completed in 2.53s, 2 tests, 1 failure <<< FAILURES!
[...truncated 865 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:428: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:408: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:39: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build.xml:189: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\common-build.xml:501: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:1275: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:911: There were test failures: 335 suites, 1475 tests, 1 error, 1 failure, 42 ignored (13 assumptions)
Total time: 92 minutes 29 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/jdk1.6.0_45 -server -XX:+UseConcMarkSweepGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure