You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2013/11/28 05:22:25 UTC
[JENKINS] Lucene-Solr-trunk-Windows (64bit/jdk1.7.0_45) - Build #
3514 - Still Failing!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/3514/
Java: 64bit/jdk1.7.0_45 -XX:+UseCompressedOops -XX:+UseSerialGC
6 tests failed.
REGRESSION: org.apache.solr.analytics.expression.ExpressionTest.addTest
Error Message:
empty String
Stack Trace:
java.lang.NumberFormatException: empty String
at __randomizedtesting.SeedInfo.seed([6464EA1A559F44AF:924847C4145043E8]:0)
at sun.misc.FloatingDecimal.readJavaFormatString(FloatingDecimal.java:1011)
at java.lang.Double.parseDouble(Double.java:540)
at org.apache.solr.analytics.AbstractAnalyticsStatsTest.getStatResult(AbstractAnalyticsStatsTest.java:98)
at org.apache.solr.analytics.expression.ExpressionTest.addTest(ExpressionTest.java:78)
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:606)
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:744)
REGRESSION: org.apache.solr.analytics.expression.ExpressionTest.divideTest
Error Message:
empty String
Stack Trace:
java.lang.NumberFormatException: empty String
at __randomizedtesting.SeedInfo.seed([6464EA1A559F44AF:3C6901EC715D2679]:0)
at sun.misc.FloatingDecimal.readJavaFormatString(FloatingDecimal.java:1011)
at java.lang.Double.parseDouble(Double.java:540)
at org.apache.solr.analytics.AbstractAnalyticsStatsTest.getStatResult(AbstractAnalyticsStatsTest.java:98)
at org.apache.solr.analytics.expression.ExpressionTest.divideTest(ExpressionTest.java:106)
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:606)
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:744)
REGRESSION: org.apache.solr.analytics.expression.ExpressionTest.absoluteValueTest
Error Message:
empty String
Stack Trace:
java.lang.NumberFormatException: empty String
at __randomizedtesting.SeedInfo.seed([6464EA1A559F44AF:45C8CA0F98021CC9]:0)
at sun.misc.FloatingDecimal.readJavaFormatString(FloatingDecimal.java:1011)
at java.lang.Double.parseDouble(Double.java:540)
at org.apache.solr.analytics.AbstractAnalyticsStatsTest.getStatResult(AbstractAnalyticsStatsTest.java:98)
at org.apache.solr.analytics.expression.ExpressionTest.absoluteValueTest(ExpressionTest.java:143)
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:606)
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:744)
REGRESSION: org.apache.solr.analytics.expression.ExpressionTest.powerTest
Error Message:
empty String
Stack Trace:
java.lang.NumberFormatException: empty String
at __randomizedtesting.SeedInfo.seed([6464EA1A559F44AF:AD65D19BD881A7E]:0)
at sun.misc.FloatingDecimal.readJavaFormatString(FloatingDecimal.java:1011)
at java.lang.Double.parseDouble(Double.java:540)
at org.apache.solr.analytics.AbstractAnalyticsStatsTest.getStatResult(AbstractAnalyticsStatsTest.java:98)
at org.apache.solr.analytics.expression.ExpressionTest.powerTest(ExpressionTest.java:119)
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:606)
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:744)
REGRESSION: org.apache.solr.analytics.expression.ExpressionTest.negateTest
Error Message:
empty String
Stack Trace:
java.lang.NumberFormatException: empty String
at __randomizedtesting.SeedInfo.seed([6464EA1A559F44AF:6C6C82BFF1B2141F]:0)
at sun.misc.FloatingDecimal.readJavaFormatString(FloatingDecimal.java:1011)
at java.lang.Double.parseDouble(Double.java:540)
at org.apache.solr.analytics.AbstractAnalyticsStatsTest.getStatResult(AbstractAnalyticsStatsTest.java:98)
at org.apache.solr.analytics.expression.ExpressionTest.negateTest(ExpressionTest.java:132)
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:606)
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:744)
REGRESSION: org.apache.solr.analytics.expression.ExpressionTest.multiplyTest
Error Message:
empty String
Stack Trace:
java.lang.NumberFormatException: empty String
at __randomizedtesting.SeedInfo.seed([6464EA1A559F44AF:FE5F464609172C1]:0)
at sun.misc.FloatingDecimal.readJavaFormatString(FloatingDecimal.java:1011)
at java.lang.Double.parseDouble(Double.java:540)
at org.apache.solr.analytics.AbstractAnalyticsStatsTest.getStatResult(AbstractAnalyticsStatsTest.java:98)
at org.apache.solr.analytics.expression.ExpressionTest.multiplyTest(ExpressionTest.java:92)
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:606)
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:744)
Build Log:
[...truncated 10886 lines...]
[junit4] Suite: org.apache.solr.analytics.expression.ExpressionTest
[junit4] 2> 2900024 T8521 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-ExpressionTest-1385612036201
[junit4] 2> 2900026 T8521 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4] 2> 2900028 T8521 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 2900029 T8521 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 2900119 T8521 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 2900173 T8521 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-basic.xml
[junit4] 2> 2900173 T8521 oass.IndexSchema.readSchema Reading Solr Schema from schema-analytics.xml
[junit4] 2> 2900182 T8521 oass.IndexSchema.readSchema [null] Schema name=schema-docValues
[junit4] 2> 2900232 T8521 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2900234 T8521 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2900234 T8521 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr
[junit4] 2> 2900234 T8521 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\'
[junit4] 2> 2900250 T8521 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2900250 T8521 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr
[junit4] 2> 2900250 T8521 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\'
[junit4] 2> 2900335 T8521 oasc.CoreContainer.<init> New CoreContainer 1201624637
[junit4] 2> 2900335 T8521 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\]
[junit4] 2> 2900337 T8521 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 2900337 T8521 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2900337 T8521 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 2900337 T8521 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2900337 T8521 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2900337 T8521 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2900337 T8521 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2900337 T8521 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2900337 T8521 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2900346 T8521 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2900346 T8521 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2900347 T8521 oasc.CoreContainer.load Host Name:
[junit4] 2> 2900358 T8522 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1
[junit4] 2> 2900358 T8522 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4] 2> 2900358 T8522 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 2900358 T8522 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 2900412 T8522 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 2900465 T8522 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-basic.xml
[junit4] 2> 2900465 T8522 oass.IndexSchema.readSchema Reading Solr Schema from schema-analytics.xml
[junit4] 2> 2900474 T8522 oass.IndexSchema.readSchema [collection1] Schema name=schema-docValues
[junit4] 2> 2900521 T8522 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2900522 T8522 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2900523 T8522 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-ExpressionTest-1385612036201\
[junit4] 2> 2900523 T8522 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 2900524 T8522 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-ExpressionTest-1385612036201\
[junit4] 2> 2900524 T8522 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-ExpressionTest-1385612036201\index/
[junit4] 2> 2900524 T8522 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-ExpressionTest-1385612036201\index' doesn't exist. Creating new index...
[junit4] 2> 2900524 T8522 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-ExpressionTest-1385612036201\index
[junit4] 2> 2900525 T8522 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@4bdf021c
[junit4] 2> 2900525 T8522 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@2eff0889 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4d5bfe9c),segFN=segments_1,generation=1}
[junit4] 2> 2900525 T8522 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2900527 T8522 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2900528 T8522 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2900528 T8522 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2900536 T8522 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2900538 T8522 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2900538 T8522 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2900539 T8522 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=650082611, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 2900539 T8522 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@2eff0889 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4d5bfe9c),segFN=segments_1,generation=1}
[junit4] 2> 2900539 T8522 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2900539 T8522 oass.SolrIndexSearcher.<init> Opening Searcher@181d4f48 main
[junit4] 2> 2900540 T8523 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@181d4f48 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2900541 T8522 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2900543 T8521 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> ASYNC NEW_CORE C5924 name=collection1 org.apache.solr.core.SolrCore@20a50906
[junit4] 2> 2900549 T8521 C5924 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 2900549 T8521 C5924 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 0
[junit4] 2> 2900556 T8521 C5924 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10000]} 0 0
[junit4] 2> 2900567 T8521 C5924 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10001]} 0 0
[junit4] 2> 2900575 T8521 C5924 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10002]} 0 0
[junit4] 2> ASYNC NEW_CORE C5925 name=collection1 org.apache.solr.core.SolrCore@20a50906
[junit4] 2> 2900598 T8521 C5925 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10003]} 0 0
[junit4] 2> 2900606 T8521 C5925 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10004]} 0 0
[junit4] 2> 2900615 T8521 C5925 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10005]} 0 0
[junit4] 2> 2900622 T8521 C5925 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10006]} 0 0
[junit4] 2> 2900631 T8521 C5925 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10007]} 0 0
[junit4] 2> 2900640 T8521 C5925 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10008]} 0 1
[junit4] 2> 2900647 T8521 C5925 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10009]} 0 0
[junit4] 2> 2900656 T8521 C5925 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100010]} 0 1
[junit4] 2> 2900663 T8521 C5925 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100011]} 0 0
[junit4] 2> 2900671 T8521 C5925 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100012]} 0 0
[junit4] 2> 2900679 T8521 C5925 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100013]} 0 0
[junit4] 2> 2900688 T8521 C5925 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100014]} 0 0
[junit4] 2> 2900696 T8521 C5925 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100015]} 0 0
[junit4] 2> 2900704 T8521 C5925 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100016]} 0 1
[junit4] 2> 2900712 T8521 C5925 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100017]} 0 0
[junit4] 2> 2900720 T8521 C5925 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100018]} 0 0
[junit4] 2> 2900728 T8521 C5925 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100019]} 0 0
[junit4] 2> 2900737 T8521 C5925 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100020]} 0 0
[junit4] 2> 2900744 T8521 C5925 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100021]} 0 0
[junit4] 2> 2900753 T8521 C5925 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100022]} 0 0
[junit4] 2> 2900761 T8521 C5925 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100023]} 0 0
[junit4] 2> 2900770 T8521 C5925 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100024]} 0 1
[junit4] 2> 2900778 T8521 C5925 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100025]} 0 0
[junit4] 2> 2900786 T8521 C5925 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100026]} 0 0
[junit4] 2> 2900794 T8521 C5925 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100027]} 0 0
[junit4] 2> 2900802 T8521 C5925 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100028]} 0 0
[junit4] 2> ASYNC NEW_CORE C5926 name=collection1 org.apache.solr.core.SolrCore@20a50906
[junit4] 2> 2900821 T8521 C5926 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100029]} 0 0
[junit4] 2> 2900829 T8521 C5926 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100030]} 0 0
[junit4] 2> 2900838 T8521 C5926 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100031]} 0 0
[junit4] 2> 2900847 T8521 C5926 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100032]} 0 1
[junit4] 2> 2900855 T8521 C5926 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100033]} 0 0
[junit4] 2> 2900864 T8521 C5926 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100034]} 0 1
[junit4] 2> 2900872 T8521 C5926 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100035]} 0 0
[junit4] 2> 2900880 T8521 C5926 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100036]} 0 0
[junit4] 2> 2900889 T8521 C5926 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100037]} 0 0
[junit4] 2> 2900897 T8521 C5926 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100038]} 0 0
[junit4] 2> 2900906 T8521 C5926 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100039]} 0 0
[junit4] 2> 2900914 T8521 C5926 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100040]} 0 1
[junit4] 2> 2900922 T8521 C5926 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100041]} 0 0
[junit4] 2> 2900930 T8521 C5926 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100042]} 0 0
[junit4] 2> 2900939 T8521 C5926 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100043]} 0 0
[junit4] 2> 2900947 T8521 C5926 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100044]} 0 0
[junit4] 2> 2900955 T8521 C5926 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100045]} 0 0
[junit4] 2> 2900964 T8521 C5926 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100046]} 0 0
[junit4] 2> 2900974 T8521 C5926 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100047]} 0 1
[junit4] 2> 2900981 T8521 C5926 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100048]} 0 0
[junit4] 2> 2900990 T8521 C5926 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100049]} 0 0
[junit4] 2> 2900997 T8521 C5926 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100050]} 0 0
[junit4] 2> 2901005 T8521 C5926 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100051]} 0 0
[junit4] 2> 2901013 T8521 C5926 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100052]} 0 0
[junit4] 2> 2901021 T8521 C5926 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100053]} 0 0
[junit4] 2> 2901029 T8521 C5926 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100054]} 0 0
[junit4] 2> ASYNC NEW_CORE C5927 name=collection1 org.apache.solr.core.SolrCore@20a50906
[junit4] 2> 2901060 T8521 C5927 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100055]} 0 1
[junit4] 2> 2901071 T8521 C5927 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100056]} 0 0
[junit4] 2> 2901079 T8521 C5927 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100057]} 0 1
[junit4] 2> 2901087 T8521 C5927 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100058]} 0 1
[junit4] 2> 2901096 T8521 C5927 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100059]} 0 0
[junit4] 2> 2901104 T8521 C5927 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100060]} 0 0
[junit4] 2> 2901112 T8521 C5927 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100061]} 0 0
[junit4] 2> 2901120 T8521 C5927 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100062]} 0 0
[junit4] 2> 2901128 T8521 C5927 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100063]} 0 0
[junit4] 2> 2901136 T8521 C5927 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100064]} 0 0
[junit4] 2> 2901145 T8521 C5927 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100065]} 0 0
[junit4] 2> 2901154 T8521 C5927 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100066]} 0 0
[junit4] 2> 2901161 T8521 C5927 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100067]} 0 0
[junit4] 2> 2901170 T8521 C5927 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100068]} 0 1
[junit4] 2> 2901179 T8521 C5927 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100069]} 0 0
[junit4] 2> 2901188 T8521 C5927 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100070]} 0 1
[junit4] 2> 2901197 T8521 C5927 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100071]} 0 1
[junit4] 2> 2901204 T8521 C5927 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100072]} 0 0
[junit4] 2> 2901214 T8521 C5927 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100073]} 0 0
[junit4] 2> 2901222 T8521 C5927 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100074]} 0 0
[junit4] 2> 2901231 T8521 C5927 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100075]} 0 1
[junit4] 2> 2901239 T8521 C5927 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100076]} 0 0
[junit4] 2> 2901247 T8521 C5927 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100077]} 0 0
[junit4] 2> 2901255 T8521 C5927 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100078]} 0 0
[junit4] 2> 2901263 T8521 C5927 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100079]} 0 0
[junit4] 2> 2901272 T8521 C5927 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100080]} 0 0
[junit4] 2> ASYNC NEW_CORE C5928 name=collection1 org.apache.solr.core.SolrCore@20a50906
[junit4] 2> 2901290 T8521 C5928 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100081]} 0 0
[junit4] 2> 2901301 T8521 C5928 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100082]} 0 0
[junit4] 2> 2901313 T8521 C5928 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100083]} 0 0
[junit4] 2> 2901324 T8521 C5928 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100084]} 0 1
[junit4] 2> 2901332 T8521 C5928 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100085]} 0 1
[junit4] 2> 2901340 T8521 C5928 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100086]} 0 1
[junit4] 2> 2901349 T8521 C5928 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100087]} 0 1
[junit4] 2> 2901357 T8521 C5928 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100088]} 0 1
[junit4] 2> 2901365 T8521 C5928 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100089]} 0 0
[junit4] 2> 2901372 T8521 C5928 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100090]} 0 0
[junit4] 2> 2901381 T8521 C5928 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100091]} 0 0
[junit4] 2> 2901389 T8521 C5928 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100092]} 0 0
[junit4] 2> 2901397 T8521 C5928 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100093]} 0 0
[junit4] 2> 2901406 T8521 C5928 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100094]} 0 0
[junit4] 2> 2901413 T8521 C5928 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100095]} 0 0
[junit4] 2> 2901421 T8521 C5928 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100096]} 0 0
[junit4] 2> 2901430 T8521 C5928 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100097]} 0 1
[junit4] 2> 2901438 T8521 C5928 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100098]} 0 0
[junit4] 2> 2901447 T8521 C5928 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100099]} 0 1
[junit4] 2> 2901454 T8521 C5928 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2901461 T8521 C5928 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@2eff0889 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4d5bfe9c),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@2eff0889 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4d5bfe9c),segFN=segments_2,generation=2}
[junit4] 2> 2901462 T8521 C5928 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 2901463 T8521 C5928 oass.SolrIndexSearcher.<init> Opening Searcher@460ecda3 main
[junit4] 2> 2901463 T8521 C5928 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 2901463 T8523 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@460ecda3 main{StandardDirectoryReader(segments_2:4:nrt _0(5.0):C100)}
[junit4] 2> 2901465 T8521 C5928 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 12
[junit4] 2> 2901490 T8521 C5928 oasc.SolrCore.execute [collection1] webapp=null path=null params={o.mr.s.median=median(int_id)&o.avr.s.s=abs(neg(sum(int_id)))&o.pr.s.sum=sum(int_id)&o.dmr.s.cma=const_str(%2B2MONTHS)&o.avr.s.sum=sum(int_id)&o.mr.s.sum=sum(int_id)&o.rr.s.min=min(string_sd)&o.ar.s.mcm=add(mean(int_id),count(long_ld),median(int_id))&o.dmr.s.dmme=date_math(median(date_dtd),const_str(%2B2YEARS))&o.cr.s.min=min(string_sd)&o.dmr.s.median=median(date_dtd)&o.pr.s.mc=pow(mean(int_id),count(long_ld))&o.dr.s.mc=div(mean(int_id),count(long_ld))&o.avr.s.count=count(long_ld)&indent=true&o.rr.s.rmax=rev(max(string_sd))&o.cr.s.max=max(string_sd)&o.ar.s.median=median(int_id)&o.dr.s.sum=sum(int_id)&o.csr.s.cs2=const_str(this+is+the+second)&stats=true&rows=0&o.rr.s.max=max(string_sd)&o.dmr.s.dmma=date_math(max(date_dtd),const_str(%2B2MONTHS))&o.csr.s.cs3=const_str(this+is+the+third)&o.pr.s.mean=mean(int_id)&o.cnr.s.c8=const_num(8)&o.mr.s.mean=mean(int_id)&o.avr.s.c=abs(neg(count(long_ld)))&o.dr.s.su=div(sum(int_id),unique(long_ld))&o.ar.s.sum=sum(int_id)&o.mr.s.mcm=mult(mean(int_id),count(long_ld),median(int_id))&o.mr.s.unique=unique(long_ld)&o.cdr.s.cs2=const_str(1804-06-30T23:59:59Z)&o.mr.s.count=count(long_ld)&o.cdr.s.cs1=const_str(1800-12-31T23:59:59Z)&o.rr.s.rmin=rev(min(string_sd))&o.dr.s.unique=unique(long_ld)&o.dmr.s.cme=const_str(%2B2YEARS)&o.nr.s.c=neg(count(long_ld))&o.cdr.s.cd2=const_date(1804-06-30T23:59:59Z)&o.cr.s.csmax=const_str(this+is+the+second)&o.pr.s.su=pow(sum(int_id),unique(long_ld))&o.nr.s.sum=sum(int_id)&o.nr.s.count=count(long_ld)&o.ar.s.su=add(sum(int_id),unique(long_ld))&o.ar.s.mean=mean(int_id)&o.ar.s.count=count(long_ld)&q=*:*&o.dmr.s.max=max(date_dtd)&o.mr.s.su=mult(sum(int_id),unique(long_ld))&o.dr.s.mean=mean(int_id)&o.cr.s.ccmax=concat(const_str(this+is+the+second),max(string_sd))&olap=true&o.pr.s.count=count(long_ld)&o.ar.s.unique=unique(long_ld)&o.nr.s.s=neg(sum(int_id))&o.csr.s.cs1=const_str(this+is+the+first)&o.cr.s.csmin=const_str(this+is+the+first)&o.cr.s.ccmin=concat(const_str(this+is+the+first),min(string_sd))&o.cdr.s.cd1=const_date(1800-12-31T23:59:59Z)&o.cnr.s.c10=const_num(10)&o.pr.s.unique=unique(long_ld)&o.dr.s.count=count(long_ld)} hits=100 status=0 QTime=17
[junit4] 2> 2901499 T8521 oas.SolrTestCaseJ4.setUp ###Starting addTest
[junit4] 2> 2901501 T8521 oas.SolrTestCaseJ4.tearDown ###Ending addTest
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ExpressionTest -Dtests.method=addTest -Dtests.seed=6464EA1A559F44AF -Dtests.slow=true -Dtests.locale=ru_RU -Dtests.timezone=Asia/Dhaka -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.02s | ExpressionTest.addTest <<<
[junit4] > Throwable #1: java.lang.NumberFormatException: empty String
[junit4] > at __randomizedtesting.SeedInfo.seed([6464EA1A559F44AF:924847C4145043E8]:0)
[junit4] > at sun.misc.FloatingDecimal.readJavaFormatString(FloatingDecimal.java:1011)
[junit4] > at java.lang.Double.parseDouble(Double.java:540)
[junit4] > at org.apache.solr.analytics.AbstractAnalyticsStatsTest.getStatResult(AbstractAnalyticsStatsTest.java:98)
[junit4] > at org.apache.solr.analytics.expression.ExpressionTest.addTest(ExpressionTest.java:78)
[junit4] > at java.lang.Thread.run(Thread.java:744)
[junit4] 2> 2901521 T8521 oas.SolrTestCaseJ4.setUp ###Starting constantNumberTest
[junit4] 2> 2901525 T8521 oas.SolrTestCaseJ4.tearDown ###Ending constantNumberTest
[junit4] 2> 2901530 T8521 oas.SolrTestCaseJ4.setUp ###Starting concatenateTest
[junit4] 2> 2901537 T8521 oas.SolrTestCaseJ4.tearDown ###Ending concatenateTest
[junit4] 2> 2901542 T8521 oas.SolrTestCaseJ4.setUp ###Starting dateMathTest
[junit4] 2> 2901550 T8521 oas.SolrTestCaseJ4.tearDown ###Ending dateMathTest
[junit4] 2> 2901556 T8521 oas.SolrTestCaseJ4.setUp ###Starting reverseTest
[junit4] 2> 2901561 T8521 oas.SolrTestCaseJ4.tearDown ###Ending reverseTest
[junit4] 2> 2901566 T8521 oas.SolrTestCaseJ4.setUp ###Starting divideTest
[junit4] 2> 2901568 T8521 oas.SolrTestCaseJ4.tearDown ###Ending divideTest
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ExpressionTest -Dtests.method=divideTest -Dtests.seed=6464EA1A559F44AF -Dtests.slow=true -Dtests.locale=ru_RU -Dtests.timezone=Asia/Dhaka -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.01s | ExpressionTest.divideTest <<<
[junit4] > Throwable #1: java.lang.NumberFormatException: empty String
[junit4] > at __randomizedtesting.SeedInfo.seed([6464EA1A559F44AF:3C6901EC715D2679]:0)
[junit4] > at sun.misc.FloatingDecimal.readJavaFormatString(FloatingDecimal.java:1011)
[junit4] > at java.lang.Double.parseDouble(Double.java:540)
[junit4] > at org.apache.solr.analytics.AbstractAnalyticsStatsTest.getStatResult(AbstractAnalyticsStatsTest.java:98)
[junit4] > at org.apache.solr.analytics.expression.ExpressionTest.divideTest(ExpressionTest.java:106)
[junit4] > at java.lang.Thread.run(Thread.java:744)
[junit4] 2> 2901576 T8521 oas.SolrTestCaseJ4.setUp ###Starting absoluteValueTest
[junit4] 2> 2901577 T8521 oas.SolrTestCaseJ4.tearDown ###Ending absoluteValueTest
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ExpressionTest -Dtests.method=absoluteValueTest -Dtests.seed=6464EA1A559F44AF -Dtests.slow=true -Dtests.locale=ru_RU -Dtests.timezone=Asia/Dhaka -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.03s | ExpressionTest.absoluteValueTest <<<
[junit4] > Throwable #1: java.lang.NumberFormatException: empty String
[junit4] > at __randomizedtesting.SeedInfo.seed([6464EA1A559F44AF:45C8CA0F98021CC9]:0)
[junit4] > at sun.misc.FloatingDecimal.readJavaFormatString(FloatingDecimal.java:1011)
[junit4] > at java.lang.Double.parseDouble(Double.java:540)
[junit4] > at org.apache.solr.analytics.AbstractAnalyticsStatsTest.getStatResult(AbstractAnalyticsStatsTest.java:98)
[junit4] > at org.apache.solr.analytics.expression.ExpressionTest.absoluteValueTest(ExpressionTest.java:143)
[junit4] > at java.lang.Thread.run(Thread.java:744)
[junit4] 2> 2901604 T8521 oas.SolrTestCaseJ4.setUp ###Starting powerTest
[junit4] 2> 2901606 T8521 oas.SolrTestCaseJ4.tearDown ###Ending powerTest
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ExpressionTest -Dtests.method=powerTest -Dtests.seed=6464EA1A559F44AF -Dtests.slow=true -Dtests.locale=ru_RU -Dtests.timezone=Asia/Dhaka -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.01s | ExpressionTest.powerTest <<<
[junit4] > Throwable #1: java.lang.NumberFormatException: empty String
[junit4] > at __randomizedtesting.SeedInfo.seed([6464EA1A559F44AF:AD65D19BD881A7E]:0)
[junit4] > at sun.misc.FloatingDecimal.readJavaFormatString(FloatingDecimal.java:1011)
[junit4] > at java.lang.Double.parseDouble(Double.java:540)
[junit4] > at org.apache.solr.analytics.AbstractAnalyticsStatsTest.getStatResult(AbstractAnalyticsStatsTest.java:98)
[junit4] > at org.apache.solr.analytics.expression.ExpressionTest.powerTest(ExpressionTest.java:119)
[junit4] > at java.lang.Thread.run(Thread.java:744)
[junit4] 2> 2901614 T8521 oas.SolrTestCaseJ4.setUp ###Starting negateTest
[junit4] 2> 2901616 T8521 oas.SolrTestCaseJ4.tearDown ###Ending negateTest
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ExpressionTest -Dtests.method=negateTest -Dtests.seed=6464EA1A559F44AF -Dtests.slow=true -Dtests.locale=ru_RU -Dtests.timezone=Asia/Dhaka -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.01s | ExpressionTest.negateTest <<<
[junit4] > Throwable #1: java.lang.NumberFormatException: empty String
[junit4] > at __randomizedtesting.SeedInfo.seed([6464EA1A559F44AF:6C6C82BFF1B2141F]:0)
[junit4] > at sun.misc.FloatingDecimal.readJavaFormatString(FloatingDecimal.java:1011)
[junit4] > at java.lang.Double.parseDouble(Double.java:540)
[junit4] > at org.apache.solr.analytics.AbstractAnalyticsStatsTest.getStatResult(AbstractAnalyticsStatsTest.java:98)
[junit4] > at org.apache.solr.analytics.expression.ExpressionTest.negateTest(ExpressionTest.java:132)
[junit4] > at java.lang.Thread.run(Thread.java:744)
[junit4] 2> 2901624 T8521 oas.SolrTestCaseJ4.setUp ###Starting multiplyTest
[junit4] 2> 2901625 T8521 oas.SolrTestCaseJ4.tearDown ###Ending multiplyTest
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ExpressionTest -Dtests.method=multiplyTest -Dtests.seed=6464EA1A559F44AF -Dtests.slow=true -Dtests.locale=ru_RU -Dtests.timezone=Asia/Dhaka -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.01s | ExpressionTest.multiplyTest <<<
[junit4] > Throwable #1: java.lang.NumberFormatException: empty String
[junit4] > at __randomizedtesting.SeedInfo.seed([6464EA1A559F44AF:FE5F464609172C1]:0)
[junit4] > at sun.misc.FloatingDecimal.readJavaFormatString(FloatingDecimal.java:1011)
[junit4] > at java.lang.Double.parseDouble(Double.java:540)
[junit4] > at org.apache.solr.analytics.AbstractAnalyticsStatsTest.getStatResult(AbstractAnalyticsStatsTest.java:98)
[junit4] > at org.apache.solr.analytics.expression.ExpressionTest.multiplyTest(ExpressionTest.java:92)
[junit4] > at java.lang.Thread.run(Thread.java:744)
[junit4] 2> 2901634 T8521 oas.SolrTestCaseJ4.setUp ###Starting constantStringTest
[junit4] 2> 2901638 T8521 oas.SolrTestCaseJ4.tearDown ###Ending constantStringTest
[junit4] 2> 2901644 T8521 oas.SolrTestCaseJ4.setUp ###Starting constantDateTest
[junit4] 2> 2901650 T8521 oas.SolrTestCaseJ4.tearDown ###Ending constantDateTest
[junit4] 2> 2901651 T8521 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 2901651 T8521 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1201624637
[junit4] 2> 2901653 T8521 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@20a50906
[junit4] 2> 2901653 T8521 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=100,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4] 2> 2901653 T8521 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2901653 T8521 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2901653 T8521 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2901655 T8521 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 2901656 T8521 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2901656 T8521 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-ExpressionTest-1385612036201\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-ExpressionTest-1385612036201\;done=false>>]
[junit4] 2> 2901656 T8521 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-ExpressionTest-1385612036201\
[junit4] 2> 2901656 T8521 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-ExpressionTest-1385612036201\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-ExpressionTest-1385612036201\index;done=false>>]
[junit4] 2> 2901656 T8521 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-ExpressionTest-1385612036201\index
[junit4] 2> NOTE: test params are: codec=Lucene46: {float_fd=MockFixedIntBlock(blockSize=479), id=Pulsing41(freqCutoff=16 minBlockSize=6 maxBlockSize=36), double_dd=Pulsing41(freqCutoff=2 minBlockSize=6 maxBlockSize=36), date_dtd=Pulsing41(freqCutoff=16 minBlockSize=6 maxBlockSize=36), long_ld=Pulsing41(freqCutoff=16 minBlockSize=6 maxBlockSize=36), int_id=Pulsing41(freqCutoff=16 minBlockSize=6 maxBlockSize=36), string_sd=Pulsing41(freqCutoff=16 minBlockSize=6 maxBlockSize=36)}, docValues:{float_fd=DocValuesFormat(name=Disk), doubledv=DocValuesFormat(name=SimpleText), double_dd=DocValuesFormat(name=Memory), date_dtd=DocValuesFormat(name=SimpleText), longdv=DocValuesFormat(name=Memory), intdv=DocValuesFormat(name=Disk), stringdv=DocValuesFormat(name=SimpleText), stringdvm=DocValuesFormat(name=Memory), datedv=DocValuesFormat(name=Lucene45), floatdv=DocValuesFormat(name=Memory), int_id=DocValuesFormat(name=SimpleText), long_ld=DocValuesFormat(name=SimpleText), string_sd=DocValuesFormat(name=SimpleText)}, sim=RandomSimilarityProvider(queryNorm=true,coord=no): {}, locale=ru_RU, timezone=Asia/Dhaka
[junit4] 2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.7.0_45 (64-bit)/cpus=2,threads=1,free=177745304,total=295723008
[junit4] 2> NOTE: All tests run in this JVM: [HdfsBasicDistributedZk2Test, TestCloudManagedSchema, CoreAdminCreateDiscoverTest, TestCollationField, TestReload, OverseerCollectionProcessorTest, BinaryUpdateRequestHandlerTest, TestStressReorder, TestFuzzyAnalyzedSuggestions, NoCacheHeaderTest, RangeFacetTest, TestGroupingSearch, SolrTestCaseJ4Test, AbstractAnalyticsStatsTest, XsltUpdateRequestHandlerTest, ZkNodePropsTest, TestFieldTypeCollectionResource, DistanceFunctionTest, DeleteInactiveReplicaTest, TestDocumentBuilder, TestCollapseQParserPlugin, CustomCollectionTest, BasicFunctionalityTest, DOMUtilTest, SchemaVersionSpecificBehaviorTest, InfoHandlerTest, TestDocSet, CurrencyFieldOpenExchangeTest, QueryElevationComponentTest, TestSolrDeletionPolicy2, CoreAdminHandlerTest, AddBlockUpdateTest, TestUpdate, OverseerTest, QueryEqualityTest, HdfsDirectoryTest, SpellingQueryConverterTest, PolyFieldTest, AlternateDirectoryTest, DistributedSuggesterComponentTest, SignatureUpdateProcessorFactoryTest, TestLMJelinekMercerSimilarityFactory, NumericFieldsTest, TestSolrCoreProperties, TestIBSimilarityFactory, NotRequiredUniqueKeyTest, XmlUpdateRequestHandlerTest, CSVRequestHandlerTest, TestQueryUtils, SuggesterFSTTest, PluginInfoTest, HardAutoCommitTest, CachingDirectoryFactoryTest, DistributedSpellCheckComponentTest, ShardSplitTest, HighlighterTest, AnalysisAfterCoreReloadTest, TestFaceting, TestDocBasedVersionConstraints, HdfsRecoveryZkTest, TestSolrIndexConfig, DefaultValueUpdateProcessorTest, TestMaxScoreQueryParser, SolrPluginUtilsTest, ExternalFileFieldSortTest, CircularListTest, HdfsBasicDistributedZkTest, ShardRoutingTest, TestQuerySenderNoQuery, TestFastWriter, ClusterStateUpdateTest, TestAnalyzedSuggestions, MultiTermTest, TestNumberUtils, BJQParserTest, CacheHeaderTest, TestConfig, LukeRequestHandlerTest, ResponseLogComponentTest, SolrIndexConfigTest, HdfsCollectionsAPIDistributedZkTest, TestJmxIntegration, DirectUpdateHandlerTest, TestBadConfig, TestPostingsSolrHighlighter, EchoParamsTest, TestSchemaSimilarityResource, TestJmxMonitoredMap, TestCharFilters, UniqFieldsUpdateProcessorFactoryTest, SoftAutoCommitTest, TestZkChroot, ZkCLITest, CoreContainerCoreInitFailuresTest, ShowFileRequestHandlerTest, TestInfoStreamLogging, SpellCheckCollatorTest, TestDistributedSearch, TestSolrXmlPersistor, TestExtendedDismaxParser, HdfsUnloadDistributedZkTest, RegexBoostProcessorTest, DateFieldTest, TestSweetSpotSimilarityFactory, TestModifyConfFiles, FileBasedSpellCheckerTest, BasicDistributedZkTest, BasicZkTest, ChangedSchemaMergeTest, TestPHPSerializedResponseWriter, RecoveryZkTest, TestReversedWildcardFilterFactory, PreAnalyzedUpdateProcessorTest, TestMergePolicyConfig, TestRemoteStreaming, ShardRoutingCustomTest, TestHighFrequencyDictionaryFactory, QueryParsingTest, SimpleFacetsTest, TestFastLRUCache, TestCoreDiscovery, BlockCacheTest, SolrCoreTest, TestDistribDocBasedVersion, SystemInfoHandlerTest, TestSolrXmlPersistence, TestDefaultSearchFieldResource, FastVectorHighlighterTest, TestDistributedGrouping, HdfsLockFactoryTest, TestPseudoReturnFields, TestLRUCache, QueryResultKeyTest, TestRandomDVFaceting, TestLazyCores, SpatialFilterTest, DebugComponentTest, TestWriterPerf, TestSchemaResource, WordBreakSolrSpellCheckerTest, FieldFacetTest, TestQueryTypes, MBeansHandlerTest, TestFieldTypeResource, TestNoOpRegenerator, SolrCoreCheckLockOnStartupTest, LeaderElectionTest, TestFieldResource, ReturnFieldsTest, DeleteReplicaTest, JsonLoaderTest, EnumFieldTest, SuggestComponentTest, TestSchemaNameResource, TestStressRecovery, TestSurroundQueryParser, TestPhraseSuggestions, HdfsSyncSliceTest, TestArbitraryIndexDir, RequestHandlersTest, TermVectorComponentTest, ChaosMonkeyNothingIsSafeTest, TestSchemaVersionResource, TestSolrDeletionPolicy1, DistributedTermsComponentTest, SimplePostToolTest, FunctionTest, TestSolrQueryParser, CurrencyFieldXmlFileTest, TestSuggestSpellingConverter, PeerSyncTest, TestPerFieldSimilarity, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, UnloadDistributedZkTest, CollectionsAPIDistributedZkTest, SyncSliceTest, OpenCloseCoreStressTest, LeaderElectionIntegrationTest, FullSolrCloudDistribCmdsTest, TestReplicationHandler, TestRandomFaceting, ZkSolrClientTest, SliceStateUpdateTest, AutoCommitTest, TestRecovery, TestHashPartitioner, TermVectorComponentDistributedTest, ZkControllerTest, TestRealTimeGet, TestJoin, TestMultiCoreConfBootstrap, TestStressVersions, TestRangeQuery, TestCoreContainer, TestSolr4Spatial, StatsComponentTest, SpellCheckComponentTest, SolrCmdDistributorTest, BadIndexSchemaTest, ConvertedLegacyTest, TestSort, TestFiltering, TestFunctionQuery, TestIndexSearcher, DistributedQueryElevationComponentTest, SolrIndexSplitterTest, SolrRequestParserTest, TestFoldingMultitermQuery, DocValuesTest, SuggesterTSTTest, SuggesterTest, TestStressLucene, TestTrie, SuggesterWFSTTest, TestCSVLoader, FieldMutatingUpdateProcessorTest, TestAtomicUpdateErrorCases, TestWordDelimiterFilterFactory, DirectUpdateHandlerOptimizeTest, StatelessScriptUpdateProcessorFactoryTest, SortByFunctionTest, DocValuesMultiTest, SolrInfoMBeanTest, IndexBasedSpellCheckerTest, DisMaxRequestHandlerTest, StandardRequestHandlerTest, DirectSolrSpellCheckerTest, DocumentAnalysisRequestHandlerTest, PrimitiveFieldTypeTest, TestOmitPositions, TermsComponentTest, DocumentBuilderTest, TestValueSourceCache, PathHierarchyTokenizerFactoryTest, TestIndexingPerformance, MoreLikeThisHandlerTest, RequiredFieldsTest, FieldAnalysisRequestHandlerTest, IndexSchemaRuntimeFieldTest, LoggingHandlerTest, IndexSchemaTest, UpdateRequestProcessorFactoryTest, JSONWriterTest, TestCSVResponseWriter, TestPartialUpdateDeduplication, PingRequestHandlerTest, TestComponentsName, SearchHandlerTest, TestBinaryResponseWriter, TestLFUCache, HighlighterConfigTest, SOLR749Test, TestQuerySenderListener, UpdateParamsTest, CopyFieldTest, BadComponentTest, SampleTest, TestBinaryField, TestSearchPerf, MinimalSchemaTest, TestElisionMultitermQuery, OutputWriterTest, DirectSolrConnectionTest, TestLuceneMatchVersion, SpellPossibilityIteratorTest, TestCodecSupport, SynonymTokenizerTest, TestXIncludeConfig, TestDFRSimilarityFactory, TestLMDirichletSimilarityFactory, TestBM25SimilarityFactory, TestDefaultSimilarityFactory, LegacyHTMLStripCharFilterTest, TimeZoneUtilsTest, ResourceLoaderTest, TestFastOutputStream, ScriptEngineTest, OpenExchangeRatesOrgProviderTest, URLClassifyProcessorTest, DateMathParserTest, TestSolrXMLSerializer, PreAnalyzedFieldTest, TestSystemIdResolver, PrimUtilsTest, RAMDirectoryFactoryTest, ClusterStateTest, TestSolrJ, TestUtils, SliceStateTest, UUIDFieldTest, FileUtilsTest, TestRTGBase, NoFacetTest, ExpressionTest]
[junit4] Completed in 1.66s, 12 tests, 6 errors <<< FAILURES!
[...truncated 158 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:420: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:400: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:39: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\extra-targets.xml:37: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:189: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:491: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1275: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:908: There were test failures: 354 suites, 1566 tests, 6 errors, 39 ignored (11 assumptions)
Total time: 80 minutes 52 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.7.0_45 -XX:+UseCompressedOops -XX:+UseSerialGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
[JENKINS] Lucene-Solr-trunk-Windows (32bit/jdk1.7.0_45) - Build #
3515 - Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/3515/
Java: 32bit/jdk1.7.0_45 -server -XX:+UseG1GC
1 tests failed.
REGRESSION: org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest.testDistribSearch
Error Message:
There were expected update fails expected:<0> but was:<1>
Stack Trace:
java.lang.AssertionError: There were expected update fails expected:<0> but was:<1>
at __randomizedtesting.SeedInfo.seed([F8729A664875CF4F:7994147E3F2AAF73]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest.doTest(ChaosMonkeyNothingIsSafeTest.java:191)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:835)
at sun.reflect.GeneratedMethodAccessor46.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
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:744)
Build Log:
[...truncated 10735 lines...]
[junit4] Suite: org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest
[junit4] 2> 2928682 T8598 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4] 2> 2928689 T8598 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-ChaosMonkeyNothingIsSafeTest-1385635050266
[junit4] 2> 2928691 T8598 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 2> 2928692 T8599 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 2928771 T8598 oasc.ZkTestServer.run start zk server on port:59985
[junit4] 2> 2928773 T8598 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2928781 T8605 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1ebd27d name:ZooKeeperConnection Watcher:127.0.0.1:59985 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2928782 T8598 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2928782 T8598 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 2928788 T8600 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x1429e48f3220000, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:744)
[junit4] 2>
[junit4] 2> 2928791 T8598 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2928793 T8607 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@e9362b name:ZooKeeperConnection Watcher:127.0.0.1:59985/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2928794 T8598 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2928794 T8598 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 2928799 T8598 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 2928802 T8598 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 2928806 T8598 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 2928812 T8598 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 2928813 T8598 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 2928820 T8598 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\schema.xml to /configs/conf1/schema.xml
[junit4] 2> 2928820 T8598 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 2928825 T8598 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 2928826 T8598 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 2928830 T8598 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 2928830 T8598 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 2928834 T8598 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 2928835 T8598 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 2928839 T8598 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml
[junit4] 2> 2928840 T8598 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 2928844 T8598 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 2928845 T8598 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 2928848 T8598 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 2928848 T8598 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 2928853 T8598 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 2928854 T8598 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 2928858 T8598 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 2928859 T8598 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 2928862 T8598 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 2928862 T8598 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 2928869 T8600 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x1429e48f3220001, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:744)
[junit4] 2>
[junit4] 2> 2929234 T8598 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2929243 T8598 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:59992
[junit4] 2> 2929244 T8598 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2929244 T8598 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2929245 T8598 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1385635050445
[junit4] 2> 2929245 T8598 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1385635050445\'
[junit4] 2> 2929274 T8598 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1385635050445\solr.xml
[junit4] 2> 2929345 T8598 oasc.CoreContainer.<init> New CoreContainer 12608973
[junit4] 2> 2929346 T8598 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1385635050445\]
[junit4] 2> 2929347 T8598 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2929347 T8598 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2929348 T8598 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2929348 T8598 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2929349 T8598 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2929349 T8598 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2929349 T8598 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2929350 T8598 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2929350 T8598 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2929358 T8598 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2929359 T8598 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2929360 T8598 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2929360 T8598 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:59985/solr
[junit4] 2> 2929360 T8598 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 2929363 T8598 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2929370 T8618 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@67ee45 name:ZooKeeperConnection Watcher:127.0.0.1:59985 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2929371 T8598 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2929374 T8598 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2929377 T8620 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@a3dc74 name:ZooKeeperConnection Watcher:127.0.0.1:59985/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2929377 T8598 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2929379 T8598 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 2929383 T8598 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 2929386 T8598 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 2929389 T8598 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59992_
[junit4] 2> 2929390 T8598 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:59992_
[junit4] 2> 2929393 T8598 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 2929403 T8598 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 2929407 T8598 oasc.Overseer.start Overseer (id=90808978654756867-127.0.0.1:59992_-n_0000000000) starting
[junit4] 2> 2929417 T8598 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 2929423 T8622 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 2929424 T8598 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 2929428 T8598 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 2929431 T8598 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2929435 T8621 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 2929440 T8623 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 2929441 T8623 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2929442 T8623 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 2929442 T8620 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2929445 T8621 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2929446 T8621 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:59992",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:59992_",
[junit4] 2> "shard":null,
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":null}
[junit4] 2> 2929446 T8621 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1]
[junit4] 2> 2929446 T8621 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 2929449 T8620 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2929451 T8620 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2930228 T8623 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 2930228 T8623 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1385635050445\collection1
[junit4] 2> 2930229 T8623 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4] 2> 2930229 T8623 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 2930229 T8623 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 2930231 T8623 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1385635050445\collection1\'
[junit4] 2> 2930233 T8623 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1385635050445/collection1/lib/classes/' to classloader
[junit4] 2> 2930234 T8623 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1385635050445/collection1/lib/README' to classloader
[junit4] 2> 2930290 T8623 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 2930341 T8623 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2930343 T8623 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2930351 T8623 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2930887 T8623 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 2930894 T8623 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 2930898 T8623 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2930917 T8623 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2930922 T8623 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2930927 T8623 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2930929 T8623 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 2930929 T8623 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 2930929 T8623 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2930930 T8623 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 2930930 T8623 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 2930931 T8623 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4] 2> 2930931 T8623 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1385635050445\collection1\, dataDir=.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1385635050265/control/data\
[junit4] 2> 2930931 T8623 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@c2c1a8
[junit4] 2> 2930932 T8623 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1385635050265\control\data
[junit4] 2> 2930933 T8623 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1385635050265/control/data\index/
[junit4] 2> 2930934 T8623 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1385635050265\control\data\index' doesn't exist. Creating new index...
[junit4] 2> 2930934 T8623 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1385635050265\control\data\index
[junit4] 2> 2930934 T8623 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=27, maxMergeAtOnceExplicit=40, maxMergedSegmentMB=77.0498046875, floorSegmentMB=0.5947265625, forceMergeDeletesPctAllowed=6.166250316508858, segmentsPerTier=34.0, maxCFSSegmentSizeMB=0.2177734375, noCFSRatio=0.36120918980099437
[junit4] 2> 2930938 T8623 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1385635050265\control\data\index,segFN=segments_1,generation=1}
[junit4] 2> 2930939 T8623 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2930942 T8623 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 2930943 T8623 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 2930943 T8623 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 2930943 T8623 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 2930943 T8623 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 2930943 T8623 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 2930944 T8623 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2930944 T8623 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2930944 T8623 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2930945 T8623 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2930945 T8623 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 2930945 T8623 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 2930945 T8623 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2930947 T8623 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2930947 T8623 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 2930948 T8623 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 2930952 T8623 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2930957 T8623 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2930957 T8623 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2930957 T8623 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=29, maxMergeAtOnceExplicit=10, maxMergedSegmentMB=51.42578125, floorSegmentMB=1.388671875, forceMergeDeletesPctAllowed=1.0288149846681915, segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 2930957 T8623 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1385635050265\control\data\index,segFN=segments_1,generation=1}
[junit4] 2> 2930957 T8623 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2930957 T8623 oass.SolrIndexSearcher.<init> Opening Searcher@1753e39 main
[junit4] 2> 2930962 T8624 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1753e39 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2930964 T8623 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2930964 T8623 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:59992 collection:control_collection shard:shard1
[junit4] 2> 2930964 T8623 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4] 2> 2930972 T8623 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 2930975 T8620 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2930976 T8623 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 2930976 T8623 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C2489 name=collection1 org.apache.solr.core.SolrCore@17c5060 url=http://127.0.0.1:59992/collection1 node=127.0.0.1:59992_ C2489_STATE=coll:control_collection core:collection1 props:{state=down, base_url=http://127.0.0.1:59992, core=collection1, node_name=127.0.0.1:59992_}
[junit4] 2> 2930976 T8623 C2489 P59992 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:59992/collection1/
[junit4] 2> 2930976 T8623 C2489 P59992 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 2930976 T8623 C2489 P59992 oasc.SyncStrategy.syncToMe http://127.0.0.1:59992/collection1/ has no replicas
[junit4] 2> 2930977 T8623 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:59992/collection1/ shard1
[junit4] 2> 2930978 T8623 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 2930978 T8621 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2930983 T8620 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2930984 T8620 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2930990 T8621 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2930992 T8620 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2931067 T8620 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2931097 T8623 oasc.ZkController.register We are http://127.0.0.1:59992/collection1/ and leader is http://127.0.0.1:59992/collection1/
[junit4] 2> 2931098 T8623 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:59992
[junit4] 2> 2931098 T8623 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 2931098 T8623 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 2931098 T8623 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2931100 T8620 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2931100 T8620 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2931100 T8620 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2931101 T8623 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2931102 T8598 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2931103 T8598 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2931105 T8621 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2931106 T8598 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2931107 T8621 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:59992",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:59992_",
[junit4] 2> "shard":"shard1",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 2931109 T8627 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@68c578 name:ZooKeeperConnection Watcher:127.0.0.1:59985/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2931109 T8598 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2931110 T8620 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2931110 T8598 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2931114 T8598 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 2931186 T8627 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2931187 T8620 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2931450 T8598 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2931453 T8598 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:60004
[junit4] 2> 2931455 T8598 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2931455 T8598 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2931455 T8598 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty1-1385635052690
[junit4] 2> 2931455 T8598 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty1-1385635052690\'
[junit4] 2> 2931481 T8598 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty1-1385635052690\solr.xml
[junit4] 2> 2931542 T8598 oasc.CoreContainer.<init> New CoreContainer 9699407
[junit4] 2> 2931542 T8598 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty1-1385635052690\]
[junit4] 2> 2931545 T8598 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2931545 T8598 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2931545 T8598 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2931545 T8598 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2931545 T8598 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2931545 T8598 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2931545 T8598 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2931545 T8598 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2931545 T8598 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2931551 T8598 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2931551 T8598 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2931551 T8598 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2931554 T8598 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:59985/solr
[junit4] 2> 2931554 T8598 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 2931555 T8598 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2931561 T8638 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1cdddac name:ZooKeeperConnection Watcher:127.0.0.1:59985 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2931561 T8598 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2931563 T8600 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x1429e48f3220005, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:744)
[junit4] 2>
[junit4] 2> 2931565 T8598 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2931567 T8640 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1846ac1 name:ZooKeeperConnection Watcher:127.0.0.1:59985/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2931568 T8598 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2931573 T8598 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2932294 T8598 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60004_
[junit4] 2> 2932296 T8598 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:60004_
[junit4] 2> 2932299 T8627 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 2932299 T8620 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 2932299 T8640 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 2932308 T8641 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 2932310 T8641 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2932312 T8620 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2932312 T8620 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2932312 T8641 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 2932312 T8620 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2932315 T8621 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2932315 T8621 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:60004",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:60004_",
[junit4] 2> "shard":null,
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":null}
[junit4] 2> 2932315 T8621 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2]
[junit4] 2> 2932316 T8621 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4] 2> 2932319 T8620 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2932408 T8620 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 2932408 T8640 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 2932408 T8627 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 2933031 T8641 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 2933031 T8641 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty1-1385635052690\collection1
[junit4] 2> 2933031 T8641 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 2933032 T8641 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 2933032 T8641 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 2933034 T8641 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty1-1385635052690\collection1\'
[junit4] 2> 2933035 T8641 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty1-1385635052690/collection1/lib/classes/' to classloader
[junit4] 2> 2933036 T8641 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty1-1385635052690/collection1/lib/README' to classloader
[junit4] 2> 2933090 T8641 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 2933144 T8641 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2933146 T8641 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2933154 T8641 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2933682 T8641 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 2933689 T8641 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 2933691 T8641 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2933710 T8641 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2933715 T8641 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2933721 T8641 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2933722 T8641 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 2933722 T8641 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 2933722 T8641 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2933724 T8641 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 2933724 T8641 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 2933724 T8641 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4] 2> 2933724 T8641 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty1-1385635052690\collection1\, dataDir=.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1385635050265/jetty1\
[junit4] 2> 2933725 T8641 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@c2c1a8
[junit4] 2> 2933726 T8641 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1385635050265\jetty1
[junit4] 2> 2933727 T8641 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1385635050265/jetty1\index/
[junit4] 2> 2933727 T8641 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1385635050265\jetty1\index' doesn't exist. Creating new index...
[junit4] 2> 2933727 T8641 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1385635050265\jetty1\index
[junit4] 2> 2933728 T8641 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=27, maxMergeAtOnceExplicit=40, maxMergedSegmentMB=77.0498046875, floorSegmentMB=0.5947265625, forceMergeDeletesPctAllowed=6.166250316508858, segmentsPerTier=34.0, maxCFSSegmentSizeMB=0.2177734375, noCFSRatio=0.36120918980099437
[junit4] 2> 2933732 T8641 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1385635050265\jetty1\index,segFN=segments_1,generation=1}
[junit4] 2> 2933732 T8641 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2933736 T8641 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 2933736 T8641 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 2933737 T8641 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 2933737 T8641 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 2933737 T8641 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 2933738 T8641 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 2933738 T8641 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2933738 T8641 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2933738 T8641 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2933739 T8641 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2933740 T8641 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 2933740 T8641 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 2933740 T8641 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2933741 T8641 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2933742 T8641 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 2933742 T8641 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 2933750 T8641 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2933755 T8641 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2933755 T8641 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2933755 T8641 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=29, maxMergeAtOnceExplicit=10, maxMergedSegmentMB=51.42578125, floorSegmentMB=1.388671875, forceMergeDeletesPctAllowed=1.0288149846681915, segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 2933757 T8641 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1385635050265\jetty1\index,segFN=segments_1,generation=1}
[junit4] 2> 2933757 T8641 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2933757 T8641 oass.SolrIndexSearcher.<init> Opening Searcher@15468f4 main
[junit4] 2> 2933760 T8642 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@15468f4 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2933763 T8641 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2933764 T8641 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:60004 collection:collection1 shard:shard2
[junit4] 2> 2933764 T8641 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4] 2> 2933773 T8641 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2
[junit4] 2> 2933776 T8620 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2933776 T8620 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2933776 T8620 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2933777 T8641 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 2933777 T8641 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C2490 name=collection1 org.apache.solr.core.SolrCore@165b001 url=http://127.0.0.1:60004/collection1 node=127.0.0.1:60004_ C2490_STATE=coll:collection1 core:collection1 props:{state=down, base_url=http://127.0.0.1:60004, core=collection1, node_name=127.0.0.1:60004_}
[junit4] 2> 2933777 T8641 C2490 P60004 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:60004/collection1/
[junit4] 2> 2933777 T8641 C2490 P60004 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 2933777 T8641 C2490 P60004 oasc.SyncStrategy.syncToMe http://127.0.0.1:60004/collection1/ has no replicas
[junit4] 2> 2933777 T8641 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:60004/collection1/ shard2
[junit4] 2> 2933777 T8641 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4] 2> 2933780 T8621 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2933784 T8620 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2933786 T8620 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2933790 T8620 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2933865 T8627 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 2933865 T8620 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 2933865 T8640 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 2933896 T8641 oasc.ZkController.register We are http://127.0.0.1:60004/collection1/ and leader is http://127.0.0.1:60004/collection1/
[junit4] 2> 2933896 T8641 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:60004
[junit4] 2> 2933896 T8641 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 2933897 T8641 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 2933897 T8641 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2933898 T8620 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2933898 T8620 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2933899 T8620 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2933899 T8641 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2933900 T8598 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2933900 T8598 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2933900 T8621 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2933902 T8621 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:60004",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:60004_",
[junit4] 2> "shard":"shard2",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 2933907 T8620 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2933982 T8627 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 2933985 T8620 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 2933985 T8640 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 2934232 T8598 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2934235 T8598 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:60013
[junit4] 2> 2934236 T8598 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2934236 T8598 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2934236 T8598 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1385635055478
[junit4] 2> 2934236 T8598 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1385635055478\'
[junit4] 2> 2934262 T8598 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1385635055478\solr.xml
[junit4] 2> 2934322 T8598 oasc.CoreContainer.<init> New CoreContainer 20611731
[junit4] 2> 2934322 T8598 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1385635055478\]
[junit4] 2> 2934324 T8598 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2934324 T8598 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2934324 T8598 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2934324 T8598 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2934324 T8598 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2934324 T8598 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2934324 T8598 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2934326 T8598 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2934326 T8598 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2934332 T8598 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2934332 T8598 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2934332 T8598 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2934332 T8598 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:59985/solr
[junit4] 2> 2934334 T8598 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 2934335 T8598 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2934340 T8654 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1277c85 name:ZooKeeperConnection Watcher:127.0.0.1:59985 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2934341 T8598 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2934347 T8598 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2934349 T8656 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@d72d02 name:ZooKeeperConnection Watcher:127.0.0.1:59985/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2934349 T8598 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2934354 T8598 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2935075 T8598 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60013_
[junit4] 2> 2935077 T8598 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:60013_
[junit4] 2> 2935080 T8627 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 2935081 T8656 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 2935082 T8620 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 2935082 T8640 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 2935089 T8657 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 2935090 T8657 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2935091 T8657 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 2935091 T8620 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2935092 T8620 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2935092 T8620 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2935094 T8621 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2935095 T8621 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:60013",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:60013_",
[junit4] 2> "shard":null,
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":null}
[junit4] 2> 2935095 T8621 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4] 2> 2935095 T8621 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 2935098 T8620 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2935173 T8620 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 2935173 T8640 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 2935173 T8627 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 2935173 T8656 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 2935810 T8657 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 2935810 T8657 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1385635055478\collection1
[junit4] 2> 2935810 T8657 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 2935811 T8657 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 2935811 T8657 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 2935813 T8657 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1385635055478\collection1\'
[junit4] 2> 2935814 T8657 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1385635055478/collection1/lib/classes/' to classloader
[junit4] 2> 2935815 T8657 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1385635055478/collection1/lib/README' to classloader
[junit4] 2> 2935869 T8657 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 2935920 T8657 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2935922 T8657 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2935930 T8657 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2936463 T8657 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 2936472 T8657 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 2936475 T8657 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2936490 T8657 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2936494 T8657 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2936498 T8657 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2936501 T8657 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 2936501 T8657 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 2936501 T8657 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2936502 T8657 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 2936502 T8657 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 2936502 T8657 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4] 2> 2936503 T8657 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1385635055478\collection1\, dataDir=.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1385635050265/jetty2\
[junit4] 2> 2936503 T8657 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@c2c1a8
[junit4] 2> 2936503 T8657 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1385635050265\jetty2
[junit4] 2> 2936503 T8657 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1385635050265/jetty2\index/
[junit4] 2> 2936505 T8657 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1385635050265\jetty2\index' doesn't exist. Creating new index...
[junit4] 2> 2936505 T8657 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1385635050265\jetty2\index
[junit4] 2> 2936505 T8657 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=27, maxMergeAtOnceExplicit=40, maxMergedSegmentMB=77.0498046875, floorSegmentMB=0.5947265625, forceMergeDeletesPctAllowed=6.166250316508858, segmentsPerTier=34.0, maxCFSSegmentSizeMB=0.2177734375, noCFSRatio=0.36120918980099437
[junit4] 2> 2936509 T8657 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1385635050265\jetty2\index,segFN=segments_1,generation=1}
[junit4] 2> 2936509 T8657 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2936513 T8657 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 2936513 T8657 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 2936514 T8657 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 2936514 T8657 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 2936514 T8657 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 2936515 T8657 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 2936515 T8657 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2936515 T8657 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2936515 T8657 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2936516 T8657 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2936517 T8657 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 2936517 T8657 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 2936517 T8657 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2936518 T8657 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2936518 T8657 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 2936519 T8657 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 2936526 T8657 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2936531 T8657 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2936531 T8657 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2936533 T8657 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=29, maxMergeAtOnceExplicit=10, maxMergedSegmentMB=51.42578125, floorSegmentMB=1.388671875, forceMergeDeletesPctAllowed=1.0288149846681915, segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 2936534 T8657 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1385635050265\jetty2\index,segFN=segments_1,generation=1}
[junit4] 2> 2936534 T8657 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2936534 T8657 oass.SolrIndexSearcher.<init> Opening Searcher@14795ec main
[junit4] 2> 2936542 T8657 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2936542 T8657 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:60013 collection:collection1 shard:shard1
[junit4] 2> 2936543 T8657 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4] 2> 2936546 T8658 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@14795ec main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2936550 T8657 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 2936553 T8620 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2936553 T8620 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2936553 T8620 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2936554 T8657 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 2936554 T8657 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C2491 name=collection1 org.apache.solr.core.SolrCore@c19934 url=http://127.0.0.1:60013/collection1 node=127.0.0.1:60013_ C2491_STATE=coll:collection1 core:collection1 props:{state=down, base_url=http://127.0.0.1:60013, core=collection1, node_name=127.0.0.1:60013_}
[junit4] 2> 2936554 T8657 C2491 P60013 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:60013/collection1/
[junit4] 2> 2936554 T8657 C2491 P60013 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 2936554 T8657 C2491 P60013 oasc.SyncStrategy.syncToMe http://127.0.0.1:60013/collection1/ has no replicas
[junit4] 2> 2936554 T8657 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:60013/collection1/ shard1
[junit4] 2> 2936554 T8657 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4] 2> 2936557 T8621 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2936564 T8620 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2936566 T8620 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2936570 T8620 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2936645 T8656 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 2936646 T8627 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 2936646 T8620 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 2936646 T8640 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 2936678 T8657 oasc.ZkController.register We are http://127.0.0.1:60013/collection1/ and leader is http://127.0.0.1:60013/collection1/
[junit4] 2> 2936679 T8657 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:60013
[junit4] 2> 2936679 T8657 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 2936679 T8657 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 2936679 T8657 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2936681 T8620 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2936681 T8620 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2936681 T8620 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2936681 T8657 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2936683 T8621 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2936683 T8598 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2936683 T8598 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2936684 T8621 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:60013",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:60013_",
[junit4] 2> "shard":"shard1",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":"core_node2"}
[junit4] 2> 2936688 T8620 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2936763 T8620 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 2936763 T8640 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 2936763 T8656 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 2936763 T8627 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 2937014 T8598 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2937018 T8598 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:60022
[junit4] 2> 2937020 T8598 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2937020 T8598 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2937020 T8598 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty3-1385635058261
[junit4] 2> 2937020 T8598 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty3-1385635058261\'
[junit4] 2> 2937046 T8598 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty3-1385635058261\solr.xml
[junit4] 2> 2937107 T8598 oasc.CoreContainer.<init> New CoreContainer 23050280
[junit4] 2> 2937107 T8598 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty3-1385635058261\]
[junit4] 2> 2937109 T8598 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2937109 T8598 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2937109 T8598 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2937109 T8598 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2937109 T8598 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2937109 T8598 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2937109 T8598 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2937111 T8598 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2937112 T8598 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2937117 T8598 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2937117 T8598 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2937117 T8598 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2937117 T8598 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:59985/solr
[junit4] 2> 2937119 T8598 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 2937122 T8598 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2937129 T8670 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1caaba name:ZooKeeperConnection Watcher:127.0.0.1:59985 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2937130 T8598 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2937135 T8598 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2937138 T8672 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@10cdd7c name:ZooKeeperConnection Watcher:127.0.0.1:59985/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2937138 T8598 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2937144 T8598 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2937808 T8598 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60022_
[junit4] 2> 2937811 T8598 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:60022_
[junit4] 2> 2937814 T8620 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 2937814 T8627 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 2937814 T8640 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 2937814 T8672 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 2937814 T8656 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 2937823 T8673 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 2937823 T8673 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2937826 T8673 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 2937826 T8620 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2937826 T8620 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2937826 T8620 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2937828 T8621 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2937828 T8621 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:60022",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:60022_",
[junit4] 2> "shard":null,
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":null}
[junit4] 2> 2937829 T8621 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4] 2> 2937829 T8621 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4] 2> 2937832 T8620 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2937900 T8627 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 2937900 T8640 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 2937900 T8620 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 2937900 T8672 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 2937900 T8656 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 2938472 T8673 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 2938472 T8673 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty3-1385635058261\collection1
[junit4] 2> 2938472 T8673 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 2938473 T8673 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 2938473 T8673 oascc.ZkStateReader.readConfigName Load collection config from:/collectio
[...truncated too long message...]
:
[junit4] 1>
[junit4] 1>
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ChaosMonkeyNothingIsSafeTest -Dtests.method=testDistribSearch -Dtests.seed=F8729A664875CF4F -Dtests.slow=true -Dtests.locale=lv_LV -Dtests.timezone=Etc/GMT+11 -Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 52.7s | ChaosMonkeyNothingIsSafeTest.testDistribSearch <<<
[junit4] > Throwable #1: java.lang.AssertionError: There were expected update fails expected:<0> but was:<1>
[junit4] > at __randomizedtesting.SeedInfo.seed([F8729A664875CF4F:7994147E3F2AAF73]:0)
[junit4] > at org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest.doTest(ChaosMonkeyNothingIsSafeTest.java:191)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:835)
[junit4] > at java.lang.Thread.run(Thread.java:744)
[junit4] 2> 2981409 T8598 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: test params are: codec=Lucene46: {timestamp=Pulsing41(freqCutoff=9 minBlockSize=95 maxBlockSize=250), other_tl1=PostingsFormat(name=Memory doPackFST= false), _version_=PostingsFormat(name=Memory doPackFST= false), range_facet_sl=TestBloomFilteredLucene41Postings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), text=PostingsFormat(name=FSTOrdPulsing41), id=TestBloomFilteredLucene41Postings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), a_si=PostingsFormat(name=Memory doPackFST= false), range_facet_l=Pulsing41(freqCutoff=9 minBlockSize=95 maxBlockSize=250), multiDefault=Pulsing41(freqCutoff=9 minBlockSize=95 maxBlockSize=250), range_facet_si=Pulsing41(freqCutoff=9 minBlockSize=95 maxBlockSize=250), intDefault=PostingsFormat(name=Memory doPackFST= false), a_t=Pulsing41(freqCutoff=9 minBlockSize=95 maxBlockSize=250)}, docValues:{timestamp=DocValuesFormat(name=Lucene45)}, sim=DefaultSimilarity, locale=lv_LV, timezone=Etc/GMT+11
[junit4] 2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.7.0_45 (32-bit)/cpus=2,threads=3,free=295791008,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [CoreAdminCreateDiscoverTest, TestExtendedDismaxParser, TestPerFieldSimilarity, RequestHandlersTest, TestClassNameShortening, TestXIncludeConfig, DeleteReplicaTest, CurrencyFieldXmlFileTest, HdfsDirectoryTest, InfoHandlerTest, TestUpdate, AddBlockUpdateTest, TestAtomicUpdateErrorCases, TestPartialUpdateDeduplication, HdfsUnloadDistributedZkTest, SuggesterWFSTTest, DistributedDebugComponentTest, OutputWriterTest, TestRandomMergePolicy, TestSchemaSimilarityResource, AnalysisAfterCoreReloadTest, TestLFUCache, StressHdfsTest, SpatialFilterTest, WordBreakSolrSpellCheckerTest, PreAnalyzedUpdateProcessorTest, BasicFunctionalityTest, NoCacheHeaderTest, DocumentBuilderTest, StatelessScriptUpdateProcessorFactoryTest, RequiredFieldsTest, TestIndexSearcher, HdfsSyncSliceTest, TestFunctionQuery, TestFastWriter, LeaderElectionIntegrationTest, SolrCoreCheckLockOnStartupTest, TestCodecSupport, TestFastLRUCache, QueryResultKeyTest, ZkControllerTest, TestCollapseQParserPlugin, EnumFieldTest, PeerSyncTest, TestBadConfig, TestRecovery, QueryParsingTest, SOLR749Test, SignatureUpdateProcessorFactoryTest, TestCSVLoader, SpellPossibilityIteratorTest, TestQueryUtils, TestStressReorder, SolrIndexSplitterTest, TestAddFieldRealTimeGet, BasicDistributedZk2Test, TestQueryTypes, BadComponentTest, ExternalFileFieldSortTest, SimpleFacetsTest, TestRangeQuery, TestDocSet, TestReversedWildcardFilterFactory, SystemInfoHandlerTest, SolrPluginUtilsTest, XsltUpdateRequestHandlerTest, ChaosMonkeySafeLeaderTest, AbstractAnalyticsFacetTest, HighlighterTest, TestIndexingPerformance, HdfsBasicDistributedZkTest, CacheHeaderTest, CoreMergeIndexesAdminHandlerTest, TestRecoveryHdfs, SampleTest, TestDistribDocBasedVersion, TermVectorComponentDistributedTest, TestReplicationHandler, MBeansHandlerTest, TestUtils, SuggesterTSTTest, JSONWriterTest, FullSolrCloudDistribCmdsTest, CoreContainerCoreInitFailuresTest, ZkNodePropsTest, TestLMJelinekMercerSimilarityFactory, TestSolrXml, SynonymTokenizerTest, TestSolrXmlPersistor, DirectUpdateHandlerTest, TestGroupingSearch, TestMergePolicyConfig, SchemaVersionSpecificBehaviorTest, SyncSliceTest, TestCollationFieldDocValues, SliceStateTest, DistanceFunctionTest, TestFuzzyAnalyzedSuggestions, SolrIndexConfigTest, NotRequiredUniqueKeyTest, TestNoOpRegenerator, CustomCollectionTest, SolrInfoMBeanTest, DistributedSuggesterComponentTest, TestRandomFaceting, TestConfig, BadIndexSchemaTest, BlockCacheTest, TestDynamicFieldResource, TestCSVResponseWriter, PrimUtilsTest, ResourceLoaderTest, DocValuesMultiTest, LukeRequestHandlerTest, TestDocBasedVersionConstraints, TestMaxScoreQueryParser, AbstractAnalyticsStatsTest, StatsComponentTest, TestCollationField, AddSchemaFieldsUpdateProcessorFactoryTest, SliceStateUpdateTest, TestPseudoReturnFields, TestWriterPerf, BasicZkTest, LoggingHandlerTest, TestElisionMultitermQuery, TestManagedSchemaFieldResource, FileUtilsTest, ShowFileRequestHandlerTest, TestNumberUtils, SolrRequestParserTest, TestSolrXmlPersistence, TestIBSimilarityFactory, RangeFacetTest, UniqFieldsUpdateProcessorFactoryTest, DefaultValueUpdateProcessorTest, DeleteShardTest, SuggesterFSTTest, TestSchemaVersionResource, NumericFieldsTest, TriLevelCompositeIdRoutingTest, TestSolrQueryParserResource, TestFieldTypeCollectionResource, TestBinaryField, TestPHPSerializedResponseWriter, TestRealTimeGet, TestLRUCache, MinimalSchemaTest, CurrencyFieldOpenExchangeTest, TestSolrCoreProperties, PrimitiveFieldTypeTest, DateMathParserTest, JsonLoaderTest, TestSort, CoreAdminHandlerTest, TestDFRSimilarityFactory, OverseerCollectionProcessorTest, DistributedTermsComponentTest, URLClassifyProcessorTest, FieldFacetExtrasTest, FieldMutatingUpdateProcessorTest, IndexSchemaRuntimeFieldTest, TestStressVersions, TestDistributedSearch, ParsingFieldUpdateProcessorsTest, TestDefaultSearchFieldResource, TestSolrDeletionPolicy2, FastVectorHighlighterTest, TestSolrQueryParserDefaultOperatorResource, BasicDistributedZkTest, UnloadDistributedZkTest, CollectionsAPIDistributedZkTest, OpenCloseCoreStressTest, OverseerTest, ShardRoutingTest, RecoveryZkTest, ClusterStateUpdateTest, LeaderElectionTest, ZkSolrClientTest, TestZkChroot, TestRandomDVFaceting, ZkCLITest, ShardRoutingCustomTest, AutoCommitTest, TestDistributedGrouping, TestFaceting, TestHashPartitioner, DistributedSpellCheckComponentTest, TestJoin, TestMultiCoreConfBootstrap, TestReload, HardAutoCommitTest, TestCoreContainer, TestSolr4Spatial, SolrCoreTest, SpellCheckComponentTest, SolrCmdDistributorTest, QueryElevationComponentTest, ConvertedLegacyTest, TestFiltering, TestLazyCores, SoftAutoCommitTest, DistributedQueryElevationComponentTest, SimplePostToolTest, TestCoreDiscovery, SpellCheckCollatorTest, TestFoldingMultitermQuery, DocValuesTest, SuggesterTest, TestStressLucene, TestTrie, PolyFieldTest, TestWordDelimiterFilterFactory, QueryEqualityTest, DirectUpdateHandlerOptimizeTest, SortByFunctionTest, TestRemoteStreaming, TestSolrDeletionPolicy1, DebugComponentTest, IndexBasedSpellCheckerTest, TestSurroundQueryParser, DisMaxRequestHandlerTest, StandardRequestHandlerTest, DirectSolrSpellCheckerTest, DocumentAnalysisRequestHandlerTest, TestOmitPositions, FileBasedSpellCheckerTest, TermsComponentTest, XmlUpdateRequestHandlerTest, TestValueSourceCache, PathHierarchyTokenizerFactoryTest, TermVectorComponentTest, MoreLikeThisHandlerTest, FieldAnalysisRequestHandlerTest, TestSolrQueryParser, TestArbitraryIndexDir, RegexBoostProcessorTest, IndexSchemaTest, TestJmxIntegration, ReturnFieldsTest, UpdateRequestProcessorFactoryTest, TestAnalyzedSuggestions, BinaryUpdateRequestHandlerTest, PingRequestHandlerTest, CSVRequestHandlerTest, TestComponentsName, SearchHandlerTest, TestBinaryResponseWriter, HighlighterConfigTest, TestQuerySenderListener, UpdateParamsTest, AlternateDirectoryTest, TestSolrIndexConfig, TestQuerySenderNoQuery, CopyFieldTest, ResponseLogComponentTest, TestStressRecovery, MultiTermTest, TestSearchPerf, TestPostingsSolrHighlighter, DirectSolrConnectionTest, TestLuceneMatchVersion, TestPhraseSuggestions, TestCharFilters, EchoParamsTest, TestSweetSpotSimilarityFactory, TestLMDirichletSimilarityFactory, TestBM25SimilarityFactory, TestDefaultSimilarityFactory, LegacyHTMLStripCharFilterTest, TestJmxMonitoredMap, TimeZoneUtilsTest, TestFastOutputStream, ScriptEngineTest, OpenExchangeRatesOrgProviderTest, PluginInfoTest, ChaosMonkeyNothingIsSafeTest]
[junit4] Completed in 52.80s, 1 test, 1 failure <<< FAILURES!
[...truncated 218 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:420: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:400: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:39: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\extra-targets.xml:37: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:189: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:491: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1275: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:908: There were test failures: 354 suites, 1566 tests, 1 failure, 39 ignored (11 assumptions)
Total time: 87 minutes 46 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/jdk1.7.0_45 -server -XX:+UseG1GC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure