lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Jenkins Server <jenk...@builds.apache.org>
Subject [JENKINS] Lucene-Solr-Tests-4.x-Java6 - Build # 668 - Failure
Date Tue, 18 Sep 2012 14:57:07 GMT
Build: https://builds.apache.org/job/Lucene-Solr-Tests-4.x-Java6/668/

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

Error Message:
Shard still reported as live in zk

Stack Trace:
java.lang.AssertionError: Shard still reported as live in zk
	at __randomizedtesting.SeedInfo.seed([1BC5E3322EC3B556:9A236D2A599CD56A]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitToSeeNotLive(AbstractFullDistribZkTestBase.java:1220)
	at org.apache.solr.cloud.BasicDistributedZk2Test.brindDownShardIndexSomeDocsAndRecover(BasicDistributedZk2Test.java:190)
	at org.apache.solr.cloud.BasicDistributedZk2Test.doTest(BasicDistributedZk2Test.java:83)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:695)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:616)
	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:45)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	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:45)
	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:679)


REGRESSION:  org.apache.solr.cloud.LeaderElectionIntegrationTest.testLeaderElectionAfterClientTimeout

Error Message:
expected:<7000> but was:<7001>

Stack Trace:
java.lang.AssertionError: expected:<7000> but was:<7001>
	at __randomizedtesting.SeedInfo.seed([1BC5E3322EC3B556:B2F2469109E63DBD]: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.cloud.LeaderElectionIntegrationTest.testLeaderElectionAfterClientTimeout(LeaderElectionIntegrationTest.java:252)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:616)
	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:45)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	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:45)
	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:679)


REGRESSION:  org.apache.solr.cloud.SyncSliceTest.testDistribSearch

Error Message:
There are still nodes recoverying

Stack Trace:
java.lang.AssertionError: There are still nodes recoverying
	at __randomizedtesting.SeedInfo.seed([1BC5E3322EC3B556:9A236D2A599CD56A]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:154)
	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForRecoveriesToFinish(AbstractFullDistribZkTestBase.java:560)
	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForThingsToLevelOut(AbstractFullDistribZkTestBase.java:1135)
	at org.apache.solr.cloud.SyncSliceTest.doTest(SyncSliceTest.java:162)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:695)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:616)
	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:45)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	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:45)
	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:679)




