lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Jenkins Server <jenk...@builds.apache.org>
Subject [JENKINS] Lucene-Solr-Tests-4.x-java7 - Build # 1066 - Still Failing
Date Thu, 14 Mar 2013 03:28:52 GMT
Build: https://builds.apache.org/job/Lucene-Solr-Tests-4.x-java7/1066/

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

Error Message:
No registered leader was found, collection:collection1 slice:shard1

Stack Trace:
org.apache.solr.common.SolrException: No registered leader was found, collection:collection1 slice:shard1
	at __randomizedtesting.SeedInfo.seed([D5AD33B84432039A:544BBDA0336D63A6]:0)
	at org.apache.solr.common.cloud.ZkStateReader.getLeaderRetry(ZkStateReader.java:484)
	at org.apache.solr.cloud.BasicDistributedZk2Test.brindDownShardIndexSomeDocsAndRecover(BasicDistributedZk2Test.java:291)
	at org.apache.solr.cloud.BasicDistributedZk2Test.doTest(BasicDistributedZk2Test.java:116)
	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)


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=1086, name=coreLoadExecutor-422-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=1086, name=coreLoadExecutor-422-thread-1, state=RUNNABLE, group=TGRP-RecoveryZkTest], registration stack trace below.
	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:520)
	at org.apache.lucene.util.LuceneTestCase.wrapDirectory(LuceneTestCase.java:980)
	at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:878)
	at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:870)
	at org.apache.solr.core.MockDirectoryFactory.create(MockDirectoryFactory.java:34)
	at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:272)
	at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:224)
	at org.apache.solr.core.SolrCore.getNewIndexDir(SolrCore.java:244)
	at org.apache.solr.core.SolrCore.initIndex(SolrCore.java:458)
	at org.apache.solr.core.SolrCore.<init>(SolrCore.java:744)
	at org.apache.solr.core.SolrCore.<init>(SolrCore.java:619)
	at org.apache.solr.core.CoreContainer.createFromZk(CoreContainer.java:872)
	at org.apache.solr.core.CoreContainer.create(CoreContainer.java:955)
	at org.apache.solr.core.CoreContainer$2.call(CoreContainer.java:579)
	at org.apache.solr.core.CoreContainer$2.call(CoreContainer.java:574)
	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@17ff929c lockFactory=org.apache.lucene.store.NativeFSLockFactory@50607985)
	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


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=1088, name=RecoveryThread, 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=1088, name=RecoveryThread, state=RUNNABLE, group=TGRP-RecoveryZkTest], registration stack trace below.
	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:520)
	at org.apache.lucene.util.LuceneTestCase.wrapDirectory(LuceneTestCase.java:980)
	at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:878)
	at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:870)
	at org.apache.solr.core.MockDirectoryFactory.create(MockDirectoryFactory.java:34)
	at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:272)
	at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:224)
	at org.apache.solr.handler.SnapPuller.fetchLatestIndex(SnapPuller.java:383)
	at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:281)
	at org.apache.solr.cloud.RecoveryStrategy.replicate(RecoveryStrategy.java:153)
	at org.apache.solr.cloud.RecoveryStrategy.doRecovery(RecoveryStrategy.java:409)
	at org.apache.solr.cloud.RecoveryStrategy.run(RecoveryStrategy.java:223)
Caused by: java.lang.AssertionError: Directory not closed: BaseDirectoryWrapper(org.apache.lucene.store.RAMDirectory@5dbe3491 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6583023d)
	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)
	at java.lang.Thread.run(Thread.java:722)


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

Error Message:


Stack Trace:
java.lang.AssertionError
	at __randomizedtesting.SeedInfo.seed([D5AD33B84432039A:544BBDA0336D63A6]:0)
	at org.junit.Assert.fail(Assert.java:92)
	at org.junit.Assert.assertTrue(Assert.java:43)
	at org.junit.Assert.assertTrue(Assert.java:54)
	at org.apache.solr.cloud.SyncSliceTest.doTest(SyncSliceTest.java:196)
	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 9076 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.SyncSliceTest
