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-trunk-java7 - Build # 3882 - Still Failing
Date Sat, 13 Apr 2013 03:39:56 GMT
Build: https://builds.apache.org/job/Lucene-Solr-Tests-trunk-java7/3882/

3 tests failed.
FAILED:  junit.framework.TestSuite.org.apache.solr.cloud.RecoveryZkTest

Error Message:
Resource in scope SUITE failed to close. Resource was registered from thread Thread[id=1392, name=coreLoadExecutor-735-thread-1, state=RUNNABLE, group=TGRP-RecoveryZkTest], registration stack trace below.

Stack Trace:
com.carrotsearch.randomizedtesting.ResourceDisposalError: Resource in scope SUITE failed to close. Resource was registered from thread Thread[id=1392, name=coreLoadExecutor-735-thread-1, state=RUNNABLE, group=TGRP-RecoveryZkTest], registration stack trace below.
	at __randomizedtesting.SeedInfo.seed([DCEF4AAEC6205F92]:0)
	at java.lang.Thread.getStackTrace(Thread.java:1567)
	at com.carrotsearch.randomizedtesting.RandomizedContext.closeAtEnd(RandomizedContext.java:150)
	at org.apache.lucene.util.LuceneTestCase.closeAfterSuite(LuceneTestCase.java:524)
	at org.apache.lucene.util.LuceneTestCase.wrapDirectory(LuceneTestCase.java:985)
	at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:883)
	at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:875)
	at org.apache.solr.core.MockDirectoryFactory.create(MockDirectoryFactory.java:37)
	at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:343)
	at org.apache.solr.core.SolrCore.getNewIndexDir(SolrCore.java:246)
	at org.apache.solr.core.SolrCore.initIndex(SolrCore.java:458)
	at org.apache.solr.core.SolrCore.<init>(SolrCore.java:745)
	at org.apache.solr.core.SolrCore.<init>(SolrCore.java:618)
	at org.apache.solr.core.CoreContainer.createFromZk(CoreContainer.java:884)
	at org.apache.solr.core.CoreContainer.create(CoreContainer.java:968)
	at org.apache.solr.core.CoreContainer$2.call(CoreContainer.java:590)
	at org.apache.solr.core.CoreContainer$2.call(CoreContainer.java:585)
	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334)
	at java.util.concurrent.FutureTask.run(FutureTask.java:166)
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334)
	at java.util.concurrent.FutureTask.run(FutureTask.java:166)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
	at java.lang.Thread.run(Thread.java:722)
Caused by: java.lang.AssertionError: Directory not closed: BaseDirectoryWrapper(org.apache.lucene.store.RAMDirectory@3dc8b75 lockFactory=org.apache.lucene.store.NativeFSLockFactory@732c54b)
	at org.junit.Assert.fail(Assert.java:93)
	at org.apache.lucene.util.CloseableDirectory.close(CloseableDirectory.java:47)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$2$1.apply(RandomizedRunner.java:602)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$2$1.apply(RandomizedRunner.java:599)
	at com.carrotsearch.randomizedtesting.RandomizedContext.closeResources(RandomizedContext.java:167)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$2.afterAlways(RandomizedRunner.java:615)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	... 1 more


REGRESSION:  org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigAliasReplication

Error Message:
ERROR: [doc=2000] unknown field 'newname'

Stack Trace:
org.apache.solr.common.SolrException: ERROR: [doc=2000] unknown field 'newname'
	at __randomizedtesting.SeedInfo.seed([DCEF4AAEC6205F92:2B9CA4F600C8F074]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:404)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:181)
	at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:117)
	at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:116)
	at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:102)
	at org.apache.solr.handler.TestReplicationHandler.index(TestReplicationHandler.java:167)
	at org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigAliasReplication(TestReplicationHandler.java:1176)
	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:601)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:722)


FAILED:  org.apache.solr.cloud.ChaosMonkeyShardSplitTest.testDistribSearch

Error Message:
Wrong doc count on shard1_1 expected:<49> but was:<50>

Stack Trace:
java.lang.AssertionError: Wrong doc count on shard1_1 expected:<49> but was:<50>
	at __randomizedtesting.SeedInfo.seed([DCEF4AAEC6205F92:5D09C4B6B17F3FAE]: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.apache.solr.cloud.ChaosMonkeyShardSplitTest.doTest(ChaosMonkeyShardSplitTest.java:274)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:806)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:601)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:722)




