You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2014/02/16 23:30:30 UTC
[JENKINS] Lucene-Solr-trunk-Linux (32bit/ibm-j9-jdk7) - Build #
9500 - Still Failing!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/9500/
Java: 32bit/ibm-j9-jdk7 -Xjit:exclude={org/apache/lucene/util/fst/FST.pack(IIF)Lorg/apache/lucene/util/fst/FST;}
5 tests failed.
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">17</int> </lst> <result name="response" numFound="100" start="0"> </result> <lst name="stats"> <lst name="stats_fields"/> </lst> <lst name="stats"> <lst name="mr"> <long name="count">100</long> <double name="mcm">70829.5</double> <double name="mean">28.91</double> <double name="median">24.5</double> <double name="su">104076.0</double> <double name="sum">2891.0</double> <long name="unique">36</long> </lst> <lst name="nr"> <double name="c">-100.0</double> <long name="count">100</long> <double name="s">-2891.0</double> <double name="sum">2891.0</double> </lst> <lst name="avr"> <double name="c">100.0</double> <long name="count">100</long> <double name="s">2891.0</double> <double name="sum">2891.0</double> </lst> <lst name="pr"> <long name="count">100</long> <double name="mc">1.2729436151389237E146</double> <double name="mean">28.91</double> <double name="su">3.9603293116911423E124</double> <double name="sum">2891.0</double> <long name="unique">36</long> </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="cnr"> <double name="c10">10.0</double> <double name="c8">8.0</double> </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="dr"> <long name="count">100</long> <null name="mc"/> <null name="mean"/> <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="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="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="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> </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">17</int>
</lst>
<result name="response" numFound="100" start="0">
</result>
<lst name="stats">
<lst name="stats_fields"/>
</lst>
<lst name="stats">
<lst name="mr">
<long name="count">100</long>
<double name="mcm">70829.5</double>
<double name="mean">28.91</double>
<double name="median">24.5</double>
<double name="su">104076.0</double>
<double name="sum">2891.0</double>
<long name="unique">36</long>
</lst>
<lst name="nr">
<double name="c">-100.0</double>
<long name="count">100</long>
<double name="s">-2891.0</double>
<double name="sum">2891.0</double>
</lst>
<lst name="avr">
<double name="c">100.0</double>
<long name="count">100</long>
<double name="s">2891.0</double>
<double name="sum">2891.0</double>
</lst>
<lst name="pr">
<long name="count">100</long>
<double name="mc">1.2729436151389237E146</double>
<double name="mean">28.91</double>
<double name="su">3.9603293116911423E124</double>
<double name="sum">2891.0</double>
<long name="unique">36</long>
</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="cnr">
<double name="c10">10.0</double>
<double name="c8">8.0</double>
</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="dr">
<long name="count">100</long>
<null name="mc"/>
<null name="mean"/>
<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="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="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="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>
</response>
at __randomizedtesting.SeedInfo.seed([9A8A0CE5F02C00B1:C287E713D4EE6267]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.analytics.AbstractAnalyticsStatsTest.getStatResult(AbstractAnalyticsStatsTest.java:114)
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:88)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:55)
at java.lang.reflect.Method.invoke(Method.java:613)
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:780)
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">17</int> </lst> <result name="response" numFound="100" start="0"> </result> <lst name="stats"> <lst name="stats_fields"/> </lst> <lst name="stats"> <lst name="mr"> <long name="count">100</long> <double name="mcm">70829.5</double> <double name="mean">28.91</double> <double name="median">24.5</double> <double name="su">104076.0</double> <double name="sum">2891.0</double> <long name="unique">36</long> </lst> <lst name="nr"> <double name="c">-100.0</double> <long name="count">100</long> <double name="s">-2891.0</double> <double name="sum">2891.0</double> </lst> <lst name="avr"> <double name="c">100.0</double> <long name="count">100</long> <double name="s">2891.0</double> <double name="sum">2891.0</double> </lst> <lst name="pr"> <long name="count">100</long> <double name="mc">1.2729436151389237E146</double> <double name="mean">28.91</double> <double name="su">3.9603293116911423E124</double> <double name="sum">2891.0</double> <long name="unique">36</long> </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="cnr"> <double name="c10">10.0</double> <double name="c8">8.0</double> </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="dr"> <long name="count">100</long> <null name="mc"/> <null name="mean"/> <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="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="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="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> </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">17</int>
</lst>
<result name="response" numFound="100" start="0">
</result>
<lst name="stats">
<lst name="stats_fields"/>
</lst>
<lst name="stats">
<lst name="mr">
<long name="count">100</long>
<double name="mcm">70829.5</double>
<double name="mean">28.91</double>
<double name="median">24.5</double>
<double name="su">104076.0</double>
<double name="sum">2891.0</double>
<long name="unique">36</long>
</lst>
<lst name="nr">
<double name="c">-100.0</double>
<long name="count">100</long>
<double name="s">-2891.0</double>
<double name="sum">2891.0</double>
</lst>
<lst name="avr">
<double name="c">100.0</double>
<long name="count">100</long>
<double name="s">2891.0</double>
<double name="sum">2891.0</double>
</lst>
<lst name="pr">
<long name="count">100</long>
<double name="mc">1.2729436151389237E146</double>
<double name="mean">28.91</double>
<double name="su">3.9603293116911423E124</double>
<double name="sum">2891.0</double>
<long name="unique">36</long>
</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="cnr">
<double name="c10">10.0</double>
<double name="c8">8.0</double>
</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="dr">
<long name="count">100</long>
<null name="mc"/>
<null name="mean"/>
<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="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="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="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>
</response>
at __randomizedtesting.SeedInfo.seed([9A8A0CE5F02C00B1:6CA6A13BB1E307F6]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.analytics.AbstractAnalyticsStatsTest.getStatResult(AbstractAnalyticsStatsTest.java:114)
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:88)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:55)
at java.lang.reflect.Method.invoke(Method.java:613)
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:780)
REGRESSION: org.apache.solr.handler.component.DistributedQueryElevationComponentTest.testDistribSearch
Error Message:
.responseHeader.params.rows!=sort (unordered or missing)
Stack Trace:
junit.framework.AssertionFailedError: .responseHeader.params.rows!=sort (unordered or missing)
at __randomizedtesting.SeedInfo.seed([9A8A0CE5F02C00B1:1B6C82FD8773608D]:0)
at junit.framework.Assert.fail(Assert.java:50)
at org.apache.solr.BaseDistributedSearchTestCase.compareSolrResponses(BaseDistributedSearchTestCase.java:843)
at org.apache.solr.BaseDistributedSearchTestCase.compareResponses(BaseDistributedSearchTestCase.java:862)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:565)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:545)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:524)
at org.apache.solr.handler.component.DistributedQueryElevationComponentTest.doTest(DistributedQueryElevationComponentTest.java:81)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:870)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:88)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:55)
at java.lang.reflect.Method.invoke(Method.java:613)
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:780)
REGRESSION: org.apache.solr.handler.component.TermVectorComponentDistributedTest.testDistribSearch
Error Message:
.termVectors.0.test_basictv!=test_offtv (unordered or missing)
Stack Trace:
junit.framework.AssertionFailedError: .termVectors.0.test_basictv!=test_offtv (unordered or missing)
at __randomizedtesting.SeedInfo.seed([9A8A0CE5F02C00B1:1B6C82FD8773608D]:0)
at junit.framework.Assert.fail(Assert.java:50)
at org.apache.solr.BaseDistributedSearchTestCase.compareSolrResponses(BaseDistributedSearchTestCase.java:843)
at org.apache.solr.BaseDistributedSearchTestCase.compareResponses(BaseDistributedSearchTestCase.java:862)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:565)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:545)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:524)
at org.apache.solr.handler.component.TermVectorComponentDistributedTest.doTest(TermVectorComponentDistributedTest.java:127)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:876)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:88)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:55)
at java.lang.reflect.Method.invoke(Method.java:613)
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:780)
REGRESSION: org.apache.solr.update.processor.SignatureUpdateProcessorFactoryTest.testDupeDetection
Error Message:
expected:<1> but was:<3>
Stack Trace:
java.lang.AssertionError: expected:<1> but was:<3>
at __randomizedtesting.SeedInfo.seed([9A8A0CE5F02C00B1:C1BAF72DF36BA3CD]: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.junit.Assert.assertEquals(Assert.java:456)
at org.apache.solr.update.processor.SignatureUpdateProcessorFactoryTest.checkNumDocs(SignatureUpdateProcessorFactoryTest.java:71)
at org.apache.solr.update.processor.SignatureUpdateProcessorFactoryTest.testDupeDetection(SignatureUpdateProcessorFactoryTest.java:119)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:88)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:55)
at java.lang.reflect.Method.invoke(Method.java:613)
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:780)
Build Log:
[...truncated 10090 lines...]
[junit4] Suite: org.apache.solr.handler.component.TermVectorComponentDistributedTest
[junit4] 2> 140130 T291 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (false)
[junit4] 2> 140130 T291 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4] 2> 140134 T291 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> 140136 T291 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 140138 T291 oejus.SslContextFactory.doStart Enabled Protocols [TLSv1] of [SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 140143 T291 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:44761
[junit4] 2> 140145 T291 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 140145 T291 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 140146 T291 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr
[junit4] 2> 140146 T291 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/'
[junit4] 2> 140158 T291 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/solr.xml
[junit4] 2> 140192 T291 oasc.CoreContainer.<init> New CoreContainer -1079207935
[junit4] 2> 140193 T291 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/]
[junit4] 2> 140194 T291 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 140195 T291 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: https
[junit4] 2> 140195 T291 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 140196 T291 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 140197 T291 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 140197 T291 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 140198 T291 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 140198 T291 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 140199 T291 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 140205 T291 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 140205 T291 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 140206 T291 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 140210 T301 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1
[junit4] 2> 140210 T301 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/'
[junit4] 2> 140210 T301 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 140211 T301 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 140264 T301 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 140316 T301 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 140317 T301 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 140327 T301 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 140665 T301 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 140672 T301 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 140674 T301 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 140682 T301 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 140686 T301 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 140689 T301 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 140690 T301 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 140690 T301 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 140691 T301 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 140691 T301 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 140692 T301 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 140692 T301 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 140692 T301 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/, dataDir=./org.apache.solr.handler.component.TermVectorComponentDistributedTest-1392588023048/control/data/
[junit4] 2> 140693 T301 oasc.JmxMonitoredMap.<init> No JMX servers found, not exposing Solr information with JMX.
[junit4] 2> 140694 T301 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.component.TermVectorComponentDistributedTest-1392588023048/control/data
[junit4] 2> 140694 T301 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.handler.component.TermVectorComponentDistributedTest-1392588023048/control/data/index/
[junit4] 2> 140694 T301 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.handler.component.TermVectorComponentDistributedTest-1392588023048/control/data/index' doesn't exist. Creating new index...
[junit4] 2> 140695 T301 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.component.TermVectorComponentDistributedTest-1392588023048/control/data/index
[junit4] 2> 140695 T301 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=20, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8550329942889382]
[junit4] 2> 140696 T301 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(BaseDirectoryWrapper(RAMDirectory@96e6ffea lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@daf7b81e)),segFN=segments_1,generation=1}
[junit4] 2> 140696 T301 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 140697 T301 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 140698 T301 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 140698 T301 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 140699 T301 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 140699 T301 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 140700 T301 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 140700 T301 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 140700 T301 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 140701 T301 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 140701 T301 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 140702 T301 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 140702 T301 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 140702 T301 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 140703 T301 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 140703 T301 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 140703 T301 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 140704 T301 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 140704 T301 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 140705 T301 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 140705 T301 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 140705 T301 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 140706 T301 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 140706 T301 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 140706 T301 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 140707 T301 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 140707 T301 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 140707 T301 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 140708 T301 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 140708 T301 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 140708 T301 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 140709 T301 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 140709 T301 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 140709 T301 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 140710 T301 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 140710 T301 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 140711 T301 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 140712 T301 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 140713 T301 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 140716 T301 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 140716 T301 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 140716 T301 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=20, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=0.5869140625, floorSegmentMB=0.419921875, forceMergeDeletesPctAllowed=5.12541051459408, segmentsPerTier=22.0, maxCFSSegmentSizeMB=0.310546875, noCFSRatio=0.8591470605894478
[junit4] 2> 140717 T301 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(BaseDirectoryWrapper(RAMDirectory@96e6ffea lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@daf7b81e)),segFN=segments_1,generation=1}
[junit4] 2> 140717 T301 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 140718 T301 oass.SolrIndexSearcher.<init> Opening Searcher@317617d6[collection1] main
[junit4] 2> 140718 T301 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 140829 T301 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 140846 T302 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@317617d6[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 140846 T301 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 140847 T291 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4] 2> 140848 T291 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 140850 T291 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 140853 T291 oejus.SslContextFactory.doStart Enabled Protocols [TLSv1] of [SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 140856 T291 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:56489
[junit4] 2> 140858 T291 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 140859 T291 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 140860 T291 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr
[junit4] 2> 140861 T291 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/'
[junit4] 2> 140874 T291 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/solr.xml
[junit4] 2> 140908 T291 oasc.CoreContainer.<init> New CoreContainer 1455662676
[junit4] 2> 140909 T291 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/]
[junit4] 2> 140910 T291 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 140911 T291 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: https
[junit4] 2> 140912 T291 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 140912 T291 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 140913 T291 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 140913 T291 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 140914 T291 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 140915 T291 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 140915 T291 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 140921 T291 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 140921 T291 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 140922 T291 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 140926 T313 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1
[junit4] 2> 140927 T313 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/'
[junit4] 2> 140927 T313 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 140928 T313 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 140967 T313 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 141023 T313 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 141023 T313 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 141030 T313 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 141354 T313 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 141361 T313 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 141363 T313 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 141371 T313 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 141375 T313 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 141378 T313 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 141379 T313 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 141379 T313 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 141380 T313 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 141380 T313 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 141381 T313 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 141381 T313 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 141381 T313 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/, dataDir=./org.apache.solr.handler.component.TermVectorComponentDistributedTest-1392588023048/shard0/data/
[junit4] 2> 141382 T313 oasc.JmxMonitoredMap.<init> No JMX servers found, not exposing Solr information with JMX.
[junit4] 2> 141383 T313 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.component.TermVectorComponentDistributedTest-1392588023048/shard0/data
[junit4] 2> 141383 T313 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.handler.component.TermVectorComponentDistributedTest-1392588023048/shard0/data/index/
[junit4] 2> 141383 T313 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.handler.component.TermVectorComponentDistributedTest-1392588023048/shard0/data/index' doesn't exist. Creating new index...
[junit4] 2> 141384 T313 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.handler.component.TermVectorComponentDistributedTest-1392588023048/shard0/data/index
[junit4] 2> 141384 T313 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=20, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8550329942889382]
[junit4] 2> 141385 T313 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(BaseDirectoryWrapper(RAMDirectory@7fd5591e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ecc186f1)),segFN=segments_1,generation=1}
[junit4] 2> 141385 T313 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 141386 T313 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 141387 T313 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 141387 T313 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 141388 T313 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 141388 T313 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 141388 T313 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 141389 T313 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 141389 T313 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 141390 T313 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 141390 T313 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 141391 T313 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 141391 T313 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 141391 T313 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 141392 T313 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 141392 T313 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 141392 T313 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 141393 T313 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 141393 T313 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 141393 T313 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 141394 T313 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 141394 T313 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 141394 T313 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 141395 T313 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 141395 T313 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 141395 T313 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 141396 T313 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 141396 T313 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 141397 T313 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 141397 T313 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 141397 T313 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 141398 T313 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 141398 T313 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 141398 T313 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 141399 T313 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 141399 T313 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 141399 T313 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 141401 T313 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 141402 T313 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 141405 T313 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 141405 T313 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 141406 T313 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=20, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=0.5869140625, floorSegmentMB=0.419921875, forceMergeDeletesPctAllowed=5.12541051459408, segmentsPerTier=22.0, maxCFSSegmentSizeMB=0.310546875, noCFSRatio=0.8591470605894478
[junit4] 2> 141406 T313 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(BaseDirectoryWrapper(RAMDirectory@7fd5591e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ecc186f1)),segFN=segments_1,generation=1}
[junit4] 2> 141407 T313 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 141407 T313 oass.SolrIndexSearcher.<init> Opening Searcher@906d7236[collection1] main
[junit4] 2> 141408 T313 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 141412 T313 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 141431 T314 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@906d7236[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 141431 T313 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 141433 T291 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4] 2> 141434 T291 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> ASYNC NEW_CORE C511 name=collection1 org.apache.solr.core.SolrCore@8540807f
[junit4] 2> 141545 T300 C511 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[0 (1460234380330926080)]} 0 3
[junit4] 2> ASYNC NEW_CORE C512 name=collection1 org.apache.solr.core.SolrCore@facfb57e
[junit4] 2> 141654 T309 C512 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[0 (1460234380445220864)]} 0 3
[junit4] 2> 141658 T297 C511 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[1 (1460234380451512320)]} 0 1
[junit4] 2> 141663 T310 C512 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[1 (1460234380455706624)]} 0 2
[junit4] 2> 141666 T298 C511 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[2 (1460234380459900928)]} 0 1
[junit4] 2> 141670 T312 C512 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[2 (1460234380464095232)]} 0 1
[junit4] 2> 141674 T299 C511 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[3 (1460234380467240960)]} 0 2
[junit4] 2> 141678 T309 C512 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[3 (1460234380472483840)]} 0 1
[junit4] 2> 141681 T300 C511 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[4 (1460234380475629568)]} 0 1
[junit4] 2> ASYNC NEW_CORE C513 name=collection1 org.apache.solr.core.SolrCore@facfb57e
[junit4] 2> 141692 T311 C513 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[4 (1460234380479823872)]} 0 9
[junit4] 2> ASYNC NEW_CORE C514 name=collection1 org.apache.solr.core.SolrCore@8540807f
[junit4] 2> 141696 T297 C514 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[5 (1460234380491358208)]} 0 1
[junit4] 2> 141699 T310 C513 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[5 (1460234380494503936)]} 0 1
[junit4] 2> 141702 T298 C514 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[6 (1460234380498698240)]} 0 1
[junit4] 2> 141705 T312 C513 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[6 (1460234380501843968)]} 0 0
[junit4] 2> 141709 T299 C514 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[7 (1460234380504989696)]} 0 1
[junit4] 2> 141713 T309 C513 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[7 (1460234380509184000)]} 0 1
[junit4] 2> 141716 T300 C514 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[8 (1460234380512329728)]} 0 1
[junit4] 2> 141719 T311 C513 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[8 (1460234380515475456)]} 0 1
[junit4] 2> 141723 T297 C514 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[9 (1460234380519669760)]} 0 1
[junit4] 2> 141727 T310 C513 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={version=2&wt=javabin} {add=[9 (1460234380523864064)]} 0 1
[junit4] 2> 141759 T298 C514 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 141764 T298 C514 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(BaseDirectoryWrapper(RAMDirectory@96e6ffea lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@daf7b81e)),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(BaseDirectoryWrapper(RAMDirectory@96e6ffea lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@daf7b81e)),segFN=segments_2,generation=2}
[junit4] 2> 141765 T298 C514 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 141768 T298 C514 oass.SolrIndexSearcher.<init> Opening Searcher@b5b1c88e[collection1] main
[junit4] 2> 141770 T298 C514 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 141770 T302 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@b5b1c88e[collection1] main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):C10)}
[junit4] 2> 141771 T298 C514 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={commit=true&wt=javabin&version=2&waitSearcher=true&softCommit=false} {commit=} 0 12
[junit4] 2> 141774 T312 C513 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 141778 T312 C513 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(BaseDirectoryWrapper(RAMDirectory@7fd5591e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ecc186f1)),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(BaseDirectoryWrapper(RAMDirectory@7fd5591e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ecc186f1)),segFN=segments_2,generation=2}
[junit4] 2> 141778 T312 C513 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 141780 T312 C513 oass.SolrIndexSearcher.<init> Opening Searcher@b14a4f97[collection1] main
[junit4] 2> 141781 T312 C513 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 141781 T314 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@b14a4f97[collection1] main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):C10)}
[junit4] 2> 141782 T312 C513 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&commit=true&version=2&waitSearcher=true&softCommit=false} {commit=} 0 8
[junit4] 2> 141790 T297 C514 oasc.SolrCore.execute [collection1] webapp= path=/select params={version=2&distrib=false&qt=tvrh&tv=true&shards.qt=tvrh&sort=id+desc&wt=javabin&q=id:0&tv.tf=true} hits=1 status=0 QTime=5
[junit4] 2> 141898 T310 C513 oasc.SolrCore.execute [collection1] webapp= path=/select params={qt=tvrh&distrib=false&version=2&shards.qt=tvrh&tv=true&isShard=true&NOW=1392588024705&fsv=true&tv.tf=true&fl=id&wt=javabin&q=id:0&start=0&rows=10&sort=id+desc&shard.url=[ff01::083]:33332/|https://127.0.0.1:56489/|[ff01::114]:33332/|[ff01::213]:33332/} hits=1 status=0 QTime=0
[junit4] 2> 141902 T312 C513 oasc.SolrCore.execute [collection1] webapp= path=/select params={distrib=false&qt=tvrh&ids=0&version=2&tv=true&shards.qt=tvrh&isShard=true&NOW=1392588024705&tv.tf=true&wt=javabin&q=id:0&shard.url=[ff01::083]:33332/|https://127.0.0.1:56489/|[ff01::114]:33332/|[ff01::213]:33332/} status=0 QTime=0
[junit4] 2> 141904 T309 C513 oasc.SolrCore.execute [collection1] webapp= path=/select params={shards=[ff01::083]:33332/|https://127.0.0.1:56489/|[ff01::114]:33332/|[ff01::213]:33332/&tv.tf=true&wt=javabin&q=id:0&sort=id+desc&tv=true&shards.qt=tvrh&qt=tvrh&version=2} hits=1 status=0 QTime=112
[junit4] 2> 141907 T299 C514 oasc.SolrCore.execute [collection1] webapp= path=/select params={wt=javabin&q=id:0&tv.tf=true&fl=*,score&sort=id+asc&tv=true&tv.fl=test_basictv,test_offtv&shards.qt=tvrh&version=2&distrib=false&qt=tvrh} hits=1 status=0 QTime=1
[junit4] 2> 141911 T310 C513 oasc.SolrCore.execute [collection1] webapp= path=/select params={start=0&tv.fl=test_basictv,test_offtv&sort=id+asc&rows=10&shard.url=https://127.0.0.1:56489/&version=2&qt=tvrh&distrib=false&isShard=true&shards.qt=tvrh&tv=true&fsv=true&NOW=1392588024822&wt=javabin&q=id:0&tv.tf=true&fl=id,score} hits=1 status=0 QTime=0
[junit4] 2> 141914 T312 C513 oasc.SolrCore.execute [collection1] webapp= path=/select params={NOW=1392588024822&q=id:0&wt=javabin&tv.tf=true&fl=*,score&version=2&ids=0&qt=tvrh&distrib=false&isShard=true&shards.qt=tvrh&tv=true&shard.url=https://127.0.0.1:56489/&tv.fl=test_basictv,test_offtv} status=0 QTime=0
[junit4] 2> 141915 T311 C513 oasc.SolrCore.execute [collection1] webapp= path=/select params={qt=tvrh&version=2&shards.qt=tvrh&tv=true&tv.fl=test_basictv,test_offtv&sort=id+asc&shards=https://127.0.0.1:56489/&tv.tf=true&fl=*,score&wt=javabin&q=id:0} hits=1 status=0 QTime=6
[junit4] 2> 141917 T291 oas.BaseDistributedSearchTestCase.compareSolrResponses ERROR Mismatched responses:
[junit4] 2> {responseHeader={status=0,QTime=6},response={numFound=1,start=0,maxScore=2.609438,docs=[SolrDocument{id=0, range_facet_si=0, range_facet_l=[0], range_facet_sl=[0], test_posofftv=[This is a title and another title], test_basictv=[This is a title and another title], test_notv=[This is a title and another title], test_postv=[This is a title and another title], test_offtv=[This is a title and another title], _version_=1460234380445220864, intDefault=42, timestamp=Mon Feb 17 03:30:24 IST 2014, multiDefault=[muLti-Default], score=2.609438}]},termVectors={uniqueKeyFieldName=id,0={uniqueKey=0,test_basictv={anoth={tf=1},titl={tf=2}},test_offtv={anoth={tf=1},titl={tf=2}}}}}
[junit4] 2> {responseHeader={status=0,QTime=1},response={numFound=1,start=0,maxScore=2.609438,docs=[SolrDocument{id=0, range_facet_si=0, range_facet_l=[0], range_facet_sl=[0], test_posofftv=[This is a title and another title], test_basictv=[This is a title and another title], test_notv=[This is a title and another title], test_postv=[This is a title and another title], test_offtv=[This is a title and another title], _version_=1460234380330926080, intDefault=42, timestamp=Mon Feb 17 03:30:24 IST 2014, multiDefault=[muLti-Default], score=2.609438}]},termVectors={uniqueKeyFieldName=id,0={uniqueKey=0,test_offtv={anoth={tf=1},titl={tf=2}},test_basictv={anoth={tf=1},titl={tf=2}}}}}
[junit4] 2> 141938 T291 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=-1079207935
[junit4] 2> 141940 T291 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@8540807f
[junit4] 2> 141940 T291 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=10,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0,transaction_logs_total_size=1285,transaction_logs_total_number=1}
[junit4] 2> 141941 T291 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 141942 T291 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 141942 T291 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 141944 T291 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 141944 T291 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 141945 T291 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.component.TermVectorComponentDistributedTest-1392588023048/control/data [CachedDir<<refCount=0;path=./org.apache.solr.handler.component.TermVectorComponentDistributedTest-1392588023048/control/data;done=false>>]
[junit4] 2> 141946 T291 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.component.TermVectorComponentDistributedTest-1392588023048/control/data
[junit4] 2> 141946 T291 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.component.TermVectorComponentDistributedTest-1392588023048/control/data/index [CachedDir<<refCount=0;path=./org.apache.solr.handler.component.TermVectorComponentDistributedTest-1392588023048/control/data/index;done=false>>]
[junit4] 2> 141947 T291 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.component.TermVectorComponentDistributedTest-1392588023048/control/data/index
[junit4] 2> 141959 T291 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/,null}
[junit4] 2> 142033 T291 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1455662676
[junit4] 2> 142034 T291 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@facfb57e
[junit4] 2> 142035 T291 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=10,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0,transaction_logs_total_size=1285,transaction_logs_total_number=1}
[junit4] 2> 142036 T291 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 142037 T291 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 142038 T291 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 142048 T291 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 142049 T291 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 142050 T291 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.component.TermVectorComponentDistributedTest-1392588023048/shard0/data/index [CachedDir<<refCount=0;path=./org.apache.solr.handler.component.TermVectorComponentDistributedTest-1392588023048/shard0/data/index;done=false>>]
[junit4] 2> 142051 T291 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.component.TermVectorComponentDistributedTest-1392588023048/shard0/data/index
[junit4] 2> 142075 T291 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.component.TermVectorComponentDistributedTest-1392588023048/shard0/data [CachedDir<<refCount=0;path=./org.apache.solr.handler.component.TermVectorComponentDistributedTest-1392588023048/shard0/data;done=false>>]
[junit4] 2> 142076 T291 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.component.TermVectorComponentDistributedTest-1392588023048/shard0/data
[junit4] 2> 142078 T291 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/,null}
[junit4] 2> 142137 T291 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TermVectorComponentDistributedTest -Dtests.method=testDistribSearch -Dtests.seed=9A8A0CE5F02C00B1 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=mt -Dtests.timezone=Asia/Kolkata -Dtests.file.encoding=UTF-8
[junit4] FAILURE 2.02s J1 | TermVectorComponentDistributedTest.testDistribSearch <<<
[junit4] > Throwable #1: junit.framework.AssertionFailedError: .termVectors.0.test_basictv!=test_offtv (unordered or missing)
[junit4] > at __randomizedtesting.SeedInfo.seed([9A8A0CE5F02C00B1:1B6C82FD8773608D]:0)
[junit4] > at junit.framework.Assert.fail(Assert.java:50)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.compareSolrResponses(BaseDistributedSearchTestCase.java:843)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.compareResponses(BaseDistributedSearchTestCase.java:862)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:565)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:545)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:524)
[junit4] > at org.apache.solr.handler.component.TermVectorComponentDistributedTest.doTest(TermVectorComponentDistributedTest.java:127)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:876)
[junit4] > at java.lang.Thread.run(Thread.java:780)
[junit4] 2> 142150 T291 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: test params are: codec=CheapBastard, sim=DefaultSimilarity, locale=mt, timezone=Asia/Kolkata
[junit4] 2> NOTE: Linux 3.8.0-35-generic x86/IBM Corporation 1.7.0 (32-bit)/cpus=8,threads=1,free=169687080,total=259588096
[junit4] 2> NOTE: All tests run in this JVM: [TestReloadAndDeleteDocs, IndexSchemaRuntimeFieldTest, TestQuerySenderNoQuery, SolrCoreCheckLockOnStartupTest, XsltUpdateRequestHandlerTest, TestFreeTextSuggestions, TestCodecSupport, TestIBSimilarityFactory, TestSolrXmlPersistor, TestDistributedSearch, TestStressRecovery, CurrencyFieldOpenExchangeTest, NoFacetTest, TestSolr4Spatial, TestCollapseQParserPlugin, IndexBasedSpellCheckerTest, TestSolrJ, TestAddFieldRealTimeGet, InfoHandlerTest, TermVectorComponentDistributedTest]
[junit4] Completed on J1 in 2.05s, 1 test, 1 failure <<< FAILURES!
[...truncated 182 lines...]
[junit4] Suite: org.apache.solr.analytics.expression.ExpressionTest
[junit4] 2> 296930 T1228 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (true)
[junit4] 2> 296932 T1228 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-ExpressionTest-1392588179815
[junit4] 2> 296933 T1228 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/'
[junit4] 2> 296936 T1228 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 296938 T1228 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 297000 T1228 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 297053 T1228 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-basic.xml
[junit4] 2> 297055 T1228 oass.IndexSchema.readSchema Reading Solr Schema from schema-analytics.xml
[junit4] 2> 297067 T1228 oass.IndexSchema.readSchema [null] Schema name=schema-docValues
[junit4] 2> 297114 T1228 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 297117 T1228 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 297118 T1228 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr
[junit4] 2> 297119 T1228 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/'
[junit4] 2> 297191 T1228 oasc.CoreContainer.<init> New CoreContainer 1688333028
[junit4] 2> 297192 T1228 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/]
[junit4] 2> 297194 T1228 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 297195 T1228 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null
[junit4] 2> 297195 T1228 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 297196 T1228 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 297197 T1228 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 297198 T1228 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 297199 T1228 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 297200 T1228 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 297200 T1228 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 297224 T1228 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 297225 T1228 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 297225 T1228 oasc.CoreContainer.load Host Name:
[junit4] 2> 297241 T1229 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1
[junit4] 2> 297242 T1229 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/'
[junit4] 2> 297243 T1229 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 297243 T1229 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 297321 T1229 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 297372 T1229 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-basic.xml
[junit4] 2> 297374 T1229 oass.IndexSchema.readSchema Reading Solr Schema from schema-analytics.xml
[junit4] 2> 297386 T1229 oass.IndexSchema.readSchema [collection1] Schema name=schema-docValues
[junit4] 2> 297433 T1229 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 297436 T1229 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 297437 T1229 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-ExpressionTest-1392588179815/
[junit4] 2> 297438 T1229 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 297440 T1229 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-ExpressionTest-1392588179815
[junit4] 2> 297441 T1229 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-ExpressionTest-1392588179815/index/
[junit4] 2> 297441 T1229 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-ExpressionTest-1392588179815/index' doesn't exist. Creating new index...
[junit4] 2> 297443 T1229 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-ExpressionTest-1392588179815/index
[junit4] 2> 297443 T1229 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@997ec5e9
[junit4] 2> 297447 T1229 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@35774fe6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b64c649b),segFN=segments_1,generation=1}
[junit4] 2> 297447 T1229 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 297456 T1229 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 297457 T1229 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 297457 T1229 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 297458 T1229 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 297460 T1229 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 297461 T1229 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 297461 T1229 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=33, maxMergeAtOnceExplicit=14, maxMergedSegmentMB=56.640625, floorSegmentMB=0.701171875, forceMergeDeletesPctAllowed=5.5462420026927575, segmentsPerTier=27.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.28320537298108245
[junit4] 2> 297462 T1229 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@35774fe6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b64c649b),segFN=segments_1,generation=1}
[junit4] 2> 297462 T1229 o
[...truncated too long message...]
mit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@4493f09b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@d448eea6)),segFN=segments_10,generation=36}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@4493f09b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@d448eea6)),segFN=segments_11,generation=37}
[junit4] 2> 1514019 T3610 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 37
[junit4] 2> 1514020 T3610 oass.SolrIndexSearcher.<init> Opening Searcher@600286d0[collection1] main
[junit4] 2> 1514021 T3610 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1514023 T3612 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@600286d0[collection1] main{StandardDirectoryReader(segments_11:164:nrt _1d(5.0):c2)}
[junit4] 2> 1514026 T3610 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1514028 T3610 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@4493f09b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@d448eea6)),segFN=segments_11,generation=37}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@4493f09b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@d448eea6)),segFN=segments_12,generation=38}
[junit4] 2> 1514028 T3610 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 38
[junit4] 2> 1514029 T3610 oass.SolrIndexSearcher.<init> Opening Searcher@f84c9647[collection1] main
[junit4] 2> 1514030 T3610 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1514032 T3612 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@f84c9647[collection1] main{StandardDirectoryReader(segments_12:166:nrt _1d(5.0):c2 _1e(5.0):c1)}
[junit4] 2> 1514032 T3610 oas.SolrTestCaseJ4.tearDown ###Ending testDupeDetection
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SignatureUpdateProcessorFactoryTest -Dtests.method=testDupeDetection -Dtests.seed=9A8A0CE5F02C00B1 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=sl -Dtests.timezone=Iran -Dtests.file.encoding=UTF-8
[junit4] FAILURE 0.05s J0 | SignatureUpdateProcessorFactoryTest.testDupeDetection <<<
[junit4] > Throwable #1: java.lang.AssertionError: expected:<1> but was:<3>
[junit4] > at __randomizedtesting.SeedInfo.seed([9A8A0CE5F02C00B1:C1BAF72DF36BA3CD]:0)
[junit4] > at org.apache.solr.update.processor.SignatureUpdateProcessorFactoryTest.checkNumDocs(SignatureUpdateProcessorFactoryTest.java:71)
[junit4] > at org.apache.solr.update.processor.SignatureUpdateProcessorFactoryTest.testDupeDetection(SignatureUpdateProcessorFactoryTest.java:119)
[junit4] > at java.lang.Thread.run(Thread.java:780)
[junit4] 2> 1514047 T3610 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 1514048 T3610 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=-1728705661
[junit4] 2> 1514048 T3610 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@9637e1be
[junit4] 2> 1514057 T3610 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=42,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=253,cumulative_deletesById=0,cumulative_deletesByQuery=6,cumulative_errors=0}
[junit4] 2> 1514058 T3610 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 1514058 T3610 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1514059 T3610 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 1514060 T3610 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 1514060 T3610 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1514061 T3610 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-SignatureUpdateProcessorFactoryTest-1392589395372/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-SignatureUpdateProcessorFactoryTest-1392589395372/index;done=false>>]
[junit4] 2> 1514062 T3610 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-SignatureUpdateProcessorFactoryTest-1392589395372/index
[junit4] 2> 1514062 T3610 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-SignatureUpdateProcessorFactoryTest-1392589395372 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-SignatureUpdateProcessorFactoryTest-1392589395372;done=false>>]
[junit4] 2> 1514063 T3610 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-SignatureUpdateProcessorFactoryTest-1392589395372
[junit4] 2> NOTE: test params are: codec=CheapBastard, sim=RandomSimilarityProvider(queryNorm=false,coord=crazy): {}, locale=sl, timezone=Iran
[junit4] 2> NOTE: Linux 3.8.0-35-generic x86/IBM Corporation 1.7.0 (32-bit)/cpus=8,threads=1,free=87643016,total=369426432
[junit4] 2> NOTE: All tests run in this JVM: [TestMergePolicyConfig, DistributedQueryComponentCustomSortTest, TestJmxMonitoredMap, HdfsCollectionsAPIDistributedZkTest, JsonLoaderTest, FileBasedSpellCheckerTest, HdfsUnloadDistributedZkTest, TestSearchPerf, TestShardHandlerFactory, TestHighlightDedupGrouping, TestExtendedDismaxParser, TestCharFilters, SpatialFilterTest, TestSurroundQueryParser, DistributedDebugComponentTest, CoreAdminCreateDiscoverTest, TestDocSet, TestUniqueKeyFieldResource, MoreLikeThisHandlerTest, LoggingHandlerTest, DistributedSpellCheckComponentTest, TestFaceting, PrimitiveFieldTypeTest, SuggesterTest, TestSerializedLuceneMatchVersion, TestJoin, TestLuceneMatchVersion, DocValuesMissingTest, TestSuggestSpellingConverter, TestDynamicFieldCollectionResource, TestDefaultSearchFieldResource, TestMaxScoreQueryParser, AssignTest, TestNonDefinedSimilarityFactory, QueryResultKeyTest, TestFieldTypeCollectionResource, TestHighFrequencyDictionaryFactory, SpellPossibilityIteratorTest, TestReversedWildcardFilterFactory, QueryEqualityTest, TestPHPSerializedResponseWriter, UUIDFieldTest, TestLMDirichletSimilarityFactory, ResponseLogComponentTest, CacheHeaderTest, TestRemoteStreaming, ClusterStateTest, TestConfig, HdfsDirectoryTest, TestTrie, MBeansHandlerTest, SolrCoreTest, TestSolrXmlPersistence, ShardRoutingTest, CircularListTest, SoftAutoCommitTest, TestQueryUtils, LegacyHTMLStripCharFilterTest, AnalysisErrorHandlingTest, AnalysisAfterCoreReloadTest, SolrInfoMBeanTest, BasicFunctionalityTest, AbstractAnalyticsStatsTest, HdfsBasicDistributedZk2Test, TermVectorComponentTest, SynonymTokenizerTest, RangeFacetTest, DistributedSuggestComponentTest, ExpressionTest, CustomCollectionTest, TestStandardQParsers, TestSolrXml, MigrateRouteKeyTest, QueryElevationComponentTest, BadComponentTest, SolrXmlInZkTest, CoreMergeIndexesAdminHandlerTest, DistribCursorPagingTest, UnloadDistributedZkTest, TestRandomDVFaceting, TestPostingsSolrHighlighter, TestElisionMultitermQuery, TestSystemIdResolver, PreAnalyzedUpdateProcessorTest, TestCopyFieldCollectionResource, SyncSliceTest, ResourceLoaderTest, FullSolrCloudDistribCmdsTest, ClusterStateUpdateTest, ZkSolrClientTest, ZkCLITest, TestRecovery, TestRealTimeGet, TestReload, HardAutoCommitTest, DistributedTermsComponentTest, TestCoreContainer, SpellCheckComponentTest, PeerSyncTest, BadIndexSchemaTest, TestSort, TestFunctionQuery, TestLazyCores, TestBadConfig, OverseerCollectionProcessorTest, TestIndexSearcher, ShowFileRequestHandlerTest, SolrIndexSplitterTest, SignatureUpdateProcessorFactoryTest]
[junit4] Completed on J0 in 1.60s, 6 tests, 1 failure <<< FAILURES!
[...truncated 457 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:453: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:446: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/extra-targets.xml:37: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:189: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:491: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1268: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:901: There were test failures: 372 suites, 1604 tests, 5 failures, 35 ignored (7 assumptions)
Total time: 59 minutes 0 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/ibm-j9-jdk7 -Xjit:exclude={org/apache/lucene/util/fst/FST.pack(IIF)Lorg/apache/lucene/util/fst/FST;}
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
[JENKINS] Lucene-Solr-trunk-Linux (32bit/jdk1.8.0-fcs-b129) - Build
# 9501 - Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/9501/
Java: 32bit/jdk1.8.0-fcs-b129 -server -XX:+UseG1GC
1 tests failed.
REGRESSION: org.apache.solr.TestDistributedSearch.testDistribSearch
Error Message:
Expected the partialResults header to be null
Stack Trace:
java.lang.AssertionError: Expected the partialResults header to be null
at __randomizedtesting.SeedInfo.seed([ED137D06E24A61FB:6CF5F31E951501C7]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNull(Assert.java:551)
at org.apache.solr.TestDistributedSearch.validateControlData(TestDistributedSearch.java:525)
at org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:445)
at org.apache.solr.TestDistributedSearch.doTest(TestDistributedSearch.java:379)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:876)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:483)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at java.lang.Thread.run(Thread.java:744)
Build Log:
[...truncated 10600 lines...]
[junit4] Suite: org.apache.solr.TestDistributedSearch
[junit4] 2> 1088307 T4611 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (true)
[junit4] 2> 1088308 T4611 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4] 2> 1088311 T4611 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> 1088312 T4611 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1088317 T4611 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:50359
[junit4] 2> 1088317 T4611 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1088318 T4611 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1088318 T4611 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr
[junit4] 2> 1088319 T4611 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/'
[junit4] 2> 1088329 T4611 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/solr.xml
[junit4] 2> 1088337 T4611 oasc.CoreContainer.<init> New CoreContainer 1116020
[junit4] 2> 1088338 T4611 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/]
[junit4] 2> 1088338 T4611 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 1088339 T4611 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 1088339 T4611 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1088339 T4611 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1088340 T4611 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1088340 T4611 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1088340 T4611 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1088341 T4611 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1088341 T4611 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1088343 T4611 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1088343 T4611 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1088343 T4611 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 1088346 T4621 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1
[junit4] 2> 1088346 T4621 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/'
[junit4] 2> 1088346 T4621 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 1088346 T4621 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 1088370 T4621 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 1088394 T4621 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1088395 T4621 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1088399 T4621 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1088556 T4621 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1088562 T4621 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1088564 T4621 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1088569 T4621 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1088572 T4621 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1088574 T4621 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1088574 T4621 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1088575 T4621 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1088575 T4621 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1088575 T4621 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1088575 T4621 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1088576 T4621 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1088576 T4621 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/, dataDir=./org.apache.solr.TestDistributedSearch-1392596342598/control/data/
[junit4] 2> 1088576 T4621 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1ce88ef
[junit4] 2> 1088577 T4621 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.TestDistributedSearch-1392596342598/control/data
[junit4] 2> 1088577 T4621 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.TestDistributedSearch-1392596342598/control/data/index/
[junit4] 2> 1088577 T4621 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.TestDistributedSearch-1392596342598/control/data/index' doesn't exist. Creating new index...
[junit4] 2> 1088578 T4621 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.TestDistributedSearch-1392596342598/control/data/index
[junit4] 2> 1088578 T4621 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1808897326, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 1088579 T4621 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@128608 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@d75863)),segFN=segments_1,generation=1}
[junit4] 2> 1088580 T4621 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1088580 T4621 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 1088583 T4621 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 1088583 T4621 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1088583 T4621 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 1088583 T4621 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 1088584 T4621 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 1088584 T4621 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1088584 T4621 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 1088585 T4621 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 1088585 T4621 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1088585 T4621 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1088586 T4621 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1088586 T4621 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1088586 T4621 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 1088586 T4621 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 1088587 T4621 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1088587 T4621 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 1088587 T4621 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 1088588 T4621 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 1088588 T4621 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 1088589 T4621 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 1088589 T4621 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 1088589 T4621 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 1088590 T4621 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 1088590 T4621 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 1088590 T4621 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1088591 T4621 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1088591 T4621 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1088591 T4621 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1088592 T4621 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1088592 T4621 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1088593 T4621 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1088593 T4621 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1088593 T4621 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1088594 T4621 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 1088594 T4621 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 1088596 T4621 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1088597 T4621 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 1088599 T4621 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1088599 T4621 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1088600 T4621 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=39, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 1088600 T4621 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@128608 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@d75863)),segFN=segments_1,generation=1}
[junit4] 2> 1088600 T4621 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1088600 T4621 oass.SolrIndexSearcher.<init> Opening Searcher@117e692[collection1] main
[junit4] 2> 1088601 T4621 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 1088605 T4621 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1088626 T4622 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@117e692[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 1088631 T4621 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1088632 T4611 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4] 2> 1088633 T4611 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1088634 T4611 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1088636 T4611 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:42269
[junit4] 2> 1088637 T4611 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1088637 T4611 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1088639 T4611 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr
[junit4] 2> 1088640 T4611 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/'
[junit4] 2> 1088654 T4611 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/solr.xml
[junit4] 2> 1088665 T4611 oasc.CoreContainer.<init> New CoreContainer 30725411
[junit4] 2> 1088666 T4611 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/]
[junit4] 2> 1088667 T4611 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 1088667 T4611 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 1088667 T4611 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1088668 T4611 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1088668 T4611 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1088669 T4611 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1088669 T4611 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1088670 T4611 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1088670 T4611 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1088673 T4611 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1088673 T4611 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1088674 T4611 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 1088675 T4633 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1
[junit4] 2> 1088676 T4633 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/'
[junit4] 2> 1088676 T4633 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 1088676 T4633 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 1088699 T4633 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 1088724 T4633 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1088725 T4633 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1088729 T4633 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1088847 T4633 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1088851 T4633 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1088852 T4633 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1088856 T4633 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1088858 T4633 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1088859 T4633 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1088860 T4633 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1088860 T4633 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1088860 T4633 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1088860 T4633 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1088860 T4633 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1088861 T4633 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1088861 T4633 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/, dataDir=./org.apache.solr.TestDistributedSearch-1392596342598/shard0/data/
[junit4] 2> 1088861 T4633 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1ce88ef
[junit4] 2> 1088862 T4633 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.TestDistributedSearch-1392596342598/shard0/data
[junit4] 2> 1088862 T4633 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.TestDistributedSearch-1392596342598/shard0/data/index/
[junit4] 2> 1088862 T4633 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.TestDistributedSearch-1392596342598/shard0/data/index' doesn't exist. Creating new index...
[junit4] 2> 1088862 T4633 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.TestDistributedSearch-1392596342598/shard0/data/index
[junit4] 2> 1088863 T4633 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1808897326, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 1088864 T4633 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@16e83bb lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1ce9d97)),segFN=segments_1,generation=1}
[junit4] 2> 1088864 T4633 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1088864 T4633 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 1088866 T4633 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 1088867 T4633 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1088867 T4633 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 1088867 T4633 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 1088867 T4633 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 1088867 T4633 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1088868 T4633 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 1088868 T4633 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 1088868 T4633 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1088868 T4633 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1088869 T4633 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1088869 T4633 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1088869 T4633 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 1088869 T4633 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 1088869 T4633 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1088870 T4633 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 1088870 T4633 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 1088870 T4633 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 1088871 T4633 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 1088871 T4633 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 1088871 T4633 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 1088872 T4633 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 1088872 T4633 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 1088872 T4633 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 1088872 T4633 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1088873 T4633 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1088873 T4633 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1088873 T4633 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1088874 T4633 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1088874 T4633 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1088874 T4633 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1088875 T4633 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1088875 T4633 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1088875 T4633 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 1088876 T4633 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 1088877 T4633 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1088878 T4633 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 1088879 T4633 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1088879 T4633 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1088880 T4633 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=39, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 1088880 T4633 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@16e83bb lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1ce9d97)),segFN=segments_1,generation=1}
[junit4] 2> 1088880 T4633 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1088880 T4633 oass.SolrIndexSearcher.<init> Opening Searcher@644c0f[collection1] main
[junit4] 2> 1088881 T4633 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 1088885 T4633 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1088902 T4634 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@644c0f[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 1088907 T4633 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1088907 T4611 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4] 2> 1088908 T4611 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> ASYNC NEW_CORE C4800 name=collection1 org.apache.solr.core.SolrCore@aad1b
[junit4] 2> 1088910 T4618 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1460243103164137472)} 0 0
[junit4] 2> ASYNC NEW_CORE C4801 name=collection1 org.apache.solr.core.SolrCore@2b2be5
[junit4] 2> 1088912 T4629 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1460243103165186048)} 0 1
[junit4] 2> 1088916 T4619 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[1 (1460243103169380352)]} 0 2
[junit4] 2> 1088919 T4630 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[1 (1460243103172526080)]} 0 1
[junit4] 2> 1088922 T4620 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[2 (1460243103176720384)]} 0 0
[junit4] 2> 1088926 T4631 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[2 (1460243103178817536)]} 0 2
[junit4] 2> 1088929 T4617 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[3 (1460243103184060416)]} 0 0
[junit4] 2> 1088932 T4632 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[3 (1460243103186157568)]} 0 1
[junit4] 2> 1088935 T4618 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[4 (1460243103190351872)]} 0 0
[junit4] 2> 1088938 T4629 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[4 (1460243103192449024)]} 0 1
[junit4] 2> 1088941 T4619 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[5 (1460243103196643328)]} 0 0
[junit4] 2> 1088944 T4630 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[5 (1460243103199789056)]} 0 1
[junit4] 2> 1088947 T4620 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[6 (1460243103201886208)]} 0 1
[junit4] 2> 1088950 T4631 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[6 (1460243103205031936)]} 0 0
[junit4] 2> 1088952 T4617 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[7 (1460243103208177664)]} 0 0
[junit4] 2> 1088955 T4632 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[7 (1460243103210274816)]} 0 1
[junit4] 2> 1088958 T4618 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[8 (1460243103213420544)]} 0 1
[junit4] 2> 1088960 T4629 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[8 (1460243103216566272)]} 0 0
[junit4] 2> 1088963 T4619 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[9 (1460243103218663424)]} 0 1
[junit4] 2> 1088966 T4630 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[9 (1460243103221809152)]} 0 1
[junit4] 2> 1088968 T4620 C4800 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1088975 T4620 C4800 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@128608 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@d75863)),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@128608 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@d75863)),segFN=segments_2,generation=2}
[junit4] 2> 1088975 T4620 C4800 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 1088978 T4620 C4800 oass.SolrIndexSearcher.<init> Opening Searcher@13c37e0[collection1] main
[junit4] 2> 1088979 T4620 C4800 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1088980 T4622 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@13c37e0[collection1] main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):C9)}
[junit4] 2> 1088980 T4620 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 12
[junit4] 2> 1088982 T4631 C4801 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1088988 T4631 C4801 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@16e83bb lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1ce9d97)),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@16e83bb lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1ce9d97)),segFN=segments_2,generation=2}
[junit4] 2> 1088989 T4631 C4801 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 1088990 T4631 C4801 oass.SolrIndexSearcher.<init> Opening Searcher@1e05f4e[collection1] main
[junit4] 2> 1088991 T4631 C4801 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1088992 T4634 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1e05f4e[collection1] main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):C9)}
[junit4] 2> 1088993 T4631 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 11
[junit4] 2> 1088998 T4618 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[10 (1460243103254315008)]} 0 3
[junit4] 2> 1089002 T4632 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[10 (1460243103258509312)]} 0 2
[junit4] 2> 1089005 T4619 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[11 (1460243103263752192)]} 0 1
[junit4] 2> 1089008 T4629 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[11 (1460243103265849344)]} 0 1
[junit4] 2> 1089011 T4620 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[12 (1460243103268995072)]} 0 1
[junit4] 2> 1089014 T4630 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[12 (1460243103272140800)]} 0 1
[junit4] 2> 1089016 T4617 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[13 (1460243103275286528)]} 0 0
[junit4] 2> 1089019 T4631 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[13 (1460243103277383680)]} 0 1
[junit4] 2> 1089022 T4618 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[1001 (1460243103280529408)]} 0 1
[junit4] 2> 1089024 T4632 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[1001 (1460243103283675136)]} 0 0
[junit4] 2> 1089027 T4619 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[14 (1460243103285772288)]} 0 1
[junit4] 2> 1089029 T4629 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[14 (1460243103288918016)]} 0 0
[junit4] 2> 1089032 T4620 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[15 (1460243103291015168)]} 0 1
[junit4] 2> 1089035 T4630 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[15 (1460243103294160896)]} 0 1
[junit4] 2> 1089037 T4617 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[16 (1460243103297306624)]} 0 0
[junit4] 2> 1089040 T4631 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[16 (1460243103300452352)]} 0 1
[junit4] 2> 1089044 T4618 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[17 (1460243103303598080)]} 0 2
[junit4] 2> 1089049 T4629 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[17 (1460243103307792384)]} 0 2
[junit4] 2> 1089052 T4619 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[100 (1460243103311986688)]} 0 1
[junit4] 2> 1089054 T4630 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[100 (1460243103315132416)]} 0 0
[junit4] 2> 1089057 T4620 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[101 (1460243103318278144)]} 0 1
[junit4] 2> 1089059 T4632 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[101 (1460243103320375296)]} 0 0
[junit4] 2> 1089062 T4617 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[102 (1460243103322472448)]} 0 1
[junit4] 2> 1089068 T4631 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[102 (1460243103329812480)]} 0 0
[junit4] 2> 1089071 T4618 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[103 (1460243103331909632)]} 0 1
[junit4] 2> 1089073 T4629 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[103 (1460243103334006784)]} 0 1
[junit4] 2> 1089076 T4619 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[104 (1460243103337152512)]} 0 1
[junit4] 2> 1089078 T4630 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[104 (1460243103340298240)]} 0 0
[junit4] 2> 1089081 T4620 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[105 (1460243103342395392)]} 0 1
[junit4] 2> 1089083 T4632 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[105 (1460243103345541120)]} 0 0
[junit4] 2> 1089086 T4617 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[106 (1460243103347638272)]} 0 1
[junit4] 2> 1089088 T4631 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[106 (1460243103349735424)]} 0 1
[junit4] 2> 1089091 T4618 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[107 (1460243103352881152)]} 0 1
[junit4] 2> 1089093 T4629 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[107 (1460243103356026880)]} 0 0
[junit4] 2> 1089096 T4619 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[108 (1460243103358124032)]} 0 1
[junit4] 2> 1089098 T4630 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[108 (1460243103361269760)]} 0 0
[junit4] 2> 1089101 T4620 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[109 (1460243103364415488)]} 0 0
[junit4] 2> 1089104 T4632 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[109 (1460243103366512640)]} 0 1
[junit4] 2> 1089106 T4617 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[110 (1460243103369658368)]} 0 0
[junit4] 2> 1089108 T4631 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[110 (1460243103371755520)]} 0 0
[junit4] 2> 1089111 T4618 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[111 (1460243103373852672)]} 0 1
[junit4] 2> 1089114 T4629 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[111 (1460243103376998400)]} 0 1
[junit4] 2> 1089116 T4620 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[112 (1460243103380144128)]} 0 0
[junit4] 2> 1089119 T4630 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[112 (1460243103383289856)]} 0 0
[junit4] 2> 1089122 T4617 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[113 (1460243103386435584)]} 0 1
[junit4] 2> 1089125 T4632 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[113 (1460243103388532736)]} 0 1
[junit4] 2> 1089127 T4618 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[114 (1460243103391678464)]} 0 0
[junit4] 2> 1089129 T4631 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[114 (1460243103393775616)]} 0 0
[junit4] 2> 1089132 T4619 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[115 (1460243103395872768)]} 0 1
[junit4] 2> 1089134 T4629 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[115 (1460243103399018496)]} 0 0
[junit4] 2> 1089137 T4620 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[116 (1460243103401115648)]} 0 1
[junit4] 2> 1089139 T4630 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[116 (1460243103404261376)]} 0 0
[junit4] 2> 1089141 T4617 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[117 (1460243103406358528)]} 0 0
[junit4] 2> 1089143 T4632 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[117 (1460243103408455680)]} 0 0
[junit4] 2> 1089146 T4618 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[118 (1460243103410552832)]} 0 1
[junit4] 2> 1089148 T4631 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[118 (1460243103413698560)]} 0 1
[junit4] 2> 1089150 T4619 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[119 (1460243103415795712)]} 0 0
[junit4] 2> 1089152 T4629 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[119 (1460243103417892864)]} 0 0
[junit4] 2> 1089155 T4620 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[120 (1460243103419990016)]} 0 1
[junit4] 2> 1089157 T4630 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[120 (1460243103423135744)]} 0 0
[junit4] 2> 1089159 T4617 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[121 (1460243103425232896)]} 0 0
[junit4] 2> 1089161 T4632 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[121 (1460243103427330048)]} 0 0
[junit4] 2> 1089164 T4618 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[122 (1460243103429427200)]} 0 1
[junit4] 2> 1089166 T4631 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[122 (1460243103432572928)]} 0 0
[junit4] 2> 1089168 T4619 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[123 (1460243103434670080)]} 0 0
[junit4] 2> 1089170 T4629 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[123 (1460243103436767232)]} 0 0
[junit4] 2> 1089173 T4620 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[124 (1460243103438864384)]} 0 1
[junit4] 2> 1089176 T4630 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[124 (1460243103443058688)]} 0 0
[junit4] 2> 1089179 T4617 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[125 (1460243103445155840)]} 0 1
[junit4] 2> 1089181 T4632 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[125 (1460243103448301568)]} 0 0
[junit4] 2> 1089183 T4618 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[126 (1460243103450398720)]} 0 0
[junit4] 2> 1089185 T4631 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[126 (1460243103452495872)]} 0 0
[junit4] 2> 1089188 T4619 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[127 (1460243103454593024)]} 0 1
[junit4] 2> 1089190 T4629 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[127 (1460243103457738752)]} 0 0
[junit4] 2> 1089192 T4620 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[128 (1460243103459835904)]} 0 0
[junit4] 2> 1089195 T4630 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[128 (1460243103461933056)]} 0 1
[junit4] 2> 1089197 T4617 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[129 (1460243103464030208)]} 0 1
[junit4] 2> 1089199 T4632 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[129 (1460243103467175936)]} 0 0
[junit4] 2> 1089201 T4618 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[130 (1460243103469273088)]} 0 0
[junit4] 2> 1089203 T4631 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[130 (1460243103471370240)]} 0 0
[junit4] 2> 1089205 T4619 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[131 (1460243103473467392)]} 0 0
[junit4] 2> 1089207 T4629 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[131 (1460243103475564544)]} 0 0
[junit4] 2> 1089210 T4620 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[132 (1460243103477661696)]} 0 1
[junit4] 2> 1089212 T4630 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[132 (1460243103479758848)]} 0 1
[junit4] 2> 1089214 T4617 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[133 (1460243103482904576)]} 0 1
[junit4] 2> 1089216 T4632 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[133 (1460243103485001728)]} 0 1
[junit4] 2> 1089218 T4618 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[134 (1460243103487098880)]} 0 0
[junit4] 2> 1089220 T4631 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[134 (1460243103489196032)]} 0 0
[junit4] 2> 1089223 T4619 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[135 (1460243103491293184)]} 0 1
[junit4] 2> 1089225 T4629 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[135 (1460243103493390336)]} 0 1
[junit4] 2> 1089228 T4620 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[136 (1460243103496536064)]} 0 1
[junit4] 2> 1089230 T4630 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[136 (1460243103499681792)]} 0 0
[junit4] 2> 1089233 T4617 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[137 (1460243103501778944)]} 0 1
[junit4] 2> 1089236 T4632 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[137 (1460243103504924672)]} 0 1
[junit4] 2> 1089239 T4618 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[138 (1460243103508070400)]} 0 1
[junit4] 2> 1089241 T4631 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[138 (1460243103511216128)]} 0 0
[junit4] 2> 1089244 T4619 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[139 (1460243103514361856)]} 0 0
[junit4] 2> 1089247 T4629 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[139 (1460243103516459008)]} 0 1
[junit4] 2> 1089250 T4620 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[140 (1460243103519604736)]} 0 1
[junit4] 2> 1089252 T4630 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[140 (1460243103522750464)]} 0 0
[junit4] 2> 1089255 T4617 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[141 (1460243103525896192)]} 0 0
[junit4] 2> 1089257 T4632 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[141 (1460243103527993344)]} 0 0
[junit4] 2> 1089260 T4618 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[142 (1460243103530090496)]} 0 1
[junit4] 2> 1089263 T4631 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[142 (1460243103533236224)]} 0 1
[junit4] 2> 1089266 T4619 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[143 (1460243103536381952)]} 0 1
[junit4] 2> 1089268 T4629 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[143 (1460243103539527680)]} 0 0
[junit4] 2> 1089270 T4620 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[144 (1460243103541624832)]} 0 0
[junit4] 2> 1089273 T4630 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[144 (1460243103543721984)]} 0 1
[junit4] 2> 1089275 T4617 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[145 (1460243103546867712)]} 0 0
[junit4] 2> 1089278 T4632 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[145 (1460243103548964864)]} 0 1
[junit4] 2> 1089280 T4618 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[146 (1460243103552110592)]} 0 0
[junit4] 2> 1089283 T4631 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[146 (1460243103554207744)]} 0 1
[junit4] 2> 1089286 T4619 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[147 (1460243103557353472)]} 0 1
[junit4] 2> 1089288 T4629 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[147 (1460243103560499200)]} 0 0
[junit4] 2> 1089291 T4620 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[148 (1460243103563644928)]} 0 0
[junit4] 2> 1089294 T4630 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[148 (1460243103566790656)]} 0 0
[junit4] 2> 1089297 T4617 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[149 (1460243103568887808)]} 0 1
[junit4] 2> 1089300 T4632 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[149 (1460243103572033536)]} 0 1
[junit4] 2> 1089302 T4618 C4800 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1089316 T4618 C4800 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@128608 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@d75863)),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@128608 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@d75863)),segFN=segments_3,generation=3}
[junit4] 2> 1089317 T4618 C4800 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 1089369 T4618 C4800 oass.SolrIndexSearcher.<init> Opening Searcher@1a65857[collection1] main
[junit4] 2> 1089370 T4618 C4800 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1089371 T4622 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1a65857[collection1] main{StandardDirectoryReader(segments_3:5:nrt _0(5.0):C9 _1(5.0):C59)}
[junit4] 2> 1089371 T4618 C4800 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 69
[junit4] 2> 1089373 T4631 C4801 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1089385 T4631 C4801 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@16e83bb lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1ce9d97)),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@16e83bb lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1ce9d97)),segFN=segments_3,generation=3}
[junit4] 2> 1089386 T4631 C4801 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 1089438 T4631 C4801 oass.SolrIndexSearcher.<init> Opening Searcher@fef8c8[collection1] main
[junit4] 2> 1089438 T4631 C4801 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1089440 T4634 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@fef8c8[collection1] main{StandardDirectoryReader(segments_3:5:nrt _0(5.0):C9 _1(5.0):C59)}
[junit4] 2> 1089440 T4631 C4801 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 67
[junit4] 2> 1089450 T4620 C4800 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=8
[junit4] 2> 1089463 T4629 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&fl=id&NOW=1392596343740&start=0&fsv=true&isShard=true&sort=n_ti1+desc&shard.url=http://127.0.0.1:42269/|[ff01::083]:33332/|[ff01::114]:33332/&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=6
[junit4] 2> 1089467 T4631 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&NOW=1392596343740&ids=143,112,135,114,137,148,5,9,109,10&isShard=true&shard.url=http://127.0.0.1:42269/|[ff01::083]:33332/|[ff01::114]:33332/&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 1089471 T4632 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:42269/|[ff01::083]:33332/|[ff01::114]:33332/&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=18
[junit4] 2> 1089473 T4617 C4800 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1089477 T4629 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&fl=id&NOW=1392596343763&start=0&fsv=true&isShard=true&sort=n_ti1+asc&shard.url=[ff01::114]:33332/|http://127.0.0.1:42269/|[ff01::083]:33332/&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1089480 T4631 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&NOW=1392596343763&ids=110,123,146,16,127,128,107,8,129,119&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:42269/|[ff01::083]:33332/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1089484 T4630 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:42269/|[ff01::083]:33332/&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=8
[junit4] 2> 1089486 T4618 C4800 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1089490 T4629 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&fl=id&NOW=1392596343775&start=0&fsv=true&isShard=true&sort=n_f1+desc&shard.url=http://127.0.0.1:42269/|[ff01::114]:33332/|[ff01::083]:33332/&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1089492 T4631 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&NOW=1392596343775&ids=121,110,11,5,138,105,127,106,128,9&isShard=true&shard.url=http://127.0.0.1:42269/|[ff01::114]:33332/|[ff01::083]:33332/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1089494 T4632 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:42269/|[ff01::114]:33332/|[ff01::083]:33332/&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=6
[junit4] 2> 1089497 T4619 C4800 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_f1+asc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1089501 T4629 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&fl=id&NOW=1392596343786&start=0&fsv=true&isShard=true&sort=n_f1+asc&shard.url=http://127.0.0.1:42269/&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1089503 T4631 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&NOW=1392596343786&ids=1,111,100,13,124,136,4,107,109,10&isShard=true&shard.url=http://127.0.0.1:42269/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1089506 T4630 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:42269/&sort=n_f1+asc&wt=javabin&version=2} hits=68 status=0 QTime=7
[junit4] 2> 1089508 T4620 C4800 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tf1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1089512 T4629 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&fl=id&NOW=1392596343798&start=0&fsv=true&isShard=true&sort=n_tf1+desc&shard.url=http://127.0.0.1:42269/|[ff01::213]:33332/&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1089515 T4631 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&NOW=1392596343798&ids=132,133,102,135,5,17,9,119,140,141&isShard=true&shard.url=http://127.0.0.1:42269/|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1089517 T4632 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:42269/|[ff01::213]:33332/&sort=n_tf1+desc&wt=javabin&version=2} hits=68 status=0 QTime=6
[junit4] 2> 1089519 T4618 C4800 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tf1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1089523 T4629 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&fl=id&NOW=1392596343808&start=0&fsv=true&isShard=true&sort=n_tf1+asc&shard.url=[ff01::083]:33332/|http://127.0.0.1:42269/|[ff01::114]:33332/|[ff01::213]:33332/&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1089525 T4631 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&NOW=1392596343808&ids=11,12,101,112,2,146,15,7,106,129&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:42269/|[ff01::114]:33332/|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1089526 T4630 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:42269/|[ff01::114]:33332/|[ff01::213]:33332/&sort=n_tf1+asc&wt=javabin&version=2} hits=68 status=0 QTime=5
[junit4] 2> 1089528 T4619 C4800 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_d1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1089534 T4629 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&fl=id&NOW=1392596343818&start=0&fsv=true&isShard=true&sort=n_d1+desc&shard.url=http://127.0.0.1:42269/|[ff01::213]:33332/&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1089537 T4631 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&NOW=1392596343818&ids=133,144,134,14,126,115,105,1001,129,131&isShard=true&shard.url=http://127.0.0.1:42269/|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1089538 T4632 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:42269/|[ff01::213]:33332/&sort=n_d1+desc&wt=javabin&version=2} hits=68 status=0 QTime=7
[junit4] 2> 1089540 T4620 C4800 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_d1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1089545 T4629 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&fl=id&NOW=1392596343830&start=0&fsv=true&isShard=true&sort=n_d1+asc&shard.url=http://127.0.0.1:42269/&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1089547 T4631 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&NOW=1392596343830&ids=11,145,15,103,125,149,128,8,130,10&isShard=true&shard.url=http://127.0.0.1:42269/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1089548 T4630 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:42269/&sort=n_d1+asc&wt=javabin&version=2} hits=68 status=0 QTime=5
[junit4] 2> 1089550 T4617 C4800 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_td1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1089554 T4629 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&fl=id&NOW=1392596343840&start=0&fsv=true&isShard=true&sort=n_td1+desc&shard.url=[ff01::213]:33332/|http://127.0.0.1:42269/&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1089556 T4631 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&NOW=1392596343840&ids=133,103,15,114,137,127,105,106,139,117&isShard=true&shard.url=[ff01::213]:33332/|http://127.0.0.1:42269/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1089557 T4632 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=[ff01::213]:33332/|http://127.0.0.1:42269/&sort=n_td1+desc&wt=javabin&version=2} hits=68 status=0 QTime=4
[junit4] 2> 1089560 T4618 C4800 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_td1+asc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1089563 T4629 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&fl=id&NOW=1392596343849&start=0&fsv=true&isShard=true&sort=n_td1+asc&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:42269/|[ff01::213]:33332/&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1089566 T4631 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&NOW=1392596343849&ids=143,135,102,124,147,16,128,107,118,131&isShard=true&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:42269/|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 1089566 T4630 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:42269/|[ff01::213]:33332/&sort=n_td1+asc&wt=javabin&version=2} hits=68 status=0 QTime=4
[junit4] 2> 1089568 T4619 C4800 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_l1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1089572 T4629 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&fl=id&NOW=1392596343858&start=0&fsv=true&isShard=true&sort=n_l1+desc&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:42269/&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1089575 T4631 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&NOW=1392596343858&ids=121,11,12,146,147,114,7,119,109,10&isShard=true&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:42269/&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 1089576 T4632 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:42269/&sort=n_l1+desc&wt=javabin&version=2} hits=68 status=0 QTime=5
[junit4] 2> 1089579 T4620 C4800 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_l1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1089583 T4629 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&fl=id&NOW=1392596343868&start=0&fsv=true&isShard=true&sort=n_l1+asc&shard.url=http://127.0.0.1:42269/|[ff01::083]:33332/|[ff01::114]:33332/|[ff01::213]:33332/&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1089586 T4631 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&NOW=1392596343868&ids=113,16,127,139,118,107,108,140,120,142&isShard=true&shard.url=http://127.0.0.1:42269/|[ff01::083]:33332/|[ff01::114]:33332/|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 1089586 T4630 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:42269/|[ff01::083]:33332/|[ff01::114]:33332/|[ff01::213]:33332/&sort=n_l1+asc&wt=javabin&version=2} hits=68 status=0 QTime=5
[junit4] 2> 1089589 T4617 C4800 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tl1+desc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1089592 T4629 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&fl=id&NOW=1392596343878&start=0&fsv=true&isShard=true&sort=n_tl1+desc&shard.url=http://127.0.0.1:42269/|[ff01::083]:33332/|[ff01::213]:33332/&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1089595 T4631 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&NOW=1392596343878&ids=123,145,146,14,4,147,127,138,116,131&isShard=true&shard.url=http://127.0.0.1:42269/|[ff01::083]:33332/|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 1089595 T4632 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:42269/|[ff01::083]:33332/|[ff01::213]:33332/&sort=n_tl1+desc&wt=javabin&version=2} hits=68 status=0 QTime=4
[junit4] 2> 1089598 T4618 C4800 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tl1+asc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1089601 T4629 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&fl=id&NOW=1392596343886&start=0&fsv=true&isShard=true&sort=n_tl1+asc&shard.url=[ff01::114]:33332/|http://127.0.0.1:42269/|[ff01::083]:33332/|[ff01::213]:33332/&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1089603 T4631 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&NOW=1392596343886&ids=144,112,135,16,115,17,139,9,130,10&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:42269/|[ff01::083]:33332/|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1089604 T4630 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:42269/|[ff01::083]:33332/|[ff01::213]:33332/&sort=n_tl1+asc&wt=javabin&version=2} hits=68 status=0 QTime=5
[junit4] 2> 1089606 T4619 C4800 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_dt1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1089612 T4629 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&fl=id&NOW=1392596343895&start=0&fsv=true&isShard=true&sort=n_dt1+desc&shard.url=[ff01::083]:33332/|http://127.0.0.1:42269/|[ff01::114]:33332/&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1089614 T4631 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&NOW=1392596343895&ids=121,145,16,148,115,17,138,128,7,109&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:42269/|[ff01::114]:33332/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1089615 T4632 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:42269/|[ff01::114]:33332/&sort=n_dt1+desc&wt=javabin&version=2} hits=68 status=0 QTime=7
[junit4] 2> 1089617 T4620 C4800 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_dt1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1089621 T4629 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&fl=id&NOW=1392596343906&start=0&fsv=true&isShard=true&sort=n_dt1+asc&shard.url=http://127.0.0.1:42269/|[ff01::083]:33332/|[ff01::114]:33332/|[ff01::213]:33332/&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1089623 T4631 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&NOW=1392596343906&ids=132,1,122,2,124,102,113,116,8,108&isShard=true&shard.url=http://127.0.0.1:42269/|[ff01::083]:33332/|[ff01::114]:33332/|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1089624 T4630 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:42269/|[ff01::083]:33332/|[ff01::114]:33332/|[ff01::213]:33332/&sort=n_dt1+asc&wt=javabin&version=2} hits=68 status=0 QTime=5
[junit4] 2> 1089626 T4617 C4800 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tdt1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1089630 T4629 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&fl=id&NOW=1392596343915&start=0&fsv=true&isShard=true&sort=n_tdt1+desc&shard.url=http://127.0.0.1:42269/&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1089632 T4631 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&NOW=1392596343915&ids=100,1,146,147,4,15,126,17,7,140&isShard=true&shard.url=http://127.0.0.1:42269/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1089633 T4632 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:42269/&sort=n_tdt1+desc&wt=javabin&version=2} hits=68 status=0 QTime=5
[junit4] 2> 1089635 T4618 C4800 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tdt1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1089639 T4629 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&fl=id&NOW=1392596343924&start=0&fsv=true&isShard=true&sort=n_tdt1+asc&shard.url=[ff01::083]:33332/|http://127.0.0.1:42269/&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1089641 T4631 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&NOW=1392596343924&ids=132,122,124,135,148,138,8,118,108,9&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:42269/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1089642 T4630 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:42269/&sort=n_tdt1+asc&wt=javabin&version=2} hits=68 status=0 QTime=5
[junit4] 2> 1089644 T4619 C4800 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=a_si+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1089648 T4629 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&fl=id&NOW=1392596343934&start=0&fsv=true&isShard=true&sort=a_si+desc&shard.url=[ff01::213]:33332/|http://127.0.0.1:42269/&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1089651 T4631 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&NOW=1392596343934&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=[ff01::213]:33332/|http://127.0.0.1:42269/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1089652 T4632 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=[ff01::213]:33332/|http://127.0.0.1:42269/&sort=a_si+desc&wt=javabin&version=2} hits=68 status=0 QTime=5
[junit4] 2> 1089655 T4620 C4800 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort={!func}testfunc(add(a_si,5))+desc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1089660 T4629 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&fl=id&NOW=1392596343944&start=0&fsv=true&isShard=true&sort={!func}testfunc(add(a_si,5))+desc&shard.url=[ff01::083]:33332/|http://127.0.0.1:42269/|[ff01::114]:33332/|[ff01::213]:33332/&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1089663 T4631 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&NOW=1392596343944&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:42269/|[ff01::114]:33332/|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1089664 T4630 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:42269/|[ff01::114]:33332/|[ff01::213]:33332/&sort={!func}testfunc(add(a_si,5))+desc&wt=javabin&version=2} hits=68 status=0 QTime=7
[junit4] 2> 1089666 T4617 C4800 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=a_si+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1089670 T4629 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&fl=id&NOW=1392596343955&start=0&fsv=true&isShard=true&sort=a_si+asc&shard.url=[ff01::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:42269/&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1089673 T4631 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&NOW=1392596343955&ids=11,12,1,13,2,3,4,6,7,9&isShard=true&shard.url=[ff01::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:42269/&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 1089673 T4632 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:42269/&sort=a_si+asc&wt=javabin&version=2} hits=68 status=0 QTime=5
[junit4] 2> 1089676 T4618 C4800 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&fl=*,score&sort=a_si+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1089682 T4629 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&fl=id,score&NOW=1392596343965&start=0&fsv=true&isShard=true&sort=a_si+desc&shard.url=[ff01::114]:33332/|http://127.0.0.1:42269/&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1089685 T4631 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&fl=*,score&NOW=1392596343965&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:42269/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1089686 T4630 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:42269/&fl=*,score&sort=a_si+desc&wt=javabin&version=2} hits=68 status=0 QTime=8
[junit4] 2> 1089688 T4619 C4800 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&fl=*,score&sort=n_tl1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1089692 T4629 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&fl=id,score&NOW=1392596343977&start=0&fsv=true&isShard=true&sort=n_tl1+asc&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:42269/&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1089694 T4631 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&fl=*,score&NOW=1392596343977&ids=144,112,135,16,115,17,139,9,130,10&isShard=true&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:42269/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1089695 T4632 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:42269/&fl=*,score&sort=n_tl1+asc&wt=javabin&version=2} hits=68 status=0 QTime=5
[junit4] 2> 1089697 T4620 C4800 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tl1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1089700 T4629 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&fl=id&NOW=1392596343986&start=0&fsv=true&isShard=true&sort=n_tl1+desc&shard.url=http://127.0.0.1:42269/|[ff01::083]:33332/|[ff01::213]:33332/&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1089703 T4631 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&NOW=1392596343986&ids=123,145,146,14,4,147,127,138,116,131&isShard=true&shard.url=http://127.0.0.1:42269/|[ff01::083]:33332/|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 1089703 T4630 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:42269/|[ff01::083]:33332/|[ff01::213]:33332/&sort=n_tl1+desc&wt=javabin&version=2} hits=68 status=0 QTime=4
[junit4] 2> 1089706 T4617 C4800 oasc.SolrCore.execute [collection1] webapp= path=/select params={q={!func}a_si&distrib=false&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1089709 T4629 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q={!func}a_si&distrib=false&fl=id,score&NOW=1392596343995&start=0&fsv=true&isShard=true&shard.url=[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:42269/|[ff01::083]:33332/&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1089715 T4631 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q={!func}a_si&distrib=false&NOW=1392596343995&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:42269/|[ff01::083]:33332/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1089716 T4632 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q={!func}a_si&shards=[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:42269/|[ff01::083]:33332/&wt=javabin&version=2} hits=68 status=0 QTime=8
[junit4] 2> 1089718 T4618 C4800 oasc.SolrCore.execute [collection1] webapp= path=/select params={q={!func}a_si&distrib=false&fl=*,score&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1089722 T4629 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q={!func}a_si&distrib=false&fl=id,score&NOW=1392596344008&start=0&fsv=true&isShard=true&shard.url=http://127.0.0.1:42269/&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1089724 T4631 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q={!func}a_si&distrib=false&fl=*,score&NOW=1392596344008&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=http://127.0.0.1:42269/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1089725 T4630 C4801 oasc.SolrCore.execute [collection1] webapp= path=/select params={q={!func}a_si&shards=http://127.0.0.1:42269/&fl=*,score&wt=javabin&version=2} hits=68 status=0 QTime=4
[junit4] 2> 1089728 T4619 C4800 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=quick&distrib=false&wt=javabin&version=2} hits=2 status=0 QTime=1
[junit4] 2> 1089732 T4629 C4801 oasc.SolrCore.execute [collection1] webapp= p
[...truncated too long message...]
cher on request.
[junit4] 2> 1096820 T4611 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1096820 T4611 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.TestDistributedSearch-1392596342598/shard0/data/index [CachedDir<<refCount=0;path=./org.apache.solr.TestDistributedSearch-1392596342598/shard0/data/index;done=false>>]
[junit4] 2> 1096821 T4611 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.TestDistributedSearch-1392596342598/shard0/data/index
[junit4] 2> 1096821 T4611 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.TestDistributedSearch-1392596342598/shard0/data [CachedDir<<refCount=0;path=./org.apache.solr.TestDistributedSearch-1392596342598/shard0/data;done=false>>]
[junit4] 2> 1096822 T4611 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.TestDistributedSearch-1392596342598/shard0/data
[junit4] 2> 1096825 T4611 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/,null}
[junit4] 2> 1096897 T4611 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=21561889
[junit4] 2> 1096898 T4611 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1e8403b
[junit4] 2> 1096915 T4611 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=3,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=36,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0,transaction_logs_total_size=8910,transaction_logs_total_number=3}
[junit4] 2> 1096915 T4611 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 1096916 T4611 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1096916 T4611 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 1096917 T4611 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 1096918 T4611 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1096918 T4611 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.TestDistributedSearch-1392596342598/shard1/data [CachedDir<<refCount=0;path=./org.apache.solr.TestDistributedSearch-1392596342598/shard1/data;done=false>>]
[junit4] 2> 1096919 T4611 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.TestDistributedSearch-1392596342598/shard1/data
[junit4] 2> 1096919 T4611 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.TestDistributedSearch-1392596342598/shard1/data/index [CachedDir<<refCount=0;path=./org.apache.solr.TestDistributedSearch-1392596342598/shard1/data/index;done=false>>]
[junit4] 2> 1096920 T4611 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.TestDistributedSearch-1392596342598/shard1/data/index
[junit4] 2> 1096921 T4611 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/,null}
[junit4] 2> 1096980 T4611 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDistributedSearch -Dtests.method=testDistribSearch -Dtests.seed=ED137D06E24A61FB -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=el_CY -Dtests.timezone=Europe/Kiev -Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 8.68s J0 | TestDistributedSearch.testDistribSearch <<<
[junit4] > Throwable #1: java.lang.AssertionError: Expected the partialResults header to be null
[junit4] > at __randomizedtesting.SeedInfo.seed([ED137D06E24A61FB:6CF5F31E951501C7]:0)
[junit4] > at org.apache.solr.TestDistributedSearch.validateControlData(TestDistributedSearch.java:525)
[junit4] > at org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:445)
[junit4] > at org.apache.solr.TestDistributedSearch.doTest(TestDistributedSearch.java:379)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:876)
[junit4] > at java.lang.Thread.run(Thread.java:744)
[junit4] 2> 1096987 T4611 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: test params are: codec=Lucene46: {other_tl1=PostingsFormat(name=Lucene41WithOrds), SubjectTerms_mfacet=PostingsFormat(name=Lucene41WithOrds), n_l1=PostingsFormat(name=Lucene41VarGapFixedInterval), a_n_tdt=MockFixedIntBlock(blockSize=446), multiDefault=PostingsFormat(name=Lucene41VarGapFixedInterval), intDefault=PostingsFormat(name=Lucene41WithOrds), n_td1=PostingsFormat(name=Lucene41WithOrds), n_d1=PostingsFormat(name=Lucene41VarGapFixedInterval), n_f1=PostingsFormat(name=Lucene41WithOrds), range_facet_l=PostingsFormat(name=Lucene41VarGapFixedInterval), n_tl1=PostingsFormat(name=Lucene41WithOrds), oddField_s=PostingsFormat(name=SimpleText), n_tf1=PostingsFormat(name=Lucene41VarGapFixedInterval), lowerfilt1and2=PostingsFormat(name=Lucene41VarGapFixedInterval), a_t=PostingsFormat(name=Lucene41VarGapFixedInterval), a_si=PostingsFormat(name=Lucene41WithOrds), lowerfilt=PostingsFormat(name=Lucene41VarGapFixedInterval), id=PostingsFormat(name=SimpleText), text=MockFixedIntBlock(blockSize=446), timestamp=PostingsFormat(name=Lucene41VarGapFixedInterval), range_facet_sl=PostingsFormat(name=SimpleText), n_dt1=PostingsFormat(name=Lucene41WithOrds), range_facet_si=PostingsFormat(name=Lucene41VarGapFixedInterval), b_n_tdt=PostingsFormat(name=Lucene41WithOrds), n_ti1=PostingsFormat(name=SimpleText), rnd_b=PostingsFormat(name=SimpleText), _version_=PostingsFormat(name=Lucene41WithOrds), foo_b=MockFixedIntBlock(blockSize=446), n_tdt1=PostingsFormat(name=Lucene41WithOrds), foo_d=PostingsFormat(name=SimpleText), foo_f=MockFixedIntBlock(blockSize=446)}, docValues:{timestamp=DocValuesFormat(name=Asserting)}, sim=DefaultSimilarity, locale=el_CY, timezone=Europe/Kiev
[junit4] 2> NOTE: Linux 3.8.0-35-generic i386/Oracle Corporation 1.8.0 (32-bit)/cpus=8,threads=2,free=120251936,total=527433728
[junit4] 2> NOTE: All tests run in this JVM: [DistribCursorPagingTest, TestBM25SimilarityFactory, DocValuesMissingTest, QueryElevationComponentTest, TestFieldCollectionResource, WordBreakSolrSpellCheckerTest, MBeansHandlerTest, TestFileDictionaryLookup, DirectUpdateHandlerOptimizeTest, TestConfig, TestRandomMergePolicy, TestCoreContainer, TestCloudManagedSchema, BasicDistributedZkTest, HdfsLockFactoryTest, SearchHandlerTest, TestLMDirichletSimilarityFactory, CustomCollectionTest, DistributedTermsComponentTest, SampleTest, AddBlockUpdateTest, TestTrie, TestBadConfig, CircularListTest, DistributedSuggestComponentTest, ModifyConfFileTest, AbstractAnalyticsFacetTest, TestSchemaVersionResource, IndexSchemaTest, TestDocumentBuilder, TestCharFilters, StatelessScriptUpdateProcessorFactoryTest, HdfsDirectoryTest, HdfsUnloadDistributedZkTest, TestPostingsSolrHighlighter, HdfsBasicDistributedZk2Test, TestSolrQueryParser, DistributedQueryElevationComponentTest, BasicFunctionalityTest, TestSolrXml, StressHdfsTest, ChangedSchemaMergeTest, TestDynamicFieldCollectionResource, TermsComponentTest, TestLRUCache, JsonLoaderTest, TestFieldTypeResource, TestElisionMultitermQuery, TestReplicationHandler, TestSimpleQParserPlugin, TestModifyConfFiles, TestInitQParser, AutoCommitTest, SOLR749Test, LegacyHTMLStripCharFilterTest, SoftAutoCommitTest, FieldMutatingUpdateProcessorTest, LukeRequestHandlerTest, LoggingHandlerTest, TestManagedSchemaFieldResource, SchemaVersionSpecificBehaviorTest, TestWriterPerf, TestFaceting, SpatialFilterTest, SolrInfoMBeanTest, PeerSyncTest, CacheHeaderTest, TestSolrIndexConfig, SuggesterFSTTest, RegexBoostProcessorTest, ClusterStateUpdateTest, AnalysisAfterCoreReloadTest, TestLuceneMatchVersion, TestPHPSerializedResponseWriter, TestJoin, SuggesterTest, SyncSliceTest, ScriptEngineTest, UpdateRequestProcessorFactoryTest, TestDocSet, TestValueSourceCache, SliceStateTest, TestMergePolicyConfig, SolrCoreTest, BlockDirectoryTest, ResponseLogComponentTest, BinaryUpdateRequestHandlerTest, SolrXmlInZkTest, OverseerCollectionProcessorTest, ChaosMonkeySafeLeaderTest, OpenCloseCoreStressTest, ShardRoutingTest, RecoveryZkTest, TestZkChroot, ZkCLITest, ShardRoutingCustomTest, SliceStateUpdateTest, TestDistributedSearch]
[junit4] Completed on J0 in 8.70s, 1 test, 1 failure <<< FAILURES!
[...truncated 536 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:453: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:433: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/extra-targets.xml:37: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:189: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:491: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1268: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:901: There were test failures: 372 suites, 1604 tests, 1 failure, 35 ignored (7 assumptions)
Total time: 50 minutes 4 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/jdk1.8.0-fcs-b129 -server -XX:+UseG1GC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure