You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2014/01/04 09:56:58 UTC
[JENKINS] Lucene-Solr-Tests-trunk-Java7 - Build # 4565 - Failure
Build: https://builds.apache.org/job/Lucene-Solr-Tests-trunk-Java7/4565/
6 tests failed.
REGRESSION: org.apache.solr.analytics.expression.ExpressionTest.absoluteValueTest
Error Message:
Caught exception in getStatResult, xPath = /response/lst[@name='stats']/lst[@name='avr']/double[@name='sum'] raw data: <?xml version="1.0" encoding="UTF-8"?> <response> <lst name="responseHeader"> <int name="status">0</int> <int name="QTime">11</int> </lst> <result name="response" numFound="100" start="0"> </result> <lst name="stats"> <lst name="stats_fields"/> </lst> <lst name="stats"> <lst name="csr"> <str name="cs1">this is the first</str> <str name="cs2">this is the second</str> <str name="cs3">this is the third</str> </lst> <lst name="mr"> <long name="count">100</long> <null name="mcm"/> <null name="mean"/> <null name="median"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> <lst name="rr"> <str name="max">str9</str> <str name="min">str0</str> <str name="rmax">9rts</str> <str name="rmin">0rts</str> </lst> <lst name="dmr"> <str name="cma">+2MONTHS</str> <str name="cme">+2YEARS</str> <date name="dmma">1812-02-29T23:59:59Z</date> <date name="dmme">1807-12-31T23:59:59Z</date> <date name="max">1811-12-31T23:59:59Z</date> <date name="median">1805-12-31T23:59:59Z</date> </lst> <lst name="cr"> <str name="ccmax">this is the secondstr9</str> <str name="ccmin">this is the firststr0</str> <str name="csmax">this is the second</str> <str name="csmin">this is the first</str> <str name="max">str9</str> <str name="min">str0</str> </lst> <lst name="dr"> <long name="count">100</long> <null name="mc"/> <null name="mean"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> <lst name="ar"> <long name="count">100</long> <null name="mcm"/> <null name="mean"/> <null name="median"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> <lst name="avr"> <double name="c">100.0</double> <long name="count">100</long> <null name="s"/> <null name="sum"/> </lst> <lst name="cnr"> <double name="c10">10.0</double> <double name="c8">8.0</double> </lst> <lst name="nr"> <double name="c">-100.0</double> <long name="count">100</long> <null name="s"/> <null name="sum"/> </lst> <lst name="cdr"> <date name="cd1">1800-12-31T23:59:59Z</date> <date name="cd2">1804-06-30T23:59:59Z</date> <str name="cs1">1800-12-31T23:59:59Z</str> <str name="cs2">1804-06-30T23:59:59Z</str> </lst> <lst name="pr"> <long name="count">100</long> <null name="mc"/> <null name="mean"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> </lst> </response>
Stack Trace:
java.lang.AssertionError: Caught exception in getStatResult, xPath = /response/lst[@name='stats']/lst[@name='avr']/double[@name='sum']
raw data: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader">
<int name="status">0</int>
<int name="QTime">11</int>
</lst>
<result name="response" numFound="100" start="0">
</result>
<lst name="stats">
<lst name="stats_fields"/>
</lst>
<lst name="stats">
<lst name="csr">
<str name="cs1">this is the first</str>
<str name="cs2">this is the second</str>
<str name="cs3">this is the third</str>
</lst>
<lst name="mr">
<long name="count">100</long>
<null name="mcm"/>
<null name="mean"/>
<null name="median"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
<lst name="rr">
<str name="max">str9</str>
<str name="min">str0</str>
<str name="rmax">9rts</str>
<str name="rmin">0rts</str>
</lst>
<lst name="dmr">
<str name="cma">+2MONTHS</str>
<str name="cme">+2YEARS</str>
<date name="dmma">1812-02-29T23:59:59Z</date>
<date name="dmme">1807-12-31T23:59:59Z</date>
<date name="max">1811-12-31T23:59:59Z</date>
<date name="median">1805-12-31T23:59:59Z</date>
</lst>
<lst name="cr">
<str name="ccmax">this is the secondstr9</str>
<str name="ccmin">this is the firststr0</str>
<str name="csmax">this is the second</str>
<str name="csmin">this is the first</str>
<str name="max">str9</str>
<str name="min">str0</str>
</lst>
<lst name="dr">
<long name="count">100</long>
<null name="mc"/>
<null name="mean"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
<lst name="ar">
<long name="count">100</long>
<null name="mcm"/>
<null name="mean"/>
<null name="median"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
<lst name="avr">
<double name="c">100.0</double>
<long name="count">100</long>
<null name="s"/>
<null name="sum"/>
</lst>
<lst name="cnr">
<double name="c10">10.0</double>
<double name="c8">8.0</double>
</lst>
<lst name="nr">
<double name="c">-100.0</double>
<long name="count">100</long>
<null name="s"/>
<null name="sum"/>
</lst>
<lst name="cdr">
<date name="cd1">1800-12-31T23:59:59Z</date>
<date name="cd2">1804-06-30T23:59:59Z</date>
<str name="cs1">1800-12-31T23:59:59Z</str>
<str name="cs2">1804-06-30T23:59:59Z</str>
</lst>
<lst name="pr">
<long name="count">100</long>
<null name="mc"/>
<null name="mean"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
</lst>
</response>
at __randomizedtesting.SeedInfo.seed([DE934D7117FF594E:FF3F6D64DA620128]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.analytics.AbstractAnalyticsStatsTest.getStatResult(AbstractAnalyticsStatsTest.java:113)
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:724)
REGRESSION: org.apache.solr.analytics.expression.ExpressionTest.multiplyTest
Error Message:
Caught exception in getStatResult, xPath = /response/lst[@name='stats']/lst[@name='mr']/double[@name='sum'] raw data: <?xml version="1.0" encoding="UTF-8"?> <response> <lst name="responseHeader"> <int name="status">0</int> <int name="QTime">11</int> </lst> <result name="response" numFound="100" start="0"> </result> <lst name="stats"> <lst name="stats_fields"/> </lst> <lst name="stats"> <lst name="csr"> <str name="cs1">this is the first</str> <str name="cs2">this is the second</str> <str name="cs3">this is the third</str> </lst> <lst name="mr"> <long name="count">100</long> <null name="mcm"/> <null name="mean"/> <null name="median"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> <lst name="rr"> <str name="max">str9</str> <str name="min">str0</str> <str name="rmax">9rts</str> <str name="rmin">0rts</str> </lst> <lst name="dmr"> <str name="cma">+2MONTHS</str> <str name="cme">+2YEARS</str> <date name="dmma">1812-02-29T23:59:59Z</date> <date name="dmme">1807-12-31T23:59:59Z</date> <date name="max">1811-12-31T23:59:59Z</date> <date name="median">1805-12-31T23:59:59Z</date> </lst> <lst name="cr"> <str name="ccmax">this is the secondstr9</str> <str name="ccmin">this is the firststr0</str> <str name="csmax">this is the second</str> <str name="csmin">this is the first</str> <str name="max">str9</str> <str name="min">str0</str> </lst> <lst name="dr"> <long name="count">100</long> <null name="mc"/> <null name="mean"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> <lst name="ar"> <long name="count">100</long> <null name="mcm"/> <null name="mean"/> <null name="median"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> <lst name="avr"> <double name="c">100.0</double> <long name="count">100</long> <null name="s"/> <null name="sum"/> </lst> <lst name="cnr"> <double name="c10">10.0</double> <double name="c8">8.0</double> </lst> <lst name="nr"> <double name="c">-100.0</double> <long name="count">100</long> <null name="s"/> <null name="sum"/> </lst> <lst name="cdr"> <date name="cd1">1800-12-31T23:59:59Z</date> <date name="cd2">1804-06-30T23:59:59Z</date> <str name="cs1">1800-12-31T23:59:59Z</str> <str name="cs2">1804-06-30T23:59:59Z</str> </lst> <lst name="pr"> <long name="count">100</long> <null name="mc"/> <null name="mean"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> </lst> </response>
Stack Trace:
java.lang.AssertionError: Caught exception in getStatResult, xPath = /response/lst[@name='stats']/lst[@name='mr']/double[@name='sum']
raw data: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader">
<int name="status">0</int>
<int name="QTime">11</int>
</lst>
<result name="response" numFound="100" start="0">
</result>
<lst name="stats">
<lst name="stats_fields"/>
</lst>
<lst name="stats">
<lst name="csr">
<str name="cs1">this is the first</str>
<str name="cs2">this is the second</str>
<str name="cs3">this is the third</str>
</lst>
<lst name="mr">
<long name="count">100</long>
<null name="mcm"/>
<null name="mean"/>
<null name="median"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
<lst name="rr">
<str name="max">str9</str>
<str name="min">str0</str>
<str name="rmax">9rts</str>
<str name="rmin">0rts</str>
</lst>
<lst name="dmr">
<str name="cma">+2MONTHS</str>
<str name="cme">+2YEARS</str>
<date name="dmma">1812-02-29T23:59:59Z</date>
<date name="dmme">1807-12-31T23:59:59Z</date>
<date name="max">1811-12-31T23:59:59Z</date>
<date name="median">1805-12-31T23:59:59Z</date>
</lst>
<lst name="cr">
<str name="ccmax">this is the secondstr9</str>
<str name="ccmin">this is the firststr0</str>
<str name="csmax">this is the second</str>
<str name="csmin">this is the first</str>
<str name="max">str9</str>
<str name="min">str0</str>
</lst>
<lst name="dr">
<long name="count">100</long>
<null name="mc"/>
<null name="mean"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
<lst name="ar">
<long name="count">100</long>
<null name="mcm"/>
<null name="mean"/>
<null name="median"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
<lst name="avr">
<double name="c">100.0</double>
<long name="count">100</long>
<null name="s"/>
<null name="sum"/>
</lst>
<lst name="cnr">
<double name="c10">10.0</double>
<double name="c8">8.0</double>
</lst>
<lst name="nr">
<double name="c">-100.0</double>
<long name="count">100</long>
<null name="s"/>
<null name="sum"/>
</lst>
<lst name="cdr">
<date name="cd1">1800-12-31T23:59:59Z</date>
<date name="cd2">1804-06-30T23:59:59Z</date>
<str name="cs1">1800-12-31T23:59:59Z</str>
<str name="cs2">1804-06-30T23:59:59Z</str>
</lst>
<lst name="pr">
<long name="count">100</long>
<null name="mc"/>
<null name="mean"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
</lst>
</response>
at __randomizedtesting.SeedInfo.seed([DE934D7117FF594E:B512530F22F16F20]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.analytics.AbstractAnalyticsStatsTest.getStatResult(AbstractAnalyticsStatsTest.java:113)
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:724)
REGRESSION: org.apache.solr.analytics.expression.ExpressionTest.negateTest
Error Message:
Caught exception in getStatResult, xPath = /response/lst[@name='stats']/lst[@name='nr']/double[@name='sum'] raw data: <?xml version="1.0" encoding="UTF-8"?> <response> <lst name="responseHeader"> <int name="status">0</int> <int name="QTime">11</int> </lst> <result name="response" numFound="100" start="0"> </result> <lst name="stats"> <lst name="stats_fields"/> </lst> <lst name="stats"> <lst name="csr"> <str name="cs1">this is the first</str> <str name="cs2">this is the second</str> <str name="cs3">this is the third</str> </lst> <lst name="mr"> <long name="count">100</long> <null name="mcm"/> <null name="mean"/> <null name="median"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> <lst name="rr"> <str name="max">str9</str> <str name="min">str0</str> <str name="rmax">9rts</str> <str name="rmin">0rts</str> </lst> <lst name="dmr"> <str name="cma">+2MONTHS</str> <str name="cme">+2YEARS</str> <date name="dmma">1812-02-29T23:59:59Z</date> <date name="dmme">1807-12-31T23:59:59Z</date> <date name="max">1811-12-31T23:59:59Z</date> <date name="median">1805-12-31T23:59:59Z</date> </lst> <lst name="cr"> <str name="ccmax">this is the secondstr9</str> <str name="ccmin">this is the firststr0</str> <str name="csmax">this is the second</str> <str name="csmin">this is the first</str> <str name="max">str9</str> <str name="min">str0</str> </lst> <lst name="dr"> <long name="count">100</long> <null name="mc"/> <null name="mean"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> <lst name="ar"> <long name="count">100</long> <null name="mcm"/> <null name="mean"/> <null name="median"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> <lst name="avr"> <double name="c">100.0</double> <long name="count">100</long> <null name="s"/> <null name="sum"/> </lst> <lst name="cnr"> <double name="c10">10.0</double> <double name="c8">8.0</double> </lst> <lst name="nr"> <double name="c">-100.0</double> <long name="count">100</long> <null name="s"/> <null name="sum"/> </lst> <lst name="cdr"> <date name="cd1">1800-12-31T23:59:59Z</date> <date name="cd2">1804-06-30T23:59:59Z</date> <str name="cs1">1800-12-31T23:59:59Z</str> <str name="cs2">1804-06-30T23:59:59Z</str> </lst> <lst name="pr"> <long name="count">100</long> <null name="mc"/> <null name="mean"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> </lst> </response>
Stack Trace:
java.lang.AssertionError: Caught exception in getStatResult, xPath = /response/lst[@name='stats']/lst[@name='nr']/double[@name='sum']
raw data: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader">
<int name="status">0</int>
<int name="QTime">11</int>
</lst>
<result name="response" numFound="100" start="0">
</result>
<lst name="stats">
<lst name="stats_fields"/>
</lst>
<lst name="stats">
<lst name="csr">
<str name="cs1">this is the first</str>
<str name="cs2">this is the second</str>
<str name="cs3">this is the third</str>
</lst>
<lst name="mr">
<long name="count">100</long>
<null name="mcm"/>
<null name="mean"/>
<null name="median"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
<lst name="rr">
<str name="max">str9</str>
<str name="min">str0</str>
<str name="rmax">9rts</str>
<str name="rmin">0rts</str>
</lst>
<lst name="dmr">
<str name="cma">+2MONTHS</str>
<str name="cme">+2YEARS</str>
<date name="dmma">1812-02-29T23:59:59Z</date>
<date name="dmme">1807-12-31T23:59:59Z</date>
<date name="max">1811-12-31T23:59:59Z</date>
<date name="median">1805-12-31T23:59:59Z</date>
</lst>
<lst name="cr">
<str name="ccmax">this is the secondstr9</str>
<str name="ccmin">this is the firststr0</str>
<str name="csmax">this is the second</str>
<str name="csmin">this is the first</str>
<str name="max">str9</str>
<str name="min">str0</str>
</lst>
<lst name="dr">
<long name="count">100</long>
<null name="mc"/>
<null name="mean"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
<lst name="ar">
<long name="count">100</long>
<null name="mcm"/>
<null name="mean"/>
<null name="median"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
<lst name="avr">
<double name="c">100.0</double>
<long name="count">100</long>
<null name="s"/>
<null name="sum"/>
</lst>
<lst name="cnr">
<double name="c10">10.0</double>
<double name="c8">8.0</double>
</lst>
<lst name="nr">
<double name="c">-100.0</double>
<long name="count">100</long>
<null name="s"/>
<null name="sum"/>
</lst>
<lst name="cdr">
<date name="cd1">1800-12-31T23:59:59Z</date>
<date name="cd2">1804-06-30T23:59:59Z</date>
<str name="cs1">1800-12-31T23:59:59Z</str>
<str name="cs2">1804-06-30T23:59:59Z</str>
</lst>
<lst name="pr">
<long name="count">100</long>
<null name="mc"/>
<null name="mean"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
</lst>
</response>
at __randomizedtesting.SeedInfo.seed([DE934D7117FF594E:D69B25D4B3D209FE]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.analytics.AbstractAnalyticsStatsTest.getStatResult(AbstractAnalyticsStatsTest.java:113)
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:724)
REGRESSION: org.apache.solr.analytics.expression.ExpressionTest.powerTest
Error Message:
Caught exception in getStatResult, xPath = /response/lst[@name='stats']/lst[@name='pr']/double[@name='sum'] raw data: <?xml version="1.0" encoding="UTF-8"?> <response> <lst name="responseHeader"> <int name="status">0</int> <int name="QTime">11</int> </lst> <result name="response" numFound="100" start="0"> </result> <lst name="stats"> <lst name="stats_fields"/> </lst> <lst name="stats"> <lst name="csr"> <str name="cs1">this is the first</str> <str name="cs2">this is the second</str> <str name="cs3">this is the third</str> </lst> <lst name="mr"> <long name="count">100</long> <null name="mcm"/> <null name="mean"/> <null name="median"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> <lst name="rr"> <str name="max">str9</str> <str name="min">str0</str> <str name="rmax">9rts</str> <str name="rmin">0rts</str> </lst> <lst name="dmr"> <str name="cma">+2MONTHS</str> <str name="cme">+2YEARS</str> <date name="dmma">1812-02-29T23:59:59Z</date> <date name="dmme">1807-12-31T23:59:59Z</date> <date name="max">1811-12-31T23:59:59Z</date> <date name="median">1805-12-31T23:59:59Z</date> </lst> <lst name="cr"> <str name="ccmax">this is the secondstr9</str> <str name="ccmin">this is the firststr0</str> <str name="csmax">this is the second</str> <str name="csmin">this is the first</str> <str name="max">str9</str> <str name="min">str0</str> </lst> <lst name="dr"> <long name="count">100</long> <null name="mc"/> <null name="mean"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> <lst name="ar"> <long name="count">100</long> <null name="mcm"/> <null name="mean"/> <null name="median"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> <lst name="avr"> <double name="c">100.0</double> <long name="count">100</long> <null name="s"/> <null name="sum"/> </lst> <lst name="cnr"> <double name="c10">10.0</double> <double name="c8">8.0</double> </lst> <lst name="nr"> <double name="c">-100.0</double> <long name="count">100</long> <null name="s"/> <null name="sum"/> </lst> <lst name="cdr"> <date name="cd1">1800-12-31T23:59:59Z</date> <date name="cd2">1804-06-30T23:59:59Z</date> <str name="cs1">1800-12-31T23:59:59Z</str> <str name="cs2">1804-06-30T23:59:59Z</str> </lst> <lst name="pr"> <long name="count">100</long> <null name="mc"/> <null name="mean"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> </lst> </response>
Stack Trace:
java.lang.AssertionError: Caught exception in getStatResult, xPath = /response/lst[@name='stats']/lst[@name='pr']/double[@name='sum']
raw data: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader">
<int name="status">0</int>
<int name="QTime">11</int>
</lst>
<result name="response" numFound="100" start="0">
</result>
<lst name="stats">
<lst name="stats_fields"/>
</lst>
<lst name="stats">
<lst name="csr">
<str name="cs1">this is the first</str>
<str name="cs2">this is the second</str>
<str name="cs3">this is the third</str>
</lst>
<lst name="mr">
<long name="count">100</long>
<null name="mcm"/>
<null name="mean"/>
<null name="median"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
<lst name="rr">
<str name="max">str9</str>
<str name="min">str0</str>
<str name="rmax">9rts</str>
<str name="rmin">0rts</str>
</lst>
<lst name="dmr">
<str name="cma">+2MONTHS</str>
<str name="cme">+2YEARS</str>
<date name="dmma">1812-02-29T23:59:59Z</date>
<date name="dmme">1807-12-31T23:59:59Z</date>
<date name="max">1811-12-31T23:59:59Z</date>
<date name="median">1805-12-31T23:59:59Z</date>
</lst>
<lst name="cr">
<str name="ccmax">this is the secondstr9</str>
<str name="ccmin">this is the firststr0</str>
<str name="csmax">this is the second</str>
<str name="csmin">this is the first</str>
<str name="max">str9</str>
<str name="min">str0</str>
</lst>
<lst name="dr">
<long name="count">100</long>
<null name="mc"/>
<null name="mean"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
<lst name="ar">
<long name="count">100</long>
<null name="mcm"/>
<null name="mean"/>
<null name="median"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
<lst name="avr">
<double name="c">100.0</double>
<long name="count">100</long>
<null name="s"/>
<null name="sum"/>
</lst>
<lst name="cnr">
<double name="c10">10.0</double>
<double name="c8">8.0</double>
</lst>
<lst name="nr">
<double name="c">-100.0</double>
<long name="count">100</long>
<null name="s"/>
<null name="sum"/>
</lst>
<lst name="cdr">
<date name="cd1">1800-12-31T23:59:59Z</date>
<date name="cd2">1804-06-30T23:59:59Z</date>
<str name="cs1">1800-12-31T23:59:59Z</str>
<str name="cs2">1804-06-30T23:59:59Z</str>
</lst>
<lst name="pr">
<long name="count">100</long>
<null name="mc"/>
<null name="mean"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
</lst>
</response>
at __randomizedtesting.SeedInfo.seed([DE934D7117FF594E:B021FA72FFE8079F]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.analytics.AbstractAnalyticsStatsTest.getStatResult(AbstractAnalyticsStatsTest.java:113)
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:724)
REGRESSION: org.apache.solr.analytics.expression.ExpressionTest.addTest
Error Message:
Caught exception in getStatResult, xPath = /response/lst[@name='stats']/lst[@name='ar']/double[@name='sum'] raw data: <?xml version="1.0" encoding="UTF-8"?> <response> <lst name="responseHeader"> <int name="status">0</int> <int name="QTime">11</int> </lst> <result name="response" numFound="100" start="0"> </result> <lst name="stats"> <lst name="stats_fields"/> </lst> <lst name="stats"> <lst name="csr"> <str name="cs1">this is the first</str> <str name="cs2">this is the second</str> <str name="cs3">this is the third</str> </lst> <lst name="mr"> <long name="count">100</long> <null name="mcm"/> <null name="mean"/> <null name="median"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> <lst name="rr"> <str name="max">str9</str> <str name="min">str0</str> <str name="rmax">9rts</str> <str name="rmin">0rts</str> </lst> <lst name="dmr"> <str name="cma">+2MONTHS</str> <str name="cme">+2YEARS</str> <date name="dmma">1812-02-29T23:59:59Z</date> <date name="dmme">1807-12-31T23:59:59Z</date> <date name="max">1811-12-31T23:59:59Z</date> <date name="median">1805-12-31T23:59:59Z</date> </lst> <lst name="cr"> <str name="ccmax">this is the secondstr9</str> <str name="ccmin">this is the firststr0</str> <str name="csmax">this is the second</str> <str name="csmin">this is the first</str> <str name="max">str9</str> <str name="min">str0</str> </lst> <lst name="dr"> <long name="count">100</long> <null name="mc"/> <null name="mean"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> <lst name="ar"> <long name="count">100</long> <null name="mcm"/> <null name="mean"/> <null name="median"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> <lst name="avr"> <double name="c">100.0</double> <long name="count">100</long> <null name="s"/> <null name="sum"/> </lst> <lst name="cnr"> <double name="c10">10.0</double> <double name="c8">8.0</double> </lst> <lst name="nr"> <double name="c">-100.0</double> <long name="count">100</long> <null name="s"/> <null name="sum"/> </lst> <lst name="cdr"> <date name="cd1">1800-12-31T23:59:59Z</date> <date name="cd2">1804-06-30T23:59:59Z</date> <str name="cs1">1800-12-31T23:59:59Z</str> <str name="cs2">1804-06-30T23:59:59Z</str> </lst> <lst name="pr"> <long name="count">100</long> <null name="mc"/> <null name="mean"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> </lst> </response>
Stack Trace:
java.lang.AssertionError: Caught exception in getStatResult, xPath = /response/lst[@name='stats']/lst[@name='ar']/double[@name='sum']
raw data: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader">
<int name="status">0</int>
<int name="QTime">11</int>
</lst>
<result name="response" numFound="100" start="0">
</result>
<lst name="stats">
<lst name="stats_fields"/>
</lst>
<lst name="stats">
<lst name="csr">
<str name="cs1">this is the first</str>
<str name="cs2">this is the second</str>
<str name="cs3">this is the third</str>
</lst>
<lst name="mr">
<long name="count">100</long>
<null name="mcm"/>
<null name="mean"/>
<null name="median"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
<lst name="rr">
<str name="max">str9</str>
<str name="min">str0</str>
<str name="rmax">9rts</str>
<str name="rmin">0rts</str>
</lst>
<lst name="dmr">
<str name="cma">+2MONTHS</str>
<str name="cme">+2YEARS</str>
<date name="dmma">1812-02-29T23:59:59Z</date>
<date name="dmme">1807-12-31T23:59:59Z</date>
<date name="max">1811-12-31T23:59:59Z</date>
<date name="median">1805-12-31T23:59:59Z</date>
</lst>
<lst name="cr">
<str name="ccmax">this is the secondstr9</str>
<str name="ccmin">this is the firststr0</str>
<str name="csmax">this is the second</str>
<str name="csmin">this is the first</str>
<str name="max">str9</str>
<str name="min">str0</str>
</lst>
<lst name="dr">
<long name="count">100</long>
<null name="mc"/>
<null name="mean"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
<lst name="ar">
<long name="count">100</long>
<null name="mcm"/>
<null name="mean"/>
<null name="median"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
<lst name="avr">
<double name="c">100.0</double>
<long name="count">100</long>
<null name="s"/>
<null name="sum"/>
</lst>
<lst name="cnr">
<double name="c10">10.0</double>
<double name="c8">8.0</double>
</lst>
<lst name="nr">
<double name="c">-100.0</double>
<long name="count">100</long>
<null name="s"/>
<null name="sum"/>
</lst>
<lst name="cdr">
<date name="cd1">1800-12-31T23:59:59Z</date>
<date name="cd2">1804-06-30T23:59:59Z</date>
<str name="cs1">1800-12-31T23:59:59Z</str>
<str name="cs2">1804-06-30T23:59:59Z</str>
</lst>
<lst name="pr">
<long name="count">100</long>
<null name="mc"/>
<null name="mean"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
</lst>
</response>
at __randomizedtesting.SeedInfo.seed([DE934D7117FF594E:28BFE0AF56305E09]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.analytics.AbstractAnalyticsStatsTest.getStatResult(AbstractAnalyticsStatsTest.java:113)
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:724)
REGRESSION: org.apache.solr.analytics.expression.ExpressionTest.divideTest
Error Message:
Caught exception in getStatResult, xPath = /response/lst[@name='stats']/lst[@name='dr']/double[@name='sum'] raw data: <?xml version="1.0" encoding="UTF-8"?> <response> <lst name="responseHeader"> <int name="status">0</int> <int name="QTime">11</int> </lst> <result name="response" numFound="100" start="0"> </result> <lst name="stats"> <lst name="stats_fields"/> </lst> <lst name="stats"> <lst name="csr"> <str name="cs1">this is the first</str> <str name="cs2">this is the second</str> <str name="cs3">this is the third</str> </lst> <lst name="mr"> <long name="count">100</long> <null name="mcm"/> <null name="mean"/> <null name="median"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> <lst name="rr"> <str name="max">str9</str> <str name="min">str0</str> <str name="rmax">9rts</str> <str name="rmin">0rts</str> </lst> <lst name="dmr"> <str name="cma">+2MONTHS</str> <str name="cme">+2YEARS</str> <date name="dmma">1812-02-29T23:59:59Z</date> <date name="dmme">1807-12-31T23:59:59Z</date> <date name="max">1811-12-31T23:59:59Z</date> <date name="median">1805-12-31T23:59:59Z</date> </lst> <lst name="cr"> <str name="ccmax">this is the secondstr9</str> <str name="ccmin">this is the firststr0</str> <str name="csmax">this is the second</str> <str name="csmin">this is the first</str> <str name="max">str9</str> <str name="min">str0</str> </lst> <lst name="dr"> <long name="count">100</long> <null name="mc"/> <null name="mean"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> <lst name="ar"> <long name="count">100</long> <null name="mcm"/> <null name="mean"/> <null name="median"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> <lst name="avr"> <double name="c">100.0</double> <long name="count">100</long> <null name="s"/> <null name="sum"/> </lst> <lst name="cnr"> <double name="c10">10.0</double> <double name="c8">8.0</double> </lst> <lst name="nr"> <double name="c">-100.0</double> <long name="count">100</long> <null name="s"/> <null name="sum"/> </lst> <lst name="cdr"> <date name="cd1">1800-12-31T23:59:59Z</date> <date name="cd2">1804-06-30T23:59:59Z</date> <str name="cs1">1800-12-31T23:59:59Z</str> <str name="cs2">1804-06-30T23:59:59Z</str> </lst> <lst name="pr"> <long name="count">100</long> <null name="mc"/> <null name="mean"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> </lst> </response>
Stack Trace:
java.lang.AssertionError: Caught exception in getStatResult, xPath = /response/lst[@name='stats']/lst[@name='dr']/double[@name='sum']
raw data: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader">
<int name="status">0</int>
<int name="QTime">11</int>
</lst>
<result name="response" numFound="100" start="0">
</result>
<lst name="stats">
<lst name="stats_fields"/>
</lst>
<lst name="stats">
<lst name="csr">
<str name="cs1">this is the first</str>
<str name="cs2">this is the second</str>
<str name="cs3">this is the third</str>
</lst>
<lst name="mr">
<long name="count">100</long>
<null name="mcm"/>
<null name="mean"/>
<null name="median"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
<lst name="rr">
<str name="max">str9</str>
<str name="min">str0</str>
<str name="rmax">9rts</str>
<str name="rmin">0rts</str>
</lst>
<lst name="dmr">
<str name="cma">+2MONTHS</str>
<str name="cme">+2YEARS</str>
<date name="dmma">1812-02-29T23:59:59Z</date>
<date name="dmme">1807-12-31T23:59:59Z</date>
<date name="max">1811-12-31T23:59:59Z</date>
<date name="median">1805-12-31T23:59:59Z</date>
</lst>
<lst name="cr">
<str name="ccmax">this is the secondstr9</str>
<str name="ccmin">this is the firststr0</str>
<str name="csmax">this is the second</str>
<str name="csmin">this is the first</str>
<str name="max">str9</str>
<str name="min">str0</str>
</lst>
<lst name="dr">
<long name="count">100</long>
<null name="mc"/>
<null name="mean"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
<lst name="ar">
<long name="count">100</long>
<null name="mcm"/>
<null name="mean"/>
<null name="median"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
<lst name="avr">
<double name="c">100.0</double>
<long name="count">100</long>
<null name="s"/>
<null name="sum"/>
</lst>
<lst name="cnr">
<double name="c10">10.0</double>
<double name="c8">8.0</double>
</lst>
<lst name="nr">
<double name="c">-100.0</double>
<long name="count">100</long>
<null name="s"/>
<null name="sum"/>
</lst>
<lst name="cdr">
<date name="cd1">1800-12-31T23:59:59Z</date>
<date name="cd2">1804-06-30T23:59:59Z</date>
<str name="cs1">1800-12-31T23:59:59Z</str>
<str name="cs2">1804-06-30T23:59:59Z</str>
</lst>
<lst name="pr">
<long name="count">100</long>
<null name="mc"/>
<null name="mean"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
</lst>
</response>
at __randomizedtesting.SeedInfo.seed([DE934D7117FF594E:869EA687333D3B98]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.analytics.AbstractAnalyticsStatsTest.getStatResult(AbstractAnalyticsStatsTest.java:113)
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:724)
Build Log:
[...truncated 10976 lines...]
[junit4] Suite: org.apache.solr.analytics.expression.ExpressionTest
[junit4] 2> 2147150 T4092 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> Creating dataDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/./solrtest-ExpressionTest-1388825658503
[junit4] 2> 2147152 T4092 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test-files/solr/collection1/'
[junit4] 2> 2147153 T4092 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 2147154 T4092 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 2147320 T4092 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 2147378 T4092 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-basic.xml
[junit4] 2> 2147379 T4092 oass.IndexSchema.readSchema Reading Solr Schema from schema-analytics.xml
[junit4] 2> 2147387 T4092 oass.IndexSchema.readSchema [null] Schema name=schema-docValues
[junit4] 2> 2147443 T4092 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2147445 T4092 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2147446 T4092 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test-files/solr
[junit4] 2> 2147446 T4092 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test-files/solr/'
[junit4] 2> 2147466 T4092 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2147467 T4092 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test-files/solr
[junit4] 2> 2147467 T4092 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test-files/solr/'
[junit4] 2> 2147552 T4092 oasc.CoreContainer.<init> New CoreContainer 229699263
[junit4] 2> 2147553 T4092 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test-files/solr/]
[junit4] 2> 2147555 T4092 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 2147555 T4092 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2147556 T4092 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 2147556 T4092 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2147556 T4092 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2147557 T4092 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2147557 T4092 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2147557 T4092 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2147558 T4092 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2147570 T4092 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2147570 T4092 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2147571 T4092 oasc.CoreContainer.load Host Name:
[junit4] 2> 2147577 T4093 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test-files/solr/collection1
[junit4] 2> 2147577 T4093 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test-files/solr/collection1/'
[junit4] 2> 2147579 T4093 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 2147579 T4093 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 2147637 T4093 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 2147695 T4093 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-basic.xml
[junit4] 2> 2147696 T4093 oass.IndexSchema.readSchema Reading Solr Schema from schema-analytics.xml
[junit4] 2> 2147704 T4093 oass.IndexSchema.readSchema [collection1] Schema name=schema-docValues
[junit4] 2> 2147756 T4093 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2147758 T4093 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2147758 T4093 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test-files/solr/collection1/, dataDir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/./solrtest-ExpressionTest-1388825658503/
[junit4] 2> 2147759 T4093 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 2147759 T4093 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/./solrtest-ExpressionTest-1388825658503
[junit4] 2> 2147760 T4093 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/./solrtest-ExpressionTest-1388825658503/index/
[junit4] 2> 2147760 T4093 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/./solrtest-ExpressionTest-1388825658503/index' doesn't exist. Creating new index...
[junit4] 2> 2147761 T4093 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/./solrtest-ExpressionTest-1388825658503/index
[junit4] 2> 2147761 T4093 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=40, maxMergedSegmentMB=25.134765625, floorSegmentMB=1.2451171875, forceMergeDeletesPctAllowed=9.435796576698397, segmentsPerTier=15.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8645835467698073
[junit4] 2> 2147762 T4093 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@6db2e91e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@689d5b30),segFN=segments_1,generation=1}
[junit4] 2> 2147763 T4093 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2147765 T4093 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2147765 T4093 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2147766 T4093 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2147782 T4093 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2147786 T4093 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2147786 T4093 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2147786 T4093 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=21, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8816394258643254]
[junit4] 2> 2147787 T4093 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@6db2e91e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@689d5b30),segFN=segments_1,generation=1}
[junit4] 2> 2147787 T4093 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2147788 T4093 oass.SolrIndexSearcher.<init> Opening Searcher@307248fc main
[junit4] 2> 2147788 T4094 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@307248fc main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2147788 T4093 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2147791 T4092 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> ASYNC NEW_CORE C1304 name=collection1 org.apache.solr.core.SolrCore@2844bf50
[junit4] 2> 2147798 T4092 C1304 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 2147799 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 2147808 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10000]} 0 2
[junit4] 2> 2147819 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10001]} 0 0
[junit4] 2> 2147829 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10002]} 0 1
[junit4] 2> 2147838 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10003]} 0 1
[junit4] 2> 2147847 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10004]} 0 1
[junit4] 2> 2147856 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10005]} 0 0
[junit4] 2> 2147866 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10006]} 0 1
[junit4] 2> 2147875 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10007]} 0 1
[junit4] 2> 2147884 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10008]} 0 1
[junit4] 2> 2147893 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10009]} 0 1
[junit4] 2> 2147902 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100010]} 0 0
[junit4] 2> 2147912 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100011]} 0 1
[junit4] 2> 2147921 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100012]} 0 1
[junit4] 2> 2147932 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100013]} 0 1
[junit4] 2> 2147944 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100014]} 0 1
[junit4] 2> 2147954 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100015]} 0 1
[junit4] 2> 2147964 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100016]} 0 1
[junit4] 2> 2147974 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100017]} 0 1
[junit4] 2> 2147983 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100018]} 0 0
[junit4] 2> 2147993 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100019]} 0 1
[junit4] 2> 2148002 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100020]} 0 1
[junit4] 2> 2148011 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100021]} 0 1
[junit4] 2> 2148020 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100022]} 0 1
[junit4] 2> 2148029 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100023]} 0 0
[junit4] 2> 2148039 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100024]} 0 1
[junit4] 2> 2148048 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100025]} 0 1
[junit4] 2> 2148057 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100026]} 0 0
[junit4] 2> 2148067 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100027]} 0 1
[junit4] 2> 2148076 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100028]} 0 1
[junit4] 2> 2148085 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100029]} 0 1
[junit4] 2> 2148094 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100030]} 0 1
[junit4] 2> 2148103 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100031]} 0 0
[junit4] 2> 2148112 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100032]} 0 0
[junit4] 2> 2148122 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100033]} 0 1
[junit4] 2> 2148131 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100034]} 0 1
[junit4] 2> 2148140 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100035]} 0 0
[junit4] 2> 2148149 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100036]} 0 0
[junit4] 2> 2148159 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100037]} 0 1
[junit4] 2> 2148168 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100038]} 0 1
[junit4] 2> 2148177 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100039]} 0 1
[junit4] 2> 2148186 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100040]} 0 1
[junit4] 2> 2148195 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100041]} 0 0
[junit4] 2> 2148204 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100042]} 0 0
[junit4] 2> 2148214 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100043]} 0 1
[junit4] 2> 2148223 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100044]} 0 1
[junit4] 2> 2148232 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100045]} 0 1
[junit4] 2> 2148241 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100046]} 0 1
[junit4] 2> 2148251 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100047]} 0 1
[junit4] 2> 2148260 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100048]} 0 1
[junit4] 2> 2148269 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100049]} 0 0
[junit4] 2> 2148278 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100050]} 0 0
[junit4] 2> 2148288 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100051]} 0 1
[junit4] 2> 2148297 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100052]} 0 1
[junit4] 2> 2148306 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100053]} 0 1
[junit4] 2> 2148316 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100054]} 0 1
[junit4] 2> 2148325 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100055]} 0 1
[junit4] 2> 2148334 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100056]} 0 0
[junit4] 2> 2148344 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100057]} 0 1
[junit4] 2> 2148353 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100058]} 0 1
[junit4] 2> 2148362 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100059]} 0 1
[junit4] 2> 2148371 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100060]} 0 1
[junit4] 2> 2148380 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100061]} 0 0
[junit4] 2> 2148389 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100062]} 0 0
[junit4] 2> 2148399 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100063]} 0 1
[junit4] 2> 2148409 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100064]} 0 1
[junit4] 2> 2148418 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100065]} 0 1
[junit4] 2> 2148427 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100066]} 0 1
[junit4] 2> 2148436 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100067]} 0 1
[junit4] 2> 2148445 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100068]} 0 0
[junit4] 2> 2148454 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100069]} 0 0
[junit4] 2> 2148464 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100070]} 0 1
[junit4] 2> 2148473 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100071]} 0 1
[junit4] 2> 2148482 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100072]} 0 1
[junit4] 2> 2148491 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100073]} 0 1
[junit4] 2> 2148500 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100074]} 0 1
[junit4] 2> 2148509 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100075]} 0 0
[junit4] 2> 2148518 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100076]} 0 0
[junit4] 2> 2148528 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100077]} 0 1
[junit4] 2> 2148537 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100078]} 0 1
[junit4] 2> 2148544 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100079]} 0 1
[junit4] 2> 2148550 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100080]} 0 1
[junit4] 2> 2148556 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100081]} 0 0
[junit4] 2> 2148562 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100082]} 0 0
[junit4] 2> 2148569 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100083]} 0 1
[junit4] 2> 2148575 T4092 C1304 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100084]} 0 1
[junit4] 2> ASYNC NEW_CORE C1305 name=collection1 org.apache.solr.core.SolrCore@2844bf50
[junit4] 2> 2148612 T4092 C1305 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100085]} 0 1
[junit4] 2> 2148619 T4092 C1305 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100086]} 0 1
[junit4] 2> 2148625 T4092 C1305 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100087]} 0 1
[junit4] 2> 2148631 T4092 C1305 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100088]} 0 0
[junit4] 2> 2148638 T4092 C1305 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100089]} 0 1
[junit4] 2> 2148644 T4092 C1305 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100090]} 0 0
[junit4] 2> 2148651 T4092 C1305 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100091]} 0 1
[junit4] 2> 2148657 T4092 C1305 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100092]} 0 0
[junit4] 2> 2148663 T4092 C1305 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100093]} 0 0
[junit4] 2> 2148670 T4092 C1305 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100094]} 0 1
[junit4] 2> 2148676 T4092 C1305 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100095]} 0 1
[junit4] 2> 2148682 T4092 C1305 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100096]} 0 0
[junit4] 2> 2148688 T4092 C1305 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100097]} 0 0
[junit4] 2> 2148694 T4092 C1305 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100098]} 0 0
[junit4] 2> 2148701 T4092 C1305 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100099]} 0 1
[junit4] 2> 2148706 T4092 C1305 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2148711 T4092 C1305 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@6db2e91e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@689d5b30),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@6db2e91e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@689d5b30),segFN=segments_2,generation=2}
[junit4] 2> 2148712 T4092 C1305 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 2148713 T4092 C1305 oass.SolrIndexSearcher.<init> Opening Searcher@68437b2a main
[junit4] 2> 2148714 T4092 C1305 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 2148714 T4094 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@68437b2a main{StandardDirectoryReader(segments_2:4:nrt _0(5.0):c100)}
[junit4] 2> 2148715 T4092 C1305 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 9
[junit4] 2> 2148731 T4092 C1305 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&o.mr.s.su=mult(sum(int_id),unique(long_ld))&o.mr.s.sum=sum(int_id)&o.pr.s.count=count(long_ld)&o.cr.s.ccmax=concat(const_str(this+is+the+second),max(string_sd))&o.ar.s.su=add(sum(int_id),unique(long_ld))&o.csr.s.cs3=const_str(this+is+the+third)&o.mr.s.mcm=mult(mean(int_id),count(long_ld),median(int_id))&o.pr.s.mean=mean(int_id)&o.ar.s.unique=unique(long_ld)&o.ar.s.count=count(long_ld)&o.cdr.s.cd1=const_date(1800-12-31T23:59:59Z)&o.dmr.s.cme=const_str(%2B2YEARS)&o.cdr.s.cs1=const_str(1800-12-31T23:59:59Z)&indent=true&o.cdr.s.cs2=const_str(1804-06-30T23:59:59Z)&o.mr.s.median=median(int_id)&o.mr.s.mean=mean(int_id)&o.rr.s.max=max(string_sd)&o.nr.s.s=neg(sum(int_id))&o.mr.s.count=count(long_ld)&o.cr.s.csmax=const_str(this+is+the+second)&o.nr.s.sum=sum(int_id)&o.mr.s.unique=unique(long_ld)&o.nr.s.c=neg(count(long_ld))&o.dr.s.sum=sum(int_id)&o.cr.s.csmin=const_str(this+is+the+first)&o.dr.s.mean=mean(int_id)&o.rr.s.min=min(string_sd)&o.rr.s.rmax=rev(max(string_sd))&o.avr.s.s=abs(neg(sum(int_id)))&o.cnr.s.c10=const_num(10)&o.pr.s.su=pow(sum(int_id),unique(long_ld))&o.avr.s.count=count(long_ld)&o.cr.s.max=max(string_sd)&o.ar.s.sum=sum(int_id)&o.avr.s.c=abs(neg(count(long_ld)))&o.pr.s.mc=pow(mean(int_id),count(long_ld))&o.rr.s.rmin=rev(min(string_sd))&o.csr.s.cs2=const_str(this+is+the+second)&o.dmr.s.max=max(date_dtd)&o.cnr.s.c8=const_num(8)&o.ar.s.mcm=add(mean(int_id),count(long_ld),median(int_id))&o.dr.s.count=count(long_ld)&o.pr.s.sum=sum(int_id)&o.cdr.s.cd2=const_date(1804-06-30T23:59:59Z)&o.nr.s.count=count(long_ld)&o.dr.s.unique=unique(long_ld)&o.dmr.s.median=median(date_dtd)&o.cr.s.ccmin=concat(const_str(this+is+the+first),min(string_sd))&o.ar.s.mean=mean(int_id)&o.cr.s.min=min(string_sd)&o.avr.s.sum=sum(int_id)&o.dmr.s.dmme=date_math(median(date_dtd),const_str(%2B2YEARS))&o.dmr.s.cma=const_str(%2B2MONTHS)&o.csr.s.cs1=const_str(this+is+the+first)&o.dr.s.mc=div(mean(int_id),count(long_ld))&o.dmr.s.dmma=date_math(max(date_dtd),const_str(%2B2MONTHS))&o.ar.s.median=median(int_id)&o.pr.s.unique=unique(long_ld)&stats=true&olap=true&rows=0&o.dr.s.su=div(sum(int_id),unique(long_ld))} hits=100 status=0 QTime=11
[junit4] 2> 2148738 T4092 oas.SolrTestCaseJ4.setUp ###Starting constantStringTest
[junit4] 2> 2148741 T4092 oas.SolrTestCaseJ4.tearDown ###Ending constantStringTest
[junit4] 2> 2148745 T4092 oas.SolrTestCaseJ4.setUp ###Starting absoluteValueTest
[junit4] 2> 2148747 T4092 oas.SolrTestCaseJ4.tearDown ###Ending absoluteValueTest
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ExpressionTest -Dtests.method=absoluteValueTest -Dtests.seed=DE934D7117FF594E -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=fr_CA -Dtests.timezone=Atlantic/St_Helena -Dtests.file.encoding=US-ASCII
[junit4] FAILURE 0.03s J0 | ExpressionTest.absoluteValueTest <<<
[junit4] > Throwable #1: java.lang.AssertionError: Caught exception in getStatResult, xPath = /response/lst[@name='stats']/lst[@name='avr']/double[@name='sum']
[junit4] > raw data: <?xml version="1.0" encoding="UTF-8"?>
[junit4] > <response>
[junit4] > <lst name="responseHeader">
[junit4] > <int name="status">0</int>
[junit4] > <int name="QTime">11</int>
[junit4] > </lst>
[junit4] > <result name="response" numFound="100" start="0">
[junit4] > </result>
[junit4] > <lst name="stats">
[junit4] > <lst name="stats_fields"/>
[junit4] > </lst>
[junit4] > <lst name="stats">
[junit4] > <lst name="csr">
[junit4] > <str name="cs1">this is the first</str>
[junit4] > <str name="cs2">this is the second</str>
[junit4] > <str name="cs3">this is the third</str>
[junit4] > </lst>
[junit4] > <lst name="mr">
[junit4] > <long name="count">100</long>
[junit4] > <null name="mcm"/>
[junit4] > <null name="mean"/>
[junit4] > <null name="median"/>
[junit4] > <null name="su"/>
[junit4] > <null name="sum"/>
[junit4] > <null name="unique"/>
[junit4] > </lst>
[junit4] > <lst name="rr">
[junit4] > <str name="max">str9</str>
[junit4] > <str name="min">str0</str>
[junit4] > <str name="rmax">9rts</str>
[junit4] > <str name="rmin">0rts</str>
[junit4] > </lst>
[junit4] > <lst name="dmr">
[junit4] > <str name="cma">+2MONTHS</str>
[junit4] > <str name="cme">+2YEARS</str>
[junit4] > <date name="dmma">1812-02-29T23:59:59Z</date>
[junit4] > <date name="dmme">1807-12-31T23:59:59Z</date>
[junit4] > <date name="max">1811-12-31T23:59:59Z</date>
[junit4] > <date name="median">1805-12-31T23:59:59Z</date>
[junit4] > </lst>
[junit4] > <lst name="cr">
[junit4] > <str name="ccmax">this is the secondstr9</str>
[junit4] > <str name="ccmin">this is the firststr0</str>
[junit4] > <str name="csmax">this is the second</str>
[junit4] > <str name="csmin">this is the first</str>
[junit4] > <str name="max">str9</str>
[junit4] > <str name="min">str0</str>
[junit4] > </lst>
[junit4] > <lst name="dr">
[junit4] > <long name="count">100</long>
[junit4] > <null name="mc"/>
[junit4] > <null name="mean"/>
[junit4] > <null name="su"/>
[junit4] > <null name="sum"/>
[junit4] > <null name="unique"/>
[junit4] > </lst>
[junit4] > <lst name="ar">
[junit4] > <long name="count">100</long>
[junit4] > <null name="mcm"/>
[junit4] > <null name="mean"/>
[junit4] > <null name="median"/>
[junit4] > <null name="su"/>
[junit4] > <null name="sum"/>
[junit4] > <null name="unique"/>
[junit4] > </lst>
[junit4] > <lst name="avr">
[junit4] > <double name="c">100.0</double>
[junit4] > <long name="count">100</long>
[junit4] > <null name="s"/>
[junit4] > <null name="sum"/>
[junit4] > </lst>
[junit4] > <lst name="cnr">
[junit4] > <double name="c10">10.0</double>
[junit4] > <double name="c8">8.0</double>
[junit4] > </lst>
[junit4] > <lst name="nr">
[junit4] > <double name="c">-100.0</double>
[junit4] > <long name="count">100</long>
[junit4] > <null name="s"/>
[junit4]
[...truncated too long message...]
nit4] > <long name="count">100</long>
[junit4] > <null name="mc"/>
[junit4] > <null name="mean"/>
[junit4] > <null name="su"/>
[junit4] > <null name="sum"/>
[junit4] > <null name="unique"/>
[junit4] > </lst>
[junit4] > <lst name="ar">
[junit4] > <long name="count">100</long>
[junit4] > <null name="mcm"/>
[junit4] > <null name="mean"/>
[junit4] > <null name="median"/>
[junit4] > <null name="su"/>
[junit4] > <null name="sum"/>
[junit4] > <null name="unique"/>
[junit4] > </lst>
[junit4] > <lst name="avr">
[junit4] > <double name="c">100.0</double>
[junit4] > <long name="count">100</long>
[junit4] > <null name="s"/>
[junit4] > <null name="sum"/>
[junit4] > </lst>
[junit4] > <lst name="cnr">
[junit4] > <double name="c10">10.0</double>
[junit4] > <double name="c8">8.0</double>
[junit4] > </lst>
[junit4] > <lst name="nr">
[junit4] > <double name="c">-100.0</double>
[junit4] > <long name="count">100</long>
[junit4] > <null name="s"/>
[junit4] > <null name="sum"/>
[junit4] > </lst>
[junit4] > <lst name="cdr">
[junit4] > <date name="cd1">1800-12-31T23:59:59Z</date>
[junit4] > <date name="cd2">1804-06-30T23:59:59Z</date>
[junit4] > <str name="cs1">1800-12-31T23:59:59Z</str>
[junit4] > <str name="cs2">1804-06-30T23:59:59Z</str>
[junit4] > </lst>
[junit4] > <lst name="pr">
[junit4] > <long name="count">100</long>
[junit4] > <null name="mc"/>
[junit4] > <null name="mean"/>
[junit4] > <null name="su"/>
[junit4] > <null name="sum"/>
[junit4] > <null name="unique"/>
[junit4] > </lst>
[junit4] > </lst>
[junit4] > </response>
[junit4] > at __randomizedtesting.SeedInfo.seed([DE934D7117FF594E:869EA687333D3B98]:0)
[junit4] > at org.apache.solr.analytics.AbstractAnalyticsStatsTest.getStatResult(AbstractAnalyticsStatsTest.java:113)
[junit4] > at org.apache.solr.analytics.expression.ExpressionTest.divideTest(ExpressionTest.java:106)
[junit4] > at java.lang.Thread.run(Thread.java:724)
[junit4] 2> 2148861 T4092 oas.SolrTestCaseJ4.setUp ###Starting concatenateTest
[junit4] 2> 2148867 T4092 oas.SolrTestCaseJ4.tearDown ###Ending concatenateTest
[junit4] 2> 2148868 T4092 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 2148868 T4092 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=229699263
[junit4] 2> 2148869 T4092 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@2844bf50
[junit4] 2> 2148869 T4092 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> 2148869 T4092 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2148870 T4092 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2148870 T4092 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2148870 T4092 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 2148871 T4092 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2148871 T4092 oasc.CachingDirectoryFactory.closeCacheValue looking to close /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/./solrtest-ExpressionTest-1388825658503 [CachedDir<<refCount=0;path=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/./solrtest-ExpressionTest-1388825658503;done=false>>]
[junit4] 2> 2148871 T4092 oasc.CachingDirectoryFactory.close Closing directory: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/./solrtest-ExpressionTest-1388825658503
[junit4] 2> 2148872 T4092 oasc.CachingDirectoryFactory.closeCacheValue looking to close /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/./solrtest-ExpressionTest-1388825658503/index [CachedDir<<refCount=0;path=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/./solrtest-ExpressionTest-1388825658503/index;done=false>>]
[junit4] 2> 2148872 T4092 oasc.CachingDirectoryFactory.close Closing directory: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/./solrtest-ExpressionTest-1388825658503/index
[junit4] 2> NOTE: test params are: codec=Lucene45, sim=DefaultSimilarity, locale=fr_CA, timezone=Atlantic/St_Helena
[junit4] 2> NOTE: FreeBSD 9.1-RELEASE-p3 amd64/Oracle Corporation 1.7.0_25 (64-bit)/cpus=16,threads=1,free=371436520,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [ShardSplitTest, AutoCommitTest, TestManagedSchema, SuggesterTest, ClusterStateTest, TestPHPSerializedResponseWriter, ParsingFieldUpdateProcessorsTest, TestCustomSort, TestNoOpRegenerator, SolrCoreTest, DeleteShardTest, TestSearchPerf, RecoveryZkTest, EchoParamsTest, SliceStateTest, TestCloudManagedSchemaAddField, TestShardHandlerFactory, TestSort, TestNonNRTOpen, TestSchemaSimilarityResource, ExternalFileFieldSortTest, PolyFieldTest, ResponseLogComponentTest, TestCoreContainer, TestHighFrequencyDictionaryFactory, HdfsSyncSliceTest, TriLevelCompositeIdRoutingTest, FieldMutatingUpdateProcessorTest, TestExtendedDismaxParser, RangeFacetTest, SchemaVersionSpecificBehaviorTest, ResourceLoaderTest, TestRandomDVFaceting, ScriptEngineTest, TestTrie, TestDynamicFieldCollectionResource, SpatialFilterTest, OverseerCollectionProcessorTest, DocValuesTest, DeleteReplicaTest, CoreAdminHandlerTest, TestDynamicFieldResource, TestSolrXmlPersistor, TestPseudoReturnFields, LegacyHTMLStripCharFilterTest, TestSolrQueryParser, UpdateRequestProcessorFactoryTest, TestBadConfig, NoFacetTest, BasicDistributedZkTest, TestRemoteStreaming, TestMergePolicyConfig, ShardRoutingTest, TestJmxMonitoredMap, SpellPossibilityIteratorTest, RegexBoostProcessorTest, BadComponentTest, QueryParsingTest, HdfsRecoveryZkTest, TestElisionMultitermQuery, HdfsBasicDistributedZkTest, FastVectorHighlighterTest, SoftAutoCommitTest, TestCharFilters, TestSolrXMLSerializer, HdfsLockFactoryTest, TestJoin, PrimitiveFieldTypeTest, ModifyConfFileTest, TestLuceneMatchVersion, MBeansHandlerTest, ShowFileRequestHandlerTest, TestReversedWildcardFilterFactory, TestAnalyzeInfixSuggestions, AnalysisAfterCoreReloadTest, UUIDFieldTest, TestPerFieldSimilarity, TestCollapseQParserPlugin, UnloadDistributedZkTest, SuggesterFSTTest, TestPostingsSolrHighlighter, TermsComponentTest, SystemInfoHandlerTest, DistributedSpellCheckComponentTest, TestSystemIdResolver, TestLMJelinekMercerSimilarityFactory, SyncSliceTest, TestGroupingSearch, TestFunctionQuery, BasicFunctionalityTest, HighlighterTest, DistributedQueryElevationComponentTest, SolrIndexSplitterTest, TestCoreDiscovery, SignatureUpdateProcessorFactoryTest, SpellCheckCollatorTest, SolrRequestParserTest, TestStressLucene, NoCacheHeaderTest, WordBreakSolrSpellCheckerTest, QueryEqualityTest, StatelessScriptUpdateProcessorFactoryTest, DocValuesMultiTest, TestSolrDeletionPolicy1, XsltUpdateRequestHandlerTest, CacheHeaderTest, TestSurroundQueryParser, DisMaxRequestHandlerTest, TestQueryUtils, StandardRequestHandlerTest, DirectSolrSpellCheckerTest, FileBasedSpellCheckerTest, DocumentBuilderTest, TermVectorComponentTest, LoggingHandlerTest, IndexSchemaTest, ReturnFieldsTest, TestCSVResponseWriter, JsonLoaderTest, TestPartialUpdateDeduplication, CSVRequestHandlerTest, SearchHandlerTest, TestLFUCache, SOLR749Test, TestSolrIndexConfig, TestStressRecovery, PreAnalyzedFieldTest, TestSuggestSpellingConverter, SpellingQueryConverterTest, DOMUtilTest, TestLRUCache, TestUtils, TestDocumentBuilder, ZkNodePropsTest, CircularListTest, TestRTGBase, SolrTestCaseJ4Test, TestDistributedMissingSort, ExpressionTest]
[junit4] Completed on J0 in 1.76s, 12 tests, 6 failures <<< FAILURES!
[...truncated 161 lines...]
BUILD FAILED
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/build.xml:453: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/build.xml:433: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/build.xml:39: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/extra-targets.xml:37: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build.xml:189: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/common-build.xml:491: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/lucene/common-build.xml:1304: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/lucene/common-build.xml:937: There were test failures: 358 suites, 1577 tests, 6 failures, 35 ignored (6 assumptions)
Total time: 61 minutes 59 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
[JENKINS] Lucene-Solr-Tests-trunk-Java7 - Build # 4566 - Still
Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://builds.apache.org/job/Lucene-Solr-Tests-trunk-Java7/4566/
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([E29C7DC22F7C4783:637AF3DA582327BF]: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:202)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:849)
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:724)
Build Log:
[...truncated 11075 lines...]
[junit4] Suite: org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest
[junit4] 2> 2004313 T4135 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /x_spf/n
[junit4] 2> 2004320 T4135 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> Creating dataDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/./solrtest-ChaosMonkeyNothingIsSafeTest-1388871037612
[junit4] 2> 2004322 T4135 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 2> 2004322 T4136 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 2004423 T4135 oasc.ZkTestServer.run start zk server on port:28991
[junit4] 2> 2004424 T4135 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2004430 T4143 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@295477a9 name:ZooKeeperConnection Watcher:127.0.0.1:28991 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2004430 T4135 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2004430 T4135 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 2004452 T4135 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2004459 T4146 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2bb07ed8 name:ZooKeeperConnection Watcher:127.0.0.1:28991/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2004459 T4135 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2004460 T4135 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 2004472 T4135 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 2004475 T4135 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 2004477 T4135 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 2004481 T4135 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 2004482 T4135 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 2004491 T4135 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test-files/solr/collection1/conf/schema15.xml to /configs/conf1/schema.xml
[junit4] 2> 2004492 T4135 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 2004598 T4135 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 2004599 T4135 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 2004611 T4135 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 2004612 T4135 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 2004615 T4135 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 2004615 T4135 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 2004618 T4135 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 2004619 T4135 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 2004622 T4135 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 2004622 T4135 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 2004625 T4135 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 2004626 T4135 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 2004629 T4135 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 2004629 T4135 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 2004632 T4135 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 2004633 T4135 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 2004636 T4135 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 2004637 T4135 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 2004947 T4135 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2004952 T4135 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:20077
[junit4] 2> 2004953 T4135 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2004954 T4135 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2004954 T4135 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1388871037936
[junit4] 2> 2004954 T4135 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1388871037936/'
[junit4] 2> 2004994 T4135 oasc.ConfigSolr.fromFile Loading container configuration from /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1388871037936/solr.xml
[junit4] 2> 2005094 T4135 oasc.CoreContainer.<init> New CoreContainer 254533195
[junit4] 2> 2005095 T4135 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1388871037936/]
[junit4] 2> 2005097 T4135 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2005097 T4135 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2005098 T4135 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2005098 T4135 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2005099 T4135 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2005099 T4135 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2005100 T4135 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2005100 T4135 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2005100 T4135 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2005111 T4135 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2005112 T4135 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2005112 T4135 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2005113 T4135 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:28991/solr
[junit4] 2> 2005113 T4135 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 2005114 T4135 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2005117 T4158 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3d5a22b8 name:ZooKeeperConnection Watcher:127.0.0.1:28991 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2005118 T4135 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2005121 T4135 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2005133 T4161 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4d050cf7 name:ZooKeeperConnection Watcher:127.0.0.1:28991/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2005134 T4135 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2005136 T4135 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 2005145 T4135 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 2005148 T4135 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 2005155 T4135 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:20077_x_spf%2Fn
[junit4] 2> 2005156 T4135 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:20077_x_spf%2Fn
[junit4] 2> 2005164 T4135 oascc.SolrZkClient.makePath makePath: /overseer_elect
[junit4] 2> 2005166 T4135 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 2005171 T4135 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 2005181 T4135 oasc.Overseer.start Overseer (id=91021052321398787-127.0.0.1:20077_x_spf%2Fn-n_0000000000) starting
[junit4] 2> 2005190 T4135 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 2005203 T4163 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 2005204 T4135 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 2005206 T4135 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 2005208 T4135 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2005210 T4162 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 2005216 T4164 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 2005217 T4164 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2005218 T4161 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2005218 T4164 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 2005219 T4162 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2005220 T4162 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:20077/x_spf/n",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:20077_x_spf%2Fn",
[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> 2005220 T4162 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1]
[junit4] 2> 2005220 T4162 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 2005224 T4161 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2005231 T4161 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> 2006220 T4164 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 2006220 T4164 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1388871037936/collection1
[junit4] 2> 2006220 T4164 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4] 2> 2006221 T4164 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 2006222 T4164 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 2006223 T4164 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1388871037936/collection1/'
[junit4] 2> 2006224 T4164 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1388871037936/collection1/lib/README' to classloader
[junit4] 2> 2006225 T4164 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1388871037936/collection1/lib/classes/' to classloader
[junit4] 2> 2006293 T4164 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 2006339 T4164 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2006441 T4164 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2006461 T4164 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2006881 T4164 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 2006884 T4164 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2006885 T4164 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2006889 T4164 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2006908 T4164 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4] 2> 2006909 T4164 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1388871037936/collection1/, dataDir=./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388871037612/control/data/
[junit4] 2> 2006909 T4164 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7fd4880e
[junit4] 2> 2006910 T4164 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388871037612/control/data
[junit4] 2> 2006911 T4164 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388871037612/control/data/index/
[junit4] 2> 2006911 T4164 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388871037612/control/data/index' doesn't exist. Creating new index...
[junit4] 2> 2006912 T4164 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388871037612/control/data/index
[junit4] 2> 2006913 T4164 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=41, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8240650615171344]
[junit4] 2> 2006916 T4164 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388871037612/control/data/index,segFN=segments_1,generation=1}
[junit4] 2> 2006917 T4164 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2006919 T4164 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 2006919 T4164 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 2006920 T4164 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 2006920 T4164 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 2006920 T4164 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 2006920 T4164 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 2006921 T4164 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2006921 T4164 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2006921 T4164 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2006922 T4164 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2006922 T4164 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 2006922 T4164 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 2006922 T4164 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2006923 T4164 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2006923 T4164 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 2006923 T4164 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 2006924 T4164 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 2006935 T4164 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2006938 T4164 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2006938 T4164 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2006938 T4164 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=28, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=43.16015625, floorSegmentMB=0.5830078125, forceMergeDeletesPctAllowed=20.2835437540111, segmentsPerTier=14.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8443343273919544
[junit4] 2> 2006940 T4164 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388871037612/control/data/index,segFN=segments_1,generation=1}
[junit4] 2> 2006940 T4164 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2006940 T4164 oass.SolrIndexSearcher.<init> Opening Searcher@d2636e9 main
[junit4] 2> 2006943 T4165 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@d2636e9 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2006943 T4164 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2006944 T4135 oass.SolrDispatchFilter.init user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1
[junit4] 2> 2006945 T4135 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2006944 T4168 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:20077/x_spf/n collection:control_collection shard:shard1
[junit4] 2> 2006947 T4168 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4] 2> 2006947 T4135 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2006950 T4171 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@55f15f3e name:ZooKeeperConnection Watcher:127.0.0.1:28991/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2006950 T4135 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2006960 T4135 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2006971 T4135 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 2006972 T4168 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 2006979 T4161 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2006980 T4168 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 2006980 T4168 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C1296 name=collection1 org.apache.solr.core.SolrCore@47acc06f url=http://127.0.0.1:20077/x_spf/n/collection1 node=127.0.0.1:20077_x_spf%2Fn C1296_STATE=coll:control_collection core:collection1 props:{state=down, base_url=http://127.0.0.1:20077/x_spf/n, core=collection1, node_name=127.0.0.1:20077_x_spf%2Fn}
[junit4] 2> 2006980 T4168 C1296 P20077 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:20077/x_spf/n/collection1/
[junit4] 2> 2006980 T4162 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2006981 T4168 C1296 P20077 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 2006981 T4168 C1296 P20077 oasc.SyncStrategy.syncToMe http://127.0.0.1:20077/x_spf/n/collection1/ has no replicas
[junit4] 2> 2006981 T4168 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:20077/x_spf/n/collection1/ shard1
[junit4] 2> 2006982 T4168 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 2006989 T4161 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2007000 T4171 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> 2007000 T4161 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> 2007101 T4162 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2007109 T4161 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2007164 T4135 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2007167 T4135 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:21022
[junit4] 2> 2007167 T4135 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2007168 T4135 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2007168 T4135 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../../usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty1-1388871040262
[junit4] 2> 2007168 T4135 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../../usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty1-1388871040262/'
[junit4] 2> 2007196 T4135 oasc.ConfigSolr.fromFile Loading container configuration from /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/../../../../../../../../../../../usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty1-1388871040262/solr.xml
[junit4] 2> 2007211 T4171 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> 2007211 T4161 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> 2007254 T4168 oasc.ZkController.register We are http://127.0.0.1:20077/x_spf/n/collection1/ and leader is http://127.0.0.1:20077/x_spf/n/collection1/
[junit4] 2> 2007254 T4168 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:20077/x_spf/n
[junit4] 2> 2007254 T4168 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 2007254 T4168 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 2007255 T4168 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2007256 T4161 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2007256 T4161 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2007257 T4161 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2007257 T4168 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2007258 T4162 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2007258 T4162 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:20077/x_spf/n",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:20077_x_spf%2Fn",
[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":"2",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 2007262 T4135 oasc.CoreContainer.<init> New CoreContainer 1685725220
[junit4] 2> 2007262 T4135 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../../usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty1-1388871040262/]
[junit4] 2> 2007264 T4135 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2007264 T4135 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2007264 T4135 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2007265 T4135 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2007265 T4135 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2007265 T4135 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2007265 T4135 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2007266 T4135 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2007266 T4135 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2007273 T4135 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2007273 T4135 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2007273 T4135 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2007274 T4135 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:28991/solr
[junit4] 2> 2007274 T4135 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 2007275 T4135 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2007286 T4161 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2007288 T4183 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6d8fd85a name:ZooKeeperConnection Watcher:127.0.0.1:28991 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2007288 T4135 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2007291 T4135 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2007342 T4186 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@78d3a9ca name:ZooKeeperConnection Watcher:127.0.0.1:28991/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2007342 T4135 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2007348 T4135 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2007391 T4186 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> 2007391 T4161 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> 2007391 T4171 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> 2008351 T4135 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:21022_x_spf%2Fn
[junit4] 2> 2008353 T4135 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:21022_x_spf%2Fn
[junit4] 2> 2008356 T4171 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 2008356 T4161 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 2008356 T4186 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 2008375 T4187 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 2008376 T4187 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2008377 T4161 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2008377 T4187 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 2008377 T4161 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2008378 T4161 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2008378 T4162 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2008379 T4162 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:21022/x_spf/n",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:21022_x_spf%2Fn",
[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> 2008379 T4162 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2]
[junit4] 2> 2008380 T4162 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 2008383 T4161 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2008485 T4171 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> 2008485 T4161 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> 2008485 T4186 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> 2009378 T4187 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 2009378 T4187 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ../../../../../../../../../../../usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty1-1388871040262/collection1
[junit4] 2> 2009378 T4187 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 2009379 T4187 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 2009380 T4187 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 2009381 T4187 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../../usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty1-1388871040262/collection1/'
[junit4] 2> 2009383 T4187 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty1-1388871040262/collection1/lib/classes/' to classloader
[junit4] 2> 2009383 T4187 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty1-1388871040262/collection1/lib/README' to classloader
[junit4] 2> 2009450 T4187 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 2009515 T4187 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2009617 T4187 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2009647 T4187 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2010244 T4187 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 2010248 T4187 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2010251 T4187 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2010256 T4187 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2010278 T4187 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4] 2> 2010278 T4187 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../../../usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty1-1388871040262/collection1/, dataDir=./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388871037612/jetty1/
[junit4] 2> 2010278 T4187 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7fd4880e
[junit4] 2> 2010280 T4187 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388871037612/jetty1
[junit4] 2> 2010281 T4187 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388871037612/jetty1/index/
[junit4] 2> 2010282 T4187 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388871037612/jetty1/index' doesn't exist. Creating new index...
[junit4] 2> 2010283 T4187 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388871037612/jetty1/index
[junit4] 2> 2010284 T4187 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=41, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8240650615171344]
[junit4] 2> 2010292 T4187 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388871037612/jetty1/index,segFN=segments_1,generation=1}
[junit4] 2> 2010292 T4187 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2010296 T4187 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 2010297 T4187 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 2010297 T4187 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 2010298 T4187 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 2010298 T4187 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 2010298 T4187 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 2010299 T4187 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2010299 T4187 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2010300 T4187 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2010300 T4187 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2010301 T4187 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 2010301 T4187 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 2010302 T4187 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2010302 T4187 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2010303 T4187 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 2010303 T4187 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 2010304 T4187 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 2010321 T4187 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2010326 T4187 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2010326 T4187 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2010327 T4187 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=28, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=43.16015625, floorSegmentMB=0.5830078125, forceMergeDeletesPctAllowed=20.2835437540111, segmentsPerTier=14.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8443343273919544
[junit4] 2> 2010329 T4187 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388871037612/jetty1/index,segFN=segments_1,generation=1}
[junit4] 2> 2010329 T4187 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2010330 T4187 oass.SolrIndexSearcher.<init> Opening Searcher@5bfa9ffc main
[junit4] 2> 2010334 T4188 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5bfa9ffc main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2010337 T4187 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2010338 T4135 oass.SolrDispatchFilter.init user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1
[junit4] 2> 2010338 T4135 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2010338 T4191 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:21022/x_spf/n collection:collection1 shard:shard1
[junit4] 2> 2010340 T4191 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4] 2> 2010364 T4191 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 2010366 T4161 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2010367 T4161 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2010367 T4191 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 2010367 T4161 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2010367 T4191 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C1297 name=collection1 org.apache.solr.core.SolrCore@35e1c902 url=http://127.0.0.1:21022/x_spf/n/collection1 node=127.0.0.1:21022_x_spf%2Fn C1297_STATE=coll:collection1 core:collection1 props:{state=down, base_url=http://127.0.0.1:21022/x_spf/n, core=collection1, node_name=127.0.0.1:21022_x_spf%2Fn}
[junit4] 2> 2010368 T4191 C1297 P21022 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:21022/x_spf/n/collection1/
[junit4] 2> 2010368 T4162 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2010368 T4191 C1297 P21022 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 2010368 T4191 C1297 P21022 oasc.SyncStrategy.syncToMe http://127.0.0.1:21022/x_spf/n/collection1/ has no replicas
[junit4] 2> 2010369 T4191 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:21022/x_spf/n/collection1/ shard1
[junit4] 2> 2010369 T4191 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4] 2> 2010379 T4161 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2010387 T4161 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> 2010387 T4171 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> 2010387 T4186 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> 2010399 T4161 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2010401 T4162 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2010404 T4161 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2010506 T4171 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> 2010506 T4161 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> 2010506 T4186 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> 2010554 T4191 oasc.ZkController.register We are http://127.0.0.1:21022/x_spf/n/collection1/ and leader is http://127.0.0.1:21022/x_spf/n/collection1/
[junit4] 2> 2010554 T4191 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:21022/x_spf/n
[junit4] 2> 2010554 T4191 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 2010554 T4191 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 2010554 T4191 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2010557 T4161 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2010558 T4161 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2010558 T4191 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2010558 T4161 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2010559 T4162 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2010560 T4162 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:21022/x_spf/n",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:21022_x_spf%2Fn",
[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_node1"}
[junit4] 2> 2010569 T4161 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2010635 T4135 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2010638 T4135 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:20941
[junit4] 2> 2010638 T4135 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2010639 T4135 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2010639 T4135 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../../usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1388871043630
[junit4] 2> 2010640 T4135 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../../usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1388871043630/'
[junit4] 2> 2010671 T4186 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> 2010671 T4161 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> 2010671 T4171 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> 2010690 T4135 oasc.ConfigSolr.fromFile Loading container configuration from /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/../../../../../../../../../../../usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1388871043630/solr.xml
[junit4] 2> 2010801 T4135 oasc.CoreContainer.<init> New CoreContainer 1849723187
[junit4] 2> 2010802 T4135 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../../usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1388871043630/]
[junit4] 2> 2010804 T4135 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2010804 T4135 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2010805 T4135 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2010805 T4135 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2010805 T4135 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2010806 T4135 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2010806 T4135 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2010807 T4135 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2010807 T4135 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2010818 T4135 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2010818 T4135 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2010819 T4135 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2010819 T4135 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:28991/solr
[junit4] 2> 2010820 T4135 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 2010821 T4135 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2010824 T4203 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@58f94ed3 name:ZooKeeperConnection Watcher:127.0.0.1:28991 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2010825 T4135 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2010828 T4135 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2010830 T4206 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3bab8828 name:ZooKeeperConnection Watcher:127.0.0.1:28991/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2010831 T4135 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2010841 T4135 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2011844 T4135 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:20941_x_spf%2Fn
[junit4] 2> 2011846 T4135 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:20941_x_spf%2Fn
[junit4] 2> 2011850 T4186 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 2011850 T4161 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 2011850 T4206 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 2011850 T4171 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 2011869 T4207 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 2011869 T4207 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2011871 T4161 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2011871 T4207 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 2011871 T4161 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2011871 T4161 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2011872 T4162 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2011873 T4162 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:20941/x_spf/n",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:20941_x_spf%2Fn",
[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> 2011873 T4162 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4] 2> 2011873 T4162 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4] 2> 2011877 T4161 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2011989 T4161 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> 2011989 T4206 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> 2011989 T4171 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> 2011989 T4186 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> 2012872 T4207 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 2012872 T4207 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ../../../../../../../../../../../usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1388871043630/collection1
[junit4] 2> 2012872 T4207 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 2012873 T4207 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 2012873 T4207 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 2012874 T4207 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../../usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1388871043630/collection1/'
[junit4] 2> 2012876 T4207 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1388871043630/collection1/lib/README' to classloader
[junit4] 2> 2012876 T4207 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1388871043630/collection1/lib/classes/' to classloader
[junit4] 2> 2012924 T4207 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 2012969 T4207 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2013071 T4207 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2013088 T4207 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2013493 T4207 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 2013496 T4207 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2013498 T4207 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2013502 T4207 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2013522 T4207 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4] 2> 2013523 T4207 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../../../usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1388871043630/collection1/, dataDir=./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388871037612/jetty2/
[junit4] 2> 2013523 T4207 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7fd4880e
[junit4] 2> 2013524 T4207 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388871037612/jetty2
[junit4] 2> 2013525 T4207 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388871037612/jetty2/index/
[junit4] 2> 2013526 T4207 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388871037612/jetty2/index' doesn't exist. Creating new index...
[junit4] 2> 2013528 T4207 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388871037612/jetty2/index
[junit4] 2> 2013528 T4207 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=41, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8240650615171344]
[junit4] 2> 2013532 T4207 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388871037612/jetty2/index,segFN=segments_1,generation=1}
[junit4] 2> 2013533 T4207 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2013536 T4207 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 2013536 T4207 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 2013536 T4207 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 2013536 T4207 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 2013537 T4207 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 2013537 T4207 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 2013537 T4207 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2013537 T4207 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2013538 T4207 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2013538 T4207 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2013539 T4207 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 2013539 T4207 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 2013539 T4207 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2013539 T4207 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2013540 T4207 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 2013540 T4207 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 2013541 T4207 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 2013554 T4207 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2013558 T4207 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2013558 T4207 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2013559 T4207 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=28, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=43.16015625, floorSegmentMB=0.5830078125, forceMergeDeletesPctAllowed=20.2835437540111, segmentsPerTier=14.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8443343273919544
[junit4] 2> 2013560 T4207 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388871037612/jetty2/index,segFN=segments_1,generation=1}
[junit4] 2> 2013560 T4207 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2013561 T4207 oass.SolrIndexSearcher.<init> Opening Searcher@26336d8a main
[junit4] 2> 2013564 T4208 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@26336d8a main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2013566 T4207 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2013566 T4135 oass.SolrDispatchFilter.init user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1
[junit4] 2> 2013567 T4135 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2013566 T4211 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:20941/x_spf/n collection:collection1 shard:shard2
[junit4] 2> 2013568 T4211 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4] 2> 2013583 T4211 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2
[junit4] 2> 2013585 T4161 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2013586 T4161 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2013586 T4211 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 2013586 T4161 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2013586 T4211 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C1298 name=collection1 org.apache.solr.core.SolrCore@d29f625 url=http://127.0.0.1:20941/x_spf/n/collection1 node=127.0.0.1:20941_x_spf%2Fn C1298_STATE=coll:collection1 core:collection1 props:{state=down, base_url=http://127.0.0.1:20941/x_spf/n, core=collection1, node_name=127.0.0.1:20941_x_spf%2Fn}
[junit4] 2> 2013586 T4211 C1298 P20941 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:20941/x_spf/n/collection1/
[junit4] 2> 2013586 T4162 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2013587 T4211 C1298 P20941 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 2013587 T4211 C1298 P20941 oasc.SyncStrategy.syncToMe http://127.0.0.1:20941/x_spf/n/collection1/ has no replicas
[junit4] 2> 2013587 T4211 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:20941/x_spf/n/collection1/ shard2
[junit4] 2> 2013588 T4211 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4] 2> 2013590 T4161 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2013591 T4206 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> 2013591 T4171 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> 2013591 T4186 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> 2013591 T4161 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> 2013595 T4162 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2013599 T4161 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2013702 T4186 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> 2013702 T4171 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> 2013702 T4206 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> 2013702 T4161 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> 2013747 T4211 oasc.ZkController.register We are http://127.0.0.1:20941/x_spf/n/collection1/ and leader is http://127.0.0.1:20941/x_spf/n/collection1/
[junit4] 2> 2013747 T4211 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:20941/x_spf/n
[junit4] 2> 2013747 T4211 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 2013747 T4211 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 2013747 T4211 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2013749 T4161 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2013749 T4161 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2013749 T4161 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2013749 T4211 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2013750 T4162 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2013751 T4162 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:20941/x_spf/n",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:20941_x_spf%2Fn",
[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_node2"}
[junit4] 2> 2013754 T4161 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2013789 T4135 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2013791 T4135 oejs.AbstractConnector.doStart Started SocketConnector@127.0.0.1:55763
[junit4] 2> 2013792 T4135 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2013792 T4135 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2013792 T4135 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../../usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty3-1388871046858
[junit4] 2> 2013793 T4135 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../../usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty3-1388871046858/'
[junit4] 2> 2013823 T4135 oasc.ConfigSolr.fromFile Loading container configuration from /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/../../../../../../../../../../../usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty3-1388871046858/solr.xml
[junit4] 2> 2013856 T4171 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> 2013856 T4186 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> 2013856 T4206 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> 2013856 T4161 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> 2013894 T4135 oasc.CoreContainer.<init> New CoreContainer 747976624
[junit4] 2> 2013895 T4135 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../../usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty3-1388871046858/]
[junit4] 2> 2013896 T4135 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2013897 T4135 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2013897 T4135 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2013897 T4135 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2013897 T4135 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2013898 T4135 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2013898 T4135 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2013898 T4135 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2013899 T4135 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2013906 T4135 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2013906 T4135 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2013906 T4135 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2013907 T4135 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:28991/solr
[junit4] 2> 2013907 T4135 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 2013908 T4135 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2013910 T4223 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5a1491a6 name:ZooKeeperConnection Watcher:127.0.0.1:28991 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2013911 T4135 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2013914 T4135 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2013920 T4226 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@42294d9a name:ZooKeeperConnection Watcher:127.0.0.1:28991/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2013920 T4135 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2013929 T4135 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2014931 T4135 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55763_x_spf%2Fn
[junit4] 2> 2014938 T4135 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:55763_x_spf%2Fn
[junit4] 2> 2014941 T4161 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 2014941 T4226 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 2014941 T4186 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 2014942 T4206 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 2014941 T4171 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 2014955 T4227 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 2014956 T4227 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2014957 T4161 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2014957 T4227 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 2014958 T4161 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2014958 T4161 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2014959 T4162 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2014960 T4162 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:55763/x_spf/n",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:55763_x_spf%2Fn",
[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> 2014960 T4162 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4] 2> 2014960 T4162 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 2014964 T4161 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2015066 T4206 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> 2015066 T4186 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> 2015066 T4171 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> 2015066 T4226 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> 2015066 T4161 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> 2015959 T4227 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 2015959 T4227 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ../../../../../../../../../../../usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty3-1388871046858/collection1
[junit4] 2> 2015959 T4227 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 2015960 T4227 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 2015961 T4227 oascc.ZkStateReader.re
[...truncated too long message...]
a:430)
[junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit4] 2> at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:546)
[junit4] 2> at org.apache.solr.core.JmxMonitoredMap.unregister(JmxMonitoredMap.java:175)
[junit4] 2> at org.apache.solr.core.JmxMonitoredMap.clear(JmxMonitoredMap.java:116)
[junit4] 2> at org.apache.solr.core.SolrCore.close(SolrCore.java:1012)
[junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:268)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:165)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:109)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:55)
[junit4] 2> at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:138)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:519)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:495)
[junit4] 2>
[junit4] 2> 2105337 T4313 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=2,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=61,cumulative_deletesById=30,cumulative_deletesByQuery=1,cumulative_errors=0,transaction_logs_total_size=0,transaction_logs_total_number=1}
[junit4] 2> 2105338 T4313 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2105338 T4313 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2105339 T4313 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2105340 T4313 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 2105340 T4313 oasc.CachingDirectoryFactory.close Closing StandardDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2105340 T4313 oasc.CachingDirectoryFactory.closeCacheValue looking to close /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388871037612/jetty7/index [CachedDir<<refCount=0;path=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388871037612/jetty7/index;done=false>>]
[junit4] 2> 2105340 T4313 oasc.CachingDirectoryFactory.close Closing directory: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388871037612/jetty7/index
[junit4] 2> 2105341 T4313 oasc.CachingDirectoryFactory.closeCacheValue looking to close /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388871037612/jetty7 [CachedDir<<refCount=0;path=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388871037612/jetty7;done=false>>]
[junit4] 2> 2105341 T4313 oasc.CachingDirectoryFactory.close Closing directory: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388871037612/jetty7
[junit4] 2> 2105342 T4313 oasc.LeaderElector$1.process WARN org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/election
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.getChildren(ZooKeeper.java:1468)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:256)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:253)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getChildren(SolrZkClient.java:253)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:93)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:55)
[junit4] 2> at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:138)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:519)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:495)
[junit4] 2>
[junit4] 2> 2105342 T4313 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> NOTE: test params are: codec=Lucene42, sim=DefaultSimilarity, locale=en_MT, timezone=Eire
[junit4] 2> NOTE: FreeBSD 9.1-RELEASE-p3 amd64/Oracle Corporation 1.7.0_25 (64-bit)/cpus=16,threads=1,free=157723456,total=445644800
[junit4] 2> NOTE: All tests run in this JVM: [TestCSVLoader, TestSchemaVersionResource, DistributedSuggestComponentTest, TestDefaultSimilarityFactory, BlockDirectoryTest, PreAnalyzedUpdateProcessorTest, TestWordDelimiterFilterFactory, AliasIntegrationTest, SimplePostToolTest, TestSolrXmlPersistence, RequiredFieldsTest, NotRequiredUniqueKeyTest, TestNRTOpen, TermVectorComponentDistributedTest, TestCoreDiscovery, MigrateRouteKeyTest, OutputWriterTest, MinimalSchemaTest, TestSweetSpotSimilarityFactory, TestClassNameShortening, TestLazyCores, TestQuerySenderNoQuery, AddSchemaFieldsUpdateProcessorFactoryTest, TestSolrDeletionPolicy2, TestFieldTypeResource, TestCollationField, UniqFieldsUpdateProcessorFactoryTest, TestSchemaResource, BadCopyFieldTest, TestStressReorder, TestAnalyzedSuggestions, AlternateDirectoryTest, TestStressUserVersions, TestFastOutputStream, TestUniqueKeyFieldResource, IndexBasedSpellCheckerTest, TestCodecSupport, TestDistributedGrouping, HighlighterConfigTest, IndexSchemaRuntimeFieldTest, DocValuesMultiTest, XmlUpdateRequestHandlerTest, TestQuerySenderListener, ZkSolrClientTest, SuggesterWFSTTest, QueryFacetTest, ChaosMonkeySafeLeaderTest, AbstractAnalyticsStatsTest, RequestHandlersTest, TestHashPartitioner, TestFoldingMultitermQuery, FileUtilsTest, DistributedQueryComponentCustomSortTest, StandardRequestHandlerTest, TestCopyFieldCollectionResource, AddBlockUpdateTest, TestFileDictionaryLookup, TestBinaryResponseWriter, TestRandomFaceting, TestIndexingPerformance, TestRTGBase, TestSchemaNameResource, RAMDirectoryFactoryTest, CollectionsAPIDistributedZkTest, TestSolrCoreProperties, SolrRequestParserTest, TestIBSimilarityFactory, OpenExchangeRatesOrgProviderTest, CachingDirectoryFactoryTest, PathHierarchyTokenizerFactoryTest, LeaderElectionIntegrationTest, StressHdfsTest, PluginInfoTest, BlockCacheTest, HighlighterTest, XsltUpdateRequestHandlerTest, TestStressRecovery, TestRandomMergePolicy, TestSolrXml, TestSolrQueryParserDefaultOperatorResource, DateFieldTest, SolrPluginUtilsTest, SpellingQueryConverterTest, TestDistributedSearch, SyncSliceTest, DistributedQueryElevationComponentTest, AnalysisAfterCoreReloadTest, SignatureUpdateProcessorFactoryTest, SpellCheckCollatorTest, SuggesterTSTTest, TestStressLucene, NoCacheHeaderTest, SolrCoreCheckLockOnStartupTest, TestAtomicUpdateErrorCases, DefaultValueUpdateProcessorTest, TestRemoteStreaming, TestSolrDeletionPolicy1, DebugComponentTest, DisMaxRequestHandlerTest, TestWriterPerf, DocumentAnalysisRequestHandlerTest, PrimitiveFieldTypeTest, DocumentBuilderTest, FieldAnalysisRequestHandlerTest, TestArbitraryIndexDir, IndexSchemaTest, UpdateRequestProcessorFactoryTest, JSONWriterTest, MBeansHandlerTest, JsonLoaderTest, PingRequestHandlerTest, TestComponentsName, CoreContainerCoreInitFailuresTest, SOLR749Test, SolrIndexConfigTest, TestDocSet, TestBinaryField, TestElisionMultitermQuery, DirectSolrConnectionTest, TestPhraseSuggestions, TestCharFilters, TestXIncludeConfig, TestNumberUtils, TestBM25SimilarityFactory, TimeZoneUtilsTest, ResourceLoaderTest, ChaosMonkeyNothingIsSafeTest]
[junit4] Completed on J1 in 101.87s, 1 test, 1 failure <<< FAILURES!
[...truncated 127 lines...]
BUILD FAILED
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/build.xml:453: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/build.xml:433: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/build.xml:39: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/extra-targets.xml:37: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build.xml:189: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/common-build.xml:491: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/lucene/common-build.xml:1304: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/lucene/common-build.xml:937: There were test failures: 358 suites, 1577 tests, 1 failure, 35 ignored (6 assumptions)
Total time: 62 minutes 34 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure