lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@thetaphi.de>
Subject [JENKINS] Lucene-Solr-4.x-Linux (64bit/jdk1.8.0-ea-b82) - Build # 4785 - Still Failing!
Date Fri, 22 Mar 2013 23:29:48 GMT
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/4785/
Java: 64bit/jdk1.8.0-ea-b82 -XX:+UseG1GC

7 tests failed.
REGRESSION:  org.apache.solr.update.processor.ScriptEngineTest.testEvalText

Error Message:
java.lang.Integer cannot be cast to java.lang.Double

Stack Trace:
java.lang.ClassCastException: java.lang.Integer cannot be cast to java.lang.Double
	at __randomizedtesting.SeedInfo.seed([1B1CCDA38F390A7A:7F51762B12B28176]:0)
	at org.apache.solr.update.processor.ScriptEngineTest.testEvalText(ScriptEngineTest.java:72)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:487)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
	at 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 org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:722)


REGRESSION:  org.apache.solr.update.processor.ScriptEngineTest.testPut

Error Message:
java.lang.Integer cannot be cast to java.lang.Double

Stack Trace:
java.lang.ClassCastException: java.lang.Integer cannot be cast to java.lang.Double
	at __randomizedtesting.SeedInfo.seed([1B1CCDA38F390A7A:CAE177474DD52173]:0)
	at org.apache.solr.update.processor.ScriptEngineTest.testPut(ScriptEngineTest.java:93)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:487)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
	at 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 org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:722)


REGRESSION:  org.apache.solr.update.processor.ScriptEngineTest.testEvalReader

Error Message:
java.lang.Integer cannot be cast to java.lang.Double

Stack Trace:
java.lang.ClassCastException: java.lang.Integer cannot be cast to java.lang.Double
	at __randomizedtesting.SeedInfo.seed([1B1CCDA38F390A7A:195143CC70CCC961]:0)
	at org.apache.solr.update.processor.ScriptEngineTest.testEvalReader(ScriptEngineTest.java:82)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:487)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
	at 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 org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:722)


REGRESSION:  org.apache.solr.update.processor.StatelessScriptUpdateProcessorFactoryTest.testSingleScript

Error Message:
Unable to invoke function processAdd in script: trivial.updateprocessor0.js: TypeError: org.apache.solr.update.processor.StatelessScriptUpdateProcessorFactoryTest@7ff51e44 has no such function "assertNotNull" in <eval> at line number 3

Stack Trace:
org.apache.solr.common.SolrException: Unable to invoke function processAdd in script: trivial.updateprocessor0.js: TypeError: org.apache.solr.update.processor.StatelessScriptUpdateProcessorFactoryTest@7ff51e44 has no such function "assertNotNull" in <eval> at line number 3
	at __randomizedtesting.SeedInfo.seed([1B1CCDA38F390A7A:82FC37F8FB5CD4E9]:0)
	at org.apache.solr.update.processor.StatelessScriptUpdateProcessorFactory$ScriptUpdateProcessor.invokeFunction(StatelessScriptUpdateProcessorFactory.java:437)
	at org.apache.solr.update.processor.StatelessScriptUpdateProcessorFactory$ScriptUpdateProcessor.processAdd(StatelessScriptUpdateProcessorFactory.java:374)
	at org.apache.solr.update.processor.UpdateProcessorTestBase.processAdd(UpdateProcessorTestBase.java:72)
	at org.apache.solr.update.processor.UpdateProcessorTestBase.processAdd(UpdateProcessorTestBase.java:47)
	at org.apache.solr.update.processor.StatelessScriptUpdateProcessorFactoryTest.testSingleScript(StatelessScriptUpdateProcessorFactoryTest.java:90)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:487)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:722)
Caused by: javax.script.ScriptException: TypeError: org.apache.solr.update.processor.StatelessScriptUpdateProcessorFactoryTest@7ff51e44 has no such function "assertNotNull" in <eval> at line number 3
	at jdk.nashorn.api.scripting.NashornScriptEngine.throwAsScriptException(NashornScriptEngine.java:424)
	at jdk.nashorn.api.scripting.NashornScriptEngine.invokeImpl(NashornScriptEngine.java:364)
	at jdk.nashorn.api.scripting.NashornScriptEngine.invokeFunction(NashornScriptEngine.java:169)
	at org.apache.solr.update.processor.StatelessScriptUpdateProcessorFactory$ScriptUpdateProcessor.invokeFunction(StatelessScriptUpdateProcessorFactory.java:425)
	... 44 more
Caused by: <eval>:3 TypeError: org.apache.solr.update.processor.StatelessScriptUpdateProcessorFactoryTest@7ff51e44 has no such function "assertNotNull"
	at jdk.nashorn.internal.runtime.ECMAErrors.error(ECMAErrors.java:66)
	at jdk.nashorn.internal.runtime.ECMAErrors.typeError(ECMAErrors.java:222)
	at jdk.nashorn.internal.runtime.ECMAErrors.typeError(ECMAErrors.java:194)
	at jdk.nashorn.internal.runtime.ECMAErrors.typeError(ECMAErrors.java:181)
	at jdk.nashorn.internal.runtime.linker.NashornBottomLinker.linkBean(NashornBottomLinker.java:93)
	at jdk.nashorn.internal.runtime.linker.NashornBottomLinker.getGuardedInvocation(NashornBottomLinker.java:64)
	at jdk.internal.dynalink.support.CompositeGuardingDynamicLinker.getGuardedInvocation(CompositeGuardingDynamicLinker.java:124)
	at jdk.internal.dynalink.support.LinkerServicesImpl.getGuardedInvocation(LinkerServicesImpl.java:138)
	at jdk.internal.dynalink.DynamicLinker.relink(DynamicLinker.java:229)
	at jdk.nashorn.internal.scripts.Script$\^eval\_.processAdd(<eval>:3)
	at jdk.nashorn.internal.runtime.ScriptFunctionData.invoke(ScriptFunctionData.java:364)
	at jdk.nashorn.internal.runtime.ScriptFunction.invoke(ScriptFunction.java:201)
	at jdk.nashorn.internal.runtime.ScriptRuntime.apply(ScriptRuntime.java:345)
	at jdk.nashorn.internal.runtime.ScriptRuntime.checkAndApply(ScriptRuntime.java:330)
	at jdk.nashorn.api.scripting.NashornScriptEngine.invokeImpl(NashornScriptEngine.java:362)
	... 46 more


REGRESSION:  org.apache.solr.update.processor.StatelessScriptUpdateProcessorFactoryTest.testMultipleScripts

Error Message:
Unable to invoke function processAdd in script: trivial.updateprocessor0.js: TypeError: org.apache.solr.update.processor.StatelessScriptUpdateProcessorFactoryTest@3d04438f has no such function "assertNotNull" in <eval> at line number 3

Stack Trace:
org.apache.solr.common.SolrException: Unable to invoke function processAdd in script: trivial.updateprocessor0.js: TypeError: org.apache.solr.update.processor.StatelessScriptUpdateProcessorFactoryTest@3d04438f has no such function "assertNotNull" in <eval> at line number 3
	at __randomizedtesting.SeedInfo.seed([1B1CCDA38F390A7A:4E7C5DEF48B9000D]:0)
	at org.apache.solr.update.processor.StatelessScriptUpdateProcessorFactory$ScriptUpdateProcessor.invokeFunction(StatelessScriptUpdateProcessorFactory.java:437)
	at org.apache.solr.update.processor.StatelessScriptUpdateProcessorFactory$ScriptUpdateProcessor.processAdd(StatelessScriptUpdateProcessorFactory.java:374)
	at org.apache.solr.update.processor.UpdateProcessorTestBase.processAdd(UpdateProcessorTestBase.java:72)
	at org.apache.solr.update.processor.UpdateProcessorTestBase.processAdd(UpdateProcessorTestBase.java:47)
	at org.apache.solr.update.processor.StatelessScriptUpdateProcessorFactoryTest.testMultipleScripts(StatelessScriptUpdateProcessorFactoryTest.java:137)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:487)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:722)
Caused by: javax.script.ScriptException: TypeError: org.apache.solr.update.processor.StatelessScriptUpdateProcessorFactoryTest@3d04438f has no such function "assertNotNull" in <eval> at line number 3
	at jdk.nashorn.api.scripting.NashornScriptEngine.throwAsScriptException(NashornScriptEngine.java:424)
	at jdk.nashorn.api.scripting.NashornScriptEngine.invokeImpl(NashornScriptEngine.java:364)
	at jdk.nashorn.api.scripting.NashornScriptEngine.invokeFunction(NashornScriptEngine.java:169)
	at org.apache.solr.update.processor.StatelessScriptUpdateProcessorFactory$ScriptUpdateProcessor.invokeFunction(StatelessScriptUpdateProcessorFactory.java:425)
	... 44 more