Build Log:
[...truncated 7264 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.LeaderElectionIntegrationTest
[junit4:junit4]   2> 3 T1734 oas.SolrTestCaseJ4.setUp ###Starting testSimpleSliceLeaderElection
[junit4:junit4]   2> Creating dataDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J0/./solrtest-LeaderElectionIntegrationTest-1347979766283
[junit4:junit4]   2> 4 T1734 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 7 T1735 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4]   2> 8 T1735 oazs.NIOServerCnxn$Factory.<init> binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 14 T1735 oazsp.FileTxnSnapLog.save Snapshotting: 0
[junit4:junit4]   2> 105 T1734 oasc.ZkTestServer.run start zk server on port:29153
[junit4:junit4]   2> 105 T1734 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:29153 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@4f672c5a
[junit4:junit4]   2> 125 T1740 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:29153
[junit4:junit4]   2> 125 T1734 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 127 T1740 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:29153, initiating session
[junit4:junit4]   2> 127 T1736 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:38220
[junit4:junit4]   2> 127 T1736 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:38220
[junit4:junit4]   2> 128 T1738 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 130 T1738 oazs.NIOServerCnxn.finishSessionInit Established session 0x139d9daea170000 with negotiated timeout 10000 for client /140.211.11.196:38220
[junit4:junit4]   2> 130 T1740 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:29153, sessionid = 0x139d9daea170000, negotiated timeout = 10000
[junit4:junit4]   2> 131 T1741 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4f672c5a name:ZooKeeperConnection Watcher:127.0.0.1:29153 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 131 T1734 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 131 T1734 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 145 T1739 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139d9daea170000
[junit4:junit4]   2> 146 T1741 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 146 T1734 oaz.ZooKeeper.close Session: 0x139d9daea170000 closed
[junit4:junit4]   2> 147 T1736 oazs.NIOServerCnxn.closeSock Closed socket connection for client /140.211.11.196:38220 which had sessionid 0x139d9daea170000
[junit4:junit4]   2> 147 T1734 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:29153/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1cb1c2c4
[junit4:junit4]   2> 148 T1742 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:29153
[junit4:junit4]   2> 148 T1734 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 149 T1742 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:29153, initiating session
[junit4:junit4]   2> 149 T1736 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:24926
[junit4:junit4]   2> 149 T1736 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:24926
[junit4:junit4]   2> 150 T1738 oazs.NIOServerCnxn.finishSessionInit Established session 0x139d9daea170001 with negotiated timeout 10000 for client /140.211.11.196:24926
[junit4:junit4]   2> 150 T1742 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:29153, sessionid = 0x139d9daea170001, negotiated timeout = 10000
[junit4:junit4]   2> 151 T1743 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1cb1c2c4 name:ZooKeeperConnection Watcher:127.0.0.1:29153/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 151 T1734 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 151 T1734 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 154 T1734 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 163 T1734 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 165 T1734 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 168 T1734 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 169 T1734 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 184 T1734 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4:junit4]   2> 185 T1734 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 288 T1734 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 289 T1734 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 292 T1734 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4:junit4]   2> 292 T1734 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 303 T1734 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4:junit4]   2> 303 T1734 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 306 T1734 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4:junit4]   2> 307 T1734 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 310 T1734 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 310 T1734 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 313 T1734 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 314 T1734 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 316 T1734 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 317 T1734 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 320 T1734 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4:junit4]   2> 321 T1734 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 323 T1739 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139d9daea170001
[junit4:junit4]   2> 331 T1743 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 331 T1734 oaz.ZooKeeper.close Session: 0x139d9daea170001 closed
[junit4:junit4]   2> 331 T1736 oazs.NIOServerCnxn.closeSock Closed socket connection for client /140.211.11.196:24926 which had sessionid 0x139d9daea170001
[junit4:junit4]   2> 331 T1734 oasc.LeaderElectionIntegrationTest.setUp ####SETUP_START testSimpleSliceLeaderElection
[junit4:junit4]   2> 334 T1734 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 334 T1734 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr
[junit4:junit4]   2> 335 T1734 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/solr.xml
[junit4:junit4]   2> 335 T1734 oasc.CoreContainer.<init> New CoreContainer 2018258276
[junit4:junit4]   2> 335 T1734 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 336 T1734 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 379 T1734 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 402 T1734 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:29153/solr
[junit4:junit4]   2> 403 T1734 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:29153/solr sessionTimeout=3000 watcher=org.apache.solr.common.cloud.ConnectionManager@77e8f8b4
[junit4:junit4]   2> 404 T1744 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:29153
[junit4:junit4]   2> 404 T1734 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 405 T1744 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:29153, initiating session
[junit4:junit4]   2> 405 T1736 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:61040
[junit4:junit4]   2> 405 T1736 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:61040
[junit4:junit4]   2> 408 T1738 oazs.NIOServerCnxn.finishSessionInit Established session 0x139d9daea170002 with negotiated timeout 3000 for client /140.211.11.196:61040
[junit4:junit4]   2> 408 T1744 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:29153, sessionid = 0x139d9daea170002, negotiated timeout = 3000
[junit4:junit4]   2> 408 T1745 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@77e8f8b4 name:ZooKeeperConnection Watcher:127.0.0.1:29153/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 408 T1734 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 410 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170002 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 418 T1734 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 420 T1734 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:7000_solr
[junit4:junit4]   2> 420 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170002 type:delete cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:7000_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:7000_solr
[junit4:junit4]   2> 422 T1734 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:7000_solr
[junit4:junit4]   2> 425 T1734 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 425 T1734 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 426 T1734 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 426 T1734 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 426 T1734 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 427 T1734 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 427 T1734 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 427 T1734 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 428 T1734 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 428 T1734 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 443 T1734 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 448 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170002 type:delete cxid:0x15 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer_elect/leader Error:KeeperErrorCode = NoNode for /solr/overseer_elect/leader
[junit4:junit4]   2> 459 T1734 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 461 T1734 oasc.Overseer.start Overseer (id=88341201963909122-127.0.0.1:7000_solr-n_0000000000) starting
[junit4:junit4]   2> 462 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170002 type:create cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 467 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170002 type:create cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 469 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170002 type:create cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 470 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170002 type:create cxid:0x1d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 481 T1747 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 481 T1734 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 483 T1734 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 485 T1746 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 490 T1734 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1
[junit4:junit4]   2> 490 T1734 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 490 T1734 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 491 T1734 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 492 T1734 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/'
[junit4:junit4]   2> 493 T1734 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 494 T1734 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 541 T1734 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 611 T1734 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 712 T1734 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 718 T1734 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 1317 T1734 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 1328 T1734 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 1332 T1734 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 1344 T1734 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1348 T1734 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1352 T1734 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1354 T1734 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1355 T1734 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/, dataDir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J0/./solrtest-LeaderElectionIntegrationTest-1347979766283/data_7000/
[junit4:junit4]   2> 1355 T1734 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5b4bc4e6
[junit4:junit4]   2> 1356 T1734 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J0/./solrtest-LeaderElectionIntegrationTest-1347979766283/data_7000/index/
[junit4:junit4]   2> 1357 T1734 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J0/./solrtest-LeaderElectionIntegrationTest-1347979766283/data_7000/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 1357 T1734 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J0/./solrtest-LeaderElectionIntegrationTest-1347979766283/data_7000/index forceNew:false
[junit4:junit4]   2> 1360 T1734 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@4d27842a lockFactory=org.apache.lucene.store.NativeFSLockFactory@1bc2affd; maxCacheMB=0.046875 maxMergeSizeMB=0.5869140625)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 1360 T1734 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 1361 T1734 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 1362 T1734 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 1363 T1734 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 1363 T1734 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 1364 T1734 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 1365 T1734 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 1365 T1734 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 1365 T1734 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 1366 T1734 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 1366 T1734 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 1367 T1734 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 1367 T1734 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 1368 T1734 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 1368 T1734 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 1369 T1734 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 1369 T1734 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 1370 T1734 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 1370 T1734 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1370 T1734 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1371 T1734 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1371 T1734 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1372 T1734 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1372 T1734 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1373 T1734 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1373 T1734 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 1374 T1734 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 1377 T1734 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 1382 T1734 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 1383 T1734 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 1385 T1734 oass.SolrIndexSearcher.<init> Opening Searcher@2e781d5f main
[junit4:junit4]   2> 1386 T1734 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 1386 T1734 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 1387 T1734 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 1387 T1734 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 1401 T1734 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 1447 T1748 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2e781d5f main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 1449 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170002 type:create cxid:0x53 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue Error:KeeperErrorCode = NoNode for /solr/overseer/queue
[junit4:junit4]   2> 1452 T1734 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 1452 T1734 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:7000/solr shardId:shard1
[junit4:junit4]   2> 1453 T1734 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 1458 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170002 type:delete cxid:0x62 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders
[junit4:junit4]   2> 1459 T1734 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 1459 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170002 type:create cxid:0x63 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1490 T1746 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1491 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170002 type:create cxid:0x6a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue-work Error:KeeperErrorCode = NoNode for /solr/overseer/queue-work
[junit4:junit4]   2> 1498 T1745 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 1961 T1734 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 1961 T1734 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 1962 T1734 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:7000/solr/collection1/
[junit4:junit4]   2> 1962 T1734 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 1977 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170002 type:create cxid:0x88 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2002 T1746 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2014 T1745 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2036 T1734 oasc.ZkController.register We are http://127.0.0.1:7000/solr/collection1/ and leader is http://127.0.0.1:7000/solr/collection1/
[junit4:junit4]   2> 2036 T1734 oasc.ZkController.checkRecovery WARNING Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4:junit4]   2> 2038 T1734 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2041 T1734 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2041 T1734 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr
[junit4:junit4]   2> 2041 T1734 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/solr.xml
[junit4:junit4]   2> 2042 T1734 oasc.CoreContainer.<init> New CoreContainer 1493922412
[junit4:junit4]   2> 2042 T1734 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 2043 T1734 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 2083 T1734 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 2096 T1734 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:29153/solr
[junit4:junit4]   2> 2097 T1734 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:29153/solr sessionTimeout=3000 watcher=org.apache.solr.common.cloud.ConnectionManager@5db1a61d
[junit4:junit4]   2> 2097 T1749 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:29153
[junit4:junit4]   2> 2097 T1734 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2099 T1749 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:29153, initiating session
[junit4:junit4]   2> 2099 T1736 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:44196
[junit4:junit4]   2> 2099 T1736 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:44196
[junit4:junit4]   2> 2101 T1738 oazs.NIOServerCnxn.finishSessionInit Established session 0x139d9daea170003 with negotiated timeout 3000 for client /140.211.11.196:44196
[junit4:junit4]   2> 2101 T1749 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:29153, sessionid = 0x139d9daea170003, negotiated timeout = 3000
[junit4:junit4]   2> 2101 T1750 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5db1a61d name:ZooKeeperConnection Watcher:127.0.0.1:29153/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2101 T1734 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2102 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170003 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2103 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170003 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2112 T1734 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:7001_solr
[junit4:junit4]   2> 2113 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170003 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:7001_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:7001_solr
[junit4:junit4]   2> 2114 T1734 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:7001_solr
[junit4:junit4]   2> 2115 T1745 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 2116 T1734 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 2116 T1734 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 2117 T1734 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 2117 T1734 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 2117 T1734 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 2118 T1734 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 2118 T1734 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 2118 T1734 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 2119 T1734 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 2119 T1734 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 2135 T1734 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 2140 T1734 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1
[junit4:junit4]   2> 2141 T1734 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 2141 T1734 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 2142 T1734 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 2143 T1734 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/'
[junit4:junit4]   2> 2144 T1734 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 2145 T1734 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 2188 T1734 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 2251 T1734 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 2352 T1734 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 2357 T1734 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 2517 T1746 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2532 T1745 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2532 T1750 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2936 T1734 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 2943 T1734 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 2946 T1734 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 2956 T1734 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2959 T1734 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2962 T1734 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 2963 T1734 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 2964 T1734 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/, dataDir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J0/./solrtest-LeaderElectionIntegrationTest-1347979766283/data_7001/
[junit4:junit4]   2> 2964 T1734 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5b4bc4e6
[junit4:junit4]   2> 2965 T1734 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J0/./solrtest-LeaderElectionIntegrationTest-1347979766283/data_7001/index/
[junit4:junit4]   2> 2965 T1734 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J0/./solrtest-LeaderElectionIntegrationTest-1347979766283/data_7001/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 2966 T1734 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J0/./solrtest-LeaderElectionIntegrationTest-1347979766283/data_7001/index forceNew:false
[junit4:junit4]   2> 2967 T1734 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7de42014 lockFactory=org.apache.lucene.store.NativeFSLockFactory@544df234),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 2967 T1734 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 2968 T1734 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 2969 T1734 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 2970 T1734 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 2970 T1734 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 2970 T1734 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 2971 T1734 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 2971 T1734 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 2971 T1734 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 2972 T1734 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 2972 T1734 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 2973 T1734 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 2973 T1734 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 2973 T1734 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 2974 T1734 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 2974 T1734 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 2974 T1734 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 2975 T1734 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 2975 T1734 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2975 T1734 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2976 T1734 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2976 T1734 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2976 T1734 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2977 T1734 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2977 T1734 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2977 T1734 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 2978 T1734 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 2980 T1734 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 2983 T1734 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 2984 T1734 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 2986 T1734 oass.SolrIndexSearcher.<init> Opening Searcher@6dceb01 main
[junit4:junit4]   2> 2986 T1734 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 2987 T1734 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 2987 T1734 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 2988 T1734 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 3020 T1734 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 3061 T1751 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6dceb01 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 3064 T1734 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 3065 T1734 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:7001/solr shardId:shard1
[junit4:junit4]   2> 3073 T1734 oasc.ZkController.register We are http://127.0.0.1:7001/solr/collection1/ and leader is http://127.0.0.1:7000/solr/collection1/
[junit4:junit4]   2> 3073 T1734 oasc.ZkController.checkRecovery WARNING Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4:junit4]   2> 3074 T1734 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3076 T1734 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3076 T1734 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr
[junit4:junit4]   2> 3077 T1734 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/solr.xml
[junit4:junit4]   2> 3077 T1734 oasc.CoreContainer.<init> New CoreContainer 453190756
[junit4:junit4]   2> 3077 T1734 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 3077 T1734 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 3106 T1734 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 3116 T1734 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:29153/solr
[junit4:junit4]   2> 3116 T1734 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:29153/solr sessionTimeout=3000 watcher=org.apache.solr.common.cloud.ConnectionManager@53598461
[junit4:junit4]   2> 3117 T1752 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:29153
[junit4:junit4]   2> 3117 T1734 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3118 T1752 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:29153, initiating session
[junit4:junit4]   2> 3118 T1736 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:25951
[junit4:junit4]   2> 3119 T1736 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:25951
[junit4:junit4]   2> 3120 T1738 oazs.NIOServerCnxn.finishSessionInit Established session 0x139d9daea170004 with negotiated timeout 3000 for client /140.211.11.196:25951
[junit4:junit4]   2> 3120 T1752 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:29153, sessionid = 0x139d9daea170004, negotiated timeout = 3000
[junit4:junit4]   2> 3120 T1753 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@53598461 name:ZooKeeperConnection Watcher:127.0.0.1:29153/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 3121 T1734 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 3121 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170004 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3122 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170004 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3124 T1734 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:7002_solr
[junit4:junit4]   2> 3124 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170004 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:7002_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:7002_solr
[junit4:junit4]   2> 3135 T1734 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:7002_solr
[junit4:junit4]   2> 3136 T1750 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 3136 T1745 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 3137 T1734 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 3137 T1734 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 3137 T1734 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 3138 T1734 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 3138 T1734 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 3138 T1734 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 3138 T1734 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 3139 T1734 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 3139 T1734 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 3139 T1734 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 3151 T1734 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 3155 T1734 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1
[junit4:junit4]   2> 3155 T1734 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 3155 T1734 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 3156 T1734 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 3157 T1734 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/'
[junit4:junit4]   2> 3157 T1734 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 3158 T1734 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 3190 T1734 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 3234 T1734 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 3335 T1734 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 3339 T1734 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 3537 T1746 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3552 T1745 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3552 T1753 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3552 T1750 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3737 T1734 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 3744 T1734 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 3747 T1734 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 3757 T1734 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3760 T1734 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3763 T1734 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3764 T1734 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3765 T1734 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/, dataDir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J0/./solrtest-LeaderElectionIntegrationTest-1347979766283/data_7002/
[junit4:junit4]   2> 3765 T1734 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5b4bc4e6
[junit4:junit4]   2> 3766 T1734 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J0/./solrtest-LeaderElectionIntegrationTest-1347979766283/data_7002/index/
[junit4:junit4]   2> 3766 T1734 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J0/./solrtest-LeaderElectionIntegrationTest-1347979766283/data_7002/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 3767 T1734 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J0/./solrtest-LeaderElectionIntegrationTest-1347979766283/data_7002/index forceNew:false
[junit4:junit4]   2> 3768 T1734 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4ea66f2c lockFactory=org.apache.lucene.store.NativeFSLockFactory@78ff10ca),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 3768 T1734 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 3769 T1734 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 3770 T1734 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 3770 T1734 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 3770 T1734 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 3771 T1734 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 3771 T1734 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 3772 T1734 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 3772 T1734 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 3772 T1734 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 3773 T1734 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 3773 T1734 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 3773 T1734 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 3774 T1734 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 3774 T1734 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 3774 T1734 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 3775 T1734 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 3775 T1734 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 3775 T1734 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3776 T1734 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3776 T1734 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3776 T1734 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3777 T1734 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3777 T1734 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3777 T1734 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3778 T1734 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 3778 T1734 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 3781 T1734 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 3784 T1734 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 3784 T1734 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 3786 T1734 oass.SolrIndexSearcher.<init> Opening Searcher@7e5a6ccb main
[junit4:junit4]   2> 3786 T1734 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 3787 T1734 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 3787 T1734 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 3787 T1734 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 3804 T1734 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 3840 T1754 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7e5a6ccb main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 3843 T1734 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 3843 T1734 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:7002/solr shardId:shard1
[junit4:junit4]   2> 3852 T1734 oasc.ZkController.register We are http://127.0.0.1:7002/solr/collection1/ and leader is http://127.0.0.1:7000/solr/collection1/
[junit4:junit4]   2> 3852 T1734 oasc.ZkController.checkRecovery WARNING Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4:junit4]   2> 3854 T1734 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3855 T1734 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3856 T1734 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr
[junit4:junit4]   2> 3856 T1734 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/solr.xml
[junit4:junit4]   2> 3856 T1734 oasc.CoreContainer.<init> New CoreContainer 1825533644
[junit4:junit4]   2> 3856 T1734 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 3857 T1734 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 3885 T1734 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 3895 T1734 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:29153/solr
[junit4:junit4]   2> 3895 T1734 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:29153/solr sessionTimeout=3000 watcher=org.apache.solr.common.cloud.ConnectionManager@5b3990cd
[junit4:junit4]   2> 3896 T1755 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:29153
[junit4:junit4]   2> 3896 T1734 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3897 T1755 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:29153, initiating session
[junit4:junit4]   2> 3897 T1736 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:31264
[junit4:junit4]   2> 3897 T1736 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:31264
[junit4:junit4]   2> 3899 T1738 oazs.NIOServerCnxn.finishSessionInit Established session 0x139d9daea170005 with negotiated timeout 3000 for client /140.211.11.196:31264
[junit4:junit4]   2> 3899 T1755 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:29153, sessionid = 0x139d9daea170005, negotiated timeout = 3000
[junit4:junit4]   2> 3899 T1756 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5b3990cd name:ZooKeeperConnection Watcher:127.0.0.1:29153/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 3899 T1734 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 3900 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170005 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3901 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170005 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3903 T1734 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:7003_solr
[junit4:junit4]   2> 3903 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170005 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:7003_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:7003_solr
[junit4:junit4]   2> 3912 T1734 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:7003_solr
[junit4:junit4]   2> 3914 T1753 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 3914 T1750 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 3914 T1745 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 3915 T1734 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 3915 T1734 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 3915 T1734 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 3915 T1734 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 3916 T1734 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 3916 T1734 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 3916 T1734 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 3916 T1734 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 3917 T1734 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 3917 T1734 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 3929 T1734 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 3933 T1734 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1
[junit4:junit4]   2> 3933 T1734 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 3934 T1734 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 3934 T1734 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 3935 T1734 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/'
[junit4:junit4]   2> 3936 T1734 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 3936 T1734 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 3969 T1734 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 4016 T1734 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 4066 T1746 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4081 T1745 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4081 T1753 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4081 T1750 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4117 T1756 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4117 T1734 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 4122 T1734 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 4534 T1734 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 4542 T1734 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 4545 T1734 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 4554 T1734 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 4558 T1734 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 4561 T1734 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 4562 T1734 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 4563 T1734 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/, dataDir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J0/./solrtest-LeaderElectionIntegrationTest-1347979766283/data_7003/
[junit4:junit4]   2> 4563 T1734 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5b4bc4e6
[junit4:junit4]   2> 4564 T1734 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J0/./solrtest-LeaderElectionIntegrationTest-1347979766283/data_7003/index/
[junit4:junit4]   2> 4564 T1734 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J0/./solrtest-LeaderElectionIntegrationTest-1347979766283/data_7003/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 4565 T1734 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J0/./solrtest-LeaderElectionIntegrationTest-1347979766283/data_7003/index forceNew:false
[junit4:junit4]   2> 4566 T1734 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@682b87f5 lockFactory=org.apache.lucene.store.NativeFSLockFactory@724a4962),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 4566 T1734 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 4567 T1734 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 4568 T1734 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 4569 T1734 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 4569 T1734 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 4569 T1734 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 4570 T1734 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 4570 T1734 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 4570 T1734 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 4571 T1734 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 4571 T1734 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 4571 T1734 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 4572 T1734 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 4572 T1734 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 4573 T1734 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 4573 T1734 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 4573 T1734 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 4573 T1734 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 4574 T1734 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4574 T1734 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4575 T1734 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4575 T1734 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4575 T1734 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4576 T1734 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4576 T1734 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4576 T1734 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 4577 T1734 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 4579 T1734 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 4582 T1734 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 4583 T1734 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 4585 T1734 oass.SolrIndexSearcher.<init> Opening Searcher@483cf7bd main
[junit4:junit4]   2> 4585 T1734 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 4586 T1734 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 4586 T1734 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 4586 T1734 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 4601 T1734 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 4644 T1757 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@483cf7bd main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 4647 T1734 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 4647 T1734 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:7003/solr shardId:shard1
[junit4:junit4]   2> 4656 T1734 oasc.ZkController.register We are http://127.0.0.1:7003/solr/collection1/ and leader is http://127.0.0.1:7000/solr/collection1/
[junit4:junit4]   2> 4656 T1734 oasc.ZkController.checkRecovery WARNING Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4:junit4]   2> 4657 T1734 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4659 T1734 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 4659 T1734 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr
[junit4:junit4]   2> 4660 T1734 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/solr.xml
[junit4:junit4]   2> 4660 T1734 oasc.CoreContainer.<init> New CoreContainer 395702951
[junit4:junit4]   2> 4660 T1734 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 4661 T1734 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 4691 T1734 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 4701 T1734 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:29153/solr
[junit4:junit4]   2> 4701 T1734 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:29153/solr sessionTimeout=3000 watcher=org.apache.solr.common.cloud.ConnectionManager@77d77d19
[junit4:junit4]   2> 4702 T1758 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:29153
[junit4:junit4]   2> 4702 T1734 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 4703 T1758 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:29153, initiating session
[junit4:junit4]   2> 4703 T1736 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:35942
[junit4:junit4]   2> 4704 T1736 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:35942
[junit4:junit4]   2> 4705 T1738 oazs.NIOServerCnxn.finishSessionInit Established session 0x139d9daea170006 with negotiated timeout 3000 for client /140.211.11.196:35942
[junit4:junit4]   2> 4705 T1758 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:29153, sessionid = 0x139d9daea170006, negotiated timeout = 3000
[junit4:junit4]   2> 4706 T1759 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@77d77d19 name:ZooKeeperConnection Watcher:127.0.0.1:29153/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 4706 T1734 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 4707 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170006 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4708 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170006 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4715 T1734 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:7004_solr
[junit4:junit4]   2> 4716 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170006 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:7004_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:7004_solr
[junit4:junit4]   2> 4716 T1734 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:7004_solr
[junit4:junit4]   2> 4718 T1745 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 4718 T1750 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 4718 T1753 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 4718 T1756 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 4719 T1734 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 4719 T1734 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 4720 T1734 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 4720 T1734 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 4720 T1734 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 4721 T1734 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 4721 T1734 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 4721 T1734 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 4722 T1734 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 4722 T1734 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 4739 T1734 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 4744 T1734 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1
[junit4:junit4]   2> 4745 T1734 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 4745 T1734 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 4746 T1734 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 4747 T1734 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/'
[junit4:junit4]   2> 4748 T1734 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 4749 T1734 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 4795 T1734 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 4862 T1734 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 4967 T1734 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 4973 T1734 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 5097 T1746 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5120 T1745 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5120 T1750 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5120 T1753 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5120 T1759 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5120 T1756 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5563 T1734 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 5574 T1734 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 5579 T1734 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 5590 T1734 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 5600 T1734 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 5605 T1734 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 5606 T1734 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 5607 T1734 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/, dataDir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J0/./solrtest-LeaderElectionIntegrationTest-1347979766283/data_7004/
[junit4:junit4]   2> 5608 T1734 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5b4bc4e6
[junit4:junit4]   2> 5609 T1734 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J0/./solrtest-LeaderElectionIntegrationTest-1347979766283/data_7004/index/
[junit4:junit4]   2> 5609 T1734 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J0/./solrtest-LeaderElectionIntegrationTest-1347979766283/data_7004/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 5610 T1734 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J0/./solrtest-LeaderElectionIntegrationTest-1347979766283/data_7004/index forceNew:false
[junit4:junit4]   2> 5612 T1734 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@740ffedd lockFactory=org.apache.lucene.store.NativeFSLockFactory@4ecf4018),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 5612 T1734 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 5613 T1734 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 5615 T1734 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 5615 T1734 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 5616 T1734 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 5616 T1734 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 5617 T1734 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 5617 T1734 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 5618 T1734 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 5618 T1734 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 5619 T1734 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 5619 T1734 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 5620 T1734 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 5620 T1734 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 5621 T1734 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 5621 T1734 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 5622 T1734 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 5622 T1734 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 5623 T1734 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5623 T1734 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5624 T1734 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5624 T1734 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5625 T1734 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5625 T1734 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5626 T1734 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5626 T1734 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 5627 T1734 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 5631 T1734 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 5635 T1734 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 5636 T1734 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 5639 T1734 oass.SolrIndexSearcher.<init> Opening Searcher@139618e4 main
[junit4:junit4]   2> 5639 T1734 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 5640 T1734 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 5640 T1734 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 5641 T1734 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 5672 T1734 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 5842 T1760 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@139618e4 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 5847 T1734 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 5847 T1734 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:7004/solr shardId:shard1
[junit4:junit4]   2> 5850 T1734 oasc.ZkController.register We are http://127.0.0.1:7004/solr/collection1/ and leader is http://127.0.0.1:7000/solr/collection1/
[junit4:junit4]   2> 5850 T1734 oasc.ZkController.checkRecovery WARNING Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4:junit4]   2> 5852 T1734 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5854 T1734 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 5855 T1734 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr
[junit4:junit4]   2> 5855 T1734 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/solr.xml
[junit4:junit4]   2> 5855 T1734 oasc.CoreContainer.<init> New CoreContainer 822341727
[junit4:junit4]   2> 5856 T1734 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 5856 T1734 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 5900 T1734 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 5914 T1734 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:29153/solr
[junit4:junit4]   2> 5915 T1734 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:29153/solr sessionTimeout=3000 watcher=org.apache.solr.common.cloud.ConnectionManager@6f0756a2
[junit4:junit4]   2> 5915 T1761 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:29153
[junit4:junit4]   2> 5915 T1734 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 5917 T1761 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:29153, initiating session
[junit4:junit4]   2> 5917 T1736 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:13483
[junit4:junit4]   2> 5917 T1736 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:13483
[junit4:junit4]   2> 5919 T1738 oazs.NIOServerCnxn.finishSessionInit Established session 0x139d9daea170007 with negotiated timeout 3000 for client /140.211.11.196:13483
[junit4:junit4]   2> 5919 T1761 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:29153, sessionid = 0x139d9daea170007, negotiated timeout = 3000
[junit4:junit4]   2> 5919 T1762 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6f0756a2 name:ZooKeeperConnection Watcher:127.0.0.1:29153/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 5919 T1734 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 5920 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170007 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5921 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170007 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5923 T1734 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:3333_solr
[junit4:junit4]   2> 5924 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170007 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:3333_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:3333_solr
[junit4:junit4]   2> 5932 T1734 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:3333_solr
[junit4:junit4]   2> 5934 T1745 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 5934 T1756 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 5934 T1750 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 5934 T1753 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 5934 T1759 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 5935 T1734 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 5936 T1734 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 5936 T1734 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 5936 T1734 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 5937 T1734 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 5937 T1734 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 5937 T1734 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 5938 T1734 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 5938 T1734 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 5938 T1734 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 5956 T1734 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 5961 T1734 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1
[junit4:junit4]   2> 5962 T1734 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 5962 T1734 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 5963 T1734 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 5964 T1734 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/'
[junit4:junit4]   2> 5965 T1734 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 5966 T1734 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 6012 T1734 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 6079 T1734 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 6126 T1746 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6154 T1745 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6154 T1753 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6154 T1759 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6154 T1756 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6154 T1750 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6180 T1762 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6180 T1734 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 6186 T1734 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 6782 T1734 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 6793 T1734 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 6797 T1734 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 6809 T1734 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 6813 T1734 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 6818 T1734 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 6819 T1734 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 6820 T1734 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/, dataDir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J0/./solrtest-LeaderElectionIntegrationTest-1347979766283/data_3333/
[junit4:junit4]   2> 6820 T1734 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5b4bc4e6
[junit4:junit4]   2> 6821 T1734 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J0/./solrtest-LeaderElectionIntegrationTest-1347979766283/data_3333/index/
[junit4:junit4]   2> 6822 T1734 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J0/./solrtest-LeaderElectionIntegrationTest-1347979766283/data_3333/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 6822 T1734 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J0/./solrtest-LeaderElectionIntegrationTest-1347979766283/data_3333/index forceNew:false
[junit4:junit4]   2> 6824 T1734 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4598cab5 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6f396b3a),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 6825 T1734 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 6825 T1734 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 6827 T1734 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 6828 T1734 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 6828 T1734 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 6828 T1734 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 6829 T1734 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 6830 T1734 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 6830 T1734 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 6831 T1734 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 6831 T1734 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 6832 T1734 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 6832 T1734 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 6833 T1734 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 6833 T1734 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 6834 T1734 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 6834 T1734 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 6835 T1734 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 6835 T1734 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6836 T1734 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6836 T1734 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6837 T1734 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6837 T1734 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6838 T1734 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6838 T1734 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6839 T1734 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 6839 T1734 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 6843 T1734 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 6847 T1734 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 6848 T1734 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 6851 T1734 oass.SolrIndexSearcher.<init> Opening Searcher@5ae3d956 main
[junit4:junit4]   2> 6851 T1734 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 6852 T1734 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 6852 T1734 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 6853 T1734 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 6889 T1734 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 6940 T1763 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5ae3d956 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 6944 T1734 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 6945 T1734 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:3333/solr shardId:shard2
[junit4:junit4]   2> 6945 T1734 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4:junit4]   2> 6957 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170007 type:delete cxid:0x41 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders/shard2 Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders/shard2
[junit4:junit4]   2> 6963 T1734 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 6964 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170007 type:create cxid:0x42 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 7161 T1746 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7177 T1745 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7177 T1762 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7177 T1756 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7177 T1759 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7177 T1753 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7177 T1750 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7467 T1734 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 7467 T1734 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 7468 T1734 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:3333/solr/collection1/
[junit4:junit4]   2> 7468 T1734 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4:junit4]   2> 7471 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170007 type:create cxid:0x4c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 7690 T1746 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7703 T1745 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7703 T1750 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7703 T1762 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7703 T1753 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7703 T1756 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7703 T1759 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7737 T1734 oasc.ZkController.register We are http://127.0.0.1:3333/solr/collection1/ and leader is http://127.0.0.1:3333/solr/collection1/
[junit4:junit4]   2> 7737 T1734 oasc.ZkController.checkRecovery WARNING Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4:junit4]   2> 7739 T1734 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7739 T1734 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:29153/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@4b909bfe
[junit4:junit4]   2> 7740 T1764 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:29153
[junit4:junit4]   2> 7740 T1734 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 7742 T1764 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:29153, initiating session
[junit4:junit4]   2> 7742 T1736 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:48074
[junit4:junit4]   2> 7742 T1736 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:48074
[junit4:junit4]   2> 7743 T1738 oazs.NIOServerCnxn.finishSessionInit Established session 0x139d9daea170008 with negotiated timeout 10000 for client /140.211.11.196:48074
[junit4:junit4]   2> 7744 T1764 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:29153, sessionid = 0x139d9daea170008, negotiated timeout = 10000
[junit4:junit4]   2> 7744 T1765 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4b909bfe name:ZooKeeperConnection Watcher:127.0.0.1:29153/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 7744 T1734 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 7745 T1734 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 7747 T1734 oasc.LeaderElectionIntegrationTest.setUp ####SETUP_END testSimpleSliceLeaderElection
[junit4:junit4]   2> 7748 T1734 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=2018258276
[junit4:junit4]   2> 7748 T1734 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@5e7101b6
[junit4:junit4]   2> 7751 T1734 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 7752 T1734 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> 7752 T1734 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 7753 T1734 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 7754 T1746 oasc.Overseer$ClusterStateUpdater.amILeader According to ZK I (id=88341201963909122-127.0.0.1:7000_solr-n_0000000000) am no longer a leader.
[junit4:junit4]   2> 7754 T1739 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139d9daea170002
[junit4:junit4]   2> 7755 T1745 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 7756 T1756 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 7756 T1745 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 7756 T1759 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 7756 T1745 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 7756 T1753 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 7756 T1765 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 7756 T1762 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 7756 T1734 oaz.ZooKeeper.close Session: 0x139d9daea170002 closed
[junit4:junit4]   2> 7756 T1736 oazs.NIOServerCnxn.closeSock Closed socket connection for client /140.211.11.196:61040 which had sessionid 0x139d9daea170002
[junit4:junit4]   2> 7758 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170003 type:delete cxid:0x49 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders/shard1 Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders/shard1
[junit4:junit4]   2> 7758 T1750 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 7759 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170003 type:create cxid:0x4a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 7767 T1750 oasc.ShardLeaderElectionContext.shouldIBeLeader Checking if I should try and be the leader.
[junit4:junit4]   2> 7767 T1750 oasc.ShardLeaderElectionContext.shouldIBeLeader My last published State was Active, it's okay to be the leader.
[junit4:junit4]   2> 7767 T1750 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 7767 T1750 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:7001/solr/collection1/
[junit4:junit4]   2> 7768 T1750 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 7770 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170003 type:create cxid:0x52 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 7772 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170003 type:delete cxid:0x55 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer_elect/leader Error:KeeperErrorCode = NoNode for /solr/overseer_elect/leader
[junit4:junit4]   2> 7773 T1750 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 7774 T1750 oasc.Overseer.start Overseer (id=88341201963909123-127.0.0.1:7001_solr-n_0000000001) starting
[junit4:junit4]   2> 7775 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170003 type:create cxid:0x5a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 7775 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170003 type:create cxid:0x5b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 7776 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170003 type:create cxid:0x5c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 7777 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170003 type:create cxid:0x5d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 7778 T1750 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 7778 T1767 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 7779 T1766 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 7780 T1766 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7788 T1762 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7788 T1756 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7788 T1759 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7788 T1750 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7788 T1753 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7788 T1765 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 8258 T1734 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1493922412
[junit4:junit4]   2> 8258 T1734 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@3f037c85
[junit4:junit4]   2> 8262 T1734 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 8262 T1734 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> 8263 T1734 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 8263 T1734 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 8265 T1766 oasc.Overseer$ClusterStateUpdater.amILeader According to ZK I (id=88341201963909123-127.0.0.1:7001_solr-n_0000000001) am no longer a leader.
[junit4:junit4]   2> 8265 T1739 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139d9daea170003
[junit4:junit4]   2> 8266 T1750 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 8266 T1753 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 8266 T1762 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 8266 T1750 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 8267 T1736 oazs.NIOServerCnxn.closeSock Closed socket connection for client /140.211.11.196:44196 which had sessionid 0x139d9daea170003
[junit4:junit4]   2> 8266 T1756 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 8266 T1765 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 8266 T1759 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 8267 T1750 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 8266 T1734 oaz.ZooKeeper.close Session: 0x139d9daea170003 closed
[junit4:junit4]   2> 8268 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170004 type:delete cxid:0x4a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer_elect/leader Error:KeeperErrorCode = NoNode for /solr/overseer_elect/leader
[junit4:junit4]   2> 8269 T1753 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 8271 T1753 oasc.Overseer.start Overseer (id=88341201963909124-127.0.0.1:7002_solr-n_0000000002) starting
[junit4:junit4]   2> 8271 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170004 type:create cxid:0x4f zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8273 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170004 type:create cxid:0x50 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8281 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170004 type:create cxid:0x51 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8282 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170004 type:create cxid:0x52 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8283 T1769 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 8284 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170004 type:delete cxid:0x57 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders/shard1 Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders/shard1
[junit4:junit4]   2> 8284 T1768 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 8292 T1753 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 8292 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170004 type:create cxid:0x5b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8294 T1753 oasc.ShardLeaderElectionContext.shouldIBeLeader Checking if I should try and be the leader.
[junit4:junit4]   2> 8294 T1753 oasc.ShardLeaderElectionContext.shouldIBeLeader My last published State was Active, it's okay to be the leader.
[junit4:junit4]   2> 8294 T1753 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 8294 T1753 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:7002/solr/collection1/
[junit4:junit4]   2> 8295 T1753 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 8302 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170004 type:create cxid:0x64 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8795 T1768 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8814 T1759 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 8814 T1756 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 8814 T1753 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 8814 T1762 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 8814 T1765 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 9270 T1734 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=453190756
[junit4:junit4]   2> 9270 T1734 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@492ff0c
[junit4:junit4]   2> 9274 T1734 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 9274 T1734 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> 9275 T1734 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 9275 T1734 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 9277 T1768 oasc.Overseer$ClusterStateUpdater.amILeader According to ZK I (id=88341201963909124-127.0.0.1:7002_solr-n_0000000002) am no longer a leader.
[junit4:junit4]   2> 9277 T1739 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139d9daea170004
[junit4:junit4]   2> 9278 T1759 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 9278 T1753 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 9279 T1734 oaz.ZooKeeper.close Session: 0x139d9daea170004 closed
[junit4:junit4]   2> 9278 T1765 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 9278 T1762 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 9279 T1753 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 9279 T1736 oazs.NIOServerCnxn.closeSock Closed socket connection for client /140.211.11.196:25951 which had sessionid 0x139d9daea170004
[junit4:junit4]   2> 9279 T1753 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 9280 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170005 type:delete cxid:0x49 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders/shard1 Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders/shard1
[junit4:junit4]   2> 9281 T1756 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 9281 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170005 type:create cxid:0x4a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 9291 T1756 oasc.ShardLeaderElectionContext.shouldIBeLeader Checking if I should try and be the leader.
[junit4:junit4]   2> 9291 T1756 oasc.ShardLeaderElectionContext.shouldIBeLeader My last published State was Active, it's okay to be the leader.
[junit4:junit4]   2> 9291 T1756 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 9291 T1756 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:7003/solr/collection1/
[junit4:junit4]   2> 9292 T1756 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 9294 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170005 type:create cxid:0x52 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 9303 T1756 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 9304 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170005 type:delete cxid:0x56 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer_elect/leader Error:KeeperErrorCode = NoNode for /solr/overseer_elect/leader
[junit4:junit4]   2> 9304 T1756 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 9306 T1756 oasc.Overseer.start Overseer (id=88341201963909125-127.0.0.1:7003_solr-n_0000000003) starting
[junit4:junit4]   2> 9307 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170005 type:create cxid:0x5b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 9308 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170005 type:create cxid:0x5c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 9317 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170005 type:create cxid:0x5d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 9318 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170005 type:create cxid:0x5e zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 9319 T1771 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 9319 T1770 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 9321 T1770 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9340 T1756 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 9340 T1759 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 9340 T1765 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 9340 T1762 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 9780 T1734 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1825533644
[junit4:junit4]   2> 9780 T1734 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@1024eca2
[junit4:junit4]   2> 9784 T1734 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 9784 T1734 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> 9785 T1734 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 9785 T1734 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 9786 T1770 oasc.Overseer$ClusterStateUpdater.amILeader According to ZK I (id=88341201963909125-127.0.0.1:7003_solr-n_0000000003) am no longer a leader.
[junit4:junit4]   2> 9787 T1739 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139d9daea170005
[junit4:junit4]   2> 9788 T1756 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 9788 T1765 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 9788 T1762 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 9789 T1756 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 9789 T1734 oaz.ZooKeeper.close Session: 0x139d9daea170005 closed
[junit4:junit4]   2> 9789 T1756 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 9789 T1736 oazs.NIOServerCnxn.closeSock Closed socket connection for client /140.211.11.196:31264 which had sessionid 0x139d9daea170005
[junit4:junit4]   2> 9790 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170006 type:delete cxid:0x49 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders/shard1 Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders/shard1
[junit4:junit4]   2> 9799 T1759 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 9800 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170006 type:create cxid:0x4a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 9890 T1759 oasc.ShardLeaderElectionContext.shouldIBeLeader Checking if I should try and be the leader.
[junit4:junit4]   2> 9890 T1759 oasc.ShardLeaderElectionContext.shouldIBeLeader My last published State was Active, it's okay to be the leader.
[junit4:junit4]   2> 9891 T1759 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 9891 T1759 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:7004/solr/collection1/
[junit4:junit4]   2> 9891 T1759 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 9894 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170006 type:create cxid:0x52 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 9896 T1759 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 9897 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170006 type:delete cxid:0x56 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer_elect/leader Error:KeeperErrorCode = NoNode for /solr/overseer_elect/leader
[junit4:junit4]   2> 9912 T1759 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 9914 T1759 oasc.Overseer.start Overseer (id=88341201963909126-127.0.0.1:7004_solr-n_0000000004) starting
[junit4:junit4]   2> 9914 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170006 type:create cxid:0x5b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 9921 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170006 type:create cxid:0x5c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 9922 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170006 type:create cxid:0x5d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 9923 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170006 type:create cxid:0x5e zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 9932 T1773 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 9933 T1772 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 9934 T1772 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9949 T1759 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 9949 T1762 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 9949 T1765 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 10290 T1739 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139d9daea170008
[junit4:junit4]   2> 10292 T1765 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 10292 T1736 oazs.NIOServerCnxn.closeSock Closed socket connection for client /140.211.11.196:48074 which had sessionid 0x139d9daea170008
[junit4:junit4]   2> 10292 T1734 oaz.ZooKeeper.close Session: 0x139d9daea170008 closed
[junit4:junit4]   2> 10293 T1734 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=395702951
[junit4:junit4]   2> 10293 T1734 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@307d53e9
[junit4:junit4]   2> 10297 T1734 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 10297 T1734 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> 10298 T1734 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 10298 T1734 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 10299 T1772 oasc.Overseer$ClusterStateUpdater.amILeader According to ZK I (id=88341201963909126-127.0.0.1:7004_solr-n_0000000004) am no longer a leader.
[junit4:junit4]   2> 10299 T1739 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139d9daea170006
[junit4:junit4]   2> 10300 T1759 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 10301 T1759 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 10301 T1736 oazs.NIOServerCnxn.closeSock Closed socket connection for client /140.211.11.196:35942 which had sessionid 0x139d9daea170006
[junit4:junit4]   2> 10301 T1759 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 10300 T1734 oaz.ZooKeeper.close Session: 0x139d9daea170006 closed
[junit4:junit4]   2> 10301 T1739 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9daea170007 type:delete cxid:0x5c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer_elect/leader Error:KeeperErrorCode = NoNode for /solr/overseer_elect/leader
[junit4:junit4]   2> 10301 T1734 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=822341727
[junit4:junit4]   2> 10302 T1734 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@632f883a
[junit4:junit4]   2> 10302 T1762 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 10308 T1734 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 10308 T1734 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> 10309 T1734 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 10309 T1734 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 10310 T1739 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139d9daea170007
[junit4:junit4]   2> 10312 T1734 oaz.ZooKeeper.close Session: 0x139d9daea170007 closed
[junit4:junit4]   2> 10312 T1736 oazs.NIOServerCnxn.closeSock Closed socket connection for client /140.211.11.196:13483 which had sessionid 0x139d9daea170007
[junit4:junit4]   2> 10312 T1738 oazs.SyncRequestProcessor.run SyncRequestProcessor exited!
[junit4:junit4]   2> 10312 T1739 oazs.PrepRequestProcessor.run PrepRequestProcessor exited loop!
[junit4:junit4]   2> 10313 T1734 oazs.FinalRequestProcessor.shutdown shutdown of request processor complete
[junit4:junit4]   2> 10523 T1736 oazs.NIOServerCnxn$Factory.run NIOServerCnxn factory exited run method
[junit4:junit4]   2> 10523 T1734 oazs.FinalRequestProcessor.shutdown shutdown of request processor complete
[junit4:junit4]   2> 10524 T1734 oas.SolrTestCaseJ4.tearDown ###Ending testSimpleSliceLeaderElection
[junit4:junit4]   2> 10528 T1734 oas.SolrTestCaseJ4.setUp ###Starting testLeaderElectionAfterClientTimeout
[junit4:junit4]   2> Creating dataDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J0/./solrtest-LeaderElectionIntegrationTest-1347979776808
[junit4:junit4]   2> 10528 T1734 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 10531 T1774 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4]   2> 10532 T1774 oazs.NIOServerCnxn$Factory.<init> binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 10533 T1774 oazsp.FileTxnSnapLog.save Snapshotting: 0
[junit4:junit4]   2> 10630 T1734 oasc.ZkTestServer.run start zk server on port:44016
[junit4:junit4]   2> 10630 T1734 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:44016 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@7cbbf797
[junit4:junit4]   2> 10631 T1779 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:44016
[junit4:junit4]   2> 10631 T1734 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 10632 T1779 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:44016, initiating session
[junit4:junit4]   2> 10632 T1775 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:24148
[junit4:junit4]   2> 10633 T1775 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:24148
[junit4:junit4]   2> 10633 T1777 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 10639 T1777 oazs.NIOServerCnxn.finishSessionInit Established session 0x139d9db132e0000 with negotiated timeout 10000 for client /140.211.11.196:24148
[junit4:junit4]   2> 10639 T1779 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:44016, sessionid = 0x139d9db132e0000, negotiated timeout = 10000
[junit4:junit4]   2> 10639 T1780 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7cbbf797 name:ZooKeeperConnection Watcher:127.0.0.1:44016 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 10639 T1734 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 10640 T1734 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 10668 T1778 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139d9db132e0000
[junit4:junit4]   2> 10678 T1780 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 10678 T1734 oaz.ZooKeeper.close Session: 0x139d9db132e0000 closed
[junit4:junit4]   2> 10678 T1775 oazs.NIOServerCnxn.closeSock Closed socket connection for client /140.211.11.196:24148 which had sessionid 0x139d9db132e0000
[junit4:junit4]   2> 10679 T1734 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:44016/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@6692fc4e
[junit4:junit4]   2> 10679 T1781 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:44016
[junit4:junit4]   2> 10679 T1734 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 10680 T1781 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:44016, initiating session
[junit4:junit4]   2> 10680 T1775 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:33524
[junit4:junit4]   2> 10680 T1775 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:33524
[junit4:junit4]   2> 10721 T1737 oazs.SessionTrackerImpl.run SessionTrackerImpl exited loop!
[junit4:junit4]   2> 10733 T1777 oazs.NIOServerCnxn.finishSessionInit Established session 0x139d9db132e0001 with negotiated timeout 10000 for client /140.211.11.196:33524
[junit4:junit4]   2> 10733 T1781 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:44016, sessionid = 0x139d9db132e0001, negotiated timeout = 10000
[junit4:junit4]   2> 10733 T1782 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6692fc4e name:ZooKeeperConnection Watcher:127.0.0.1:44016/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 10733 T1734 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 10734 T1734 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 10745 T1734 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 10790 T1734 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 10811 T1734 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 10862 T1734 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 10863 T1734 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 10901 T1734 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4:junit4]   2> 10902 T1734 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 11009 T1734 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 11010 T1734 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 11013 T1734 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4:junit4]   2> 11013 T1734 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 11025 T1734 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4:junit4]   2> 11026 T1734 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 11029 T1734 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4:junit4]   2> 11030 T1734 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 11032 T1734 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 11033 T1734 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 11036 T1734 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 11037 T1734 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 11039 T1734 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 11040 T1734 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 11048 T1734 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4:junit4]   2> 11048 T1734 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 11051 T1778 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139d9db132e0001
[junit4:junit4]   2> 11052 T1782 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 11052 T1734 oaz.ZooKeeper.close Session: 0x139d9db132e0001 closed
[junit4:junit4]   2> 11053 T1775 oazs.NIOServerCnxn.closeSock Closed socket connection for client /140.211.11.196:33524 which had sessionid 0x139d9db132e0001
[junit4:junit4]   2> 11054 T1734 oasc.LeaderElectionIntegrationTest.setUp ####SETUP_START testLeaderElectionAfterClientTimeout
[junit4:junit4]   2> 11056 T1734 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 11056 T1734 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr
[junit4:junit4]   2> 11056 T1734 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/solr.xml
[junit4:junit4]   2> 11057 T1734 oasc.CoreContainer.<init> New CoreContainer 150955708
[junit4:junit4]   2> 11057 T1734 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 11058 T1734 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 11102 T1734 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 11116 T1734 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:44016/solr
[junit4:junit4]   2> 11117 T1734 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:44016/solr sessionTimeout=3000 watcher=org.apache.solr.common.cloud.ConnectionManager@780d4af7
[junit4:junit4]   2> 11118 T1783 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:44016
[junit4:junit4]   2> 11118 T1734 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 11119 T1783 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:44016, initiating session
[junit4:junit4]   2> 11119 T1775 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:47959
[junit4:junit4]   2> 11120 T1775 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:47959
[junit4:junit4]   2> 11121 T1777 oazs.NIOServerCnxn.finishSessionInit Established session 0x139d9db132e0002 with negotiated timeout 3000 for client /140.211.11.196:47959
[junit4:junit4]   2> 11121 T1783 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:44016, sessionid = 0x139d9db132e0002, negotiated timeout = 3000
[junit4:junit4]   2> 11122 T1784 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@780d4af7 name:ZooKeeperConnection Watcher:127.0.0.1:44016/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 11122 T1734 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 11124 T1778 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9db132e0002 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 11131 T1734 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 11134 T1734 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:7000_solr
[junit4:junit4]   2> 11134 T1778 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9db132e0002 type:delete cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:7000_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:7000_solr
[junit4:junit4]   2> 11135 T1734 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:7000_solr
[junit4:junit4]   2> 11145 T1734 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 11145 T1734 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 11146 T1734 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 11146 T1734 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 11147 T1734 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 11147 T1734 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 11147 T1734 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 11148 T1734 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 11148 T1734 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 11148 T1734 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 11163 T1734 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 11175 T1778 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9db132e0002 type:delete cxid:0x15 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer_elect/leader Error:KeeperErrorCode = NoNode for /solr/overseer_elect/leader
[junit4:junit4]   2> 11176 T1734 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 11182 T1734 oasc.Overseer.start Overseer (id=88341202653282306-127.0.0.1:7000_solr-n_0000000000) starting
[junit4:junit4]   2> 11183 T1778 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9db132e0002 type:create cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 11184 T1778 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9db132e0002 type:create cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 11186 T1778 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9db132e0002 type:create cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 11194 T1778 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9db132e0002 type:create cxid:0x1d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 11196 T1786 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 11197 T1734 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 11200 T1734 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 11202 T1785 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 11206 T1734 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1
[junit4:junit4]   2> 11207 T1734 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 11207 T1734 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 11208 T1734 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 11209 T1734 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/'
[junit4:junit4]   2> 11210 T1734 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 11211 T1734 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 11258 T1734 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 11312 T1762 oasc.LeaderElector$1.process WARNING  org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/leader
[junit4:junit4]   2> 	at org.apache.zookeeper.KeeperException.create(KeeperException.java:118)
[junit4:junit4]   2> 	at org.apache.zookeeper.KeeperException.create(KeeperException.java:42)
[junit4:junit4]   2> 	at org.apache.zookeeper.ZooKeeper.exists(ZooKeeper.java:815)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient$3.execute(SolrZkClient.java:211)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient$3.execute(SolrZkClient.java:208)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:67)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient.exists(SolrZkClient.java:208)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:522)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:443)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:424)
[junit4:junit4]   2> 	at org.apache.solr.cloud.OverseerElectionContext.runLeaderProcess(ElectionContext.java:369)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:158)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:102)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:56)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:131)
[junit4:junit4]   2> 	at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:526)
[junit4:junit4]   2> 	at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:502)
[junit4:junit4]   2> 
[junit4:junit4]   2> 11320 T1762 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 11320 T1762 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 11320 T1762 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 11334 T1734 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 11435 T1734 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 11441 T1734 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 12031 T1734 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 12043 T1734 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 12048 T1734 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 12060 T1734 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 12065 T1734 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 12069 T1734 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 12070 T1734 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 12072 T1734 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/, dataDir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J0/./solrtest-LeaderElectionIntegrationTest-1347979776808/data_7000/
[junit4:junit4]   2> 12073 T1734 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5b4bc4e6
[junit4:junit4]   2> 12074 T1734 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J0/./solrtest-LeaderElectionIntegrationTest-1347979776808/data_7000/index/
[junit4:junit4]   2> 12075 T1734 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J0/./solrtest-LeaderElectionIntegrationTest-1347979776808/data_7000/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 12076 T1734 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J0/./solrtest-LeaderElectionIntegrationTest-1347979776808/data_7000/index forceNew:false
[junit4:junit4]   2> 12079 T1734 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@cef6466 lockFactory=org.apache.lucene.store.NativeFSLockFactory@391432c3),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 12079 T1734 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 12081 T1734 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 12083 T1734 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 12084 T1734 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 12084 T1734 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 12085 T1734 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 12086 T1734 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 12087 T1734 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 12088 T1734 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 12088 T1734 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 12089 T1734 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 12090 T1734 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 12091 T1734 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 12091 T1734 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 12092 T1734 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 12093 T1734 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 12093 T1734 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 12094 T1734 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 12095 T1734 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 12095 T1734 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 12096 T1734 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 12097 T1734 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 12098 T1734 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 12098 T1734 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 12099 T1734 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 12100 T1734 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 12101 T1734 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 12107 T1734 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 12114 T1734 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 12116 T1734 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 12120 T1734 oass.SolrIndexSearcher.<init> Opening Searcher@2b0f46db main
[junit4:junit4]   2> 12120 T1734 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 12121 T1734 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 12122 T1734 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 12123 T1734 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 12158 T1734 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 12225 T1787 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2b0f46db main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 12228 T1778 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9db132e0002 type:create cxid:0x56 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue Error:KeeperErrorCode = NoNode for /solr/overseer/queue
[junit4:junit4]   2> 12240 T1734 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 12240 T1734 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:7000/solr shardId:shard1
[junit4:junit4]   2> 12241 T1734 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 12255 T1778 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9db132e0002 type:delete cxid:0x65 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders
[junit4:junit4]   2> 12263 T1734 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 12264 T1778 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9db132e0002 type:create cxid:0x66 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 12708 T1785 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 12709 T1778 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9db132e0002 type:create cxid:0x6d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue-work Error:KeeperErrorCode = NoNode for /solr/overseer/queue-work
[junit4:junit4]   2> 12735 T1784 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 12766 T1734 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 12767 T1734 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 12767 T1734 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:7000/solr/collection1/
[junit4:junit4]   2> 12767 T1734 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 12772 T1778 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d9db132e0002 type:create cxid:0x8b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 13247 T1785 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 13260 T1784 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 13291 T1734 oasc.ZkController.register We are http://127.0.0.1:7000/solr/collection1/ and leader is http://127.0.0.1:7000/solr/collection1/
[junit4:junit4]   2> 13291 T1734 oasc.ZkController.checkRecovery WARNING Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4:junit4]   2> 13293 T1734 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 13295 T1734 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 13295 T1734 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr
[junit4:junit4]   2> 13296 T1734 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/solr.xml
[junit4:junit4]   2> 13296 T1734 oasc.CoreContainer.<init> New CoreContainer 1870660789
[junit4:junit4]   2> 13296 T1734 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 13296 T1734 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 13332 T1734 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 13343 T1734 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:44016/solr
[junit4:junit4]   2> 13343 T1734 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:44016/solr sessionTimeout=3000 watcher=org.apache.solr.common.cloud.ConnectionManager@468e0e5b
[junit4:junit4]   2

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