Build Log:
[...truncated 9252 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.RecoveryZkTest
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:31.088; org.apache.solr.BaseDistributedSearchTestCase; Setting hostContext system property: /id_fe/mn
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:31.094; org.apache.solr.SolrTestCaseJ4; ###Starting testDistribSearch
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:31.107; org.apache.solr.cloud.ZkTestServer; STARTING ZK TEST SERVER
[junit4:junit4]   1> client port:0.0.0.0/0.0.0.0:0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:31.107; org.apache.solr.cloud.ZkTestServer$ZKServerMain; Starting server
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:31.208; org.apache.solr.cloud.ZkTestServer; start zk server on port:64774
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:31.209; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:31.218; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@18f30d38 name:ZooKeeperConnection Watcher:127.0.0.1:64774 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:31.218; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:31.219; org.apache.solr.common.cloud.SolrZkClient; makePath: /solr
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:31.301; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:31.340; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@23e20da0 name:ZooKeeperConnection Watcher:127.0.0.1:64774/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:31.340; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:31.341; org.apache.solr.common.cloud.SolrZkClient; makePath: /collections/collection1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:31.362; org.apache.solr.common.cloud.SolrZkClient; makePath: /collections/collection1/shards
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:31.372; org.apache.solr.common.cloud.SolrZkClient; makePath: /collections/control_collection
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:31.418; org.apache.solr.common.cloud.SolrZkClient; makePath: /collections/control_collection/shards
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:31.421; org.apache.solr.cloud.AbstractZkTestCase; put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:31.422; org.apache.solr.common.cloud.SolrZkClient; makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:31.480; org.apache.solr.cloud.AbstractZkTestCase; put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:31.482; org.apache.solr.common.cloud.SolrZkClient; makePath: /configs/conf1/schema.xml
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:31.607; org.apache.solr.cloud.AbstractZkTestCase; put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:31.608; org.apache.solr.common.cloud.SolrZkClient; makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:31.640; org.apache.solr.cloud.AbstractZkTestCase; put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:31.641; org.apache.solr.common.cloud.SolrZkClient; makePath: /configs/conf1/protwords.txt
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:31.646; org.apache.solr.cloud.AbstractZkTestCase; put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:31.647; org.apache.solr.common.cloud.SolrZkClient; makePath: /configs/conf1/currency.xml
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:31.671; org.apache.solr.cloud.AbstractZkTestCase; put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:31.672; org.apache.solr.common.cloud.SolrZkClient; makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:31.675; org.apache.solr.cloud.AbstractZkTestCase; put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:31.677; org.apache.solr.common.cloud.SolrZkClient; makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:31.696; org.apache.solr.cloud.AbstractZkTestCase; put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:31.697; org.apache.solr.common.cloud.SolrZkClient; makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:31.705; org.apache.solr.cloud.AbstractZkTestCase; put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:31.706; org.apache.solr.common.cloud.SolrZkClient; makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.099; org.eclipse.jetty.server.Server; jetty-8.1.8.v20121106
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.105; org.eclipse.jetty.server.AbstractConnector; Started SocketConnector@127.0.0.1:64777
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.106; org.apache.solr.servlet.SolrDispatchFilter; SolrDispatchFilter.init()
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.107; org.apache.solr.core.SolrResourceLoader; JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.107; org.apache.solr.core.SolrResourceLoader; using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1365823171734
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.108; org.apache.solr.core.CoreContainer$Initializer; looking for solr config file: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1365823171734/solr.xml
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.108; org.apache.solr.core.CoreContainer; New CoreContainer 1102368586
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.109; org.apache.solr.core.CoreContainer; Loading CoreContainer using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1365823171734/'
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.110; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1365823171734/'
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.199; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting socketTimeout to: 120000
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.200; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting urlScheme to: http://
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.201; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting connTimeout to: 15000
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.201; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maxConnectionsPerHost to: 20
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.202; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting corePoolSize to: 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.202; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maximumPoolSize to: 2147483647
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.203; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maxThreadIdleTime to: 5
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.203; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting sizeOfQueue to: -1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.204; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting fairnessPolicy to: false
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.204; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.228; org.apache.solr.core.CoreContainer; Registering Log Listener
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.259; org.apache.solr.core.CoreContainer; Zookeeper client=127.0.0.1:64774/solr
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.260; org.apache.solr.cloud.ZkController; zkHost includes chroot
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.261; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.265; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@727ec6d name:ZooKeeperConnection Watcher:127.0.0.1:64774 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.266; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.315; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.332; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.335; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@44003971 name:ZooKeeperConnection Watcher:127.0.0.1:64774/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.335; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.368; org.apache.solr.common.cloud.SolrZkClient; makePath: /overseer/queue
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.399; org.apache.solr.common.cloud.SolrZkClient; makePath: /overseer/collection-queue-work
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.407; org.apache.solr.common.cloud.SolrZkClient; makePath: /live_nodes
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.497; org.apache.solr.cloud.ZkController; Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64777_id_fe%2Fmn
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.500; org.apache.solr.common.cloud.SolrZkClient; makePath: /live_nodes/127.0.0.1:64777_id_fe%2Fmn
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.503; org.apache.solr.common.cloud.SolrZkClient; makePath: /overseer_elect/election
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.543; org.apache.solr.common.cloud.SolrZkClient; makePath: /overseer_elect/leader
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.547; org.apache.solr.cloud.Overseer; Overseer (id=89510587342061571-127.0.0.1:64777_id_fe%2Fmn-n_0000000000) starting
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.579; org.apache.solr.common.cloud.SolrZkClient; makePath: /overseer/queue-work
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.611; org.apache.solr.cloud.OverseerCollectionProcessor; Process current queue of collection creations
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.611; org.apache.solr.common.cloud.SolrZkClient; makePath: /clusterstate.json
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.664; org.apache.solr.common.cloud.SolrZkClient; makePath: /aliases.json
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.667; org.apache.solr.common.cloud.ZkStateReader; Updating cluster state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.669; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Starting to work on the main queue
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.701; org.apache.solr.core.CoreContainer; Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1365823171734/collection1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.702; org.apache.solr.cloud.ZkController; Check for collection zkNode:control_collection
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.702; org.apache.solr.cloud.ZkController; Collection zkNode exists
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.703; org.apache.solr.cloud.ZkController; Load collection config from:/collections/control_collection
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.704; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1365823171734/collection1/'
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.706; org.apache.solr.core.SolrResourceLoader; Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-controljetty-1365823171734/collection1/lib/classes/' to classloader
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.706; org.apache.solr.core.SolrResourceLoader; Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-controljetty-1365823171734/collection1/lib/README' to classloader
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.776; org.apache.solr.core.SolrConfig; Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.878; org.apache.solr.core.SolrConfig; Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:32.980; org.apache.solr.schema.IndexSchema; Reading Solr Schema from schema.xml
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:33.013; org.apache.solr.schema.IndexSchema; Schema name=test
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:33.801; org.apache.solr.schema.OpenExchangeRatesOrgProvider; Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:33.817; org.apache.solr.schema.IndexSchema; default search field in schema is text
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:33.821; org.apache.solr.schema.IndexSchema; unique key field: id
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:33.836; org.apache.solr.schema.FileExchangeRateProvider; Reloading exchange rates from file currency.xml
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:33.850; org.apache.solr.schema.FileExchangeRateProvider; Reloading exchange rates from file currency.xml
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:33.876; org.apache.solr.schema.OpenExchangeRatesOrgProvider; Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   1> WARN  - 2013-04-13 03:19:33.877; org.apache.solr.schema.OpenExchangeRatesOrgProvider$OpenExchangeRates; Unknown key IMPORTANT NOTE
[junit4:junit4]   1> WARN  - 2013-04-13 03:19:33.878; org.apache.solr.schema.OpenExchangeRatesOrgProvider$OpenExchangeRates; Expected key, got STRING
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:33.878; org.apache.solr.schema.OpenExchangeRatesOrgProvider; Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   1> WARN  - 2013-04-13 03:19:33.879; org.apache.solr.schema.OpenExchangeRatesOrgProvider$OpenExchangeRates; Unknown key IMPORTANT NOTE
[junit4:junit4]   1> WARN  - 2013-04-13 03:19:33.880; org.apache.solr.schema.OpenExchangeRatesOrgProvider$OpenExchangeRates; Expected key, got STRING
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:33.880; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:33.881; org.apache.solr.core.SolrCore; [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1365823171734/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1365823171094/control/data/
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:33.881; org.apache.solr.core.JmxMonitoredMap; JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@4b2241f8
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:33.881; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:33.882; org.apache.solr.core.CachingDirectoryFactory; return new directory for ./org.apache.solr.cloud.RecoveryZkTest-1365823171094/control/data
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:33.883; org.apache.solr.core.SolrCore; New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1365823171094/control/data/index/
[junit4:junit4]   1> WARN  - 2013-04-13 03:19:33.883; org.apache.solr.core.SolrCore; [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1365823171094/control/data/index' doesn't exist. Creating new index...
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:33.883; org.apache.solr.core.CachingDirectoryFactory; return new directory for ./org.apache.solr.cloud.RecoveryZkTest-1365823171094/control/data/index
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:33.888; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@9e1af64 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6a184d4f),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:33.888; org.apache.solr.core.SolrDeletionPolicy; newest commit = 1[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:33.891; org.apache.solr.update.processor.UpdateRequestProcessorChain; inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:33.892; org.apache.solr.update.processor.UpdateRequestProcessorChain; inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:33.893; org.apache.solr.update.processor.UpdateRequestProcessorChain; inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:33.893; org.apache.solr.core.RequestHandlers; created standard: solr.StandardRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:33.894; org.apache.solr.core.RequestHandlers; created /get: solr.RealTimeGetHandler
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:33.894; org.apache.solr.core.RequestHandlers; adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:33.895; org.apache.solr.core.RequestHandlers; created /replication: solr.ReplicationHandler
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:33.895; org.apache.solr.core.RequestHandlers; created /update: solr.UpdateRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:33.896; org.apache.solr.core.RequestHandlers; created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:33.910; org.apache.solr.handler.loader.XMLLoader; xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:33.917; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@33227ce main
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:33.918; org.apache.solr.update.UpdateHandler; Clearing tlog files, tlogDir=./org.apache.solr.cloud.RecoveryZkTest-1365823171094/control/data/tlog
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:33.919; org.apache.solr.update.CommitTracker; Hard AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:33.919; org.apache.solr.update.CommitTracker; Soft AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:33.922; org.apache.solr.core.SolrCore; [collection1] Registered new searcher Searcher@33227ce main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:33.924; org.apache.solr.cloud.ZkController; publishing core=collection1 state=down
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:33.925; org.apache.solr.cloud.ZkController; numShards not found on descriptor - reading it from system property
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:34.173; org.apache.solr.common.cloud.ZkStateReader; Updating cloud state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:34.174; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Update state numShards=1 message={
[junit4:junit4]   1>   "operation":"state",
[junit4:junit4]   1>   "core_node_name":null,
[junit4:junit4]   1>   "numShards":"1",
[junit4:junit4]   1>   "shard":null,
[junit4:junit4]   1>   "roles":null,
[junit4:junit4]   1>   "state":"down",
[junit4:junit4]   1>   "shard_state":"active",
[junit4:junit4]   1>   "core":"collection1",
[junit4:junit4]   1>   "collection":"control_collection",
[junit4:junit4]   1>   "shard_range":null,
[junit4:junit4]   1>   "node_name":"127.0.0.1:64777_id_fe%2Fmn",
[junit4:junit4]   1>   "base_url":"http://127.0.0.1:64777/id_fe/mn"}
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:34.175; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Create collection control_collection with numShards 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:34.175; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Assigning new node to shard=shard1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:34.217; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:34.931; org.apache.solr.core.CoreContainer; registering core: collection1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:34.931; org.apache.solr.cloud.ZkController; Register replica - core:collection1 address:http://127.0.0.1:64777/id_fe/mn collection:control_collection shard:shard1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:34.932; org.apache.solr.common.cloud.SolrZkClient; makePath: /collections/control_collection/leader_elect/shard1/election
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:34.995; org.apache.solr.cloud.ShardLeaderElectionContext; Running the leader process.
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:35.032; org.apache.solr.cloud.ShardLeaderElectionContext; Enough replicas found to continue.
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:35.033; org.apache.solr.cloud.ShardLeaderElectionContext; I may be the new leader - try and sync
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:35.033; org.apache.solr.cloud.SyncStrategy; Sync replicas to http://127.0.0.1:64777/id_fe/mn/collection1/
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:35.033; org.apache.solr.cloud.SyncStrategy; Sync Success - now sync replicas to me
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:35.034; org.apache.solr.cloud.SyncStrategy; http://127.0.0.1:64777/id_fe/mn/collection1/ has no replicas
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:35.034; org.apache.solr.cloud.ShardLeaderElectionContext; I am the new leader: http://127.0.0.1:64777/id_fe/mn/collection1/
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:35.034; org.apache.solr.common.cloud.SolrZkClient; makePath: /collections/control_collection/leaders/shard1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:35.725; org.apache.solr.common.cloud.ZkStateReader; Updating cloud state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:35.831; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:35.952; org.apache.solr.cloud.ZkController; We are http://127.0.0.1:64777/id_fe/mn/collection1/ and leader is http://127.0.0.1:64777/id_fe/mn/collection1/
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:35.952; org.apache.solr.cloud.ZkController; No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:64777/id_fe/mn
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:35.953; org.apache.solr.cloud.ZkController; I am the leader, no recovery necessary
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:35.953; org.apache.solr.cloud.ZkController; publishing core=collection1 state=active
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:35.953; org.apache.solr.cloud.ZkController; numShards not found on descriptor - reading it from system property
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:35.963; org.apache.solr.common.cloud.ZkStateReader; Updating cloud state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:35.964; org.apache.solr.servlet.SolrDispatchFilter; user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:35.965; org.apache.solr.servlet.SolrDispatchFilter; SolrDispatchFilter.init() done
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:35.966; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:36.009; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:36.031; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:36.034; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@3d58a6e9 name:ZooKeeperConnection Watcher:127.0.0.1:64774/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:36.034; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:36.035; org.apache.solr.common.cloud.ZkStateReader; Updating cluster state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:36.038; org.apache.solr.cloud.ChaosMonkey; monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:36.429; org.eclipse.jetty.server.Server; jetty-8.1.8.v20121106
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:36.433; org.eclipse.jetty.server.AbstractConnector; Started SocketConnector@127.0.0.1:64781
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:36.433; org.apache.solr.servlet.SolrDispatchFilter; SolrDispatchFilter.init()
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:36.434; org.apache.solr.core.SolrResourceLoader; JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:36.435; org.apache.solr.core.SolrResourceLoader; using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1365823176038
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:36.435; org.apache.solr.core.CoreContainer$Initializer; looking for solr config file: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1365823176038/solr.xml
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:36.436; org.apache.solr.core.CoreContainer; New CoreContainer 1759683524
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:36.436; org.apache.solr.core.CoreContainer; Loading CoreContainer using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1365823176038/'
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:36.437; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1365823176038/'
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:36.607; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting socketTimeout to: 120000
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:36.608; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting urlScheme to: http://
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:36.609; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting connTimeout to: 15000
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:36.609; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maxConnectionsPerHost to: 20
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:36.610; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting corePoolSize to: 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:36.611; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maximumPoolSize to: 2147483647
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:36.611; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maxThreadIdleTime to: 5
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:36.612; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting sizeOfQueue to: -1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:36.612; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting fairnessPolicy to: false
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:36.613; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:36.641; org.apache.solr.core.CoreContainer; Registering Log Listener
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:36.662; org.apache.solr.core.CoreContainer; Zookeeper client=127.0.0.1:64774/solr
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:36.662; org.apache.solr.cloud.ZkController; zkHost includes chroot
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:36.664; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:36.671; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@4971e139 name:ZooKeeperConnection Watcher:127.0.0.1:64774 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:36.671; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:36.685; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:36.703; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:36.709; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@35b24ff name:ZooKeeperConnection Watcher:127.0.0.1:64774/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:36.709; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:36.738; org.apache.solr.common.cloud.ZkStateReader; Updating cluster state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:37.403; org.apache.solr.common.cloud.ZkStateReader; Updating cloud state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:37.404; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Update state numShards=1 message={
[junit4:junit4]   1>   "operation":"state",
[junit4:junit4]   1>   "core_node_name":"127.0.0.1:64777_id_fe%2Fmn_collection1",
[junit4:junit4]   1>   "numShards":"1",
[junit4:junit4]   1>   "shard":"shard1",
[junit4:junit4]   1>   "roles":null,
[junit4:junit4]   1>   "state":"active",
[junit4:junit4]   1>   "shard_state":"active",
[junit4:junit4]   1>   "core":"collection1",
[junit4:junit4]   1>   "collection":"control_collection",
[junit4:junit4]   1>   "shard_range":null,
[junit4:junit4]   1>   "node_name":"127.0.0.1:64777_id_fe%2Fmn",
[junit4:junit4]   1>   "base_url":"http://127.0.0.1:64777/id_fe/mn"}
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:37.432; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:37.432; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:37.432; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:37.741; org.apache.solr.cloud.ZkController; Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64781_id_fe%2Fmn
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:37.744; org.apache.solr.common.cloud.SolrZkClient; makePath: /live_nodes/127.0.0.1:64781_id_fe%2Fmn
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:37.746; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1)
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:37.746; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1)
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:37.747; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (2)
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:37.747; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:37.748; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (2)
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:37.748; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (2)
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:37.789; org.apache.solr.core.CoreContainer; Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1365823176038/collection1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:37.790; org.apache.solr.cloud.ZkController; Check for collection zkNode:collection1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:37.790; org.apache.solr.cloud.ZkController; Collection zkNode exists
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:37.791; org.apache.solr.cloud.ZkController; Load collection config from:/collections/collection1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:37.792; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1365823176038/collection1/'
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:37.794; org.apache.solr.core.SolrResourceLoader; Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-jetty1-1365823176038/collection1/lib/README' to classloader
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:37.794; org.apache.solr.core.SolrResourceLoader; Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-jetty1-1365823176038/collection1/lib/classes/' to classloader
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:37.852; org.apache.solr.core.SolrConfig; Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:37.978; org.apache.solr.core.SolrConfig; Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:38.079; org.apache.solr.schema.IndexSchema; Reading Solr Schema from schema.xml
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:38.087; org.apache.solr.schema.IndexSchema; Schema name=test
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:38.956; org.apache.solr.schema.OpenExchangeRatesOrgProvider; Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:38.969; org.apache.solr.schema.IndexSchema; default search field in schema is text
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:38.973; org.apache.solr.schema.IndexSchema; unique key field: id
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:38.989; org.apache.solr.schema.FileExchangeRateProvider; Reloading exchange rates from file currency.xml
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:38.994; org.apache.solr.schema.FileExchangeRateProvider; Reloading exchange rates from file currency.xml
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:38.999; org.apache.solr.schema.OpenExchangeRatesOrgProvider; Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   1> WARN  - 2013-04-13 03:19:39.000; org.apache.solr.schema.OpenExchangeRatesOrgProvider$OpenExchangeRates; Unknown key IMPORTANT NOTE
[junit4:junit4]   1> WARN  - 2013-04-13 03:19:39.001; org.apache.solr.schema.OpenExchangeRatesOrgProvider$OpenExchangeRates; Expected key, got STRING
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:39.001; org.apache.solr.schema.OpenExchangeRatesOrgProvider; Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   1> WARN  - 2013-04-13 03:19:39.002; org.apache.solr.schema.OpenExchangeRatesOrgProvider$OpenExchangeRates; Unknown key IMPORTANT NOTE
[junit4:junit4]   1> WARN  - 2013-04-13 03:19:39.003; org.apache.solr.schema.OpenExchangeRatesOrgProvider$OpenExchangeRates; Expected key, got STRING
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:39.003; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:39.003; org.apache.solr.core.SolrCore; [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1365823176038/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1365823171094/jetty1/
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:39.004; org.apache.solr.core.JmxMonitoredMap; JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@4b2241f8
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:39.005; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:39.005; org.apache.solr.core.CachingDirectoryFactory; return new directory for ./org.apache.solr.cloud.RecoveryZkTest-1365823171094/jetty1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:39.006; org.apache.solr.core.SolrCore; New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1365823171094/jetty1/index/
[junit4:junit4]   1> WARN  - 2013-04-13 03:19:39.006; org.apache.solr.core.SolrCore; [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1365823171094/jetty1/index' doesn't exist. Creating new index...
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:39.006; org.apache.solr.core.CachingDirectoryFactory; return new directory for ./org.apache.solr.cloud.RecoveryZkTest-1365823171094/jetty1/index
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:39.010; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@30729af2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@38d9689d),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:39.010; org.apache.solr.core.SolrDeletionPolicy; newest commit = 1[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:39.032; org.apache.solr.update.processor.UpdateRequestProcessorChain; inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:39.032; org.apache.solr.update.processor.UpdateRequestProcessorChain; inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:39.033; org.apache.solr.update.processor.UpdateRequestProcessorChain; inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:39.034; org.apache.solr.core.RequestHandlers; created standard: solr.StandardRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:39.035; org.apache.solr.core.RequestHandlers; created /get: solr.RealTimeGetHandler
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:39.035; org.apache.solr.core.RequestHandlers; adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:39.035; org.apache.solr.core.RequestHandlers; created /replication: solr.ReplicationHandler
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:39.036; org.apache.solr.core.RequestHandlers; created /update: solr.UpdateRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:39.037; org.apache.solr.core.RequestHandlers; created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:39.050; org.apache.solr.handler.loader.XMLLoader; xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:39.062; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@1942ddfc main
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:39.063; org.apache.solr.update.UpdateHandler; Clearing tlog files, tlogDir=./org.apache.solr.cloud.RecoveryZkTest-1365823171094/jetty1/tlog
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:39.064; org.apache.solr.update.CommitTracker; Hard AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:39.064; org.apache.solr.update.CommitTracker; Soft AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:39.069; org.apache.solr.core.SolrCore; [collection1] Registered new searcher Searcher@1942ddfc main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:39.071; org.apache.solr.cloud.ZkController; publishing core=collection1 state=down
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:39.071; org.apache.solr.cloud.ZkController; numShards not found on descriptor - reading it from system property
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:40.441; org.apache.solr.common.cloud.ZkStateReader; Updating cloud state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:40.442; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Update state numShards=1 message={
[junit4:junit4]   1>   "operation":"state",
[junit4:junit4]   1>   "core_node_name":null,
[junit4:junit4]   1>   "numShards":"1",
[junit4:junit4]   1>   "shard":null,
[junit4:junit4]   1>   "roles":null,
[junit4:junit4]   1>   "state":"down",
[junit4:junit4]   1>   "shard_state":"active",
[junit4:junit4]   1>   "core":"collection1",
[junit4:junit4]   1>   "collection":"collection1",
[junit4:junit4]   1>   "shard_range":null,
[junit4:junit4]   1>   "node_name":"127.0.0.1:64781_id_fe%2Fmn",
[junit4:junit4]   1>   "base_url":"http://127.0.0.1:64781/id_fe/mn"}
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:40.442; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Create collection collection1 with numShards 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:40.442; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Assigning new node to shard=shard1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:40.462; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:40.462; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:40.462; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:41.075; org.apache.solr.core.CoreContainer; registering core: collection1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:41.075; org.apache.solr.cloud.ZkController; Register replica - core:collection1 address:http://127.0.0.1:64781/id_fe/mn collection:collection1 shard:shard1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:41.076; org.apache.solr.common.cloud.SolrZkClient; makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:41.142; org.apache.solr.cloud.ShardLeaderElectionContext; Running the leader process.
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:41.156; org.apache.solr.cloud.ShardLeaderElectionContext; Enough replicas found to continue.
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:41.156; org.apache.solr.cloud.ShardLeaderElectionContext; I may be the new leader - try and sync
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:41.156; org.apache.solr.cloud.SyncStrategy; Sync replicas to http://127.0.0.1:64781/id_fe/mn/collection1/
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:41.157; org.apache.solr.cloud.SyncStrategy; Sync Success - now sync replicas to me
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:41.157; org.apache.solr.cloud.SyncStrategy; http://127.0.0.1:64781/id_fe/mn/collection1/ has no replicas
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:41.157; org.apache.solr.cloud.ShardLeaderElectionContext; I am the new leader: http://127.0.0.1:64781/id_fe/mn/collection1/
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:41.158; org.apache.solr.common.cloud.SolrZkClient; makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:41.967; org.apache.solr.common.cloud.ZkStateReader; Updating cloud state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:42.004; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:42.004; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:42.004; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:42.048; org.apache.solr.cloud.ZkController; We are http://127.0.0.1:64781/id_fe/mn/collection1/ and leader is http://127.0.0.1:64781/id_fe/mn/collection1/
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:42.048; org.apache.solr.cloud.ZkController; No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:64781/id_fe/mn
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:42.048; org.apache.solr.cloud.ZkController; I am the leader, no recovery necessary
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:42.049; org.apache.solr.cloud.ZkController; publishing core=collection1 state=active
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:42.049; org.apache.solr.cloud.ZkController; numShards not found on descriptor - reading it from system property
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:42.052; org.apache.solr.common.cloud.ZkStateReader; Updating cloud state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:42.053; org.apache.solr.servlet.SolrDispatchFilter; user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:42.054; org.apache.solr.servlet.SolrDispatchFilter; SolrDispatchFilter.init() done
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:42.055; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:42.375; org.eclipse.jetty.server.Server; jetty-8.1.8.v20121106
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:42.379; org.eclipse.jetty.server.AbstractConnector; Started SocketConnector@127.0.0.1:64785
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:42.380; org.apache.solr.servlet.SolrDispatchFilter; SolrDispatchFilter.init()
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:42.380; org.apache.solr.core.SolrResourceLoader; JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:42.381; org.apache.solr.core.SolrResourceLoader; using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1365823182073
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:42.382; org.apache.solr.core.CoreContainer$Initializer; looking for solr config file: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1365823182073/solr.xml
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:42.382; org.apache.solr.core.CoreContainer; New CoreContainer 754478653
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:42.383; org.apache.solr.core.CoreContainer; Loading CoreContainer using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1365823182073/'
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:42.383; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1365823182073/'
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:42.458; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting socketTimeout to: 120000
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:42.459; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting urlScheme to: http://
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:42.460; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting connTimeout to: 15000
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:42.460; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maxConnectionsPerHost to: 20
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:42.461; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting corePoolSize to: 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:42.461; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maximumPoolSize to: 2147483647
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:42.462; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maxThreadIdleTime to: 5
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:42.463; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting sizeOfQueue to: -1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:42.463; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting fairnessPolicy to: false
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:42.464; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:42.491; org.apache.solr.core.CoreContainer; Registering Log Listener
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:42.513; org.apache.solr.core.CoreContainer; Zookeeper client=127.0.0.1:64774/solr
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:42.514; org.apache.solr.cloud.ZkController; zkHost includes chroot
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:42.515; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:42.524; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@1f44dc1a name:ZooKeeperConnection Watcher:127.0.0.1:64774 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:42.525; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:42.574; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:42.589; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:42.601; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@66490791 name:ZooKeeperConnection Watcher:127.0.0.1:64774/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:42.602; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:42.617; org.apache.solr.common.cloud.ZkStateReader; Updating cluster state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:43.513; org.apache.solr.common.cloud.ZkStateReader; Updating cloud state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:43.514; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Update state numShards=1 message={
[junit4:junit4]   1>   "operation":"state",
[junit4:junit4]   1>   "core_node_name":"127.0.0.1:64781_id_fe%2Fmn_collection1",
[junit4:junit4]   1>   "numShards":"1",
[junit4:junit4]   1>   "shard":"shard1",
[junit4:junit4]   1>   "roles":null,
[junit4:junit4]   1>   "state":"active",
[junit4:junit4]   1>   "shard_state":"active",
[junit4:junit4]   1>   "core":"collection1",
[junit4:junit4]   1>   "collection":"collection1",
[junit4:junit4]   1>   "shard_range":null,
[junit4:junit4]   1>   "node_name":"127.0.0.1:64781_id_fe%2Fmn",
[junit4:junit4]   1>   "base_url":"http://127.0.0.1:64781/id_fe/mn"}
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:43.533; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:43.533; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:43.533; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:43.533; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:43.621; org.apache.solr.cloud.ZkController; Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64785_id_fe%2Fmn
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:43.627; org.apache.solr.common.cloud.SolrZkClient; makePath: /live_nodes/127.0.0.1:64785_id_fe%2Fmn
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:43.649; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:43.649; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:43.649; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:43.649; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (3)
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:43.650; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:43.658; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (3)
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:43.658; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (3)
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:43.658; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (3)
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:43.678; org.apache.solr.core.CoreContainer; Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1365823182073/collection1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:43.678; org.apache.solr.cloud.ZkController; Check for collection zkNode:collection1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:43.679; org.apache.solr.cloud.ZkController; Collection zkNode exists
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:43.679; org.apache.solr.cloud.ZkController; Load collection config from:/collections/collection1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:43.680; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1365823182073/collection1/'
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:43.682; org.apache.solr.core.SolrResourceLoader; Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-jetty2-1365823182073/collection1/lib/README' to classloader
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:43.683; org.apache.solr.core.SolrResourceLoader; Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-jetty2-1365823182073/collection1/lib/classes/' to classloader
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:43.746; org.apache.solr.core.SolrConfig; Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:43.809; org.apache.solr.core.SolrConfig; Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:43.911; org.apache.solr.schema.IndexSchema; Reading Solr Schema from schema.xml
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:43.919; org.apache.solr.schema.IndexSchema; Schema name=test
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:44.919; org.apache.solr.schema.OpenExchangeRatesOrgProvider; Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:44.932; org.apache.solr.schema.IndexSchema; default search field in schema is text
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:44.936; org.apache.solr.schema.IndexSchema; unique key field: id
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:44.964; org.apache.solr.schema.FileExchangeRateProvider; Reloading exchange rates from file currency.xml
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:44.970; org.apache.solr.schema.FileExchangeRateProvider; Reloading exchange rates from file currency.xml
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:44.986; org.apache.solr.schema.OpenExchangeRatesOrgProvider; Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   1> WARN  - 2013-04-13 03:19:44.987; org.apache.solr.schema.OpenExchangeRatesOrgProvider$OpenExchangeRates; Unknown key IMPORTANT NOTE
[junit4:junit4]   1> WARN  - 2013-04-13 03:19:44.988; org.apache.solr.schema.OpenExchangeRatesOrgProvider$OpenExchangeRates; Expected key, got STRING
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:44.989; org.apache.solr.schema.OpenExchangeRatesOrgProvider; Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   1> WARN  - 2013-04-13 03:19:44.990; org.apache.solr.schema.OpenExchangeRatesOrgProvider$OpenExchangeRates; Unknown key IMPORTANT NOTE
[junit4:junit4]   1> WARN  - 2013-04-13 03:19:44.990; org.apache.solr.schema.OpenExchangeRatesOrgProvider$OpenExchangeRates; Expected key, got STRING
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:44.990; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:44.991; org.apache.solr.core.SolrCore; [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1365823182073/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1365823171094/jetty2/
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:44.991; org.apache.solr.core.JmxMonitoredMap; JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@4b2241f8
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:44.992; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:44.993; org.apache.solr.core.CachingDirectoryFactory; return new directory for ./org.apache.solr.cloud.RecoveryZkTest-1365823171094/jetty2
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:44.993; org.apache.solr.core.SolrCore; New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1365823171094/jetty2/index/
[junit4:junit4]   1> WARN  - 2013-04-13 03:19:44.993; org.apache.solr.core.SolrCore; [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1365823171094/jetty2/index' doesn't exist. Creating new index...
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:44.994; org.apache.solr.core.CachingDirectoryFactory; return new directory for ./org.apache.solr.cloud.RecoveryZkTest-1365823171094/jetty2/index
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:44.997; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1a8d3227 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d52b664),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:44.998; org.apache.solr.core.SolrDeletionPolicy; newest commit = 1[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:45.002; org.apache.solr.update.processor.UpdateRequestProcessorChain; inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:45.002; org.apache.solr.update.processor.UpdateRequestProcessorChain; inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:45.003; org.apache.solr.update.processor.UpdateRequestProcessorChain; inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:45.004; org.apache.solr.core.RequestHandlers; created standard: solr.StandardRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:45.005; org.apache.solr.core.RequestHandlers; created /get: solr.RealTimeGetHandler
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:45.005; org.apache.solr.core.RequestHandlers; adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:45.005; org.apache.solr.core.RequestHandlers; created /replication: solr.ReplicationHandler
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:45.006; org.apache.solr.core.RequestHandlers; created /update: solr.UpdateRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:45.006; org.apache.solr.core.RequestHandlers; created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:45.020; org.apache.solr.handler.loader.XMLLoader; xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:45.040; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@2f9e4b28 main
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:45.040; org.apache.solr.update.UpdateHandler; Clearing tlog files, tlogDir=./org.apache.solr.cloud.RecoveryZkTest-1365823171094/jetty2/tlog
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:45.042; org.apache.solr.update.CommitTracker; Hard AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:45.042; org.apache.solr.update.CommitTracker; Soft AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:45.047; org.apache.solr.core.SolrCore; [collection1] Registered new searcher Searcher@2f9e4b28 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:45.049; org.apache.solr.cloud.ZkController; publishing core=collection1 state=down
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:45.050; org.apache.solr.cloud.ZkController; numShards not found on descriptor - reading it from system property
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:46.543; org.apache.solr.common.cloud.ZkStateReader; Updating cloud state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:46.545; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Update state numShards=1 message={
[junit4:junit4]   1>   "operation":"state",
[junit4:junit4]   1>   "core_node_name":null,
[junit4:junit4]   1>   "numShards":"1",
[junit4:junit4]   1>   "shard":null,
[junit4:junit4]   1>   "roles":null,
[junit4:junit4]   1>   "state":"down",
[junit4:junit4]   1>   "shard_state":"active",
[junit4:junit4]   1>   "core":"collection1",
[junit4:junit4]   1>   "collection":"collection1",
[junit4:junit4]   1>   "shard_range":null,
[junit4:junit4]   1>   "node_name":"127.0.0.1:64785_id_fe%2Fmn",
[junit4:junit4]   1>   "base_url":"http://127.0.0.1:64785/id_fe/mn"}
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:46.545; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Collection already exists with numShards=1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:46.545; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Assigning new node to shard=shard1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:46.641; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:46.641; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:46.641; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:46.641; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.055; org.apache.solr.core.CoreContainer; registering core: collection1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.055; org.apache.solr.cloud.ZkController; Register replica - core:collection1 address:http://127.0.0.1:64785/id_fe/mn collection:collection1 shard:shard1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.062; org.apache.solr.cloud.ZkController; We are http://127.0.0.1:64785/id_fe/mn/collection1/ and leader is http://127.0.0.1:64781/id_fe/mn/collection1/
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.062; org.apache.solr.cloud.ZkController; No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:64785/id_fe/mn
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.062; org.apache.solr.cloud.ZkController; Core needs to recover:collection1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.063; org.apache.solr.update.DefaultSolrCoreState; Running recovery - first canceling any ongoing recovery
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.064; org.apache.solr.cloud.RecoveryStrategy; Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.064; org.apache.solr.common.cloud.ZkStateReader; Updating cloud state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.065; org.apache.solr.cloud.RecoveryStrategy; ###### startupVersions=[]
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.065; org.apache.solr.cloud.ZkController; publishing core=collection1 state=recovering
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.066; org.apache.solr.cloud.ZkController; numShards not found on descriptor - reading it from system property
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.066; org.apache.solr.servlet.SolrDispatchFilter; user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.067; org.apache.solr.servlet.SolrDispatchFilter; SolrDispatchFilter.init() done
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.067; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.090; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.090; org.apache.solr.common.cloud.ZkStateReader; Updating cloud state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.104; org.apache.solr.handler.admin.CoreAdminHandler; Going to wait for coreNodeName: 127.0.0.1:64785_id_fe%2Fmn_collection1, state: recovering, checkLive: true, onlyIfLeader: true
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.135; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@9e1af64 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6a184d4f),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.136; org.apache.solr.core.SolrDeletionPolicy; newest commit = 1[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.155; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[1 (1432169414251249664)]} 0 42
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.155; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10001 (1432169414252298240)]} 0 41
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.186; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@30729af2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@38d9689d),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.187; org.apache.solr.core.SolrDeletionPolicy; newest commit = 1[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.198; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10001 (1432169414323601408)]} 0 16
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.198; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[1 (1432169414323601409)]} 0 16
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.243; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10002 (1432169414385467392)]} 0 2
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.243; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[2 (1432169414385467393)]} 0 2
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.247; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10002 (1432169414389661696)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.248; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[2 (1432169414389661697)]} 0 2
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.295; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10001 (-1432169414441041920)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.296; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[1 (-1432169414441041921)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.300; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[1 (-1432169414445236224)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.300; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10001 (-1432169414445236225)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.304; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[3 (1432169414449430528)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.304; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10003 (1432169414449430529)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.309; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[3 (1432169414453624832)]} 0 2
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.309; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10003 (1432169414453624833)]} 0 2
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.387; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[2 (-1432169414537510912)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.388; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10002 (-1432169414537510913)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.391; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[2 (-1432169414540656640)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.392; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10002 (-1432169414541705216)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.394; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[4 (1432169414543802368)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.397; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10004 (1432169414545899520)]} 0 2
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.398; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[4 (1432169414547996672)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.401; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10004 (1432169414551142400)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.442; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[3 (-1432169414594134016)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.445; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[3 (-1432169414598328320)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.450; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[5 (1432169414601474048)]} 0 2
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.454; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[5 (1432169414606716928)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.458; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10003 (-1432169414611959808)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.461; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10003 (-1432169414615105536)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.465; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10005 (1432169414618251264)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.469; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10005 (1432169414622445568)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.520; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[6 (1432169414675922944)]} 0 2
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.524; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[6 (1432169414680117248)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.534; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10006 (1432169414690603008)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.538; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10006 (1432169414694797312)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.539; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[7 (1432169414695845888)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.543; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[7 (1432169414700040192)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.554; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10007 (1432169414711574528)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.559; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10007 (1432169414716817408)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.582; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[6 (-1432169414741983232)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.585; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[6 (-1432169414745128960)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.589; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[8 (1432169414748274688)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.593; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[8 (1432169414752468992)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.598; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10006 (-1432169414758760448)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.601; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10006 (-1432169414761906176)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.605; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10008 (1432169414765051904)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.609; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10008 (1432169414769246208)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.678; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[7 (-1432169414841597952)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.681; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[7 (-1432169414845792256)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.685; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[9 (1432169414848937984)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.689; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[9 (1432169414853132288)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.693; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10007 (-1432169414858375168)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.696; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10007 (-1432169414861520896)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.700; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10009 (1432169414864666624)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.704; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10009 (1432169414868860928)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.744; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10 (1432169414910803968)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.748; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10 (1432169414914998272)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.759; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10010 (1432169414926532608)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.763; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10010 (1432169414930726912)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.825; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[11 (1432169414996787200)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.830; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[11 (1432169415000981504)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.839; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10011 (1432169415010418688)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.843; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10011 (1432169415014612992)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.898; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[11 (-1432169415072284672)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.901; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[11 (-1432169415076478976)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.905; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[12 (1432169415079624704)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.909; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[12 (1432169415083819008)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.910; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10011 (-1432169415085916160)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.913; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10011 (-1432169415089061888)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.917; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10012 (1432169415092207616)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.921; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10012 (1432169415096401920)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.951; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[12 (-1432169415128907776)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.954; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[12 (-1432169415132053504)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.958; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[13 (1432169415135199232)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.962; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[13 (1432169415139393536)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.963; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10012 (-1432169415141490688)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.966; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10012 (-1432169415144636416)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.970; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10013 (1432169415147782144)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:47.974; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10013 (1432169415151976448)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.055; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[14 (1432169415236911104)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.059; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[14 (1432169415241105408)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.067; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10014 (1432169415249494016)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.071; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10014 (1432169415253688320)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.138; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[15 (1432169415323942912)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.142; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[15 (1432169415328137216)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.147; org.apache.solr.common.cloud.ZkStateReader; Updating cloud state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.148; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Update state numShards=1 message={
[junit4:junit4]   1>   "operation":"state",
[junit4:junit4]   1>   "core_node_name":"127.0.0.1:64785_id_fe%2Fmn_collection1",
[junit4:junit4]   1>   "numShards":"1",
[junit4:junit4]   1>   "shard":"shard1",
[junit4:junit4]   1>   "roles":null,
[junit4:junit4]   1>   "state":"recovering",
[junit4:junit4]   1>   "shard_state":"active",
[junit4:junit4]   1>   "core":"collection1",
[junit4:junit4]   1>   "collection":"collection1",
[junit4:junit4]   1>   "shard_range":null,
[junit4:junit4]   1>   "node_name":"127.0.0.1:64785_id_fe%2Fmn",
[junit4:junit4]   1>   "base_url":"http://127.0.0.1:64785/id_fe/mn"}
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.150; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10015 (1432169415336525824)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.156; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10015 (1432169415342817280)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.166; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[13 (-1432169415354351616)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.169; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[13 (-1432169415357497344)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.172; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.172; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.172; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.172; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.174; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[16 (1432169415361691648)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.180; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10013 (-1432169415369031680)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.193; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1a8d3227 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d52b664),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.194; org.apache.solr.core.SolrDeletionPolicy; newest commit = 1[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.196; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10013 (-1432169415372177408)]} 0 3
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.196; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[16 (1432169415365885952)]} 0 6
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.196; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10013 (-1432169415372177408)]} 0 13
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.197; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[16 (1432169415365885952)]} 0 20
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.201; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10016 (1432169415390003200)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.208; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10016 (1432169415394197504)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.209; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10016 (1432169415394197504)]} 0 5
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.295; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[14 (-1432169415489617920)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.302; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[14 (-1432169415492763648)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.302; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[14 (-1432169415492763648)]} 0 4
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.305; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10014 (-1432169415500103680)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.306; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[17 (1432169415500103681)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.311; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10014 (-1432169415503249408)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.312; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10014 (-1432169415503249408)]} 0 4
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.314; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[17 (1432169415504297984)]} 0 2
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.314; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[17 (1432169415504297984)]} 0 5
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.316; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10017 (1432169415510589440)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.323; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10017 (1432169415514783744)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.324; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10017 (1432169415514783744)]} 0 5
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.332; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[18 (1432169415527366656)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.339; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[18 (1432169415531560960)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.340; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[18 (1432169415531560960)]} 0 5
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.341; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10018 (1432169415536803840)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.348; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10018 (1432169415540998144)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.349; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10018 (1432169415540998144)]} 0 5
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.387; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[15 (-1432169415586086912)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.392; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[15 (-1432169415589232640)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.393; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[15 (-1432169415589232640)]} 0 3
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.395; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10015 (-1432169415594475520)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.397; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[19 (1432169415595524096)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.400; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10015 (-1432169415597621248)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.401; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10015 (-1432169415597621248)]} 0 3
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.404; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[19 (1432169415599718400)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.405; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[19 (1432169415599718400)]} 0 5
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.405; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10019 (1432169415603912704)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.412; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10019 (1432169415608107008)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.413; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10019 (1432169415608107008)]} 0 5
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.415; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[18 (-1432169415615447040)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.422; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[18 (-1432169415618592768)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.422; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10018 (-1432169415622787072)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.422; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[18 (-1432169415618592768)]} 0 4
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.427; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[20 (1432169415626981376)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.427; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10018 (-1432169415625932800)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.428; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10018 (-1432169415625932800)]} 0 3
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.432; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10020 (1432169415632224256)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.434; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[20 (1432169415631175680)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.435; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[20 (1432169415631175680)]} 0 5
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.439; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10020 (1432169415636418560)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.440; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10020 (1432169415636418560)]} 0 5
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.529; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[21 (1432169415733936128)]} 0 2
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.534; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10021 (1432169415739179008)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.538; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[21 (1432169415738130432)]} 0 2
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.539; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[21 (1432169415738130432)]} 0 7
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.541; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10021 (1432169415743373312)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.542; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10021 (1432169415743373312)]} 0 5
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.603; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[22 (1432169415811530752)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.606; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10022 (1432169415814676480)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.610; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[22 (1432169415815725056)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.611; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[22 (1432169415815725056)]} 0 5
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.613; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10022 (1432169415818870784)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.614; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10022 (1432169415818870784)]} 0 5
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.663; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[23 (1432169415874445312)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.667; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10023 (1432169415878639616)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.671; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[23 (1432169415878639616)]} 0 2
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.672; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[23 (1432169415878639616)]} 0 6
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.674; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10023 (1432169415882833920)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.675; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10023 (1432169415882833920)]} 0 5
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.711; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[24 (1432169415924776960)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.714; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10024 (1432169415927922688)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.718; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[24 (1432169415928971264)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.719; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[24 (1432169415928971264)]} 0 5
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.722; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10024 (1432169415932116992)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.723; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10024 (1432169415932116992)]} 0 6
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.822; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[24 (-1432169416042217472)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.826; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10024 (-1432169416046411776)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.829; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[24 (-1432169416045363200)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.832; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10024 (-1432169416049557504)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.833; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10024 (-1432169416049557504)]} 0 4
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.837; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[24 (-1432169416045363200)]} 0 12
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.838; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10025 (1432169416056897536)]} 0 2
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.840; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[25 (1432169416060043264)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.845; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10025 (1432169416062140416)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.846; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10025 (1432169416062140416)]} 0 5
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.847; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[25 (1432169416064237568)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.848; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[25 (1432169416064237568)]} 0 5
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.935; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10025 (-1432169416160706560)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.935; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[25 (-1432169416160706561)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.941; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[25 (-1432169416163852289)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.942; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10025 (-1432169416163852288)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.942; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[25 (-1432169416163852289)]} 0 4
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.943; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10025 (-1432169416163852288)]} 0 5
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.946; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[26 (1432169416171192320)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.947; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10026 (1432169416172240896)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.953; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[26 (1432169416175386624)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.954; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[26 (1432169416175386624)]} 0 5
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.954; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10026 (1432169416176435200)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:48.955; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10026 (1432169416176435200)]} 0 5
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.033; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[27 (1432169416263467008)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.035; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10027 (1432169416264515584)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.040; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[27 (1432169416266612736)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.041; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[27 (1432169416266612736)]} 0 5
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.042; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10027 (1432169416268709888)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.046; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10027 (1432169416268709888)]} 0 8
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.106; org.apache.solr.handler.admin.CoreAdminHandler; Waited coreNodeName: 127.0.0.1:64785_id_fe%2Fmn_collection1, state: recovering, checkLive: true, onlyIfLeader: true for: 2 seconds.
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.106; org.apache.solr.cloud.ChaosMonkey; monkey: stop shard! 64785
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.106; org.apache.solr.servlet.SolrDispatchFilter; [admin] webapp=null path=/admin/cores params={coreNodeName=127.0.0.1:64785_id_fe%252Fmn_collection1&state=recovering&nodeName=127.0.0.1:64785_id_fe%252Fmn&action=PREPRECOVERY&checkLive=true&core=collection1&wt=javabin&onlyIfLeader=true&version=2} status=0 QTime=2002 
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.107; org.apache.solr.core.CoreContainer; Shutting down CoreContainer instance=754478653
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.118; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[26 (-1432169416351547392)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.122; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10026 (-1432169416356790272)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.124; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[26 (-1432169416355741696)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.125; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[26 (-1432169416355741696)]} 0 4
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.128; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10026 (-1432169416359936000)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.129; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10026 (-1432169416359936000)]} 0 4
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.129; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[28 (1432169416363081728)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.133; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10028 (1432169416367276032)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.136; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[28 (1432169416367276032)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.137; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[28 (1432169416367276032)]} 0 5
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.140; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10028 (1432169416371470336)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.141; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10028 (1432169416371470336)]} 0 5
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.219; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[29 (1432169416458502144)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.223; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10029 (1432169416461647872)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.227; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[29 (1432169416462696448)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.228; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[29 (1432169416462696448)]} 0 5
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.230; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10029 (1432169416465842176)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.231; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10029 (1432169416465842176)]} 0 5
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.234; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[30 (1432169416473182208)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.237; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10030 (1432169416476327936)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.241; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[30 (1432169416477376512)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.242; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[30 (1432169416477376512)]} 0 5
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.244; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10030 (1432169416480522240)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.245; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10030 (1432169416480522240)]} 0 5
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.290; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[31 (1432169416531902464)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.293; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10031 (1432169416536096768)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.297; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[31 (1432169416536096768)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.298; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[31 (1432169416536096768)]} 0 5
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.301; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10031 (1432169416540291072)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.302; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10031 (1432169416540291072)]} 0 5
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.396; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[32 (1432169416643051520)]} 0 2
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.399; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10032 (1432169416646197248)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.403; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[32 (1432169416647245824)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.404; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[32 (1432169416647245824)]} 0 5
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.407; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10032 (1432169416651440128)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.408; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10032 (1432169416651440128)]} 0 5
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.460; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[33 (1432169416710160384)]} 0 2
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.463; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10033 (1432169416713306112)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.468; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[33 (1432169416714354688)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.469; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[33 (1432169416714354688)]} 0 6
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.470; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10033 (1432169416717500416)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.471; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10033 (1432169416717500416)]} 0 5
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.532; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[34 (1432169416786706432)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.533; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10034 (1432169416786706433)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.539; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[34 (1432169416789852160)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.540; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[34 (1432169416789852160)]} 0 5
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.540; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10034 (1432169416790900736)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.541; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10034 (1432169416790900736)]} 0 5
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.612; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[32 (-1432169416869543936)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.614; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10032 (-1432169416871641088)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.618; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[32 (-1432169416873738240)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.619; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[32 (-1432169416873738240)]} 0 4
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.621; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10032 (-1432169416875835392)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.622; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10032 (-1432169416875835392)]} 0 5
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.623; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[35 (1432169416881078272)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.625; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10035 (1432169416883175424)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.631; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[35 (1432169416886321152)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.632; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[35 (1432169416886321152)]} 0 6
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.632; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10035 (1432169416887369728)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.633; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10035 (1432169416887369728)]} 0 5
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.691; org.apache.solr.common.cloud.ZkStateReader; Updating cloud state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.692; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Update state numShards=null message={
[junit4:junit4]   1>   "operation":"state",
[junit4:junit4]   1>   "core_node_name":"127.0.0.1:64785_id_fe%2Fmn_collection1",
[junit4:junit4]   1>   "shard":"shard1",
[junit4:junit4]   1>   "roles":null,
[junit4:junit4]   1>   "state":"down",
[junit4:junit4]   1>   "core":"collection1",
[junit4:junit4]   1>   "collection":"collection1",
[junit4:junit4]   1>   "node_name":"127.0.0.1:64785_id_fe%2Fmn",
[junit4:junit4]   1>   "base_url":"http://127.0.0.1:64785/id_fe/mn"}
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.709; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[36 (1432169416971255808)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.710; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10036 (1432169416972304384)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.715; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.715; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.715; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.715; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.717; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10036 (1432169416976498688)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.716; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={distrib.from=http://127.0.0.1:64781/id_fe/mn/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[36 (1432169416975450112)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.719; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10036 (1432169416976498688)]} 0 6
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.719; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[36 (1432169416975450112)]} 0 7
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.752; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[37 (1432169417016344576)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.752; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10037 (1432169417017393152)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.756; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[37 (1432169417020538880)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.757; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10037 (1432169417021587456)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.771; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[33 (-1432169417036267520)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.771; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10033 (-1432169417037316096)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.774; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10033 (-1432169417040461824)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.774; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[33 (-1432169417040461825)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.778; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10038 (1432169417043607553)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.778; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[38 (1432169417043607552)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.782; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10038 (1432169417047801856)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.782; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[38 (1432169417047801857)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.874; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10039 (1432169417144270848)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.874; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[39 (1432169417145319424)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.878; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10039 (1432169417148465152)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.879; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[39 (1432169417149513728)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.972; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10034 (-1432169417248079872)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.973; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[34 (-1432169417248079873)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.975; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10034 (-1432169417251225600)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.976; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[34 (-1432169417252274176)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.979; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10040 (1432169417254371328)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.980; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[40 (1432169417255419904)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.983; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10040 (1432169417258565632)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:49.984; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[40 (1432169417259614208)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.014; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10041 (1432169417291071488)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.014; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[41 (1432169417292120064)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.018; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10041 (1432169417295265792)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.019; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[41 (1432169417295265793)]} 0 2
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.100; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10035 (-1432169417381249024)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.100; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[35 (-1432169417382297600)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.103; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[35 (-1432169417385443328)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.104; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10035 (-1432169417385443329)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.107; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[42 (1432169417388589056)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.108; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10042 (1432169417389637632)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.111; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[42 (1432169417392783360)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.112; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10042 (1432169417393831936)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.180; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[36 (-1432169417465135104)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.180; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10036 (-1432169417465135105)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.183; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[36 (-1432169417469329408)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.183; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10036 (-1432169417469329409)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.187; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[43 (1432169417472475136)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.188; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10043 (1432169417473523712)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.191; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[43 (1432169417476669440)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.192; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10043 (1432169417477718016)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.285; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10037 (-1432169417576284160)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.285; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[37 (-1432169417576284161)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.290; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[37 (-1432169417581527040)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.296; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10037 (-1432169417587818496)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.307; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[44 (1432169417597255680)]} 0 2
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.308; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10044 (1432169417599352832)]} 0 2
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.327; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10044 (1432169417619275776)]} 0 2
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.327; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[44 (1432169417619275777)]} 0 2
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.392; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[45 (1432169417687433216)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.392; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10045 (1432169417687433217)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.398; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10045 (1432169417693724672)]} 0 2
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.398; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[45 (1432169417693724673)]} 0 2
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.458; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[46 (1432169417756639232)]} 0 2
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.458; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10046 (1432169417756639233)]} 0 2
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.462; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[46 (1432169417760833536)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.463; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10046 (1432169417761882112)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.549; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[38 (-1432169417853108224)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.549; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10038 (-1432169417853108225)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.552; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[38 (-1432169417856253952)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.553; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10038 (-1432169417857302528)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.557; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10047 (1432169417860448257)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.557; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[47 (1432169417860448256)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.561; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10047 (1432169417864642560)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.561; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[47 (1432169417864642561)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.575; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10048 (1432169417879322624)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.576; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[48 (1432169417880371200)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.579; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10048 (1432169417883516928)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.580; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[48 (1432169417884565504)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.621; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10049 (1432169417928605697)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.621; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[49 (1432169417928605696)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.625; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10049 (1432169417931751424)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.626; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[49 (1432169417932800000)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.661; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10039 (-1432169417970548736)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.662; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[39 (-1432169417971597312)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.664; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10039 (-1432169417973694464)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.665; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[39 (-1432169417974743040)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.668; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10050 (1432169417976840192)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.669; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[50 (1432169417977888768)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.672; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10050 (1432169417981034496)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.673; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[50 (1432169417982083072)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.774; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10040 (-1432169418089037824)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.775; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[40 (-1432169418090086400)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.778; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10040 (-1432169418092183552)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.778; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[40 (-1432169418093232128)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.782; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10051 (1432169418096377857)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.782; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[51 (1432169418096377856)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.786; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[51 (1432169418100572160)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.787; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10051 (1432169418100572161)]} 0 2
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.843; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[52 (1432169418160340993)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.843; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10052 (1432169418160340992)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.847; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[52 (1432169418164535296)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.848; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10052 (1432169418165583872)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.944; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[41 (-1432169418267295744)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.945; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10041 (-1432169418267295745)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.947; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[41 (-1432169418270441472)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.948; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10041 (-1432169418271490048)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.951; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[53 (1432169418273587200)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.952; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10053 (1432169418274635776)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.955; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[53 (1432169418277781504)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:50.956; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10053 (1432169418278830080)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.043; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[42 (-1432169418371104768)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.045; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10042 (-1432169418372153344)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.046; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[42 (-1432169418374250496)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.048; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {delete=[10042 (-1432169418376347648)]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.050; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[54 (1432169418377396224)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.052; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10054 (1432169418379493376)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.054; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[54 (1432169418381590528)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.056; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10054 (1432169418383687680)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.109; org.apache.solr.cloud.RecoveryStrategy; Attempting to PeerSync from http://127.0.0.1:64781/id_fe/mn/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.109; org.apache.solr.update.PeerSync; PeerSync: core=collection1 url=http://127.0.0.1:64785/id_fe/mn START replicas=[http://127.0.0.1:64781/id_fe/mn/collection1/] nUpdates=100
[junit4:junit4]   1> WARN  - 2013-04-13 03:19:51.110; org.apache.solr.update.PeerSync; no frame of reference to tell of we've missed updates
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.111; org.apache.solr.cloud.RecoveryStrategy; PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.111; org.apache.solr.cloud.RecoveryStrategy; Starting Replication Recovery. core=collection1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.112; org.apache.solr.cloud.RecoveryStrategy; Begin buffering updates. core=collection1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.111; org.apache.solr.cloud.ZkController; publishing core=collection1 state=down
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.112; org.apache.solr.update.UpdateLog; Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=tlog{file=./org.apache.solr.cloud.RecoveryZkTest-1365823171094/jetty2/tlog/tlog.0000000000000000000 refcount=1}}
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.113; org.apache.solr.cloud.ZkController; numShards not found on descriptor - reading it from system property
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.113; org.apache.solr.cloud.RecoveryStrategy; Attempting to replicate from http://127.0.0.1:64781/id_fe/mn/collection1/. core=collection1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.114; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   1> WARN  - 2013-04-13 03:19:51.116; org.apache.solr.cloud.RecoveryStrategy; Stopping recovery for zkNodeName=127.0.0.1:64785_id_fe%2Fmn_collection1core=collection1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.117; org.apache.solr.core.SolrCore; [collection1] webapp=/id_fe/mn path=/get params={getVersions=100&distrib=false&wt=javabin&qt=/get&version=2} status=0 QTime=1 
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.119; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[55 (1432169418449747968)]} 0 2
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.121; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10055 (1432169418451845120)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.123; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[55 (1432169418453942272)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.125; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10055 (1432169418456039424)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.129; org.apache.solr.update.DirectUpdateHandler2; start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.142; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@30729af2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@38d9689d),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@30729af2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@38d9689d),segFN=segments_2,generation=2,filenames=[_0_NestedPulsing_0.doc, _0.fnm, _0_TestBloomFilteredLucene41Postings_0.blm, _0_Asserting_0.dvd, _0_TestBloomFilteredLucene41Postings_0.tim, _0_Lucene41_0.pos, _0_1.del, _0_TestBloomFilteredLucene41Postings_0.tip, _0_Asserting_0.dvm, _0.nvm, _0_NestedPulsing_0.tip, _0_TestBloomFilteredLucene41Postings_0.pos, _0_NestedPulsing_0.tim, _0_NestedPulsing_0.pos, _0_Lucene41_0.doc, _0_SimpleText_0.pst, _0.nvd, _0_TestBloomFilteredLucene41Postings_0.doc, segments_2, _0.fdx, _0.si, _0_Lucene41_0.tim, _0_Lucene41_0.tip, _0.fdt]
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.143; org.apache.solr.core.SolrDeletionPolicy; newest commit = 2[_0_NestedPulsing_0.doc, _0.fnm, _0_TestBloomFilteredLucene41Postings_0.blm, _0_Asserting_0.dvd, _0_TestBloomFilteredLucene41Postings_0.tim, _0_Lucene41_0.pos, _0_1.del, _0_TestBloomFilteredLucene41Postings_0.tip, _0_Asserting_0.dvm, _0.nvm, _0_NestedPulsing_0.tip, _0_TestBloomFilteredLucene41Postings_0.pos, _0_NestedPulsing_0.tim, _0_NestedPulsing_0.pos, _0_Lucene41_0.doc, _0_SimpleText_0.pst, _0.nvd, _0_TestBloomFilteredLucene41Postings_0.doc, segments_2, _0.fdx, _0.si, _0_Lucene41_0.tim, _0_Lucene41_0.tip, _0.fdt]
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.145; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@53b55a63 realtime
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.146; org.apache.solr.update.DirectUpdateHandler2; end_commit_flush
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.146; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={waitSearcher=true&openSearcher=false&commit=true&wt=javabin&commit_end_point=true&version=2&softCommit=false} {commit=} 0 17
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.147; org.apache.solr.handler.ReplicationHandler; Commits will be reserved for  10000
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.147; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[56 (1432169418480156672)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.147; org.apache.solr.handler.SnapPuller;  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.148; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10056 (1432169418480156673)]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.150; org.apache.solr.handler.ReplicationHandler; Commits will be reserved for  10000
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.151; org.apache.solr.core.SolrCore; [collection1] webapp=/id_fe/mn path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.152; org.apache.solr.handler.SnapPuller; Master's generation: 2
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.153; org.apache.solr.handler.SnapPuller; Slave's generation: 1
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.153; org.apache.solr.handler.SnapPuller; Starting replication process
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.153; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[56 (1432169418484350976)]} 0 3
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.154; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/id_fe/mn path=/update params={wt=javabin&version=2} {add=[10056 (1432169418485399552)]} 0 2
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.156; org.apache.solr.core.SolrCore; [collection1] webapp=/id_fe/mn path=/replication params={command=filelist&qt=/replication&wt=javabin&generation=2&version=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.157; org.apache.solr.handler.SnapPuller; Number of files in latest index in master: 24
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.158; org.apache.solr.core.CachingDirectoryFactory; return new directory for ./org.apache.solr.cloud.RecoveryZkTest-1365823171094/jetty2/index.20130412231951158
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.158; org.apache.solr.handler.SnapPuller; Starting download to BaseDirectoryWrapper(org.apache.lucene.store.RAMDirectory@21cbd67d lockFactory=org.apache.lucene.store.NativeFSLockFactory@66c92be2) fullCopy=true
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.161; org.apache.solr.core.SolrCore; [collection1] webapp=/id_fe/mn path=/replication params={file=_0_Lucene41_0.pos&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.165; org.apache.solr.core.SolrCore; [collection1] webapp=/id_fe/mn path=/replication params={file=_0_TestBloomFilteredLucene41Postings_0.tim&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.169; org.apache.solr.core.SolrCore; [collection1] webapp=/id_fe/mn path=/replication params={file=_0_TestBloomFilteredLucene41Postings_0.tip&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.171; org.apache.solr.core.SolrCore; [collection1] webapp=/id_fe/mn path=/replication params={file=_0_NestedPulsing_0.tip&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.174; org.apache.solr.core.SolrCore; [collection1] webapp=/id_fe/mn path=/replication params={file=_0_TestBloomFilteredLucene41Postings_0.pos&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.178; org.apache.solr.core.SolrCore; [collection1] webapp=/id_fe/mn path=/replication params={file=_0_NestedPulsing_0.tim&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.181; org.apache.solr.core.SolrCore; [collection1] webapp=/id_fe/mn path=/replication params={file=_0_NestedPulsing_0.pos&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.184; org.apache.solr.core.SolrCore; [collection1] webapp=/id_fe/mn path=/replication params={file=_0.nvd&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.188; org.apache.solr.core.SolrCore; [collection1] webapp=/id_fe/mn path=/replication params={file=_0_SimpleText_0.pst&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.191; org.apache.solr.core.SolrCore; [collection1] webapp=/id_fe/mn path=/replication params={file=_0.si&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-13 03:19:51.195; org.apache.solr.core.SolrCore; [collection1] webapp=/id_fe/mn path=/replication params={file=_0_Lucene41_0.tim&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} s

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

core/test/J0/org.apache.solr.cloud.ChaosMonkeyShardSplitTest-1365824221651/jetty4/index;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-13 03:39:34.265; org.apache.solr.core.CachingDirectoryFactory; Closing directory: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeyShardSplitTest-1365824221651/jetty4/index
[junit4:junit4]   1> INFO  - 2013-04-13 03:39:34.265; org.apache.solr.common.cloud.SolrZkClient; makePath: /collections/collection1/leaders/shard2
[junit4:junit4]   1> WARN  - 2013-04-13 03:39:34.266; org.apache.solr.cloud.LeaderElector$1; 
[junit4:junit4]   1> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /collections/collection1/leader_elect/shard2/election/89510656190513164-127.0.0.1:32639_tp%2Fad_collection1-n_0000000001
[junit4:junit4]   1> 	at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4:junit4]   1> 	at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4:junit4]   1> 	at org.apache.zookeeper.ZooKeeper.delete(ZooKeeper.java:873)
[junit4:junit4]   1> 	at org.apache.solr.common.cloud.SolrZkClient$2.execute(SolrZkClient.java:152)
[junit4:junit4]   1> 	at org.apache.solr.common.cloud.SolrZkClient$2.execute(SolrZkClient.java:149)
[junit4:junit4]   1> 	at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:65)
[junit4:junit4]   1> 	at org.apache.solr.common.cloud.SolrZkClient.delete(SolrZkClient.java:149)
[junit4:junit4]   1> 	at org.apache.solr.cloud.ElectionContext.cancelElection(ElectionContext.java:63)
[junit4:junit4]   1> 	at org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:246)
[junit4:junit4]   1> 	at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:156)
[junit4:junit4]   1> 	at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:100)
[junit4:junit4]   1> 	at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:55)
[junit4:junit4]   1> 	at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:129)
[junit4:junit4]   1> 	at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:519)
[junit4:junit4]   1> 	at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:495)
[junit4:junit4]   1> WARN  - 2013-04-13 03:39:34.267; org.apache.solr.common.cloud.ZkStateReader$3; ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   1> INFO  - 2013-04-13 03:39:34.267; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   1> WARN  - 2013-04-13 03:39:34.267; org.apache.solr.common.cloud.ZkStateReader$2; ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   1> INFO  - 2013-04-13 03:39:34.268; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   1> WARN  - 2013-04-13 03:39:34.268; org.apache.solr.common.cloud.ZkStateReader$2; ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   1> WARN  - 2013-04-13 03:39:34.268; org.apache.solr.cloud.LeaderElector$1; 
[junit4:junit4]   1> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/election
[junit4:junit4]   1> 	at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4:junit4]   1> 	at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4:junit4]   1> 	at org.apache.zookeeper.ZooKeeper.getChildren(ZooKeeper.java:1468)
[junit4:junit4]   1> 	at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:235)
[junit4:junit4]   1> 	at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:232)
[junit4:junit4]   1> 	at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:65)
[junit4:junit4]   1> 	at org.apache.solr.common.cloud.SolrZkClient.getChildren(SolrZkClient.java:232)
[junit4:junit4]   1> 	at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:84)
[junit4:junit4]   1> 	at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:55)
[junit4:junit4]   1> 	at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:129)
[junit4:junit4]   1> 	at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:519)
[junit4:junit4]   1> 	at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:495)
[junit4:junit4]   2> NOTE: test params are: codec=Lucene42: {timestamp=MockFixedIntBlock(blockSize=780), range_facet_l=MockFixedIntBlock(blockSize=780), id=PostingsFormat(name=Memory doPackFST= true), range_facet_sl=Lucene41(blocksize=128), range_facet_si=PostingsFormat(name=Asserting), _version_=MockFixedIntBlock(blockSize=780), multiDefault=PostingsFormat(name=Asserting), rnd_b=PostingsFormat(name=Memory doPackFST= true), intDefault=MockFixedIntBlock(blockSize=780)}, docValues:{timestamp=DocValuesFormat(name=Disk)}, sim=RandomSimilarityProvider(queryNorm=false,coord=yes): {}, locale=es_US, timezone=America/Danmarkshavn
[junit4:junit4]   2> NOTE: FreeBSD 9.0-RELEASE amd64/Oracle Corporation 1.7.0_17 (64-bit)/cpus=16,threads=3,free=106867168,total=297795584
[junit4:junit4]   2> NOTE: All tests run in this JVM: [LukeRequestHandlerTest, TestStressVersions, TestWriterPerf, RAMDirectoryFactoryTest, StatelessScriptUpdateProcessorFactoryTest, PreAnalyzedFieldTest, TestPluginEnable, ClusterStateUpdateTest, SolrInfoMBeanTest, QueryElevationComponentTest, TestRemoteStreaming, TestSchemaSimilarityResource, CSVRequestHandlerTest, TestCharFilters, TestLMDirichletSimilarityFactory, ZkCLITest, QueryEqualityTest, UpdateRequestProcessorFactoryTest, TestFastLRUCache, ShardSplitTest, TestGroupingSearch, TestIBSimilarityFactory, TestRandomDVFaceting, BasicDistributedZkTest, ZkControllerTest, SuggesterTest, TestPerFieldSimilarity, PluginInfoTest, SOLR749Test, TestCloudManagedSchema, FileBasedSpellCheckerTest, TestSolrQueryParser, TestManagedSchema, TestSweetSpotSimilarityFactory, ResponseLogComponentTest, ResourceLoaderTest, TestFieldCollectionResource, TestPhraseSuggestions, PeerSyncTest, SolrIndexSplitterTest, TestValueSourceCache, DateFieldTest, DocValuesTest, TestFieldResource, TestLRUCache, TestDocSet, RecoveryZkTest, LegacyHTMLStripCharFilterTest, TestTrie, PolyFieldTest, OverseerCollectionProcessorTest, SliceStateTest, MBeansHandlerTest, MinimalSchemaTest, CoreAdminHandlerTest, TestSolrCoreProperties, ChaosMonkeyNothingIsSafeTest, TestJoin, ReturnFieldsTest, WordBreakSolrSpellCheckerTest, DirectUpdateHandlerOptimizeTest, DistributedQueryElevationComponentTest, OverseerTest, ShardRoutingTest, TermsComponentTest, SynonymTokenizerTest, TestFuzzyAnalyzedSuggestions, TestPseudoReturnFields, ScriptEngineTest, FullSolrCloudDistribCmdsTest, TestRandomFaceting, TestDistributedGrouping, TestHashPartitioner, TestRealTimeGet, TestReload, TestCoreContainer, BadIndexSchemaTest, TestSort, BasicFunctionalityTest, TestBadConfig, SoftAutoCommitTest, SolrRequestParserTest, SuggesterTSTTest, TestCSVLoader, SchemaVersionSpecificBehaviorTest, TestUpdate, TestAtomicUpdateErrorCases, DefaultValueUpdateProcessorTest, DistanceFunctionTest, CacheHeaderTest, DisMaxRequestHandlerTest, StandardRequestHandlerTest, PrimitiveFieldTypeTest, RequestHandlersTest, TermVectorComponentTest, FieldAnalysisRequestHandlerTest, FastVectorHighlighterTest, RegexBoostProcessorTest, IndexSchemaTest, TestCSVResponseWriter, QueryParsingTest, TestPHPSerializedResponseWriter, SearchHandlerTest, TestLFUCache, TestPropInjectDefaults, UpdateParamsTest, TestSolrIndexConfig, BadComponentTest, TestSolrDeletionPolicy2, TestSearchPerf, NumericFieldsTest, TestElisionMultitermQuery, TestConfig, ExternalFileFieldSortTest, DirectSolrConnectionTest, NotRequiredUniqueKeyTest, TestXIncludeConfig, TestNumberUtils, TestBM25SimilarityFactory, TestDefaultSimilarityFactory, TestFastWriter, TestJmxMonitoredMap, TestFastOutputStream, OpenExchangeRatesOrgProviderTest, DateMathParserTest, TestSolrXMLSerializer, PrimUtilsTest, TestSuggestSpellingConverter, SpellingQueryConverterTest, ClusterStateTest, TestSolrJ, TestUtils, TestDocumentBuilder, ZkNodePropsTest, SystemInfoHandlerTest, UUIDFieldTest, FileUtilsTest, CircularListTest, TestRTGBase, ChaosMonkeyShardSplitTest]
[junit4:junit4] Completed on J0 in 153.26s, 1 test, 1 failure <<< FAILURES!

[...truncated 12 lines...]
BUILD FAILED
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/build.xml:375: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/build.xml:355: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/build.xml:39: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build.xml:183: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/common-build.xml:438: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/lucene/common-build.xml:1231: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/lucene/common-build.xml:875: There were test failures: 289 suites, 1200 tests, 1 suite-level error, 1 error, 1 failure, 27 ignored (8 assumptions)

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



Mime
View raw message