Caused by: <eval>:3 TypeError: org.apache.solr.update.processor.StatelessScriptUpdateProcessorFactoryTest@3d04438f has no such function "assertNotNull"
	at jdk.nashorn.internal.runtime.ECMAErrors.error(ECMAErrors.java:66)
	at jdk.nashorn.internal.runtime.ECMAErrors.typeError(ECMAErrors.java:222)
	at jdk.nashorn.internal.runtime.ECMAErrors.typeError(ECMAErrors.java:194)
	at jdk.nashorn.internal.runtime.ECMAErrors.typeError(ECMAErrors.java:181)
	at jdk.nashorn.internal.runtime.linker.NashornBottomLinker.linkBean(NashornBottomLinker.java:93)
	at jdk.nashorn.internal.runtime.linker.NashornBottomLinker.getGuardedInvocation(NashornBottomLinker.java:64)
	at jdk.internal.dynalink.support.CompositeGuardingDynamicLinker.getGuardedInvocation(CompositeGuardingDynamicLinker.java:124)
	at jdk.internal.dynalink.support.LinkerServicesImpl.getGuardedInvocation(LinkerServicesImpl.java:138)
	at jdk.internal.dynalink.DynamicLinker.relink(DynamicLinker.java:229)
	at jdk.nashorn.internal.scripts.Script$\^eval\_.processAdd(<eval>:3)
	at jdk.nashorn.internal.runtime.ScriptFunctionData.invoke(ScriptFunctionData.java:364)
	at jdk.nashorn.internal.runtime.ScriptFunction.invoke(ScriptFunction.java:201)
	at jdk.nashorn.internal.runtime.ScriptRuntime.apply(ScriptRuntime.java:345)
	at jdk.nashorn.internal.runtime.ScriptRuntime.checkAndApply(ScriptRuntime.java:330)
	at jdk.nashorn.api.scripting.NashornScriptEngine.invokeImpl(NashornScriptEngine.java:362)
	... 46 more


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

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

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


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

Error Message:
Still found shard2 in collection test_unload_shard_and_collection

Stack Trace:
java.lang.AssertionError: Still found shard2 in collection test_unload_shard_and_collection
	at __randomizedtesting.SeedInfo.seed([1B1CCDA38F390A7A:9AFA43BBF8666A46]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.apache.solr.cloud.UnloadDistributedZkTest.testUnloadShardAndCollection(UnloadDistributedZkTest.java:127)
	at org.apache.solr.cloud.UnloadDistributedZkTest.doTest(UnloadDistributedZkTest.java:77)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:806)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:487)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:722)