[junit4:junit4]   2> 0 T42 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4:junit4]   2> 9 T42 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4:junit4]   2> Creating dataDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1/./solrtest-SyncSliceTest-1363229906900
[junit4:junit4]   2> 18 T42 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 26 T43 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4:junit4]   2> 116 T43 oaz.Environment.logEnv Server environment:zookeeper.version=3.4.5-1392090, built on 09/30/2012 17:52 GMT
[junit4:junit4]   2> 116 T43 oaz.Environment.logEnv Server environment:host.name=lucene.zones.apache.org
[junit4:junit4]   2> 116 T43 oaz.Environment.logEnv Server environment:java.version=1.7.0_17
[junit4:junit4]   2> 117 T43 oaz.Environment.logEnv Server environment:java.vendor=Oracle Corporation
[junit4:junit4]   2> 117 T43 oaz.Environment.logEnv Server environment:java.home=/usr/local/openjdk7/jre
[junit4:junit4]   2> 117 T43 oaz.Environment.logEnv Server environment:java.class.path=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/classes/test:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-test-framework/classes/java:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/test-framework/classes/java:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/codecs/classes/java:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-solrj/classes/java:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/classes/java:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/analysis/common/lucene-analyzers-common-4.3-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/analysis/kuromoji/lucene-analyzers-kuromoji-4.3-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/analysis/phonetic/lucene-analyzers-phonetic-4.3-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/codecs/lucene-codecs-4.3-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/highlighter/lucene-highlighter-4.3-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/memory/lucene-memory-4.3-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/misc/lucene-misc-4.3-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/spatial/lucene-spatial-4.3-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/suggest/lucene-suggest-4.3-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/grouping/lucene-grouping-4.3-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/queries/lucene-queries-4.3-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/queryparser/lucene-queryparser-4.3-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/core/lib/cglib-nodep-2.2.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/core/lib/commons-cli-1.2.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/core/lib/commons-codec-1.7.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/core/lib/commons-fileupload-1.2.1.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/core/lib/commons-lang-2.6.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/core/lib/easymock-3.0.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/core/lib/guava-13.0.1.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/core/lib/javax.servlet-api-3.0.1.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/core/lib/objenesis-1.2.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/core/lib/org.restlet-2.1.1.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/core/lib/org.restlet.ext.servlet-2.1.1.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/core/lib/spatial4j-0.3.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/solrj/lib/commons-io-2.1.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/solrj/lib/httpclient-4.2.3.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/solrj/lib/httpcore-4.2.2.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/solrj/lib/httpmime-4.2.3.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/solrj/lib/jcl-over-slf4j-1.6.4.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/solrj/lib/slf4j-api-1.6.4.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/solrj/lib/slf4j-jdk14-1.6.4.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/solrj/lib/wstx-asl-3.2.7.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/solrj/lib/zookeeper-3.4.5.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/jetty-continuation-8.1.8.v20121106.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/jetty-deploy-8.1.8.v20121106.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/jetty-http-8.1.8.v20121106.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/jetty-io-8.1.8.v20121106.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/jetty-jmx-8.1.8.v20121106.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/jetty-security-8.1.8.v20121106.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/jetty-server-8.1.8.v20121106.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/jetty-servlet-8.1.8.v20121106.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/jetty-util-8.1.8.v20121106.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/jetty-webapp-8.1.8.v20121106.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/jetty-xml-8.1.8.v20121106.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/servlet-api-3.0.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/example-DIH/solr/db/lib/derby-10.9.1.0.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/example-DIH/solr/db/lib/hsqldb-1.8.0.10.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/core/classes/java:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/test-framework/lib/junit-4.10.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/test-framework/lib/randomizedtesting-runner-2.0.8.jar:/home/hudson/hudson-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-launcher.jar:/home/hudson/.ant/lib/ivy-2.2.0.jar:/home/hudson/hudson-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-commons-logging.jar:/home/hudson/hudson-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-regexp.jar:/home/hudson/hudson-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-oro.jar:/home/hudson/hudson-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-swing.jar:/home/hudson/hudson-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jdepend.jar:/home/hudson/hudson-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-xalan2.jar:/home/hudson/hudson-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-bsf.jar:/home/hudson/hudson-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-junit4.jar:/home/hudson/hudson-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-commons-net.jar:/home/hudson/hudson-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-antlr.jar:/home/hudson/hudson-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-junit.jar:/home/hudson/hudson-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jmf.jar:/home/hudson/hudson-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-netrexx.jar:/home/hudson/hudson-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-log4j.jar:/home/hudson/hudson-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-resolver.jar:/home/hudson/hudson-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant.jar:/home/hudson/hudson-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jsch.jar:/home/hudson/hudson-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-testutil.jar:/home/hudson/hudson-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-bcel.jar:/home/hudson/hudson-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jai.jar:/home/hudson/hudson-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-javamail.jar:/usr/local/openjdk7/lib/tools.jar:/home/hudson/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.0.8.jar
[junit4:junit4]   2> 120 T43 oaz.Environment.logEnv Server environment:java.library.path=/usr/local/openjdk7/jre/lib/amd64/server:/usr/local/openjdk7/jre/lib/amd64:/usr/local/openjdk7/jre/../lib/amd64:/usr/local/openjdk6/jre/lib/amd64/server:/usr/local/openjdk6/jre/lib/amd64:/usr/local/openjdk6/jre/../lib/amd64:/usr/java/packages/lib/amd64:/lib:/usr/lib:/usr/local/lib
[junit4:junit4]   2> 120 T43 oaz.Environment.logEnv Server environment:java.io.tmpdir=.
[junit4:junit4]   2> 121 T43 oaz.Environment.logEnv Server environment:java.compiler=<NA>
[junit4:junit4]   2> 121 T43 oaz.Environment.logEnv Server environment:os.name=FreeBSD
[junit4:junit4]   2> 121 T43 oaz.Environment.logEnv Server environment:os.arch=amd64
[junit4:junit4]   2> 122 T43 oaz.Environment.logEnv Server environment:os.version=9.0-RELEASE
[junit4:junit4]   2> 122 T43 oaz.Environment.logEnv Server environment:user.name=hudson
[junit4:junit4]   2> 123 T43 oaz.Environment.logEnv Server environment:user.home=/home/hudson
[junit4:junit4]   2> 123 T43 oaz.Environment.logEnv Server environment:user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1
[junit4:junit4]   2> 176 T43 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4]   2> 176 T43 oazs.ZooKeeperServer.setMinSessionTimeout minSessionTimeout set to -1
[junit4:junit4]   2> 177 T43 oazs.ZooKeeperServer.setMaxSessionTimeout maxSessionTimeout set to -1
[junit4:junit4]   2> 184 T43 oazs.NIOServerCnxnFactory.configure binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 227 T42 oasc.ZkTestServer.run start zk server on port:42070
[junit4:junit4]   2> 286 T43 oazsp.FileTxnSnapLog.save Snapshotting: 0x0 to /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1363229906900/zookeeper/server1/data/version-2/snapshot.0
[junit4:junit4]   2> 328 T42 oaz.Environment.logEnv Client environment:zookeeper.version=3.4.5-1392090, built on 09/30/2012 17:52 GMT
[junit4:junit4]   2> 329 T42 oaz.Environment.logEnv Client environment:host.name=lucene.zones.apache.org
[junit4:junit4]   2> 330 T42 oaz.Environment.logEnv Client environment:java.version=1.7.0_17
[junit4:junit4]   2> 331 T42 oaz.Environment.logEnv Client environment:java.vendor=Oracle Corporation
[junit4:junit4]   2> 331 T42 oaz.Environment.logEnv Client environment:java.home=/usr/local/openjdk7/jre
[junit4:junit4]   2> 332 T42 oaz.Environment.logEnv Client environment:java.class.path=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/classes/test:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-test-framework/classes/java:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/test-framework/classes/java:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/codecs/classes/java:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-solrj/classes/java:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/classes/java:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/analysis/common/lucene-analyzers-common-4.3-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/analysis/kuromoji/lucene-analyzers-kuromoji-4.3-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/analysis/phonetic/lucene-analyzers-phonetic-4.3-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/codecs/lucene-codecs-4.3-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/highlighter/lucene-highlighter-4.3-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/memory/lucene-memory-4.3-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/misc/lucene-misc-4.3-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/spatial/lucene-spatial-4.3-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/suggest/lucene-suggest-4.3-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/grouping/lucene-grouping-4.3-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/queries/lucene-queries-4.3-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/queryparser/lucene-queryparser-4.3-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/core/lib/cglib-nodep-2.2.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/core/lib/commons-cli-1.2.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/core/lib/commons-codec-1.7.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/core/lib/commons-fileupload-1.2.1.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/core/lib/commons-lang-2.6.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/core/lib/easymock-3.0.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/core/lib/guava-13.0.1.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/core/lib/javax.servlet-api-3.0.1.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/core/lib/objenesis-1.2.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/core/lib/org.restlet-2.1.1.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/core/lib/org.restlet.ext.servlet-2.1.1.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/core/lib/spatial4j-0.3.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/solrj/lib/commons-io-2.1.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/solrj/lib/httpclient-4.2.3.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/solrj/lib/httpcore-4.2.2.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/solrj/lib/httpmime-4.2.3.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/solrj/lib/jcl-over-slf4j-1.6.4.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/solrj/lib/slf4j-api-1.6.4.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/solrj/lib/slf4j-jdk14-1.6.4.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/solrj/lib/wstx-asl-3.2.7.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/solrj/lib/zookeeper-3.4.5.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/jetty-continuation-8.1.8.v20121106.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/jetty-deploy-8.1.8.v20121106.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/jetty-http-8.1.8.v20121106.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/jetty-io-8.1.8.v20121106.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/jetty-jmx-8.1.8.v20121106.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/jetty-security-8.1.8.v20121106.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/jetty-server-8.1.8.v20121106.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/jetty-servlet-8.1.8.v20121106.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/jetty-util-8.1.8.v20121106.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/jetty-webapp-8.1.8.v20121106.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/jetty-xml-8.1.8.v20121106.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/servlet-api-3.0.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/example-DIH/solr/db/lib/derby-10.9.1.0.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/example-DIH/solr/db/lib/hsqldb-1.8.0.10.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/core/classes/java:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/test-framework/lib/junit-4.10.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/test-framework/lib/randomizedtesting-runner-2.0.8.jar:/home/hudson/hudson-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-launcher.jar:/home/hudson/.ant/lib/ivy-2.2.0.jar:/home/hudson/hudson-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-commons-logging.jar:/home/hudson/hudson-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-regexp.jar:/home/hudson/hudson-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-oro.jar:/home/hudson/hudson-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-swing.jar:/home/hudson/hudson-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jdepend.jar:/home/hudson/hudson-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-xalan2.jar:/home/hudson/hudson-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-bsf.jar:/home/hudson/hudson-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-junit4.jar:/home/hudson/hudson-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-commons-net.jar:/home/hudson/hudson-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-antlr.jar:/home/hudson/hudson-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-junit.jar:/home/hudson/hudson-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jmf.jar:/home/hudson/hudson-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-netrexx.jar:/home/hudson/hudson-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-log4j.jar:/home/hudson/hudson-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-resolver.jar:/home/hudson/hudson-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant.jar:/home/hudson/hudson-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jsch.jar:/home/hudson/hudson-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-testutil.jar:/home/hudson/hudson-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-bcel.jar:/home/hudson/hudson-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jai.jar:/home/hudson/hudson-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-javamail.jar:/usr/local/openjdk7/lib/tools.jar:/home/hudson/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.0.8.jar
[junit4:junit4]   2> 336 T42 oaz.Environment.logEnv Client environment:java.library.path=/usr/local/openjdk7/jre/lib/amd64/server:/usr/local/openjdk7/jre/lib/amd64:/usr/local/openjdk7/jre/../lib/amd64:/usr/local/openjdk6/jre/lib/amd64/server:/usr/local/openjdk6/jre/lib/amd64:/usr/local/openjdk6/jre/../lib/amd64:/usr/java/packages/lib/amd64:/lib:/usr/lib:/usr/local/lib
[junit4:junit4]   2> 336 T42 oaz.Environment.logEnv Client environment:java.io.tmpdir=.
[junit4:junit4]   2> 337 T42 oaz.Environment.logEnv Client environment:java.compiler=<NA>
[junit4:junit4]   2> 338 T42 oaz.Environment.logEnv Client environment:os.name=FreeBSD
[junit4:junit4]   2> 338 T42 oaz.Environment.logEnv Client environment:os.arch=amd64
[junit4:junit4]   2> 339 T42 oaz.Environment.logEnv Client environment:os.version=9.0-RELEASE
[junit4:junit4]   2> 340 T42 oaz.Environment.logEnv Client environment:user.name=hudson
[junit4:junit4]   2> 340 T42 oaz.Environment.logEnv Client environment:user.home=/home/hudson
[junit4:junit4]   2> 341 T42 oaz.Environment.logEnv Client environment:user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1
[junit4:junit4]   2> 346 T42 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:42070 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@3cf59bb9
[junit4:junit4]   2> 432 T42 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 445 T47 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost/127.0.0.1:42070. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 447 T47 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:42070, initiating session
[junit4:junit4]   2> 448 T44 oazs.NIOServerCnxnFactory.run Accepted socket connection from /140.211.11.196:42071
[junit4:junit4]   2> 480 T44 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /140.211.11.196:42071
[junit4:junit4]   2> 495 T46 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 564 T46 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d66d599130000 with negotiated timeout 10000 for client /140.211.11.196:42071
[junit4:junit4]   2> 564 T47 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost/127.0.0.1:42070, sessionid = 0x13d66d599130000, negotiated timeout = 10000
[junit4:junit4]   2> 570 T49 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3cf59bb9 name:ZooKeeperConnection Watcher:127.0.0.1:42070 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 571 T42 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 575 T42 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 661 T48 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d66d599130000
[junit4:junit4]   2> 663 T49 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 663 T42 oaz.ZooKeeper.close Session: 0x13d66d599130000 closed
[junit4:junit4]   2> 664 T44 oazs.NIOServerCnxn.closeSock Closed socket connection for client /140.211.11.196:42071 which had sessionid 0x13d66d599130000
[junit4:junit4]   2> 664 T42 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:42070/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@11cbc6c5
[junit4:junit4]   2> 665 T42 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 666 T50 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost/127.0.0.1:42070. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 667 T50 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:42070, initiating session
[junit4:junit4]   2> 667 T44 oazs.NIOServerCnxnFactory.run Accepted socket connection from /140.211.11.196:42072
[junit4:junit4]   2> 668 T44 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /140.211.11.196:42072
[junit4:junit4]   2> 669 T46 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d66d599130001 with negotiated timeout 10000 for client /140.211.11.196:42072
[junit4:junit4]   2> 670 T50 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost/127.0.0.1:42070, sessionid = 0x13d66d599130001, negotiated timeout = 10000
[junit4:junit4]   2> 670 T51 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@11cbc6c5 name:ZooKeeperConnection Watcher:127.0.0.1:42070/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 671 T42 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 684 T42 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 691 T42 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 697 T42 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 702 T42 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 709 T42 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 738 T42 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 748 T42 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4:junit4]   2> 752 T42 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 860 T42 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4:junit4]   2> 861 T42 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 868 T42 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4:junit4]   2> 869 T42 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 875 T42 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4:junit4]   2> 876 T42 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 882 T42 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 883 T42 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 890 T42 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 892 T42 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 898 T42 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 899 T42 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 905 T42 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4:junit4]   2> 906 T42 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 912 T48 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d66d599130001
[junit4:junit4]   2> 914 T51 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 914 T44 oazs.NIOServerCnxn.closeSock Closed socket connection for client /140.211.11.196:42072 which had sessionid 0x13d66d599130001
[junit4:junit4]   2> 914 T42 oaz.ZooKeeper.close Session: 0x13d66d599130001 closed
[junit4:junit4]   2> 1127 T42 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 1135 T42 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:42073
[junit4:junit4]   2> 1136 T42 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 1136 T42 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 1137 T42 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-controljetty-1363229907806
[junit4:junit4]   2> 1137 T42 oasc.CoreContainer$Initializer.initialize looking for solr config file: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-controljetty-1363229907806/solr.xml
[junit4:junit4]   2> 1138 T42 oasc.CoreContainer.<init> New CoreContainer 1985431242
[junit4:junit4]   2> 1138 T42 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-controljetty-1363229907806/'
[junit4:junit4]   2> 1139 T42 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-controljetty-1363229907806/'
[junit4:junit4]   2> 1186 T42 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 1186 T42 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 1187 T42 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 1187 T42 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 1188 T42 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 1188 T42 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 1189 T42 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 1189 T42 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 1189 T42 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 1190 T42 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 1286 T42 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 1303 T42 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:42070/solr
[junit4:junit4]   2> 1324 T42 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 1325 T42 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:42070 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@42e7ad41
[junit4:junit4]   2> 1326 T42 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 1327 T61 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost/127.0.0.1:42070. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 1327 T61 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:42070, initiating session
[junit4:junit4]   2> 1328 T44 oazs.NIOServerCnxnFactory.run Accepted socket connection from /140.211.11.196:42074
[junit4:junit4]   2> 1328 T44 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /140.211.11.196:42074
[junit4:junit4]   2> 1330 T46 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d66d599130002 with negotiated timeout 20000 for client /140.211.11.196:42074
[junit4:junit4]   2> 1330 T61 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost/127.0.0.1:42070, sessionid = 0x13d66d599130002, negotiated timeout = 20000
[junit4:junit4]   2> 1331 T62 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@42e7ad41 name:ZooKeeperConnection Watcher:127.0.0.1:42070 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 1331 T42 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 1333 T48 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d66d599130002
[junit4:junit4]   2> 1342 T44 oazs.NIOServerCnxn.closeSock Closed socket connection for client /140.211.11.196:42074 which had sessionid 0x13d66d599130002
[junit4:junit4]   2> 1342 T42 oaz.ZooKeeper.close Session: 0x13d66d599130002 closed
[junit4:junit4]   2> 1342 T62 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 1345 T42 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 1359 T42 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:42070/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@23573e80
[junit4:junit4]   2> 1361 T42 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 1361 T63 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost/127.0.0.1:42070. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 1362 T63 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:42070, initiating session
[junit4:junit4]   2> 1362 T44 oazs.NIOServerCnxnFactory.run Accepted socket connection from /140.211.11.196:42075
[junit4:junit4]   2> 1363 T44 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /140.211.11.196:42075
[junit4:junit4]   2> 1364 T46 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d66d599130003 with negotiated timeout 20000 for client /140.211.11.196:42075
[junit4:junit4]   2> 1364 T63 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost/127.0.0.1:42070, sessionid = 0x13d66d599130003, negotiated timeout = 20000
[junit4:junit4]   2> 1365 T64 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@23573e80 name:ZooKeeperConnection Watcher:127.0.0.1:42070/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 1365 T42 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 1380 T48 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d66d599130003 type:create cxid:0x2 zxid:0x1a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1392 T42 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 1396 T42 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42073_
[junit4:junit4]   2> 1400 T48 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d66d599130003 type:delete cxid:0x8 zxid:0x1c txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:42073_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:42073_
[junit4:junit4]   2> 1402 T42 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:42073_
[junit4:junit4]   2> 1406 T42 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 1430 T48 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d66d599130003 type:delete cxid:0x16 zxid:0x21 txntype:-1 reqpath:n/a Error Path:/solr/overseer_elect/leader Error:KeeperErrorCode = NoNode for /solr/overseer_elect/leader
[junit4:junit4]   2> 1431 T42 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 1435 T42 oasc.Overseer.start Overseer (id=89340635199504387-127.0.0.1:42073_-n_0000000000) starting
[junit4:junit4]   2> 1436 T48 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d66d599130003 type:create cxid:0x1b zxid:0x23 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1458 T48 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d66d599130003 type:create cxid:0x1c zxid:0x24 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1459 T48 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d66d599130003 type:create cxid:0x1d zxid:0x25 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1473 T48 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d66d599130003 type:create cxid:0x1e zxid:0x26 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1475 T66 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 1475 T42 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 1479 T42 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4:junit4]   2> 1482 T42 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 1500 T65 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 1508 T67 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-controljetty-1363229907806/collection1
[junit4:junit4]   2> 1509 T67 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 1510 T67 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 1510 T67 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 1513 T67 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-controljetty-1363229907806/collection1/'
[junit4:junit4]   2> 1514 T67 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-controljetty-1363229907806/collection1/lib/README' to classloader
[junit4:junit4]   2> 1514 T67 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-controljetty-1363229907806/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 1573 T67 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_43
[junit4:junit4]   2> 1636 T67 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 1738 T67 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 1754 T67 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 2288 T67 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 2301 T67 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 2304 T67 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 2333 T67 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2339 T67 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2344 T67 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 2345 T67 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 2346 T67 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 2346 T67 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 2348 T67 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 2348 T67 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 2348 T67 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 2349 T67 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-controljetty-1363229907806/collection1/, dataDir=./org.apache.solr.cloud.SyncSliceTest-1363229906900/control/data/
[junit4:junit4]   2> 2349 T67 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@4b7c8ca2
[junit4:junit4]   2> 2350 T67 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 2351 T67 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.SyncSliceTest-1363229906900/control/data forceNew: false
[junit4:junit4]   2> 2351 T67 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.SyncSliceTest-1363229906900/control/data/index/
[junit4:junit4]   2> 2351 T67 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.SyncSliceTest-1363229906900/control/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 2352 T67 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.SyncSliceTest-1363229906900/control/data/index forceNew: false
[junit4:junit4]   2> 2355 T67 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2b2b1e58 lockFactory=org.apache.lucene.store.NativeFSLockFactory@4bb5249a),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 2356 T67 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 2404 T67 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 2404 T67 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 2405 T67 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 2406 T67 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 2406 T67 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 2407 T67 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 2407 T67 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 2408 T67 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 2408 T67 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 2417 T67 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 2422 T67 oass.SolrIndexSearcher.<init> Opening Searcher@42f4aaa main
[junit4:junit4]   2> 2423 T67 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=./org.apache.solr.cloud.SyncSliceTest-1363229906900/control/data/tlog
[junit4:junit4]   2> 2423 T67 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 2424 T67 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 2427 T68 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@42f4aaa main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 2430 T67 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 2430 T67 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 2431 T48 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d66d599130003 type:create cxid:0x55 zxid:0x2a txntype:-1 reqpath:n/a Error Path:/solr/overseer/queue Error:KeeperErrorCode = NoNode for /solr/overseer/queue
[junit4:junit4]   2> 3009 T65 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3010 T65 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core_node_name":"1",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"control_collection",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:42073_",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:42073"}
[junit4:junit4]   2> 3010 T65 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with numShards 1
[junit4:junit4]   2> 3034 T65 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 3035 T48 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d66d599130003 type:create cxid:0x5d zxid:0x2d txntype:-1 reqpath:n/a Error Path:/solr/overseer/queue-work Error:KeeperErrorCode = NoNode for /solr/overseer/queue-work
[junit4:junit4]   2> 3065 T64 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4:junit4]   2> 3446 T67 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 3446 T67 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:42073 collection:control_collection shard:shard1
[junit4:junit4]   2> 3456 T67 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=30000&socketTimeout=30000&retry=false
[junit4:junit4]   2> 3470 T67 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4:junit4]   2> 3488 T48 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d66d599130003 type:delete cxid:0x77 zxid:0x37 txntype:-1 reqpath:n/a Error Path:/solr/collections/control_collection/leaders Error:KeeperErrorCode = NoNode for /solr/collections/control_collection/leaders
[junit4:junit4]   2> 3495 T67 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 3495 T48 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d66d599130003 type:create cxid:0x78 zxid:0x38 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3499 T67 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 3499 T67 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 3503 T67 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:42073/collection1/
[junit4:junit4]   2> 3503 T67 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 3504 T67 oasc.SyncStrategy.syncToMe http://127.0.0.1:42073/collection1/ has no replicas
[junit4:junit4]   2> 3504 T67 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:42073/collection1/
[junit4:junit4]   2> 3504 T67 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4:junit4]   2> 3511 T48 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d66d599130003 type:create cxid:0x82 zxid:0x3c txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4573 T65 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4592 T64 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4:junit4]   2> 4642 T67 oasc.ZkController.register We are http://127.0.0.1:42073/collection1/ and leader is http://127.0.0.1:42073/collection1/
[junit4:junit4]   2> 4642 T67 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:42073
[junit4:junit4]   2> 4643 T67 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 4643 T67 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 4644 T67 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 4647 T67 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4649 T42 oass.SolrDispatchFilter.init user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1
[junit4:junit4]   2> 4649 T42 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 4650 T42 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 4659 T42 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 4663 T42 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:42070/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@2ef670c5
[junit4:junit4]   2> 4664 T42 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 4665 T70 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost/127.0.0.1:42070. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 4666 T70 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:42070, initiating session
[junit4:junit4]   2> 4666 T44 oazs.NIOServerCnxnFactory.run Accepted socket connection from /140.211.11.196:42089
[junit4:junit4]   2> 4667 T44 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /140.211.11.196:42089
[junit4:junit4]   2> 4668 T46 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d66d599130004 with negotiated timeout 10000 for client /140.211.11.196:42089
[junit4:junit4]   2> 4668 T70 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost/127.0.0.1:42070, sessionid = 0x13d66d599130004, negotiated timeout = 10000
[junit4:junit4]   2> 4669 T71 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2ef670c5 name:ZooKeeperConnection Watcher:127.0.0.1:42070/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 4670 T42 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 4672 T42 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 4677 T42 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4]   2> 4884 T42 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 4888 T42 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:42090
[junit4:junit4]   2> 4889 T42 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 4890 T42 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 4890 T42 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty1-1363229911568
[junit4:junit4]   2> 4891 T42 oasc.CoreContainer$Initializer.initialize looking for solr config file: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty1-1363229911568/solr.xml
[junit4:junit4]   2> 4892 T42 oasc.CoreContainer.<init> New CoreContainer 427668273
[junit4:junit4]   2> 4893 T42 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty1-1363229911568/'
[junit4:junit4]   2> 4893 T42 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty1-1363229911568/'
[junit4:junit4]   2> 4960 T42 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 4961 T42 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 4962 T42 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 4962 T42 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 4963 T42 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 4964 T42 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 4964 T42 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 4965 T42 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 4966 T42 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 4967 T42 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 5000 T42 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 5024 T42 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:42070/solr
[junit4:junit4]   2> 5025 T42 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 5026 T42 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:42070 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@589c66bc
[junit4:junit4]   2> 5027 T42 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 5028 T81 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost/127.0.0.1:42070. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 5029 T81 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:42070, initiating session
[junit4:junit4]   2> 5029 T44 oazs.NIOServerCnxnFactory.run Accepted socket connection from /140.211.11.196:42091
[junit4:junit4]   2> 5030 T44 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /140.211.11.196:42091
[junit4:junit4]   2> 5032 T46 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d66d599130005 with negotiated timeout 20000 for client /140.211.11.196:42091
[junit4:junit4]   2> 5032 T81 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost/127.0.0.1:42070, sessionid = 0x13d66d599130005, negotiated timeout = 20000
[junit4:junit4]   2> 5033 T82 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@589c66bc name:ZooKeeperConnection Watcher:127.0.0.1:42070 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 5034 T42 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 5035 T48 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d66d599130005
[junit4:junit4]   2> 5047 T82 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 5046 T42 oaz.ZooKeeper.close Session: 0x13d66d599130005 closed
[junit4:junit4]   2> 5047 T44 oazs.NIOServerCnxn.closeSock Closed socket connection for client /140.211.11.196:42091 which had sessionid 0x13d66d599130005
[junit4:junit4]   2> 5048 T42 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 5060 T42 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:42070/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@5055c55c
[junit4:junit4]   2> 5062 T42 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 5062 T83 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost/127.0.0.1:42070. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 5063 T83 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:42070, initiating session
[junit4:junit4]   2> 5063 T44 oazs.NIOServerCnxnFactory.run Accepted socket connection from /140.211.11.196:42092
[junit4:junit4]   2> 5064 T44 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /140.211.11.196:42092
[junit4:junit4]   2> 5066 T46 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d66d599130006 with negotiated timeout 20000 for client /140.211.11.196:42092
[junit4:junit4]   2> 5066 T83 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost/127.0.0.1:42070, sessionid = 0x13d66d599130006, negotiated timeout = 20000
[junit4:junit4]   2> 5067 T84 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5055c55c name:ZooKeeperConnection Watcher:127.0.0.1:42070/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 5067 T42 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 5069 T48 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d66d599130006 type:create cxid:0x1 zxid:0x4a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5070 T48 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d66d599130006 type:create cxid:0x2 zxid:0x4b txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5079 T42 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 6085 T42 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42090_
[junit4:junit4]   2> 6086 T48 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d66d599130006 type:delete cxid:0xb zxid:0x4c txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:42090_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:42090_
[junit4:junit4]   2> 6096 T42 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:42090_
[junit4:junit4]   2> 6113 T64 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 6113 T64 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 6113 T84 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 6114 T71 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 6117 T65 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6118 T65 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core_node_name":"1",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"control_collection",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:42073_",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:42073"}
[junit4:junit4]   2> 6135 T85 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty1-1363229911568/collection1
[junit4:junit4]   2> 6135 T85 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 6136 T85 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 6137 T85 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 6137 T84 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 6137 T71 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 6147 T85 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty1-1363229911568/collection1/'
[junit4:junit4]   2> 6152 T85 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-jetty1-1363229911568/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 6152 T64 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 6153 T85 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-jetty1-1363229911568/collection1/lib/README' to classloader
[junit4:junit4]   2> 6208 T85 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_43
[junit4:junit4]   2> 6289 T85 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 6390 T85 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 6405 T85 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 7020 T85 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 7034 T85 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 7038 T85 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 7069 T85 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 7076 T85 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 7081 T85 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 7083 T85 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 7084 T85 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 7084 T85 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 7086 T85 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 7087 T85 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 7087 T85 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 7087 T85 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty1-1363229911568/collection1/, dataDir=./org.apache.solr.cloud.SyncSliceTest-1363229906900/jetty1/
[junit4:junit4]   2> 7088 T85 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@4b7c8ca2
[junit4:junit4]   2> 7089 T85 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 7089 T85 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.SyncSliceTest-1363229906900/jetty1 forceNew: false
[junit4:junit4]   2> 7090 T85 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.SyncSliceTest-1363229906900/jetty1/index/
[junit4:junit4]   2> 7090 T85 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.SyncSliceTest-1363229906900/jetty1/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 7091 T85 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.SyncSliceTest-1363229906900/jetty1/index forceNew: false
[junit4:junit4]   2> 7094 T85 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@989e216 lockFactory=org.apache.lucene.store.NativeFSLockFactory@17ac05e3),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 7094 T85 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 7097 T85 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 7098 T85 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 7099 T85 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 7099 T85 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 7100 T85 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 7101 T85 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 7101 T85 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 7102 T85 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 7102 T85 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 7113 T85 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 7119 T85 oass.SolrIndexSearcher.<init> Opening Searcher@28be9be main
[junit4:junit4]   2> 7119 T85 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=./org.apache.solr.cloud.SyncSliceTest-1363229906900/jetty1/tlog
[junit4:junit4]   2> 7120 T85 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 7121 T85 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 7126 T86 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@28be9be main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 7129 T85 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 7129 T85 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 7655 T65 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7656 T65 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core_node_name":"2",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:42090_",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:42090"}
[junit4:junit4]   2> 7657 T65 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with numShards 1
[junit4:junit4]   2> 7657 T65 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 7671 T84 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 7671 T64 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 7671 T71 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 8133 T85 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 8133 T85 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:42090 collection:collection1 shard:shard1
[junit4:junit4]   2> 8135 T85 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 8154 T48 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d66d599130006 type:delete cxid:0x48 zxid:0x5c txntype:-1 reqpath:n/a Error Path:/solr/collections/collection1/leaders Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders
[junit4:junit4]   2> 8155 T85 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 8156 T48 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d66d599130006 type:create cxid:0x49 zxid:0x5d txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8166 T85 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 8167 T85 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 8167 T85 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:42090/collection1/
[junit4:junit4]   2> 8167 T85 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 8168 T85 oasc.SyncStrategy.syncToMe http://127.0.0.1:42090/collection1/ has no replicas
[junit4:junit4]   2> 8168 T85 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:42090/collection1/
[junit4:junit4]   2> 8169 T85 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 8176 T48 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d66d599130006 type:create cxid:0x53 zxid:0x61 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 9179 T65 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9208 T64 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 9208 T71 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 9208 T84 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 9257 T85 oasc.ZkController.register We are http://127.0.0.1:42090/collection1/ and leader is http://127.0.0.1:42090/collection1/
[junit4:junit4]   2> 9257 T85 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:42090
[junit4:junit4]   2> 9257 T85 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 9258 T85 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 9258 T85 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 9261 T85 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9263 T42 oass.SolrDispatchFilter.init user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1
[junit4:junit4]   2> 9264 T42 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 9265 T42 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 9510 T42 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 9514 T42 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:42095
[junit4:junit4]   2> 9515 T42 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 9516 T42 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 9517 T42 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty2-1363229916168
[junit4:junit4]   2> 9518 T42 oasc.CoreContainer$Initializer.initialize looking for solr config file: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty2-1363229916168/solr.xml
[junit4:junit4]   2> 9519 T42 oasc.CoreContainer.<init> New CoreContainer 1402346956
[junit4:junit4]   2> 9520 T42 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty2-1363229916168/'
[junit4:junit4]   2> 9521 T42 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty2-1363229916168/'
[junit4:junit4]   2> 9622 T42 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 9623 T42 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 9624 T42 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 9624 T42 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 9625 T42 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 9626 T42 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 9626 T42 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 9627 T42 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 9628 T42 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 9628 T42 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 9654 T42 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 9682 T42 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:42070/solr
[junit4:junit4]   2> 9683 T42 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 9684 T42 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:42070 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@1101ef9e
[junit4:junit4]   2> 9685 T42 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 9686 T97 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost/127.0.0.1:42070. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 9687 T97 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:42070, initiating session
[junit4:junit4]   2> 9687 T44 oazs.NIOServerCnxnFactory.run Accepted socket connection from /140.211.11.196:42096
[junit4:junit4]   2> 9688 T44 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /140.211.11.196:42096
[junit4:junit4]   2> 9695 T46 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d66d599130007 with negotiated timeout 20000 for client /140.211.11.196:42096
[junit4:junit4]   2> 9695 T97 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost/127.0.0.1:42070, sessionid = 0x13d66d599130007, negotiated timeout = 20000
[junit4:junit4]   2> 9696 T98 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1101ef9e name:ZooKeeperConnection Watcher:127.0.0.1:42070 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 9696 T42 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 9699 T48 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d66d599130007
[junit4:junit4]   2> 9701 T98 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 9701 T42 oaz.ZooKeeper.close Session: 0x13d66d599130007 closed
[junit4:junit4]   2> 9701 T44 oazs.NIOServerCnxn.closeSock Closed socket connection for client /140.211.11.196:42096 which had sessionid 0x13d66d599130007
[junit4:junit4]   2> 9702 T42 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 9716 T42 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:42070/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@3af039d2
[junit4:junit4]   2> 9718 T42 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 9718 T99 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost/127.0.0.1:42070. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 9720 T99 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:42070, initiating session
[junit4:junit4]   2> 9720 T44 oazs.NIOServerCnxnFactory.run Accepted socket connection from /140.211.11.196:42097
[junit4:junit4]   2> 9721 T44 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /140.211.11.196:42097
[junit4:junit4]   2> 9723 T46 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d66d599130008 with negotiated timeout 20000 for client /140.211.11.196:42097
[junit4:junit4]   2> 9723 T99 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost/127.0.0.1:42070, sessionid = 0x13d66d599130008, negotiated timeout = 20000
[junit4:junit4]   2> 9724 T100 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3af039d2 name:ZooKeeperConnection Watcher:127.0.0.1:42070/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 9724 T42 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 9726 T48 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d66d599130008 type:create cxid:0x1 zxid:0x6e txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 9728 T48 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d66d599130008 type:create cxid:0x2 zxid:0x6f txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 9732 T42 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 10719 T65 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 10721 T65 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core_node_name":"2",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:42090_",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:42090"}
[junit4:junit4]   2> 10749 T42 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42095_
[junit4:junit4]   2> 10750 T48 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d66d599130008 type:delete cxid:0xb zxid:0x71 txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:42095_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:42095_
[junit4:junit4]   2> 10751 T42 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:42095_
[junit4:junit4]   2> 10761 T71 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 10762 T84 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 10762 T100 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 10762 T84 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 10763 T100 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 10773 T71 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 10773 T84 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 10774 T71 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 10775 T64 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 10775 T64 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 10777 T64 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 10780 T101 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty2-1363229916168/collection1
[junit4:junit4]   2> 10780 T101 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 10781 T101 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 10782 T101 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 10783 T101 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty2-1363229916168/collection1/'
[junit4:junit4]   2> 10784 T101 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-jetty2-1363229916168/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 10785 T101 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-jetty2-1363229916168/collection1/lib/README' to classloader
[junit4:junit4]   2> 10828 T101 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_43
[junit4:junit4]   2> 10877 T101 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 10979 T101 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 10991 T101 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 11724 T101 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 11741 T101 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 11746 T101 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 11784 T101 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 11792 T101 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 11799 T101 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 11801 T101 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 11801 T101 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 11802 T101 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 11804 T101 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 11804 T101 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 11805 T101 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 11805 T101 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty2-1363229916168/collection1/, dataDir=./org.apache.solr.cloud.SyncSliceTest-1363229906900/jetty2/
[junit4:junit4]   2> 11806 T101 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@4b7c8ca2
[junit4:junit4]   2> 11807 T101 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 11808 T101 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.SyncSliceTest-1363229906900/jetty2 forceNew: false
[junit4:junit4]   2> 11808 T101 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.SyncSliceTest-1363229906900/jetty2/index/
[junit4:junit4]   2> 11809 T101 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.SyncSliceTest-1363229906900/jetty2/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 11809 T101 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.SyncSliceTest-1363229906900/jetty2/index forceNew: false
[junit4:junit4]   2> 11813 T101 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@9c733ba lockFactory=org.apache.lucene.store.NativeFSLockFactory@35051a1c),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 11814 T101 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 11818 T101 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 11818 T101 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 11819 T101 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 11820 T101 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 11821 T101 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 11822 T101 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 11822 T101 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 11823 T101 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 11824 T101 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 11837 T101 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 11844 T101 oass.SolrIndexSearcher.<init> Opening Searcher@7f3b28bf main
[junit4:junit4]   2> 11845 T101 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=./org.apache.solr.cloud.SyncSliceTest-1363229906900/jetty2/tlog
[junit4:junit4]   2> 11846 T101 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 11846 T101 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 11853 T102 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7f3b28bf main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 11857 T101 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 11857 T101 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 12290 T65 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 12291 T65 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core_node_name":"3",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:42095_",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:42095"}
[junit4:junit4]   2> 12292 T65 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=1
[junit4:junit4]   2> 12292 T65 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 12300 T71 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 12300 T84 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 12301 T100 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 12300 T64 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 12886 T101 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 12886 T101 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:42095 collection:collection1 shard:shard1
[junit4:junit4]   2> 12892 T101 oasc.ZkController.register We are http://127.0.0.1:42095/collection1/ and leader is http://127.0.0.1:42090/collection1/
[junit4:junit4]   2> 12892 T101 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:42095
[junit4:junit4]   2> 12893 T101 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 12893 T101 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C3 name=collection1 org.apache.solr.core.SolrCore@63226d58 url=http://127.0.0.1:42095/collection1 node=127.0.0.1:42095_ C3_STATE=coll:collection1 core:collection1 props:{state=down, core=collection1, collection=collection1, node_name=127.0.0.1:42095_, base_url=http://127.0.0.1:42095}
[junit4:junit4]   2> 12900 T103 C3 P42095 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 12901 T101 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 12901 T103 C3 P42095 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 12902 T103 C3 P42095 oasc.ZkController.publish publishing core=collection1 state=recovering
[junit4:junit4]   2> 12903 T103 C3 P42095 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 12903 T42 oass.SolrDispatchFilter.init user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1
[junit4:junit4]   2> 12904 T42 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 12905 T42 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 12905 T103 C3 P42095 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 13132 T42 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 13136 T42 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:42099
[junit4:junit4]   2> 13137 T42 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 13138 T42 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 13138 T42 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty3-1363229919815
[junit4:junit4]   2> 13139 T42 oasc.CoreContainer$Initializer.initialize looking for solr config file: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty3-1363229919815/solr.xml
[junit4:junit4]   2> 13140 T42 oasc.CoreContainer.<init> New CoreContainer 135241951
[junit4:junit4]   2> 13141 T42 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty3-1363229919815/'
[junit4:junit4]   2> 13141 T42 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty3-1363229919815/'
[junit4:junit4]   2> 13208 T42 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 13209 T42 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 13209 T42 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 13210 T42 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 13211 T42 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 13211 T42 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 13212 T42 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 13213 T42 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 13213 T42 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 13214 T42 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 13240 T42 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 13263 T42 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:42070/solr
[junit4:junit4]   2> 13264 T42 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 13265 T42 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:42070 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@20ebaf86
[junit4:junit4]   2> 13266 T42 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 13267 T114 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost/127.0.0.1:42070. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 13268 T114 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:42070, initiating session
[junit4:junit4]   2> 13268 T44 oazs.NIOServerCnxnFactory.run Accepted socket connection from /140.211.11.196:42100
[junit4:junit4]   2> 13269 T44 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /140.211.11.196:42100
[junit4:junit4]   2> 13271 T46 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d66d599130009 with negotiated timeout 20000 for client /140.211.11.196:42100
[junit4:junit4]   2> 13271 T114 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost/127.0.0.1:42070, sessionid = 0x13d66d599130009, negotiated timeout = 20000
[junit4:junit4]   2> 13272 T115 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@20ebaf86 name:ZooKeeperConnection Watcher:127.0.0.1:42070 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 13272 T42 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 13274 T48 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d66d599130009
[junit4:junit4]   2> 13275 T115 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 13275 T42 oaz.ZooKeeper.close Session: 0x13d66d599130009 closed
[junit4:junit4]   2> 13276 T44 oazs.NIOServerCnxn.closeSock Closed socket connection for client /140.211.11.196:42100 which had sessionid 0x13d66d599130009
[junit4:junit4]   2> 13276 T42 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 13291 T42 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:42070/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@14c9d776
[junit4:junit4]   2> 13293 T42 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 13293 T116 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost/127.0.0.1:42070. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 13294 T116 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:42070, initiating session
[junit4:junit4]   2> 13294 T44 oazs.NIOServerCnxnFactory.run Accepted socket connection from /140.211.11.196:42101
[junit4:junit4]   2> 13295 T44 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /140.211.11.196:42101
[junit4:junit4]   2> 13297 T46 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d66d59913000a with negotiated timeout 20000 for client /140.211.11.196:42101
[junit4:junit4]   2> 13297 T116 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost/127.0.0.1:42070, sessionid = 0x13d66d59913000a, negotiated timeout = 20000
[junit4:junit4]   2> 13298 T117 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@14c9d776 name:ZooKeeperConnection Watcher:127.0.0.1:42070/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 13298 T42 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 13300 T48 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d66d59913000a type:create cxid:0x1 zxid:0x81 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 13302 T48 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d66d59913000a type:create cxid:0x2 zxid:0x82 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 13305 T42 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 13810 T65 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 13812 T65 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core_node_name":"3",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"recovering",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:42095_",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:42095"}
[junit4:junit4]   2> 13836 T84 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 13835 T71 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 13836 T117 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 13836 T100 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 13836 T64 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 13949 T74 oass.SolrDispatchFilter.handleAdminRequest /admin/cores {coreNodeName=3&state=recovering&nodeName=127.0.0.1:42095_&action=PREPRECOVERY&checkLive=true&core=collection1&wt=javabin&onlyIfLeader=true&version=2} status=0 QTime=1008 
[junit4:junit4]   2> 14311 T42 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42099_
[junit4:junit4]   2> 14312 T48 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d66d59913000a type:delete cxid:0xd zxid:0x87 txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:42099_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:42099_
[junit4:junit4]   2> 14314 T42 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:42099_
[junit4:junit4]   2> 14318 T71 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 14318 T117 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 14318 T64 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 14319 T100 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 14319 T84 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 14319 T64 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 14319 T100 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 14320 T84 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 14321 T71 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 14322 T117 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 14347 T118 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty3-1363229919815/collection1
[junit4:junit4]   2> 14347 T118 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 14349 T118 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 14349 T118 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 14352 T118 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty3-1363229919815/collection1/'
[junit4:junit4]   2> 14353 T118 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-jetty3-1363229919815/collection1/lib/README' to classloader
[junit4:junit4]   2> 14354 T118 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-jetty3-1363229919815/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 14423 T118 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_43
[junit4:junit4]   2> 14494 T118 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 14595 T118 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 14608 T118 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 15362 T118 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 15380 T118 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 15385 T118 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 15433 T118 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 15442 T118 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 15451 T118 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 15454 T118 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 15454 T118 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 15455 T118 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 15458 T118 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 15458 T118 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 15459 T118 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 15460 T118 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty3-1363229919815/collection1/, dataDir=./org.apache.solr.cloud.SyncSliceTest-1363229906900/jetty3/
[junit4:junit4]   2> 15460 T118 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@4b7c8ca2
[junit4:junit4]   2> 15461 T118 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 15463 T118 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.SyncSliceTest-1363229906900/jetty3 forceNew: false
[junit4:junit4]   2> 15463 T118 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.SyncSliceTest-1363229906900/jetty3/index/
[junit4:junit4]   2> 15464 T118 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.SyncSliceTest-1363229906900/jetty3/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 15465 T118 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.SyncSliceTest-1363229906900/jetty3/index forceNew: false
[junit4:junit4]   2> 15470 T118 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@de6a2fc lockFactory=org.apache.lucene.store.NativeFSLockFactory@57889f9a),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 15470 T118 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 15474 T118 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 15475 T118 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 15476 T118 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 15477 T118 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 15478 T118 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 15478 T118 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 15479 T118 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 15480 T118 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 15481 T118 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 15492 T118 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 15497 T118 oass.SolrIndexSearcher.<init> Opening Searcher@491f297e main
[junit4:junit4]   2> 15498 T118 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=./org.apache.solr.cloud.SyncSliceTest-1363229906900/jetty3/tlog
[junit4:junit4]   2> 15499 T118 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 15500 T118 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 15506 T119 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@491f297e main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 15509 T118 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 15509 T118 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2>  C3_STATE=coll:collection1 core:collection1 props:{shard=shard1, state=recovering, core=collection1, collection=collection1, node_name=127.0.0.1:42095_, base_url=http://127.0.0.1:42095}
[junit4:junit4]   2> 15952 T103 C3 P42095 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:42090/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 15960 T103 C3 P42095 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&connTimeout=30000&socketTimeout=30000&retry=false
[junit4:junit4]   2> 15979 T103 C3 P42095 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:42095 START replicas=[http://127.0.0.1:42090/collection1/] nUpdates=100
[junit4:junit4]   2> 15987 T103 C3 P42095 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 15988 T103 C3 P42095 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 15988 T103 C3 P42095 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 15988 T103 C3 P42095 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 15988 T103 C3 P42095 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 15989 T103 C3 P42095 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:42090/collection1/. core=collection1
[junit4:junit4]   2> 15989 T103 C3 P42095 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C4 name=collection1 org.apache.solr.core.SolrCore@16f300fa url=http://127.0.0.1:42090/collection1 node=127.0.0.1:42090_ C4_STATE=coll:collection1 core:collection1 props:{shard=shard1, state=active, core=collection1, collection=collection1, node_name=127.0.0.1:42090_, base_url=http://127.0.0.1:42090, leader=true}
[junit4:junit4]   2> 16026 T76 C4 P42090 REQ /get {getVersions=100&distrib=false&wt=javabin&qt=/get&version=2} status=0 QTime=0 
[junit4:junit4]   2> 16048 T75 C4 P42090 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 16052 T75 C4 P42090 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@989e216 lockFactory=org.apache.lucene.store.NativeFSLockFactory@17ac05e3),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 16053 T75 C4 P42090 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 16054 T75 C4 P42090 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@989e216 lockFactory=org.apache.lucene.store.NativeFSLockFactory@17ac05e3),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@989e216 lockFactory=org.apache.lucene.store.NativeFSLockFactory@17ac05e3),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 16056 T75 C4 P42090 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 16057 T75 C4 P42090 oass.SolrIndexSearcher.<init> Opening Searcher@7715d970 realtime
[junit4:junit4]   2> 16057 T75 C4 P42090 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 16058 T75 C4 P42090 /update {waitSearcher=true&openSearcher=false&commit=true&wt=javabin&commit_end_point=true&version=2&softCommit=false} {commit=} 0 30
[junit4:junit4]   2> 16059 T103 C3 P42095 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 16060 T103 C3 P42095 oascsi.HttpClientUtil.createClient Creating new http client, config:connTimeout=5000&socketTimeout=20000&allowCompression=false&maxConnections=10000&maxConnectionsPerHost=10000
[junit4:junit4]   2> 16077 T103 C3 P42095 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 16131 T77 C4 P42090 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 16132 T77 C4 P42090 REQ /replication {command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=53 
[junit4:junit4]   2> 16133 T103 C3 P42095 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4:junit4]   2> 16134 T103 C3 P42095 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4:junit4]   2> 16134 T103 C3 P42095 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4]   2> 16138 T77 C4 P42090 REQ /replication {command=filelist&qt=/replication&wt=javabin&generation=2&version=2} status=0 QTime=1 
[junit4:junit4]   2> 16139 T103 C3 P42095 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 1
[junit4:junit4]   2> 16141 T103 C3 P42095 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.SyncSliceTest-1363229906900/jetty2/index.20130314045843030 forceNew: false
[junit4:junit4]   2> 16141 T103 C3 P42095 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory@18c8cd35 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3117c5ac) fullCopy=false
[junit4:junit4]   2> 16152 T77 C4 P42090 REQ /replication {file=segments_2&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=3 
[junit4:junit4]   2> 16159 T103 C3 P42095 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4]   2> 16166 T103 C3 P42095 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4]   2> 16167 T103 C3 P42095 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4]   2> 16171 T103 C3 P42095 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@9c733ba lockFactory=org.apache.lucene.store.NativeFSLockFactory@35051a1c),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@9c733ba lockFactory=org.apache.lucene.store.NativeFSLockFactory@35051a1c),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 16172 T103 C3 P42095 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 16173 T103 C3 P42095 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4]   2> 16174 T103 C3 P42095 oass.SolrIndexSearcher.<init> Opening Searcher@74c4f52d main
[junit4:junit4]   2> 16175 T102 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@74c4f52d main{StandardDirectoryReader(segments_2:1:nrt)}
[junit4:junit4]   2> 16176 T103 C3 P42095 oash.SnapPuller.fetchLatestIndex removing temporary index download directory files MockDirWrapper(org.apache.lucene.store.RAMDirectory@18c8cd35 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3117c5ac)
[junit4:junit4]   2> 16177 T103 C3 P42095 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 16178 T103 C3 P42095 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 16178 T103 C3 P42095 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 16178 T103 C3 P42095 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 16206 T103 C3 P42095 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 16864 T65 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 16868 T65 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core_node_name":"4",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:42099_",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:42099"}
[junit4:junit4]   2> 16869 T65 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=1
[junit4:junit4]   2> 16869 T65 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 16909 T65 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core_node_name":"3",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:42095_",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:42095"}
[junit4:junit4]   2> 16917 T71 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 16917 T117 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 16917 T100 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 16917 T64 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 16917 T84 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 17539 T118 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 17539 T118 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:42099 collection:collection1 shard:shard1
[junit4:junit4]   2> 17545 T118 oasc.ZkController.register We are http://127.0.0.1:42099/collection1/ and leader is http://127.0.0.1:42090/collection1/
[junit4:junit4]   2> 17546 T118 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:42099
[junit4:junit4]   2> 17546 T118 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 17547 T118 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C5 name=collection1 org.apache.solr.core.SolrCore@5685415b url=http://127.0.0.1:42099/collection1 node=127.0.0.1:42099_ C5_STATE=coll:collection1 core:collection1 props:{state=down, core=collection1, collection=collection1, node_name=127.0.0.1:42099_, base_url=http://127.0.0.1:42099}
[junit4:junit4]   2> 17548 T122 C5 P42099 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 17548 T118 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 17549 T122 C5 P42099 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 17550 T122 C5 P42099 oasc.ZkController.publish publishing core=collection1 state=recovering
[junit4:junit4]   2> 17550 T122 C5 P42099 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 17551 T42 oass.SolrDispatchFilter.init user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1
[junit4:junit4]   2> 17552 T122 C5 P42099 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 17552 T42 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 17554 T42 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 17780 T42 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 17784 T42 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:42113
[junit4:junit4]   2> 17785 T42 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 17786 T42 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 17786 T42 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty4-1363229924468
[junit4:junit4]   2> 17787 T42 oasc.CoreContainer$Initializer.initialize looking for solr config file: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty4-1363229924468/solr.xml
[junit4:junit4]   2> 17788 T42 oasc.CoreContainer.<init> New CoreContainer 1849728616
[junit4:junit4]   2> 17789 T42 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty4-1363229924468/'
[junit4:junit4]   2> 17789 T42 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty4-1363229924468/'
[junit4:junit4]   2> 17855 T42 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 17856 T42 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 17856 T42 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 17857 T42 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 17857 T42 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 17858 T42 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 17859 T42 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 17859 T42 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 17860 T42 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 17861 T42 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 17886 T42 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 17909 T42 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:42070/solr
[junit4:junit4]   2> 17910 T42 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 17910 T42 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:42070 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@559ac729
[junit4:junit4]   2> 17912 T42 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 17912 T133 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost/127.0.0.1:42070. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 17913 T133 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:42070, initiating session
[junit4:junit4]   2> 17914 T44 oazs.NIOServerCnxnFactory.run Accepted socket connection from /140.211.11.196:42114
[junit4:junit4]   2> 17914 T44 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /140.211.11.196:42114
[junit4:junit4]   2> 17917 T46 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d66d59913000b with negotiated timeout 20000 for client /140.211.11.196:42114
[junit4:junit4]   2> 17917 T133 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost/127.0.0.1:42070, sessionid = 0x13d66d59913000b, negotiated timeout = 20000
[junit4:junit4]   2> 17918 T134 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@559ac729 name:ZooKeeperConnection Watcher:127.0.0.1:42070 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 17918 T42 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 17920 T48 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d66d59913000b
[junit4:junit4]   2> 17931 T134 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 17931 T44 oazs.NIOServerCnxn.closeSock Closed socket connection for client /140.211.11.196:42114 which had sessionid 0x13d66d59913000b
[junit4:junit4]   2> 17931 T42 oaz.ZooKeeper.close Session: 0x13d66d59913000b closed
[junit4:junit4]   2> 17932 T42 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 17946 T42 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:42070/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@27458911
[junit4:junit4]   2> 17947 T42 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 17948 T135 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost/127.0.0.1:42070. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 17949 T135 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:42070, initiating session
[junit4:junit4]   2> 17949 T44 oazs.NIOServerCnxnFactory.run Accepted socket connection from /140.211.11.196:42115
[junit4:junit4]   2> 17950 T44 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /140.211.11.196:42115
[junit4:junit4]   2> 17951 T46 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d66d59913000c with negotiated timeout 20000 for client /140.211.11.196:42115
[junit4:junit4]   2> 17951 T135 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost/127.0.0.1:42070, sessionid = 0x13d66d59913000c, negotiated timeout = 20000
[junit4:junit4]   2> 17952 T136 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@27458911 name:ZooKeeperConnection Watcher:127.0.0.1:42070/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 17952 T42 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 17954 T48 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d66d59913000c type:create cxid:0x1 zxid:0x98 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 17956 T48 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d66d59913000c type:create cxid:0x2 zxid:0x99 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 17968 T42 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 18433 T65 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 18435 T65 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core_node_name":"4",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"recovering",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:42099_",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:42099"}
[junit4:junit4]   2> 18456 T136 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 18456 T84 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 18456 T117 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 18456 T64 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 18456 T100 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 18456 T71 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 18577 T78 oass.SolrDispatchFilter.handleAdminRequest /admin/cores {coreNodeName=4&state=recovering&nodeName=127.0.0.1:42099_&action=PREPRECOVERY&checkLive=true&core=collection1&wt=javabin&onlyIfLeader=true&version=2} status=0 QTime=1001 
[junit4:junit4]   2> 18975 T42 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42113_
[junit4:junit4]   2> 18977 T48 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d66d59913000c type:delete cxid:0xd zxid:0x9e txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:42113_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:42113_
[junit4:junit4]   2> 18982 T42 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:42113_
[junit4:junit4]   2> 18986 T136 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 18986 T71 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 18987 T117 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 18987 T84 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 18988 T64 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 18988 T84 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 18988 T100 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 18988 T64 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 18989 T100 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 18991 T117 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 18991 T71 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 18992 T136 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 19067 T137 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty4-1363229924468/collection1
[junit4:junit4]   2> 19068 T137 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 19069 T137 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 19070 T137 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 19072 T137 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty4-1363229924468/collection1/'
[junit4:junit4]   2> 19074 T137 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-jetty4-1363229924468/collection1/lib/README' to classloader
[junit4:junit4]   2> 19075 T137 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-jetty4-1363229924468/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 19132 T137 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_43
[junit4:junit4]   2> 19202 T137 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 19303 T137 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 19316 T137 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 20185 T137 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 20196 T137 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 20199 T137 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 20228 T137 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 20233 T137 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 20238 T137 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 20240 T137 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 20241 T137 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 20241 T137 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 20243 T137 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 20243 T137 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 20243 T137 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 20244 T137 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty4-1363229924468/collection1/, dataDir=./org.apache.solr.cloud.SyncSliceTest-1363229906900/jetty4/
[junit4:junit4]   2> 20244 T137 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@4b7c8ca2
[junit4:junit4]   2> 20245 T137 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 20245 T137 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.SyncSliceTest-1363229906900/jetty4 forceNew: false
[junit4:junit4]   2> 20245 T137 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.SyncSliceTest-1363229906900/jetty4/index/
[junit4:junit4]   2> 20246 T137 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.SyncSliceTest-1363229906900/jetty4/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 20246 T137 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.SyncSliceTest-1363229906900/jetty4/index forceNew: false
[junit4:junit4]   2> 20249 T137 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@704bc04c lockFactory=org.apache.lucene.store.NativeFSLockFactory@6f2ea9f0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 20250 T137 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 20252 T137 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 20252 T137 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 20253 T137 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 20254 T137 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 20255 T137 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 20255 T137 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 20255 T137 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 20256 T137 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 20256 T137 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 20267 T137 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 20272 T137 oass.SolrIndexSearcher.<init> Opening Searcher@3851660 main
[junit4:junit4]   2> 20272 T137 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=./org.apache.solr.cloud.SyncSliceTest-1363229906900/jetty4/tlog
[junit4:junit4]   2> 20273 T137 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 20273 T137 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 20278 T138 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3851660 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 20280 T137 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 20280 T137 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2>  C5_STATE=coll:collection1 core:collection1 props:{shard=shard1, state=recovering, core=collection1, collection=collection1, node_name=127.0.0.1:42099_, base_url=http://127.0.0.1:42099}
[junit4:junit4]   2> 20579 T122 C5 P42099 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:42090/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 20579 T122 C5 P42099 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:42099 START replicas=[http://127.0.0.1:42090/collection1/] nUpdates=100
[junit4:junit4]   2> 20580 T122 C5 P42099 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 20580 T122 C5 P42099 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 20580 T122 C5 P42099 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 20581 T122 C5 P42099 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 20581 T122 C5 P42099 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 20581 T122 C5 P42099 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:42090/collection1/. core=collection1
[junit4:junit4]   2> 20581 T122 C5 P42099 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 20583 T76 C4 P42090 REQ /get {getVersions=100&distrib=false&wt=javabin&qt=/get&version=2} status=0 QTime=0 
[junit4:junit4]   2> 20596 T79 C4 P42090 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 20597 T79 C4 P42090 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@989e216 lockFactory=org.apache.lucene.store.NativeFSLockFactory@17ac05e3),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@989e216 lockFactory=org.apache.lucene.store.NativeFSLockFactory@17ac05e3),segFN=segments_3,generation=3,filenames=[segments_3]
[junit4:junit4]   2> 20597 T79 C4 P42090 oasc.SolrDeletionPolicy.updateCommits newest commit = 3[segments_3]
[junit4:junit4]   2> 20598 T79 C4 P42090 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 20598 T79 C4 P42090 /update {waitSearcher=true&openSearcher=false&commit=true&wt=javabin&commit_end_point=true&version=2&softCommit=false} {commit=} 0 2
[junit4:junit4]   2> 20600 T122 C5 P42099 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 20600 T122 C5 P42099 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 20604 T77 C4 P42090 REQ /replication {command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 20605 T122 C5 P42099 oash.SnapPuller.fetchLatestIndex Master's generation: 3
[junit4:junit4]   2> 20605 T122 C5 P42099 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4:junit4]   2> 20605 T122 C5 P42099 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4]   2> 20609 T77 C4 P42090 REQ /replication {command=filelist&qt=/replication&wt=javabin&generation=3&version=2} status=0 QTime=1 
[junit4:junit4]   2> 20610 T122 C5 P42099 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 1
[junit4:junit4]   2> 20611 T122 C5 P42099 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.SyncSliceTest-1363229906900/jetty3/index.20130314045847500 forceNew: false
[junit4:junit4]   2> 20611 T122 C5 P42099 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory@3f2b3280 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6aaa1f) fullCopy=false
[junit4:junit4]   2> 20615 T77 C4 P42090 REQ /replication {file=segments_3&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=1 
[junit4:junit4]   2> 20616 T122 C5 P42099 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4]   2> 20617 T122 C5 P42099 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4]   2> 20617 T122 C5 P42099 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4]   2> 20620 T122 C5 P42099 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@de6a2fc lockFactory=org.apache.lucene.store.NativeFSLockFactory@57889f9a),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@de6a2fc lockFactory=org.apache.lucene.store.NativeFSLockFactory@57889f9a),segFN=segments_3,generation=3,filenames=[segments_3]
[junit4:junit4]   2> 20620 T122 C5 P42099 oasc.SolrDeletionPolicy.updateCommits newest commit = 3[segments_3]
[junit4:junit4]   2> 20620 T122 C5 P42099 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4]   2> 20621 T122 C5 P42099 oass.SolrIndexSearcher.<init> Opening Searcher@16e3559d main
[junit4:junit4]   2> 20622 T119 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@16e3559d main{StandardDirectoryReader(segments_3:1:nrt)}
[junit4:junit4]   2> 20622 T122 C5 P42099 oash.SnapPuller.fetchLatestIndex removing temporary index download directory files MockDirWrapper(org.apache.lucene.store.RAMDirectory@3f2b3280 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6aaa1f)
[junit4:junit4]   2> 20623 T122 C5 P42099 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 20623 T122 C5 P42099 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 20623 T122 C5 P42099 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 20623 T122 C5 P42099 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 20626 T122 C5 P42099 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 21472 T65 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 21475 T65 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core_node_name":"5",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:42113_",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:42113"}
[junit4:junit4]   2> 21476 T65 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=1
[junit4:junit4]   2> 21476 T65 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 21488 T65 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core_node_name":"4",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:42099_",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:42099"}
[junit4:junit4]   2> 21497 T71 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 21497 T136 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 21497 T117 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 21497 T100 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 21497 T84 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 21497 T64 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 22286 T137 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 22286 T137 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:42113 collection:collection1 shard:shard1
[junit4:junit4]   2> 22291 T137 oasc.ZkController.register We are http://127.0.0.1:42113/collection1/ and leader is http://127.0.0.1:42090/collection1/
[junit4:junit4]   2> 22291 T137 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:42113
[junit4:junit4]   2> 22292 T137 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 22292 T137 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C6 name=collection1 org.apache.solr.core.SolrCore@3442b24f url=http://127.0.0.1:42113/collection1 node=127.0.0.1:42113_ C6_STATE=coll:collection1 core:collection1 props:{state=down, core=collection1, collection=collection1, node_name=127.0.0.1:42113_, base_url=http://127.0.0.1:42113}
[junit4:junit4]   2> 22292 T141 C6 P42113 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 22293 T137 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 22293 T141 C6 P42113 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 22294 T141 C6 P42113 oasc.ZkController.publish publishing core=collection1 state=recovering
[junit4:junit4]   2> 22294 T141 C6 P42113 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 22295 T42 oass.SolrDispatchFilter.init user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J1
[junit4:junit4]   2> 22295 T42 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 22296 T42 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 22306 T141 C6 P42113 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 22306 T42 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 22308 T42 oasc.AbstractFullDistribZkTestBase.waitForThingsToLevelOut Wait for recoveries to finish - wait 15 for each attempt
[junit4:junit4]   2> 22309 T42 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Wait for recoveries to finish - collection: collection1 failOnTimeout:true timeout (sec):15
[junit4:junit4]   2> 22310 T42 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 23016 T65 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 23018 T65 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core_node_name":"5",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"recovering",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:42113_",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:42113"}
[junit4:junit4]   2> 23031 T136 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 23031 T71 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 23032 T117 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 23032 T100 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 23031 T84 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 23031 T64 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 23313 T42 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 23319 T80 oass.SolrDispatchFilter.handleAdminRequest /admin/cores {coreNodeName=5&state=recovering&nodeName=127.0.0.1:42113_&action=PREPRECOVERY&checkLive=true&core=collection1&wt=javabin&onlyIfLeader=true&version=2} status=0 QTime=1001 
[junit4:junit4]   2> 24317 T42 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2>  C6_STATE=coll:collection1 core:collection1 props:{shard=shard1, state=recovering, core=collection1, collection=collection1, node_name=127.0.0.1:42113_, base_url=http://127.0.0.1:42113}
[junit4:junit4]   2> 25321 T141 C6 P42113 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:42090/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 25321 T42 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 25321 T141 C6 P42113 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:42113 START replicas=[http://127.0.0.1:42090/collection1/] nUpdates=100
[junit4:junit4]   2> 25322 T141 C6 P42113 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 25322 T141 C6 P42113 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 25323 T141 C6 P42113 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 25323 T141 C6 P42113 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 25323 T141 C6 P42113 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 25323 T141 C6 P42113 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:42090/collection1/. core=collection1
[junit4:junit4]   2> 25323 T141 C6 P42113 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 25326 T76 C4 P42090 REQ /get {getVersions=100&distrib=false&wt=javabin&qt=/get&version=2} status=0 QTime=0 
[junit4:junit4]   2> 25335 T74 C4 P42090 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 25336 T74 C4 P42090 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=3
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@989e216 lockFactory=org.apache.lucene.store.NativeFSLockFactory@17ac05e3),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@989e216 lockFactory=org.apache.lucene.store.NativeFSLockFactory@17ac05e3),segFN=segments_3,generation=3,filenames=[segments_3]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@989e216 lockFactory=org.apache.lucene.store.NativeFSLockFactory@17ac05e3),segFN=segments_4,generation=4,filenames=[segments_4]
[junit4:junit4]   2> 25337 T74 C4 P42090 oasc.SolrDeletionPolicy.updateCommits newest commit = 4[segments_4]
[junit4:junit4]   2> 25338 T74 C4 P42090 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 25338 T74 C4 P42090 /update {waitSearcher=true&openSearcher=false&commit=true&wt=javabin&commit_end_point=true&version=2&softCommit=false} {commit=} 0 3
[junit4:junit4]   2> 25340 T141 C6 P42113 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 25341 T141 C6 P42113 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 25344 T77 C4 P42090 REQ /replication {command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 25345 T141 C6 P42113 oash.SnapPuller.fetchLatestIndex Master's generation: 4
[junit4:junit4]   2> 25345 T141 C6 P42113 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4:junit4]   2> 25345 T141 C6 P42113 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4]   2> 25349 T77 C4 P42090 REQ /replication {command=filelist&qt=/replication&wt=javabin&generation=4&version=2} status=0 QTime=1 
[junit4:junit4]   2> 25350 T141 C6 P42113 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 1
[junit4:junit4]   2> 25351 T141 C6 P42113 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.SyncSliceTest-1363229906900/jetty4/index.20130314045852240 forceNew: false
[junit4:junit4]   2> 25351 T141 C6 P42113 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory@7101b74e lockFactory=org.apache.lucene.store.NativeFSLockFactory@7036963f) fullCopy=false
[junit4:junit4]   2> 25355 T77 C4 P42090 REQ /replication {file=segments_4&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=1 
[junit4:junit4]   2> 25356 T141 C6 P42113 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4]   2> 25357 T141 C6 P42113 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4]   2> 25358 T141 C6 P42113 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4]   2> 25422 T141 C6 P42113 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@704bc04c lockFactory=org.apache.lucene.store.NativeFSLockFactory@6f2ea9f0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@704bc04c lockFactory=org.apache.lucene.store.NativeFSLockFactory@6f2ea9f0),segFN=segments_4,generation=4,filenames=[segments_4]
[junit4:junit4]   2> 25423 T141 C6 P42113 oasc.SolrDeletionPolicy.updateCommits newest commit = 4[segments_4]
[junit4:junit4]   2> 25423 T141 C6 P42113 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4]   2> 25424 T141 C6 P42113 oass.SolrIndexSearcher.<init> Opening Searcher@393369e7 main
[junit4:junit4]   2> 25425 T138 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@393369e7 main{StandardDirectoryReader(segments_4:1:nrt)}
[junit4:junit4]   2> 25426 T141 C6 P42113 oash.SnapPuller.fetchLatestIndex removing temporary index download directory files MockDirWrapper(org.apache.lucene.store.RAMDirectory@7101b74e lockFactory=org.apache.lucene.store.NativeFSLockFactory@7036963f)
[junit4:junit4]   2> 25427 T141 C6 P42113 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 25427 T141 C6 P42113 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 25427 T141 C6 P42113 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 25427 T141 C6 P42113 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 25438 T141 C6 P42113 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 26042 T65 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 26044 T65 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core_node_name":"5",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:42113_",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:42113"}
[junit4:junit4]   2> 26049 T71 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 26049 T117 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 26049 T64 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 26049 T100 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 26049 T84 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 26049 T136 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 26325 T42 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 26326 T42 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Recoveries finished - collection: collection1
[junit4:junit4]   2> ASYNC  NEW_CORE C7 name=collection1 org.apache.solr.core.SolrCore@3c13a762 url=http://127.0.0.1:42073/collection1 node=127.0.0.1:42073_ C7_STATE=coll:control_collection core:collection1 props:{shard=shard1, state=active, core=collection1, collection=control_collection, node_name=127.0.0.1:42073_, base_url=http://127.0.0.1:42073, leader=true}
[junit4:junit4]   2> 26343 T54 C7 P42073 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 26345 T54 C7 P42073 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.s

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