aderProcess(ElectionContext.java:213)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:158)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:102)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:56)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:131)
[junit4:junit4]   2> 	at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:526)
[junit4:junit4]   2> 	at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:502)
[junit4:junit4]   2> 
[junit4:junit4]   2> 308428 T2024 oasc.JmxMonitoredMap$SolrDynamicMBean.getMBeanInfo WARNING Could not getStatistics on info bean Lazy[solr.ReplicationHandler] java.lang.IllegalArgumentException: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1347979910286/jetty4/index does not exist
[junit4:junit4]   2> 	at org.apache.commons.io.FileUtils.sizeOfDirectory(FileUtils.java:2074)
[junit4:junit4]   2> 	at org.apache.solr.handler.ReplicationHandler.getIndexSize(ReplicationHandler.java:477)
[junit4:junit4]   2> 	at org.apache.solr.handler.ReplicationHandler.getStatistics(ReplicationHandler.java:525)
[junit4:junit4]   2> 	at org.apache.solr.core.RequestHandlers$LazyRequestHandlerWrapper.getStatistics(RequestHandlers.java:310)
[junit4:junit4]   2> 	at org.apache.solr.core.JmxMonitoredMap$SolrDynamicMBean.getMBeanInfo(JmxMonitoredMap.java:231)
[junit4:junit4]   2> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getClassName(DefaultMBeanServerInterceptor.java:1824)
[junit4:junit4]   2> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.safeGetClassName(DefaultMBeanServerInterceptor.java:1661)
[junit4:junit4]   2> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.checkMBeanPermission(DefaultMBeanServerInterceptor.java:1833)
[junit4:junit4]   2> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:436)
[junit4:junit4]   2> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:421)
[junit4:junit4]   2> 	at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:540)
[junit4:junit4]   2> 	at org.apache.solr.core.JmxMonitoredMap.unregister(JmxMonitoredMap.java:175)
[junit4:junit4]   2> 	at org.apache.solr.core.JmxMonitoredMap.clear(JmxMonitoredMap.java:116)
[junit4:junit4]   2> 	at org.apache.solr.core.SolrCore.close(SolrCore.java:864)
[junit4:junit4]   2> 	at org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:213)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:158)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:102)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:56)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:131)
[junit4:junit4]   2> 	at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:526)
[junit4:junit4]   2> 	at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:502)
[junit4:junit4]   2> 
[junit4:junit4]   2> 308432 T2024 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 308436 T2024 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=5,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=35,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> 308436 T2024 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 308438 T2024 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 308439 T2024 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 308439 T2024 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 308439 T2024 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 308439 T2024 oascc.ZkStateReader$2.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 308439 T2024 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@34e49e92 name:ZooKeeperConnection Watcher:127.0.0.1:46626/solr got event WatchedEvent state:Disconnected type:None path:null path:null type:None
[junit4:junit4]   2> 308440 T2024 oascc.ConnectionManager.process Client->ZooKeeper status change trigger but we are already closed
[junit4:junit4]   2> 308440 T2024 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> NOTE: test params are: codec=Lucene40: {n_f1=MockFixedIntBlock(blockSize=611), foo_b=MockVariableIntBlock(baseBlockSize=87), foo_d=PostingsFormat(name=Memory doPackFST= true), foo_f=MockVariableIntBlock(baseBlockSize=87), n_tl1=MockFixedIntBlock(blockSize=611), n_d1=PostingsFormat(name=MockRandom), rnd_b=PostingsFormat(name=Memory doPackFST= true), intDefault=MockFixedIntBlock(blockSize=611), n_td1=MockFixedIntBlock(blockSize=611), id=PostingsFormat(name=Memory doPackFST= true), timestamp=PostingsFormat(name=MockRandom), range_facet_sl=PostingsFormat(name=Memory doPackFST= true), range_facet_si=PostingsFormat(name=MockRandom), oddField_s=PostingsFormat(name=Memory doPackFST= true), multiDefault=PostingsFormat(name=MockRandom), n_tf1=PostingsFormat(name=MockRandom), n_dt1=MockFixedIntBlock(blockSize=611), n_ti1=PostingsFormat(name=Memory doPackFST= true), range_facet_l=PostingsFormat(name=MockRandom), text=MockVariableIntBlock(baseBlockSize=87), _version_=MockFixedIntBlock(blockSize=611), SubjectTerms_mfacet=MockFixedIntBlock(blockSize=611), a_t=PostingsFormat(name=MockRandom), n_tdt1=MockFixedIntBlock(blockSize=611), other_tl1=MockFixedIntBlock(blockSize=611), n_l1=PostingsFormat(name=MockRandom), a_si=MockFixedIntBlock(blockSize=611)}, sim=DefaultSimilarity, locale=th_TH_TH, timezone=America/Cordoba
[junit4:junit4]   2> NOTE: FreeBSD 9.0-RELEASE amd64/Sun Microsystems Inc. 1.6.0_32 (64-bit)/cpus=16,threads=2,free=84232904,total=178061312
[junit4:junit4]   2> NOTE: All tests run in this JVM: [TestRecovery, IndexSchemaRuntimeFieldTest, PrimUtilsTest, UpdateParamsTest, XsltUpdateRequestHandlerTest, DocumentBuilderTest, TestCodecSupport, DocumentAnalysisRequestHandlerTest, OpenExchangeRatesOrgProviderTest, PathHierarchyTokenizerFactoryTest, URLClassifyProcessorTest, TestWriterPerf, SolrCoreTest, StatsComponentTest, TestArbitraryIndexDir, SoftAutoCommitTest, PrimitiveFieldTypeTest, EchoParamsTest, ZkSolrClientTest, TimeZoneUtilsTest, TestSolrDeletionPolicy2, TestSolrCoreProperties, TestRealTimeGet, UUIDFieldTest, ScriptEngineTest, TestUpdate, TestDocSet, TestReversedWildcardFilterFactory, TestDistributedSearch, TestMultiCoreConfBootstrap, HighlighterTest, FieldMutatingUpdateProcessorTest, TestBM25SimilarityFactory, TestTrie, TestValueSourceCache, LeaderElectionTest, DistributedQueryElevationComponentTest, TestStressRecovery, SpellingQueryConverterTest, BasicZkTest, RAMDirectoryFactoryTest, DebugComponentTest, XmlUpdateRequestHandlerTest, PolyFieldTest, SuggesterTest, ZkNodePropsTest, TestRTGBase, MultiTermTest, OutputWriterTest, TestHashPartitioner, TestDocumentBuilder, SignatureUpdateProcessorFactoryTest, LegacyHTMLStripCharFilterTest, TestSolrXMLSerializer, IndexReaderFactoryTest, QueryEqualityTest, SolrIndexConfigTest, OverseerTest, RecoveryZkTest, FullSolrCloudDistribCmdsTest, TestDistributedGrouping, SpellCheckCollatorTest, WordBreakSolrSpellCheckerTest, TestJoin, DirectUpdateHandlerTest, LukeRequestHandlerTest, TestGroupingSearch, TestBinaryResponseWriter, SampleTest, TestFunctionQuery, SolrRequestParserTest, CacheHeaderTest, TestCSVResponseWriter, DisMaxRequestHandlerTest, BinaryUpdateRequestHandlerTest, TermsComponentTest, StandardRequestHandlerTest, TermVectorComponentTest, SuggesterWFSTTest, TestSolrQueryParser, ReturnFieldsTest, TestOmitPositions, TestQueryUtils, SuggesterTSTTest, UniqFieldsUpdateProcessorFactoryTest, ShowFileRequestHandlerTest, BadIndexSchemaTest, SolrCoreCheckLockOnStartupTest, TestSolrDeletionPolicy1, TestPropInject, BadComponentTest, TestPropInjectDefaults, TestLFUCache, TestQuerySenderNoQuery, TestNumberUtils, TestPhraseSuggestions, TestLMDirichletSimilarityFactory, TestDFRSimilarityFactory, TestFastLRUCache, PluginInfoTest, TestSystemIdResolver, DOMUtilTest, SystemInfoHandlerTest, PreAnalyzedFieldTest, CircularListTest, TestCharFilters, TestCollationKeyRangeQueries, BasicDistributedZk2Test]
[junit4:junit4] Completed on J1 in 308.97s, 1 test, 1 failure <<< FAILURES!

[...truncated 5 lines...]
BUILD FAILED
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/build.xml:32: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build.xml:146: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/lucene/module-build.xml:63: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/lucene/common-build.xml:1133: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/lucene/common-build.xml:810: There were test failures: 230 suites, 951 tests, 3 failures, 7 ignored (2 assumptions)

Total time: 31 minutes 13 seconds
Build step 'Invoke Ant' marked build as failure
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message