Build Log:
[...truncated 9276 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.BasicDistributedZk2Test
[junit4:junit4]   2> 0 T2010 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4:junit4]   2> 3 T2010 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4:junit4]   2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1363994453622
[junit4:junit4]   2> 4 T2010 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 4 T2011 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4:junit4]   2> 6 T2011 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4]   2> 6 T2011 oazs.ZooKeeperServer.setMinSessionTimeout minSessionTimeout set to -1
[junit4:junit4]   2> 6 T2011 oazs.ZooKeeperServer.setMaxSessionTimeout maxSessionTimeout set to -1
[junit4:junit4]   2> 7 T2011 oazs.NIOServerCnxnFactory.configure binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 10 T2011 oazsp.FileTxnSnapLog.save Snapshotting: 0x0 to /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1363994453622/zookeeper/server1/data/version-2/snapshot.0
[junit4:junit4]   2> 104 T2010 oasc.ZkTestServer.run start zk server on port:36379
[junit4:junit4]   2> 105 T2010 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36379 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@3d588b3f
[junit4:junit4]   2> 106 T2010 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 106 T2016 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:36379. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 106 T2016 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36379, initiating session
[junit4:junit4]   2> 107 T2012 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:54101
[junit4:junit4]   2> 107 T2012 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:54101
[junit4:junit4]   2> 107 T2014 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 115 T2014 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d9467a67c0000 with negotiated timeout 10000 for client /127.0.0.1:54101
[junit4:junit4]   2> 115 T2016 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:36379, sessionid = 0x13d9467a67c0000, negotiated timeout = 10000
[junit4:junit4]   2> 116 T2017 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3d588b3f name:ZooKeeperConnection Watcher:127.0.0.1:36379 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 116 T2010 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 117 T2010 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 119 T2015 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d9467a67c0000
[junit4:junit4]   2> 120 T2012 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:54101 which had sessionid 0x13d9467a67c0000
[junit4:junit4]   2> 119 T2010 oaz.ZooKeeper.close Session: 0x13d9467a67c0000 closed
[junit4:junit4]   2> 120 T2017 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 120 T2010 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36379/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@339f72e4
[junit4:junit4]   2> 121 T2010 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 121 T2018 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:36379. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 122 T2018 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36379, initiating session
[junit4:junit4]   2> 122 T2012 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:54102
[junit4:junit4]   2> 122 T2012 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:54102
[junit4:junit4]   2> 123 T2014 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d9467a67c0001 with negotiated timeout 10000 for client /127.0.0.1:54102
[junit4:junit4]   2> 123 T2018 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:36379, sessionid = 0x13d9467a67c0001, negotiated timeout = 10000
[junit4:junit4]   2> 123 T2019 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@339f72e4 name:ZooKeeperConnection Watcher:127.0.0.1:36379/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 123 T2010 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 124 T2010 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 127 T2010 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 129 T2010 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 131 T2010 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 133 T2010 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 134 T2010 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 137 T2010 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4:junit4]   2> 138 T2010 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 141 T2010 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4:junit4]   2> 142 T2010 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 144 T2010 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4:junit4]   2> 144 T2010 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 147 T2010 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4:junit4]   2> 147 T2010 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 150 T2010 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 150 T2010 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 152 T2010 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 153 T2010 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 156 T2010 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 156 T2010 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 159 T2010 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4:junit4]   2> 159 T2010 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 161 T2015 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d9467a67c0001
[junit4:junit4]   2> 162 T2019 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 162 T2010 oaz.ZooKeeper.close Session: 0x13d9467a67c0001 closed
[junit4:junit4]   2> 162 T2012 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:54102 which had sessionid 0x13d9467a67c0001
[junit4:junit4]   2> 224 T2010 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 243 T2010 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:51106
[junit4:junit4]   2> 243 T2010 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 244 T2010 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 244 T2010 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1363994453780
[junit4:junit4]   2> 244 T2010 oasc.CoreContainer$Initializer.initialize looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1363994453780/solr.xml
[junit4:junit4]   2> 245 T2010 oasc.CoreContainer.<init> New CoreContainer 1235899690
[junit4:junit4]   2> 245 T2010 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1363994453780/'
[junit4:junit4]   2> 245 T2010 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1363994453780/'
[junit4:junit4]   2> 263 T2010 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 264 T2010 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 264 T2010 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 265 T2010 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 265 T2010 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 265 T2010 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 266 T2010 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 266 T2010 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 266 T2010 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 266 T2010 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 272 T2010 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 282 T2010 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:36379/solr
[junit4:junit4]   2> 282 T2010 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 282 T2010 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36379 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@2d403673
[junit4:junit4]   2> 283 T2010 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 283 T2029 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:36379. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 284 T2029 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36379, initiating session
[junit4:junit4]   2> 284 T2012 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:54103
[junit4:junit4]   2> 285 T2012 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:54103
[junit4:junit4]   2> 286 T2014 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d9467a67c0002 with negotiated timeout 20000 for client /127.0.0.1:54103
[junit4:junit4]   2> 286 T2029 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:36379, sessionid = 0x13d9467a67c0002, negotiated timeout = 20000
[junit4:junit4]   2> 286 T2030 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2d403673 name:ZooKeeperConnection Watcher:127.0.0.1:36379 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 286 T2010 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 288 T2015 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d9467a67c0002
[junit4:junit4]   2> 288 T2012 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:54103 which had sessionid 0x13d9467a67c0002
[junit4:junit4]   2> 288 T2010 oaz.ZooKeeper.close Session: 0x13d9467a67c0002 closed
[junit4:junit4]   2> 289 T2030 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 289 T2010 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 291 T2010 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36379/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@53b7c5bf
[junit4:junit4]   2> 292 T2010 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 292 T2031 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:36379. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 293 T2031 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36379, initiating session
[junit4:junit4]   2> 293 T2012 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:54104
[junit4:junit4]   2> 293 T2012 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:54104
[junit4:junit4]   2> 294 T2014 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d9467a67c0003 with negotiated timeout 20000 for client /127.0.0.1:54104
[junit4:junit4]   2> 294 T2031 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:36379, sessionid = 0x13d9467a67c0003, negotiated timeout = 20000
[junit4:junit4]   2> 294 T2032 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@53b7c5bf name:ZooKeeperConnection Watcher:127.0.0.1:36379/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 295 T2010 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 296 T2010 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4:junit4]   2> 298 T2015 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d9467a67c0003 type:create cxid:0x7 zxid:0x1b txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 299 T2010 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4:junit4]   2> 301 T2010 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 302 T2010 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51106_
[junit4:junit4]   2> 303 T2015 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d9467a67c0003 type:delete cxid:0x12 zxid:0x1e txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:51106_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:51106_
[junit4:junit4]   2> 303 T2010 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:51106_
[junit4:junit4]   2> 306 T2010 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 309 T2015 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d9467a67c0003 type:delete cxid:0x20 zxid:0x23 txntype:-1 reqpath:n/a Error Path:/solr/overseer_elect/leader Error:KeeperErrorCode = NoNode for /solr/overseer_elect/leader
[junit4:junit4]   2> 310 T2010 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 312 T2010 oasc.Overseer.start Overseer (id=89390740512964611-127.0.0.1:51106_-n_0000000000) starting
[junit4:junit4]   2> 312 T2015 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d9467a67c0003 type:create cxid:0x25 zxid:0x25 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 313 T2015 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d9467a67c0003 type:create cxid:0x26 zxid:0x26 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 314 T2015 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d9467a67c0003 type:create cxid:0x28 zxid:0x27 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 315 T2010 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4:junit4]   2> 317 T2015 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d9467a67c0003 type:create cxid:0x2e zxid:0x29 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 318 T2034 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 318 T2010 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 321 T2010 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4:junit4]   2> 323 T2010 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 325 T2033 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 328 T2035 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1363994453780/collection1
[junit4:junit4]   2> 328 T2035 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 329 T2035 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 329 T2035 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 330 T2035 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1363994453780/collection1/'
[junit4:junit4]   2> 330 T2035 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1363994453780/collection1/lib/README' to classloader
[junit4:junit4]   2> 330 T2035 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1363994453780/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 354 T2035 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_43
[junit4:junit4]   2> 382 T2035 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 383 T2035 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 388 T2035 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 781 T2035 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 788 T2035 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 792 T2035 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 805 T2035 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 809 T2035 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 813 T2035 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 814 T2035 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 815 T2035 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 815 T2035 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 816 T2035 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 816 T2035 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 816 T2035 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 817 T2035 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1363994453780/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1363994453622/control/data/
[junit4:junit4]   2> 817 T2035 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@111a7e71
[junit4:junit4]   2> 817 T2035 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 818 T2035 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1363994453622/control/data
[junit4:junit4]   2> 818 T2035 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1363994453622/control/data/index/
[junit4:junit4]   2> 818 T2035 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1363994453622/control/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 819 T2035 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1363994453622/control/data/index
[junit4:junit4]   2> 821 T2035 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5bd58fba lockFactory=org.apache.lucene.store.NativeFSLockFactory@319f50e4),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 821 T2035 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 822 T2035 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 823 T2035 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 823 T2035 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 824 T2035 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 824 T2035 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 824 T2035 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 825 T2035 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 825 T2035 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 825 T2035 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 829 T2035 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 832 T2035 oass.SolrIndexSearcher.<init> Opening Searcher@32f7f9fe main
[junit4:junit4]   2> 832 T2035 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1363994453622/control/data/tlog
[junit4:junit4]   2> 833 T2035 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 833 T2035 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 837 T2036 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@32f7f9fe main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 837 T2035 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 837 T2035 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 1827 T2033 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1828 T2033 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"control_collection",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:51106_",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:51106",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "core_node_name":null}
[junit4:junit4]   2> 1828 T2033 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with numShards 1
[junit4:junit4]   2> 1829 T2033 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 1832 T2032 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> 1838 T2035 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 1839 T2035 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:51106 collection:control_collection shard:shard1
[junit4:junit4]   2> 1839 T2035 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4:junit4]   2> 1844 T2015 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d9467a67c0003 type:delete cxid:0x82 zxid:0x35 txntype:-1 reqpath:n/a Error Path:/solr/collections/control_collection/leaders Error:KeeperErrorCode = NoNode for /solr/collections/control_collection/leaders
[junit4:junit4]   2> 1845 T2035 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 1845 T2015 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d9467a67c0003 type:create cxid:0x83 zxid:0x36 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1847 T2035 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 1847 T2035 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 1847 T2035 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:51106/collection1/
[junit4:junit4]   2> 1847 T2035 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 1847 T2035 oasc.SyncStrategy.syncToMe http://127.0.0.1:51106/collection1/ has no replicas
[junit4:junit4]   2> 1847 T2035 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:51106/collection1/
[junit4:junit4]   2> 1848 T2035 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4:junit4]   2> 1850 T2015 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d9467a67c0003 type:create cxid:0x8e zxid:0x3a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3335 T2033 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3341 T2032 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> 3355 T2035 oasc.ZkController.register We are http://127.0.0.1:51106/collection1/ and leader is http://127.0.0.1:51106/collection1/
[junit4:junit4]   2> 3355 T2035 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:51106
[junit4:junit4]   2> 3356 T2035 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 3356 T2035 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 3356 T2035 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 3357 T2035 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3358 T2010 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 3359 T2010 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 3359 T2010 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 3363 T2010 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 3364 T2010 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36379/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@d36d06b
[junit4:junit4]   2> 3365 T2010 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3365 T2038 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:36379. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 3366 T2038 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36379, initiating session
[junit4:junit4]   2> 3366 T2012 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:54110
[junit4:junit4]   2> 3366 T2012 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:54110
[junit4:junit4]   2> 3367 T2014 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d9467a67c0004 with negotiated timeout 10000 for client /127.0.0.1:54110
[junit4:junit4]   2> 3367 T2038 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:36379, sessionid = 0x13d9467a67c0004, negotiated timeout = 10000
[junit4:junit4]   2> 3367 T2039 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@d36d06b name:ZooKeeperConnection Watcher:127.0.0.1:36379/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 3367 T2010 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 3369 T2010 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 3371 T2010 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4]   2> 3431 T2010 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 3433 T2010 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:48621
[junit4:junit4]   2> 3434 T2010 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 3434 T2010 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3434 T2010 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1363994456989
[junit4:junit4]   2> 3435 T2010 oasc.CoreContainer$Initializer.initialize looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1363994456989/solr.xml
[junit4:junit4]   2> 3435 T2010 oasc.CoreContainer.<init> New CoreContainer 714593151
[junit4:junit4]   2> 3435 T2010 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1363994456989/'
[junit4:junit4]   2> 3436 T2010 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1363994456989/'
[junit4:junit4]   2> 3453 T2010 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 3454 T2010 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 3454 T2010 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 3454 T2010 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 3454 T2010 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 3455 T2010 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 3455 T2010 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 3455 T2010 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 3456 T2010 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 3456 T2010 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 3464 T2010 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 3479 T2010 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:36379/solr
[junit4:junit4]   2> 3479 T2010 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 3480 T2010 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36379 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@7e3bb9c9
[junit4:junit4]   2> 3481 T2049 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:36379. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 3481 T2010 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3482 T2049 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36379, initiating session
[junit4:junit4]   2> 3482 T2012 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:54111
[junit4:junit4]   2> 3482 T2012 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:54111
[junit4:junit4]   2> 3483 T2014 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d9467a67c0005 with negotiated timeout 20000 for client /127.0.0.1:54111
[junit4:junit4]   2> 3483 T2049 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:36379, sessionid = 0x13d9467a67c0005, negotiated timeout = 20000
[junit4:junit4]   2> 3483 T2050 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7e3bb9c9 name:ZooKeeperConnection Watcher:127.0.0.1:36379 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 3484 T2010 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 3485 T2015 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d9467a67c0005
[junit4:junit4]   2> 3486 T2012 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:54111 which had sessionid 0x13d9467a67c0005
[junit4:junit4]   2> 3486 T2050 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 3486 T2010 oaz.ZooKeeper.close Session: 0x13d9467a67c0005 closed
[junit4:junit4]   2> 3486 T2010 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 3489 T2010 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36379/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@3473a205
[junit4:junit4]   2> 3490 T2010 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3490 T2051 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:36379. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 3490 T2051 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36379, initiating session
[junit4:junit4]   2> 3490 T2012 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:54112
[junit4:junit4]   2> 3491 T2012 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:54112
[junit4:junit4]   2> 3491 T2014 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d9467a67c0006 with negotiated timeout 20000 for client /127.0.0.1:54112
[junit4:junit4]   2> 3492 T2051 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:36379, sessionid = 0x13d9467a67c0006, negotiated timeout = 20000
[junit4:junit4]   2> 3492 T2052 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3473a205 name:ZooKeeperConnection Watcher:127.0.0.1:36379/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 3492 T2010 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 3493 T2015 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d9467a67c0006 type:create cxid:0x1 zxid:0x48 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3495 T2015 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d9467a67c0006 type:create cxid:0x3 zxid:0x49 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3497 T2010 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 4500 T2010 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:48621_
[junit4:junit4]   2> 4500 T2015 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d9467a67c0006 type:delete cxid:0xd zxid:0x4a txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:48621_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:48621_
[junit4:junit4]   2> 4501 T2010 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:48621_
[junit4:junit4]   2> 4504 T2052 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 4504 T2032 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 4504 T2039 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 4505 T2032 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> 4510 T2053 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1363994456989/collection1
[junit4:junit4]   2> 4510 T2053 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 4511 T2053 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 4511 T2053 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 4512 T2053 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1363994456989/collection1/'
[junit4:junit4]   2> 4512 T2053 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1363994456989/collection1/lib/README' to classloader
[junit4:junit4]   2> 4512 T2053 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1363994456989/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 4537 T2053 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_43
[junit4:junit4]   2> 4565 T2053 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 4566 T2053 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 4569 T2053 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 4845 T2033 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4846 T2033 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"control_collection",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:51106_",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:51106",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "core_node_name":"127.0.0.1:51106__collection1"}
[junit4:junit4]   2> 4850 T2032 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> 4850 T2052 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> 4850 T2039 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> 4886 T2053 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 4891 T2053 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 4893 T2053 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 4903 T2053 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 4906 T2053 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 4908 T2053 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 4909 T2053 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 4910 T2053 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 4910 T2053 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 4911 T2053 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 4911 T2053 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 4911 T2053 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 4911 T2053 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1363994456989/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1363994453622/jetty1/
[junit4:junit4]   2> 4911 T2053 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@111a7e71
[junit4:junit4]   2> 4912 T2053 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 4912 T2053 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1363994453622/jetty1
[junit4:junit4]   2> 4912 T2053 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1363994453622/jetty1/index/
[junit4:junit4]   2> 4912 T2053 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1363994453622/jetty1/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 4913 T2053 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1363994453622/jetty1/index
[junit4:junit4]   2> 4914 T2053 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@3f82ac7a lockFactory=org.apache.lucene.store.NativeFSLockFactory@44ecd75e),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 4914 T2053 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 4915 T2053 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 4916 T2053 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 4916 T2053 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 4916 T2053 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 4917 T2053 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 4917 T2053 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 4917 T2053 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 4918 T2053 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 4918 T2053 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 4921 T2053 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 4923 T2053 oass.SolrIndexSearcher.<init> Opening Searcher@1acc4685 main
[junit4:junit4]   2> 4924 T2053 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1363994453622/jetty1/tlog
[junit4:junit4]   2> 4924 T2053 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 4924 T2053 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 4927 T2054 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1acc4685 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 4929 T2053 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 4929 T2053 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 6354 T2033 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6355 T2033 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:48621_",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:48621",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "core_node_name":null}
[junit4:junit4]   2> 6355 T2033 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with numShards 2
[junit4:junit4]   2> 6355 T2033 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4:junit4]   2> 6359 T2052 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> 6359 T2032 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> 6359 T2039 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> 6931 T2053 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 6931 T2053 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:48621 collection:collection1 shard:shard2
[junit4:junit4]   2> 6932 T2053 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4:junit4]   2> 6937 T2015 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d9467a67c0006 type:delete cxid:0x4a zxid:0x5a txntype:-1 reqpath:n/a Error Path:/solr/collections/collection1/leaders Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders
[junit4:junit4]   2> 6937 T2053 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 6938 T2015 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d9467a67c0006 type:create cxid:0x4b zxid:0x5b txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 6939 T2053 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 6939 T2053 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 6939 T2053 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:48621/collection1/
[junit4:junit4]   2> 6940 T2053 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 6940 T2053 oasc.SyncStrategy.syncToMe http://127.0.0.1:48621/collection1/ has no replicas
[junit4:junit4]   2> 6940 T2053 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:48621/collection1/
[junit4:junit4]   2> 6940 T2053 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4:junit4]   2> 6943 T2015 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d9467a67c0006 type:create cxid:0x56 zxid:0x5f txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 7862 T2033 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7868 T2032 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> 7868 T2039 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> 7869 T2052 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> 7897 T2053 oasc.ZkController.register We are http://127.0.0.1:48621/collection1/ and leader is http://127.0.0.1:48621/collection1/
[junit4:junit4]   2> 7897 T2053 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:48621
[junit4:junit4]   2> 7897 T2053 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 7898 T2053 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 7898 T2053 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 7899 T2053 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7900 T2010 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 7900 T2010 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 7901 T2010 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 7960 T2010 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 7962 T2010 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:44561
[junit4:junit4]   2> 7963 T2010 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 7963 T2010 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 7964 T2010 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1363994461522
[junit4:junit4]   2> 7964 T2010 oasc.CoreContainer$Initializer.initialize looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1363994461522/solr.xml
[junit4:junit4]   2> 7964 T2010 oasc.CoreContainer.<init> New CoreContainer 125043698
[junit4:junit4]   2> 7965 T2010 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1363994461522/'
[junit4:junit4]   2> 7965 T2010 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1363994461522/'
[junit4:junit4]   2> 7983 T2010 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 7983 T2010 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 7983 T2010 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 7984 T2010 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 7984 T2010 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 7984 T2010 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 7985 T2010 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 7985 T2010 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 7985 T2010 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 7986 T2010 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 8002 T2010 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 8012 T2010 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:36379/solr
[junit4:junit4]   2> 8012 T2010 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 8013 T2010 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36379 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@6eaa8017
[junit4:junit4]   2> 8014 T2010 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 8014 T2065 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:36379. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 8015 T2065 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36379, initiating session
[junit4:junit4]   2> 8015 T2012 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:54115
[junit4:junit4]   2> 8015 T2012 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:54115
[junit4:junit4]   2> 8016 T2014 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d9467a67c0007 with negotiated timeout 20000 for client /127.0.0.1:54115
[junit4:junit4]   2> 8016 T2065 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:36379, sessionid = 0x13d9467a67c0007, negotiated timeout = 20000
[junit4:junit4]   2> 8016 T2066 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6eaa8017 name:ZooKeeperConnection Watcher:127.0.0.1:36379 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 8017 T2010 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 8018 T2015 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d9467a67c0007
[junit4:junit4]   2> 8018 T2066 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 8018 T2012 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:54115 which had sessionid 0x13d9467a67c0007
[junit4:junit4]   2> 8018 T2010 oaz.ZooKeeper.close Session: 0x13d9467a67c0007 closed
[junit4:junit4]   2> 8019 T2010 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 8022 T2010 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36379/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@42da4dfe
[junit4:junit4]   2> 8023 T2010 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 8023 T2067 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:36379. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 8024 T2067 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36379, initiating session
[junit4:junit4]   2> 8024 T2012 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:54116
[junit4:junit4]   2> 8024 T2012 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:54116
[junit4:junit4]   2> 8025 T2014 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d9467a67c0008 with negotiated timeout 20000 for client /127.0.0.1:54116
[junit4:junit4]   2> 8025 T2067 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:36379, sessionid = 0x13d9467a67c0008, negotiated timeout = 20000
[junit4:junit4]   2> 8025 T2068 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@42da4dfe name:ZooKeeperConnection Watcher:127.0.0.1:36379/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 8025 T2010 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 8026 T2015 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d9467a67c0008 type:create cxid:0x1 zxid:0x6c txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8027 T2015 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d9467a67c0008 type:create cxid:0x3 zxid:0x6d txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8029 T2010 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 9031 T2010 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44561_
[junit4:junit4]   2> 9032 T2015 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d9467a67c0008 type:delete cxid:0xd zxid:0x6e txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:44561_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:44561_
[junit4:junit4]   2> 9034 T2010 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:44561_
[junit4:junit4]   2> 9036 T2039 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> 9036 T2052 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> 9037 T2032 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 9037 T2032 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> 9037 T2068 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 9039 T2052 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 9039 T2039 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 9043 T2069 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1363994461522/collection1
[junit4:junit4]   2> 9044 T2069 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 9045 T2069 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 9045 T2069 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 9047 T2069 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1363994461522/collection1/'
[junit4:junit4]   2> 9047 T2069 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1363994461522/collection1/lib/README' to classloader
[junit4:junit4]   2> 9047 T2069 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1363994461522/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 9084 T2069 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_43
[junit4:junit4]   2> 9126 T2069 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 9127 T2069 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 9133 T2069 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 9373 T2033 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9374 T2033 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:48621_",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "shard":"shard2",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:48621",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "core_node_name":"127.0.0.1:48621__collection1"}
[junit4:junit4]   2> 9391 T2032 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> 9391 T2039 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> 9391 T2052 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> 9391 T2068 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> 9448 T2069 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 9456 T2069 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 9459 T2069 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 9471 T2069 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 9474 T2069 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 9477 T2069 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 9478 T2069 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 9478 T2069 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 9478 T2069 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 9479 T2069 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 9479 T2069 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 9479 T2069 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 9480 T2069 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1363994461522/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1363994453622/jetty2/
[junit4:junit4]   2> 9480 T2069 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@111a7e71
[junit4:junit4]   2> 9480 T2069 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 9481 T2069 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1363994453622/jetty2
[junit4:junit4]   2> 9481 T2069 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1363994453622/jetty2/index/
[junit4:junit4]   2> 9481 T2069 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1363994453622/jetty2/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 9481 T2069 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1363994453622/jetty2/index
[junit4:junit4]   2> 9483 T2069 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2e815f6a lockFactory=org.apache.lucene.store.NativeFSLockFactory@5ed9ae1b),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 9483 T2069 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 9484 T2069 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 9484 T2069 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 9485 T2069 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 9485 T2069 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 9486 T2069 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 9486 T2069 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 9486 T2069 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 9487 T2069 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 9487 T2069 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 9489 T2069 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 9491 T2069 oass.SolrIndexSearcher.<init> Opening Searcher@2f4f7118 main
[junit4:junit4]   2> 9492 T2069 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1363994453622/jetty2/tlog
[junit4:junit4]   2> 9492 T2069 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 9492 T2069 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 9497 T2070 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2f4f7118 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 9499 T2069 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 9499 T2069 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 10906 T2033 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 10907 T2033 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:44561_",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:44561",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "core_node_name":null}
[junit4:junit4]   2> 10907 T2033 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4:junit4]   2> 10907 T2033 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 10926 T2032 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> 10926 T2068 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> 10926 T2039 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> 10926 T2052 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> 11501 T2069 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 11501 T2069 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:44561 collection:collection1 shard:shard1
[junit4:junit4]   2> 11502 T2069 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 11508 T2015 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d9467a67c0008 type:delete cxid:0x49 zxid:0x7d txntype:-1 reqpath:n/a Error Path:/solr/collections/collection1/leaders/shard1 Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders/shard1
[junit4:junit4]   2> 11509 T2069 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 11509 T2015 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d9467a67c0008 type:create cxid:0x4a zxid:0x7e txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 11511 T2069 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 11512 T2069 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 11512 T2069 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:44561/collection1/
[junit4:junit4]   2> 11512 T2069 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 11513 T2069 oasc.SyncStrategy.syncToMe http://127.0.0.1:44561/collection1/ has no replicas
[junit4:junit4]   2> 11513 T2069 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:44561/collection1/
[junit4:junit4]   2> 11513 T2069 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 11516 T2015 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d9467a67c0008 type:create cxid:0x54 zxid:0x81 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 12430 T2033 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 12438 T2032 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> 12438 T2052 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> 12438 T2068 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> 12438 T2039 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> 12470 T2069 oasc.ZkController.register We are http://127.0.0.1:44561/collection1/ and leader is http://127.0.0.1:44561/collection1/
[junit4:junit4]   2> 12470 T2069 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:44561
[junit4:junit4]   2> 12470 T2069 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 12470 T2069 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 12471 T2069 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 12472 T2069 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 12473 T2010 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 12474 T2010 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 12474 T2010 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 12534 T2010 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 12536 T2010 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:37718
[junit4:junit4]   2> 12537 T2010 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 12537 T2010 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 12538 T2010 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1363994466095
[junit4:junit4]   2> 12538 T2010 oasc.CoreContainer$Initializer.initialize looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1363994466095/solr.xml
[junit4:junit4]   2> 12538 T2010 oasc.CoreContainer.<init> New CoreContainer 506072902
[junit4:junit4]   2> 12539 T2010 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1363994466095/'
[junit4:junit4]   2> 12539 T2010 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1363994466095/'
[junit4:junit4]   2> 12557 T2010 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 12558 T2010 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 12558 T2010 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 12558 T2010 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 12558 T2010 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 12559 T2010 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 12559 T2010 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 12559 T2010 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 12560 T2010 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 12560 T2010 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 12566 T2010 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 12575 T2010 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:36379/solr
[junit4:junit4]   2> 12576 T2010 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 12576 T2010 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36379 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@6fffae1
[junit4:junit4]   2> 12577 T2010 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 12577 T2081 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:36379. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 12578 T2081 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36379, initiating session
[junit4:junit4]   2> 12578 T2012 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:54117
[junit4:junit4]   2> 12578 T2012 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:54117
[junit4:junit4]   2> 12579 T2014 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d9467a67c0009 with negotiated timeout 20000 for client /127.0.0.1:54117
[junit4:junit4]   2> 12579 T2081 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:36379, sessionid = 0x13d9467a67c0009, negotiated timeout = 20000
[junit4:junit4]   2> 12580 T2082 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6fffae1 name:ZooKeeperConnection Watcher:127.0.0.1:36379 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 12580 T2010 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 12581 T2015 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d9467a67c0009
[junit4:junit4]   2> 12581 T2082 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 12581 T2012 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:54117 which had sessionid 0x13d9467a67c0009
[junit4:junit4]   2> 12581 T2010 oaz.ZooKeeper.close Session: 0x13d9467a67c0009 closed
[junit4:junit4]   2> 12582 T2010 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 12585 T2010 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36379/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@1d625d9c
[junit4:junit4]   2> 12585 T2010 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 12586 T2083 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:36379. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 12586 T2083 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36379, initiating session
[junit4:junit4]   2> 12586 T2012 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:54118
[junit4:junit4]   2> 12586 T2012 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:54118
[junit4:junit4]   2> 12587 T2014 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d9467a67c000a with negotiated timeout 20000 for client /127.0.0.1:54118
[junit4:junit4]   2> 12587 T2083 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:36379, sessionid = 0x13d9467a67c000a, negotiated timeout = 20000
[junit4:junit4]   2> 12588 T2084 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1d625d9c name:ZooKeeperConnection Watcher:127.0.0.1:36379/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 12588 T2010 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 12589 T2015 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d9467a67c000a type:create cxid:0x1 zxid:0x8e txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 12590 T2015 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d9467a67c000a type:create cxid:0x3 zxid:0x8f txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 12591 T2010 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 13594 T2010 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37718_
[junit4:junit4]   2> 13595 T2015 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d9467a67c000a type:delete cxid:0xd zxid:0x90 txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:37718_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:37718_
[junit4:junit4]   2> 13595 T2010 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:37718_
[junit4:junit4]   2> 13597 T2039 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> 13597 T2032 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> 13599 T2068 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 13599 T2052 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 13599 T2084 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 13599 T2068 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> 13599 T2052 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> 13600 T2032 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 13600 T2039 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 13606 T2085 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1363994466095/collection1
[junit4:junit4]   2> 13606 T2085 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 13607 T2085 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 13607 T2085 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 13608 T2085 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1363994466095/collection1/'
[junit4:junit4]   2> 13609 T2085 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1363994466095/collection1/lib/README' to classloader
[junit4:junit4]   2> 13609 T2085 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1363994466095/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 13637 T2085 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_43
[junit4:junit4]   2> 13670 T2085 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 13671 T2085 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 13674 T2085 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 13943 T2033 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 13944 T2033 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:44561_",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:44561",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "core_node_name":"127.0.0.1:44561__collection1"}
[junit4:junit4]   2> 13947 T2084 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> 13947 T2039 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> 13948 T2068 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> 13948 T2032 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> 13948 T2052 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> 14007 T2085 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 14012 T2085 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 14014 T2085 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 14026 T2085 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 14030 T2085 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 14035 T2085 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 14036 T2085 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 14036 T2085 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 14037 T2085 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 14038 T2085 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 14038 T2085 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 14038 T2085 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 14038 T2085 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1363994466095/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1363994453622/jetty3/
[junit4:junit4]   2> 14038 T2085 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@111a7e71
[junit4:junit4]   2> 14039 T2085 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 14039 T2085 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1363994453622/jetty3
[junit4:junit4]   2> 14039 T2085 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1363994453622/jetty3/index/
[junit4:junit4]   2> 14039 T2085 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1363994453622/jetty3/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 14040 T2085 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1363994453622/jetty3/index
[junit4:junit4]   2> 14041 T2085 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@3daac3a7 lockFactory=org.apache.lucene.store.NativeFSLockFactory@20df2a59),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 14041 T2085 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 14043 T2085 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 14043 T2085 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 14043 T2085 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 14044 T2085 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 14044 T2085 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 14044 T2085 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 14045 T2085 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 14045 T2085 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 14045 T2085 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 14048 T2085 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 14050 T2085 oass.SolrIndexSearcher.<init> Opening Searcher@658f701a main
[junit4:junit4]   2> 14050 T2085 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1363994453622/jetty3/tlog
[junit4:junit4]   2> 14051 T2085 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 14051 T2085 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 14055 T2086 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@658f701a main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 14057 T2085 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 14057 T2085 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 15452 T2033 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 15453 T2033 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:37718_",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:37718",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "core_node_name":null}
[junit4:junit4]   2> 15453 T2033 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4:junit4]   2> 15453 T2033 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4:junit4]   2> 15457 T2084 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> 15457 T2032 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> 15457 T2039 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> 15457 T2052 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> 15457 T2068 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> 16059 T2085 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 16060 T2085 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:37718 collection:collection1 shard:shard2
[junit4:junit4]   2> 16064 T2085 oasc.ZkController.register We are http://127.0.0.1:37718/collection1/ and leader is http://127.0.0.1:48621/collection1/
[junit4:junit4]   2> 16064 T2085 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:37718
[junit4:junit4]   2> 16064 T2085 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 16065 T2085 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C283 name=collection1 org.apache.solr.core.SolrCore@47b41efa url=http://127.0.0.1:37718/collection1 node=127.0.0.1:37718_ C283_STATE=coll:collection1 core:collection1 props:{core=collection1, collection=collection1, node_name=127.0.0.1:37718_, state=down, base_url=http://127.0.0.1:37718}
[junit4:junit4]   2> 16065 T2087 C283 P37718 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 16065 T2085 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 16066 T2087 C283 P37718 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 16067 T2087 C283 P37718 oasc.ZkController.publish publishing core=collection1 state=recovering
[junit4:junit4]   2> 16067 T2087 C283 P37718 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 16067 T2010 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 16068 T2087 C283 P37718 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 16068 T2010 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 16068 T2010 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 16145 T2010 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 16148 T2010 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:48539
[junit4:junit4]   2> 16148 T2010 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 16149 T2010 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 16149 T2010 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1363994469689
[junit4:junit4]   2> 16149 T2010 oasc.CoreContainer$Initializer.initialize looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1363994469689/solr.xml
[junit4:junit4]   2> 16150 T2010 oasc.CoreContainer.<init> New CoreContainer 1088047058
[junit4:junit4]   2> 16150 T2010 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1363994469689/'
[junit4:junit4]   2> 16150 T2010 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1363994469689/'
[junit4:junit4]   2> 16168 T2010 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 16168 T2010 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 16169 T2010 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 16169 T2010 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 16169 T2010 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 16170 T2010 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 16170 T2010 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 16170 T2010 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 16171 T2010 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 16171 T2010 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 16177 T2010 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 16186 T2010 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:36379/solr
[junit4:junit4]   2> 16186 T2010 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 16187 T2010 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36379 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@34501a7d
[junit4:junit4]   2> 16187 T2010 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 16188 T2098 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:36379. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 16188 T2098 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36379, initiating session
[junit4:junit4]   2> 16188 T2012 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:54120
[junit4:junit4]   2> 16189 T2012 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:54120
[junit4:junit4]   2> 16189 T2014 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d9467a67c000b with negotiated timeout 20000 for client /127.0.0.1:54120
[junit4:junit4]   2> 16189 T2098 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:36379, sessionid = 0x13d9467a67c000b, negotiated timeout = 20000
[junit4:junit4]   2> 16190 T2099 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@34501a7d name:ZooKeeperConnection Watcher:127.0.0.1:36379 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 16190 T2010 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 16191 T2015 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d9467a67c000b
[junit4:junit4]   2> 16192 T2099 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 16192 T2012 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:54120 which had sessionid 0x13d9467a67c000b
[junit4:junit4]   2> 16192 T2010 oaz.ZooKeeper.close Session: 0x13d9467a67c000b closed
[junit4:junit4]   2> 16192 T2010 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 16195 T2010 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36379/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@6f144fa6
[junit4:junit4]   2> 16196 T2010 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 16196 T2100 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:36379. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 16196 T2100 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36379, initiating session
[junit4:junit4]   2> 16197 T2012 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:54121
[junit4:junit4]   2> 16197 T2012 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:54121
[junit4:junit4]   2> 16197 T2014 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d9467a67c000c with negotiated timeout 20000 for client /127.0.0.1:54121
[junit4:junit4]   2> 16198 T2100 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:36379, sessionid = 0x13d9467a67c000c, negotiated timeout = 20000
[junit4:junit4]   2> 16198 T2101 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6f144fa6 name:ZooKeeperConnection Watcher:127.0.0.1:36379/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 16198 T2010 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 16200 T2015 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d9467a67c000c type:create cxid:0x1 zxid:0xa1 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 16201 T2015 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d9467a67c000c type:create cxid:0x3 zxid:0xa2 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 16202 T2010 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 16962 T2033 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 16962 T2033 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:37718_",
[junit4:junit4]   2> 	  "state":"recovering",
[junit4:junit4]   2> 	  "shard":"shard2",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:37718",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "core_node_name":"127.0.0.1:37718__collection1"}
[junit4:junit4]   2> 16968 T2101 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> 16968 T2068 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> 16968 T2084 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> 16969 T2052 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> 16969 T2032 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> 16968 T2039 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> 17072 T2046 oass.SolrDispatchFilter.handleAdminRequest /admin/cores {core=collection1&state=recovering&nodeName=127.0.0.1:37718_&coreNodeName=127.0.0.1:37718__collection1&action=PREPRECOVERY&version=2&wt=javabin&onlyIfLeader=true&checkLive=true} status=0 QTime=1000 
[junit4:junit4]   2> 17205 T2010 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:48539_
[junit4:junit4]   2> 17206 T2015 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d9467a67c000c type:delete cxid:0xf zxid:0xa7 txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:48539_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:48539_
[junit4:junit4]   2> 17207 T2010 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:48539_
[junit4:junit4]   2> 17209 T2032 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> 17209 T2039 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> 17210 T2068 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 17210 T2068 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> 17211 T2101 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 17211 T2084 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 17211 T2101 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> 17211 T2084 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> 17211 T2052 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 17211 T2052 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> 17212 T2039 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 17212 T2032 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 17218 T2102 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1363994469689/collection1
[junit4:junit4]   2> 17219 T2102 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 17219 T2102 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 17219 T2102 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 17220 T2102 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1363994469689/collection1/'
[junit4:junit4]   2> 17221 T2102 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1363994469689/collection1/lib/README' to classloader
[junit4:junit4]   2> 17221 T2102 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1363994469689/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 17250 T2102 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_43
[junit4:junit4]   2> 17280 T2102 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 17281 T2102 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 17285 T2102 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 17621 T2102 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 17627 T2102 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 17629 T2102 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 17642 T2102 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 17646 T2102 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 17650 T2102 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 17652 T2102 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 17652 T2102 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 17653 T2102 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 17654 T2102 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 17654 T2102 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 17654 T2102 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 17654 T2102 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1363994469689/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1363994453622/jetty4/
[junit4:junit4]   2> 17655 T2102 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@111a7e71
[junit4:junit4]   2> 17655 T2102 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 17655 T2102 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1363994453622/jetty4
[junit4:junit4]   2> 17656 T2102 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1363994453622/jetty4/index/
[junit4:junit4]   2> 17656 T2102 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1363994453622/jetty4/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 17656 T2102 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1363994453622/jetty4/index
[junit4:junit4]   2> 17657 T2102 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@54f50d40 lockFactory=org.apache.lucene.store.NativeFSLockFactory@7d7694b),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 17658 T2102 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 17660 T2102 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 17661 T2102 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 17661 T2102 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 17662 T2102 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 17663 T2102 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 17663 T2102 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 17663 T2102 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 17664 T2102 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 17664 T2102 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 17668 T2102 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 17672 T2102 oass.SolrIndexSearcher.<init> Opening Searcher@3c148585 main
[junit4:junit4]   2> 17672 T2102 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1363994453622/jetty4/tlog
[junit4:junit4]   2> 17673 T2102 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 17673 T2102 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 17678 T2103 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3c148585 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 17680 T2102 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 17680 T2102 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 18477 T2033 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 18477 T2033 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:48539_",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:48539",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "core_node_name":null}
[junit4:junit4]   2> 18477 T2033 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4:junit4]   2> 18478 T2033 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 18481 T2039 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> 18482 T2032 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> 18482 T2052 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> 18482 T2101 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> 18482 T2084 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> 18482 T2068 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> 18681 T2102 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 18682 T2102 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:48539 collection:collection1 shard:shard1
[junit4:junit4]   2> 18685 T2102 oasc.ZkController.register We are http://127.0.0.1:48539/collection1/ and leader is http://127.0.0.1:44561/collection1/
[junit4:junit4]   2> 18685 T2102 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:48539
[junit4:junit4]   2> 18685 T2102 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 18685 T2102 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C284 name=collection1 org.apache.solr.core.SolrCore@771c533f url=http://127.0.0.1:48539/collection1 node=127.0.0.1:48539_ C284_STATE=coll:collection1 core:collection1 props:{core=collection1, collection=collection1, node_name=127.0.0.1:48539_, state=down, base_url=http://127.0.0.1:48539}
[junit4:junit4]   2> 18686 T2104 C284 P48539 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 18687 T2104 C284 P48539 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 18687 T2102 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 18687 T2104 C284 P48539 oasc.ZkController.publish publishing core=collection1 state=recovering
[junit4:junit4]   2> 18687 T2104 C284 P48539 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 18688 T2104 C284 P48539 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 18688 T2010 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 18689 T2010 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 18689 T2010 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 18693 T2010 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 18694 T2010 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 18700 T2045 oasc.CoreContainer.create Creating SolrCore 'onenodecollectioncore' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1363994456989/onenodecollectioncore
[junit4:junit4]   2> 18700 T2045 oasc.ZkController.createCollectionZkNode Check for collection zkNode:onenodecollection
[junit4:junit4]   2> 18701 T2045 oasc.ZkController.createCollectionZkNode Creating collection in ZooKeeper:onenodecollection
[junit4:junit4]   2> 18701 T2045 oasc.ZkController.getConfName Looking for collection configName
[junit4:junit4]   2> 18702 T2045 oasc.ZkController.getConfName Only one config set found in zk - using it:conf1
[junit4:junit4]   2> 18702 T2045 oascc.SolrZkClient.makePath makePath: /collections/onenodecollection
[junit4:junit4]   2> 18704 T2045 oasc.ZkController.readConfigName Load collection config from:/collections/onenodecollection
[junit4:junit4]   2> 18705 T2045 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1363994456989/onenodecollectioncore/'
[junit4:junit4]   2> 18731 T2045 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_43
[junit4:junit4]   2> 18759 T2045 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 18760 T2045 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 18764 T2045 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2>  C283_STATE=coll:collection1 core:collection1 props:{core=collection1, collection=collection1, node_name=127.0.0.1:37718_, state=recovering, shard=shard2, base_url=http://127.0.0.1:37718}
[junit4:junit4]   2> 19073 T2087 C283 P37718 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:48621/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 19073 T2087 C283 P37718 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:37718 START replicas=[http://127.0.0.1:48621/collection1/] nUpdates=100
[junit4:junit4]   2> 19073 T2087 C283 P37718 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 19078 T2087 C283 P37718 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 19078 T2087 C283 P37718 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 19078 T2087 C283 P37718 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 19078 T2087 C283 P37718 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 19078 T2087 C283 P37718 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:48621/collection1/. core=collection1
[junit4:junit4]   2> 19079 T2087 C283 P37718 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C285 name=collection1 org.apache.solr.core.SolrCore@53c838df url=http://127.0.0.1:48621/collection1 node=127.0.0.1:48621_ C285_STATE=coll:collection1 core:collection1 props:{core=collection1, collection=collection1, node_name=127.0.0.1:48621_, state=active, shard=shard2, base_url=http://127.0.0.1:48621, leader=true}
[junit4:junit4]   2> 19086 T2048 C285 P48621 REQ /get {getVersions=100&qt=/get&distrib=false&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 19086 T2046 C285 P48621 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 19090 T2046 C285 P48621 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@3f82ac7a lockFactory=org.apache.lucene.store.NativeFSLockFactory@44ecd75e),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 19091 T2046 C285 P48621 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 19092 T2046 C285 P48621 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@3f82ac7a lockFactory=org.apache.lucene.store.NativeFSLockFactory@44ecd75e),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@3f82ac7a lockFactory=org.apache.lucene.store.NativeFSLockFactory@44ecd75e),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 19092 T2046 C285 P48621 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 19093 T2046 C285 P48621 oass.SolrIndexSearcher.<init> Opening Searcher@7089af58 realtime
[junit4:junit4]   2> 19093 T2046 C285 P48621 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 19094 T2046 C285 P48621 /update {softCommit=false&openSearcher=false&version=2&waitSearcher=true&wt=javabin&commit=true&commit_end_point=true} {commit=} 0 8
[junit4:junit4]   2> 19095 T2087 C283 P37718 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 19095 T2087 C283 P37718 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 19100 T2046 C285 P48621 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 19100 T2046 C285 P48621 REQ /replication {qt=/replication&command=indexversion&version=2&wt=javabin} status=0 QTime=4 
[junit4:junit4]   2> 19101 T2087 C283 P37718 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4:junit4]   2> 19101 T2087 C283 P37718 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4:junit4]   2> 19101 T2087 C283 P37718 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4]   2> 19105 T2047 C285 P48621 REQ /replication {qt=/replication&command=filelist&version=2&wt=javabin&generation=2} status=0 QTime=0 
[junit4:junit4]   2> 19106 T2087 C283 P37718 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 1
[junit4:junit4]   2> 19107 T2087 C283 P37718 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1363994453622/jetty3/index.20130323012112724
[junit4:junit4]   2> 19107 T2087 C283 P37718 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory@536ce1cb lockFactory=org.apache.lucene.store.NativeFSLockFactory@6405f0a9) fullCopy=false
[junit4:junit4]   2> 19110 T2048 C285 P48621 REQ /replication {checksum=true&qt=/replication&command=filecontent&wt=filestream&generation=2&file=segments_2} status=0 QTime=0 
[junit4:junit4]   2> 19112 T2087 C283 P37718 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4]   2> 19112 T2087 C283 P37718 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4]   2> 19113 T2087 C283 P37718 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4]   2> 19114 T2087 C283 P37718 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@3daac3a7 lockFactory=org.apache.lucene.store.NativeFSLockFactory@20df2a59),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@3daac3a7 lockFactory=org.apache.lucene.store.NativeFSLockFactory@20df2a59),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 19114 T2087 C283 P37718 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 19114 T2087 C283 P37718 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4]   2> 19114 T2087 C283 P37718 oass.SolrIndexSearcher.<init> Opening Searcher@25bed105 main
[junit4:junit4]   2> 19115 T2086 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@25bed105 main{StandardDirectoryReader(segments_2:1:nrt)}
[junit4:junit4]   2> 19116 T2087 C283 P37718 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 19116 T2087 C283 P37718 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 19116 T2087 C283 P37718 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 19116 T2045 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 19116 T2087 C283 P37718 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 19118 T2087 C283 P37718 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 19121 T2045 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 19124 T2045 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 19135 T2045 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 19138 T2045 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 19142 T2045 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 19143 T2045 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 19144 T2045 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 19144 T2045 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 19145 T2045 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Unknown key IMPORTANT NOTE
[junit4:junit4]   2> 19145 T2045 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARNING Expected key, got STRING
[junit4:junit4]   2> 19146 T2045 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 19146 T2045 oasc.SolrCore.<init> [onenodecollectioncore] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1363994456989/onenodecollectioncore/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1363994453622/onenodecollection/
[junit4:junit4]   2> 19146 T2045 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@111a7e71
[junit4:junit4]   2> 19147 T2045 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 19147 T2045 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1363994453622/onenodecollection
[junit4:junit4]   2> 19147 T2045 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1363994453622/onenodecollection/index/
[junit4:junit4]   2> 19148 T2045 oasc.SolrCore.initIndex WARNING [onenodecollectioncore] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1363994453622/onenodecollection/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 19148 T2045 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1363994453622/onenodecollection/index
[junit4:junit4]   2> 19149 T2045 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2f086b53 lockFactory=org.apache.lucene.store.NativeFSLockFactory@630a05a5),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 19150 T2045 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 19151 T2045 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 19152 T2045 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 19152 T2045 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 19153 T2045 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 19153 T2045 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 19153 T2045 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 19154 T2045 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 19154 T2045 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 19154 T2045 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 19157 T2045 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 19159 T2045 oass.SolrIndexSearcher.<init> Opening Searcher@594e9154 main
[junit4:junit4]   2> 19159 T2045 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1363994453622/onenodecollection/tlog
[junit4:junit4]   2> 19160 T2045 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 19160 T2045 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 19163 T2108 oasc.SolrCore.registerSearcher [onenodecollectioncore] Registered new searcher Searcher@594e9154 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 19163 T2045 oasc.ZkController.publish publishing core=onenodecollectioncore state=down
[junit4:junit4]   2> 19986 T2033 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 19987 T2033 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:48539_",
[junit4:junit4]   2> 	  "state":"recovering",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:48539",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "core_node_name":"127.0.0.1:48539__collection1"}
[junit4:junit4]   2> 19990 T2033 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:37718_",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "shard":"shard2",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:37718",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "core_node_name":"127.0.0.1:37718__collection1"}
[junit4:junit4]   2> 19993 T2033 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core":"onenodecollectioncore",
[junit4:junit4]   2> 	  "roles":"none",
[junit4:junit4]   2> 	  "collection":"onenodecollection",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:48621_",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:48621",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "core_node_name":null}
[junit4:junit4]   2> 19993 T2033 oasc.Overseer$ClusterStateUpdater.createCollection Create collection onenodecollection with numShards 1
[junit4:junit4]   2> 19993 T2033 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 19997 T2039 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> 19997 T2084 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> 19997 T2101 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> 19997 T2052 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> 19997 T2068 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> 19997 T2032 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> 20165 T2045 oasc.CoreContainer.registerCore registering core: onenodecollectioncore
[junit4:junit4]   2> 20165 T2045 oasc.ZkController.register Register replica - core:onenodecollectioncore address:http://127.0.0.1:48621 collection:onenodecollection shard:shard1
[junit4:junit4]   2> 20166 T2045 oascc.SolrZkClient.makePath makePath: /collections/onenodecollection/leader_elect/shard1/election
[junit4:junit4]   2> 20171 T2015 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d9467a67c0006 type:delete cxid:0xac zxid:0xc2 txntype:-1 reqpath:n/a Error Path:/solr/collections/onenodecollection/leaders Error:KeeperErrorCode = NoNode for /solr/collections/onenodecollection/leaders
[junit4:junit4]   2> 20172 T2045 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 20172 T2015 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d9467a67c0006 type:create cxid:0xad zxid:0xc3 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 20174 T2045 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 20175 T2045 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 20175 T2045 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:48621/onenodecollectioncore/
[junit4:junit4]   2> 20175 T2045 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 20175 T2045 oasc.SyncStrategy.syncToMe http://127.0.0.1:48621/onenodecollectioncore/ has no replicas
[junit4:junit4]   2> 20176 T2045 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:48621/onenodecollectioncore/
[junit4:junit4]   2> 20176 T2045 oascc.SolrZkClient.makePath makePath: /collections/onenodecollection/leaders/shard1
[junit4:junit4]   2> 20179 T2015 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d9467a67c0006 type:create cxid:0xb8 zxid:0xc7 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 20692 T2064 oass.SolrDispatchFilter.handleAdminRequest /admin/cores {core=collection1&state=recovering&nodeName=127.0.0.1:48539_&coreNodeName=127.0.0.1:48539__collection1&action=PREPRECOVERY&version=2&wt=javabin&onlyIfLeader=true&checkLive=true} status=0 QTime=2000 
[junit4:junit4]   2> 21504 T2033 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 21567 T2068 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> 21567 T2032 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> 21567 T2052 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> 21567 T2039 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> 21567 T2101 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> 21567 T2084 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> 21584 T2045 oasc.ZkController.register We are http://127.0.0.1:48621/onenodecollectioncore/ and leader is http://127.0.0.1:48621/onenodecollectioncore/
[junit4:junit4]   2> 21584 T2045 oasc.ZkController.register No LogReplay needed for core=onenodecollectioncore baseURL=http://127.0.0.1:48621
[junit4:junit4]   2> 21585 T2045 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 21585 T2045 oasc.ZkController.publish publishing core=onenodecollectioncore state=active
[junit4:junit4]   2> 21587 T2045 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 21587 T2045 oass.SolrDispatchFilter.handleAdminRequest /admin/cores {roles=none&name=onenodecollectioncore&collection=onenodecollection&action=CREATE&numShards=1&version=2&wt=javabin&dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1363994453622/onenodecollection} status=0 QTime=2887 
[junit4:junit4]   2> 21588 T2010 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Wait for recoveries to finish - collection: onenodecollection failOnTimeout:true timeout (sec):230
[junit4:junit4]   2> 21589 T2010 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 22590 T2010 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2>  C284_STATE=coll:collection1 core:collection1 props:{core=collection1, collection=collection1, node_name=127.0.0.1:48539_, state=recovering, shard=shard1, base_url=http://127.0.0.1:48539}
[junit4:junit4]   2> 22693 T2104 C284 P48539 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:44561/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 22694 T2104 C284 P48539 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:48539 START replicas=[http://127.0.0.1:44561/collection1/] nUpdates=100
[junit4:junit4]   2> 22694 T2104 C284 P48539 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 22694 T2104 C284 P48539 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 22694 T2104 C284 P48539 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 22694 T2104 C284 P48539 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 22694 T2104 C284 P48539 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 22695 T2104 C284 P48539 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:44561/collection1/. core=collection1
[junit4:junit4]   2> 22695 T2104 C284 P48539 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C286 name=collection1 org.apache.solr.core.SolrCore@16d1aa2a url=http://127.0.0.1:44561/collection1 node=127.0.0.1:44561_ C286_STATE=coll:collection1 core:collection1 props:{core=collection1, collection=collection1, node_name=127.0.0.1:44561_, state=active, shard=shard1, base_url=http://127.0.0.1:44561, leader=true}
[junit4:junit4]   2> 22698 T2063 C286 P44561 REQ /get {getVersions=100&qt=/get&dist

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

  > 	at java.lang.Thread.run(Thread.java:722)
[junit4:junit4] IGNOR/A 0.01s J1 | ScriptEngineTest.testJRuby
[junit4:junit4]    > Assumption #1: got: <[null]>, expected: each not null
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=ScriptEngineTest -Dtests.method=testEvalReader -Dtests.seed=1B1CCDA38F390A7A -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=es_PY -Dtests.timezone=Canada/Saskatchewan -Dtests.file.encoding=US-ASCII
[junit4:junit4] ERROR   0.19s J1 | ScriptEngineTest.testEvalReader <<<
[junit4:junit4]    > Throwable #1: java.lang.ClassCastException: java.lang.Integer cannot be cast to java.lang.Double
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([1B1CCDA38F390A7A:195143CC70CCC961]:0)
[junit4:junit4]    > 	at org.apache.solr.update.processor.ScriptEngineTest.testEvalReader(ScriptEngineTest.java:82)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
[junit4:junit4]    > 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4:junit4]    > 	at java.lang.reflect.Method.invoke(Method.java:487)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]   2> NOTE: test params are: codec=Lucene42: {}, docValues:{}, sim=DefaultSimilarity, locale=es_PY, timezone=Canada/Saskatchewan
[junit4:junit4]   2> NOTE: Linux 3.2.0-39-generic amd64/Oracle Corporation 1.8.0-ea (64-bit)/cpus=8,threads=1,free=252110368,total=413138944
[junit4:junit4]   2> NOTE: All tests run in this JVM: [StandardRequestHandlerTest, TestRTGBase, TestLuceneMatchVersion, IndexReaderFactoryTest, OpenCloseCoreStressTest, TestWordDelimiterFilterFactory, TestDefaultSimilarityFactory, TestLazyCores, TestSystemIdResolver, TermVectorComponentDistributedTest, SolrPluginUtilsTest, DebugComponentTest, DirectUpdateHandlerTest, TestMergePolicyConfig, DefaultValueUpdateProcessorTest, TestFiltering, UniqFieldsUpdateProcessorFactoryTest, TestReload, TestDistributedGrouping, TestJmxIntegration, TestCollationKeyRangeQueries, TestRandomFaceting, TestCopyFieldCollectionResource, CopyFieldTest, TestStressLucene, PingRequestHandlerTest, NumericFieldsTest, TestBM25SimilarityFactory, SolrCoreCheckLockOnStartupTest, HighlighterTest, SolrIndexConfigTest, TestDFRSimilarityFactory, ZkNodePropsTest, SpellCheckCollatorTest, TestIndexSearcher, TestFoldingMultitermQuery, ShardRoutingCustomTest, DOMUtilTest, ClusterStateTest, ZkSolrClientTest, DateMathParserTest, EchoParamsTest, TestUtils, FullSolrCloudDistribCmdsTest, TestNumberUtils, IndexSchemaRuntimeFieldTest, TestSuggestSpellingConverter, HighlighterConfigTest, SuggesterTSTTest, NotRequiredUniqueKeyTest, TestFastOutputStream, SolrCmdDistributorTest, TestCollationField, ChaosMonkeySafeLeaderTest, XsltUpdateRequestHandlerTest, TestStressReorder, TestSolr4Spatial, TimeZoneUtilsTest, SimplePostToolTest, TestAtomicUpdateErrorCases, TestOmitPositions, TestFieldTypeCollectionResource, TestFieldResource, ConvertedLegacyTest, BasicDistributedZk2Test, BasicDistributedZkTest, CollectionsAPIDistributedZkTest, LeaderElectionIntegrationTest, RecoveryZkTest, ClusterStateUpdateTest, LeaderElectionTest, TestDistributedSearch, ZkControllerTest, TestMultiCoreConfBootstrap, HardAutoCommitTest, TestRangeQuery, PeerSyncTest, BadIndexSchemaTest, TestFunctionQuery, CurrencyFieldOpenExchangeTest, TestSolrDiscoveryProperties, AnalysisAfterCoreReloadTest, TestExtendedDismaxParser, DocValuesTest, SuggesterWFSTTest, PolyFieldTest, SchemaVersionSpecificBehaviorTest, TestUpdate, DirectUpdateHandlerOptimizeTest, SortByFunctionTest, DocValuesMultiTest, DistanceFunctionTest, CacheHeaderTest, TestWriterPerf, DirectSolrSpellCheckerTest, DocumentAnalysisRequestHandlerTest, XmlUpdateRequestHandlerTest, TestValueSourceCache, TermVectorComponentTest, MoreLikeThisHandlerTest, FieldAnalysisRequestHandlerTest, RegexBoostProcessorTest, UpdateRequestProcessorFactoryTest, TestCSVResponseWriter, TestAnalyzedSuggestions, TestPartialUpdateDeduplication, TestComponentsName, CoreContainerCoreInitFailuresTest, TestLFUCache, TestPropInjectDefaults, TestSolrIndexConfig, TestQuerySenderNoQuery, MultiTermTest, SampleTest, TestBinaryField, TestSearchPerf, TestElisionMultitermQuery, OutputWriterTest, TestFuzzyAnalyzedSuggestions, ExternalFileFieldSortTest, TestSolrCoreProperties, TestPostingsSolrHighlighter, TestPhraseSuggestions, SpellPossibilityIteratorTest, TestCharFilters, TestCodecSupport, TestSweetSpotSimilarityFactory, TestPerFieldSimilarity, TestLMDirichletSimilarityFactory, TestLMJelinekMercerSimilarityFactory, LegacyHTMLStripCharFilterTest, TestJmxMonitoredMap, TestPluginEnable, ResourceLoaderTest, ScriptEngineTest]
[junit4:junit4] Completed on J1 in 1.61s, 6 tests, 3 errors, 1 skipped <<< FAILURES!

[...truncated 99 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:381: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:361: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build.xml:183: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/common-build.xml:449: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1212: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:876: There were test failures: 273 suites, 1149 tests, 6 errors, 1 failure, 13 ignored (7 assumptions)

Total time: 39 minutes 29 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Description set: Java: 64bit/jdk1.8.0-ea-b82 -XX:+UseG1GC
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message