4:junit4]   2> 70202 T1109 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 70202 T1109 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1)
[junit4:junit4]   2> 70202 T1109 oascc.ZkStateReader$2.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 70202 T1109 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4:junit4]   2> 70202 T1109 oascc.ZkStateReader$2.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 70202 T1109 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> NOTE: test params are: codec=SimpleText, sim=DefaultSimilarity, locale=ar_YE, timezone=Europe/Athens
[junit4:junit4]   2> NOTE: FreeBSD 9.0-RELEASE amd64/Oracle Corporation 1.7.0_17 (64-bit)/cpus=16,threads=2,free=244816536,total=368050176
[junit4:junit4]   2> NOTE: All tests run in this JVM: [ReturnFieldsTest, RegexBoostProcessorTest, TestDFRSimilarityFactory, SolrPluginUtilsTest, ShowFileRequestHandlerTest, DocumentAnalysisRequestHandlerTest, SyncSliceTest, ShardRoutingTest, TestDocumentBuilder, SolrIndexConfigTest, TestPHPSerializedResponseWriter, RequiredFieldsTest, BasicDistributedZk2Test, TestDistributedGrouping, TestSolrDeletionPolicy2, ZkNodePropsTest, BinaryUpdateRequestHandlerTest, TestStressRecovery, URLClassifyProcessorTest, DistributedSpellCheckComponentTest, RecoveryZkTest]
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=RecoveryZkTest -Dtests.seed=D5AD33B84432039A -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ar_YE -Dtests.timezone=Europe/Athens -Dtests.file.encoding=US-ASCII
[junit4:junit4] ERROR   0.00s J1 | RecoveryZkTest (suite) <<<
[junit4:junit4]    > Throwable #1: com.carrotsearch.randomizedtesting.ResourceDisposalError: Resource in scope SUITE failed to close. Resource was registered from thread Thread[id=1086, name=coreLoadExecutor-422-thread-1, state=RUNNABLE, group=TGRP-RecoveryZkTest], registration stack trace below.
[junit4:junit4]    > 	at java.lang.Thread.getStackTrace(Thread.java:1567)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedContext.closeAtEnd(RandomizedContext.java:150)
[junit4:junit4]    > 	at org.apache.lucene.util.LuceneTestCase.closeAfterSuite(LuceneTestCase.java:520)
[junit4:junit4]    > 	at org.apache.lucene.util.LuceneTestCase.wrapDirectory(LuceneTestCase.java:980)
[junit4:junit4]    > 	at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:878)
[junit4:junit4]    > 	at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:870)
[junit4:junit4]    > 	at org.apache.solr.core.MockDirectoryFactory.create(MockDirectoryFactory.java:34)
[junit4:junit4]    > 	at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:272)
[junit4:junit4]    > 	at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:224)
[junit4:junit4]    > 	at org.apache.solr.core.SolrCore.getNewIndexDir(SolrCore.java:244)
[junit4:junit4]    > 	at org.apache.solr.core.SolrCore.initIndex(SolrCore.java:458)
[junit4:junit4]    > 	at org.apache.solr.core.SolrCore.<init>(SolrCore.java:744)
[junit4:junit4]    > 	at org.apache.solr.core.SolrCore.<init>(SolrCore.java:619)
[junit4:junit4]    > 	at org.apache.solr.core.CoreContainer.createFromZk(CoreContainer.java:872)
[junit4:junit4]    > 	at org.apache.solr.core.CoreContainer.create(CoreContainer.java:955)
[junit4:junit4]    > 	at org.apache.solr.core.CoreContainer$2.call(CoreContainer.java:579)
[junit4:junit4]    > 	at org.apache.solr.core.CoreContainer$2.call(CoreContainer.java:574)
[junit4:junit4]    > 	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334)
[junit4:junit4]    > 	at java.util.concurrent.FutureTask.run(FutureTask.java:166)
[junit4:junit4]    > 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
[junit4:junit4]    > 	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334)
[junit4:junit4]    > 	at java.util.concurrent.FutureTask.run(FutureTask.java:166)
[junit4:junit4]    > 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
[junit4:junit4]    > 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]    > Caused by: java.lang.AssertionError: Directory not closed: BaseDirectoryWrapper(org.apache.lucene.store.RAMDirectory@17ff929c lockFactory=org.apache.lucene.store.NativeFSLockFactory@50607985)
[junit4:junit4]    > 	at org.junit.Assert.fail(Assert.java:93)
[junit4:junit4]    > 	at org.apache.lucene.util.CloseableDirectory.close(CloseableDirectory.java:47)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$2$1.apply(RandomizedRunner.java:602)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$2$1.apply(RandomizedRunner.java:599)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedContext.closeResources(RandomizedContext.java:167)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$2.afterAlways(RandomizedRunner.java:615)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	... 1 more
[junit4:junit4]    > Throwable #2: com.carrotsearch.randomizedtesting.ResourceDisposalError: Resource in scope SUITE failed to close. Resource was registered from thread Thread[id=1088, name=RecoveryThread, state=RUNNABLE, group=TGRP-RecoveryZkTest], registration stack trace below.
[junit4:junit4]    > 	at java.lang.Thread.getStackTrace(Thread.java:1567)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedContext.closeAtEnd(RandomizedContext.java:150)
[junit4:junit4]    > 	at org.apache.lucene.util.LuceneTestCase.closeAfterSuite(LuceneTestCase.java:520)
[junit4:junit4]    > 	at org.apache.lucene.util.LuceneTestCase.wrapDirectory(LuceneTestCase.java:980)
[junit4:junit4]    > 	at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:878)
[junit4:junit4]    > 	at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:870)
[junit4:junit4]    > 	at org.apache.solr.core.MockDirectoryFactory.create(MockDirectoryFactory.java:34)
[junit4:junit4]    > 	at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:272)
[junit4:junit4]    > 	at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:224)
[junit4:junit4]    > 	at org.apache.solr.handler.SnapPuller.fetchLatestIndex(SnapPuller.java:383)
[junit4:junit4]    > 	at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:281)
[junit4:junit4]    > 	at org.apache.solr.cloud.RecoveryStrategy.replicate(RecoveryStrategy.java:153)
[junit4:junit4]    > 	at org.apache.solr.cloud.RecoveryStrategy.doRecovery(RecoveryStrategy.java:409)
[junit4:junit4]    > 	at org.apache.solr.cloud.RecoveryStrategy.run(RecoveryStrategy.java:223)
[junit4:junit4]    > Caused by: java.lang.AssertionError: Directory not closed: BaseDirectoryWrapper(org.apache.lucene.store.RAMDirectory@5dbe3491 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6583023d)
[junit4:junit4]    > 	at org.junit.Assert.fail(Assert.java:93)
[junit4:junit4]    > 	at org.apache.lucene.util.CloseableDirectory.close(CloseableDirectory.java:47)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$2$1.apply(RandomizedRunner.java:602)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$2$1.apply(RandomizedRunner.java:599)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedContext.closeResources(RandomizedContext.java:167)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$2.afterAlways(RandomizedRunner.java:615)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:722)
[junit4:junit4] Completed on J1 in 70.44s, 1 test, 2 errors <<< FAILURES!

[...truncated 577 lines...]
BUILD FAILED
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/build.xml:381: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/build.xml:361: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/build.xml:39: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build.xml:183: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/common-build.xml:449: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/common-build.xml:1213: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/common-build.xml:877: There were test failures: 272 suites, 1145 tests, 2 suite-level errors, 1 error, 1 failure, 28 ignored (9 assumptions)

Total time: 58 minutes 15 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