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/06 00:36:50 UTC

[JENKINS] Lucene-Solr-trunk-Linux (32bit/ibm-j9-jdk7) - Build # 9374 - Still Failing!

Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/9374/
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">79</int> </lst> <result name="response" numFound="100" start="0"> </result> <lst name="stats">   <lst name="stats_fields"/> </lst> <lst name="stats">   <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="cnr">     <double name="c10">10.0</double>     <double name="c8">8.0</double>   </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 name="pr">     <long name="count">100</long>     <null name="mc"/>     <null name="mean"/>     <null name="su"/>     <null name="sum"/>     <null name="unique"/>   </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="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="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="dr">     <long name="count">100</long>     <null name="mc"/>     <null name="mean"/>     <null name="su"/>     <null name="sum"/>     <null name="unique"/>   </lst>   <lst name="ar">     <long name="count">100</long>     <double name="mcm">153.41</double>     <double name="mean">28.91</double>     <double name="median">24.5</double>     <double name="su">2927.0</double>     <double name="sum">2891.0</double>     <long name="unique">36</long>   </lst>   <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> </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">79</int>
</lst>
<result name="response" numFound="100" start="0">
</result>
<lst name="stats">
  <lst name="stats_fields"/>
</lst>
<lst name="stats">
  <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="cnr">
    <double name="c10">10.0</double>
    <double name="c8">8.0</double>
  </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 name="pr">
    <long name="count">100</long>
    <null name="mc"/>
    <null name="mean"/>
    <null name="su"/>
    <null name="sum"/>
    <null name="unique"/>
  </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="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="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="dr">
    <long name="count">100</long>
    <null name="mc"/>
    <null name="mean"/>
    <null name="su"/>
    <null name="sum"/>
    <null name="unique"/>
  </lst>
  <lst name="ar">
    <long name="count">100</long>
    <double name="mcm">153.41</double>
    <double name="mean">28.91</double>
    <double name="median">24.5</double>
    <double name="su">2927.0</double>
    <double name="sum">2891.0</double>
    <long name="unique">36</long>
  </lst>
  <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>
</response>

	at __randomizedtesting.SeedInfo.seed([80077084E3E0D614:D80A9B72C722B4C2]: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.powerTest

Error Message:
Caught exception in getStatResult, xPath = /response/lst[@name='stats']/lst[@name='pr']/double[@name='sum']  raw data: <?xml version="1.0" encoding="UTF-8"?> <response>  <lst name="responseHeader">   <int name="status">0</int>   <int name="QTime">79</int> </lst> <result name="response" numFound="100" start="0"> </result> <lst name="stats">   <lst name="stats_fields"/> </lst> <lst name="stats">   <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="cnr">     <double name="c10">10.0</double>     <double name="c8">8.0</double>   </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 name="pr">     <long name="count">100</long>     <null name="mc"/>     <null name="mean"/>     <null name="su"/>     <null name="sum"/>     <null name="unique"/>   </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="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="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="dr">     <long name="count">100</long>     <null name="mc"/>     <null name="mean"/>     <null name="su"/>     <null name="sum"/>     <null name="unique"/>   </lst>   <lst name="ar">     <long name="count">100</long>     <double name="mcm">153.41</double>     <double name="mean">28.91</double>     <double name="median">24.5</double>     <double name="su">2927.0</double>     <double name="sum">2891.0</double>     <long name="unique">36</long>   </lst>   <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> </response> 

Stack Trace:
java.lang.AssertionError: Caught exception in getStatResult, xPath = /response/lst[@name='stats']/lst[@name='pr']/double[@name='sum'] 
raw data: <?xml version="1.0" encoding="UTF-8"?>
<response>

<lst name="responseHeader">
  <int name="status">0</int>
  <int name="QTime">79</int>
</lst>
<result name="response" numFound="100" start="0">
</result>
<lst name="stats">
  <lst name="stats_fields"/>
</lst>
<lst name="stats">
  <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="cnr">
    <double name="c10">10.0</double>
    <double name="c8">8.0</double>
  </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 name="pr">
    <long name="count">100</long>
    <null name="mc"/>
    <null name="mean"/>
    <null name="su"/>
    <null name="sum"/>
    <null name="unique"/>
  </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="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="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="dr">
    <long name="count">100</long>
    <null name="mc"/>
    <null name="mean"/>
    <null name="su"/>
    <null name="sum"/>
    <null name="unique"/>
  </lst>
  <lst name="ar">
    <long name="count">100</long>
    <double name="mcm">153.41</double>
    <double name="mean">28.91</double>
    <double name="median">24.5</double>
    <double name="su">2927.0</double>
    <double name="sum">2891.0</double>
    <long name="unique">36</long>
  </lst>
  <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>
</response>

	at __randomizedtesting.SeedInfo.seed([80077084E3E0D614:EEB5C7870BF788C5]: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.powerTest(ExpressionTest.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)


REGRESSION:  org.apache.solr.handler.component.DistributedQueryElevationComponentTest.testDistribSearch

Error Message:
.responseHeader.params.version!=sort (unordered or missing)

Stack Trace:
junit.framework.AssertionFailedError: .responseHeader.params.version!=sort (unordered or missing)
	at __randomizedtesting.SeedInfo.seed([80077084E3E0D614:1E1FE9C94BFB628]:0)
	at junit.framework.Assert.fail(Assert.java:50)
	at org.apache.solr.BaseDistributedSearchTestCase.compareSolrResponses(BaseDistributedSearchTestCase.java:840)
	at org.apache.solr.BaseDistributedSearchTestCase.compareResponses(BaseDistributedSearchTestCase.java:859)
	at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:562)
	at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:542)
	at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:521)
	at org.apache.solr.handler.component.DistributedQueryElevationComponentTest.doTest(DistributedQueryElevationComponentTest.java:83)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:867)
	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([80077084E3E0D614:1E1FE9C94BFB628]:0)
	at junit.framework.Assert.fail(Assert.java:50)
	at org.apache.solr.BaseDistributedSearchTestCase.compareSolrResponses(BaseDistributedSearchTestCase.java:840)
	at org.apache.solr.BaseDistributedSearchTestCase.compareResponses(BaseDistributedSearchTestCase.java:859)
	at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:562)
	at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:542)
	at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:521)
	at org.apache.solr.handler.component.TermVectorComponentDistributedTest.doTest(TermVectorComponentDistributedTest.java:127)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:873)
	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:<2>

Stack Trace:
java.lang.AssertionError: expected:<1> but was:<2>
	at __randomizedtesting.SeedInfo.seed([80077084E3E0D614:DB378B4CE0A77568]: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 9987 lines...]
   [junit4] Suite: org.apache.solr.analytics.expression.ExpressionTest
   [junit4]   2> 142938 T1026 oas.SolrTestCaseJ4.initCore ####initCore
   [junit4]   2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-ExpressionTest-1391641915602
   [junit4]   2> 142939 T1026 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> 142940 T1026 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> 142940 T1026 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> 142969 T1026 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 143000 T1026 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-basic.xml
   [junit4]   2> 143001 T1026 oass.IndexSchema.readSchema Reading Solr Schema from schema-analytics.xml
   [junit4]   2> 143005 T1026 oass.IndexSchema.readSchema [null] Schema name=schema-docValues
   [junit4]   2> 143031 T1026 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 143032 T1026 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 143033 T1026 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> 143033 T1026 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/'
   [junit4]   2> 143048 T1026 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 143048 T1026 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> 143049 T1026 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/'
   [junit4]   2> 143090 T1026 oasc.CoreContainer.<init> New CoreContainer -818578634
   [junit4]   2> 143090 T1026 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> 143091 T1026 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
   [junit4]   2> 143092 T1026 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 143092 T1026 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
   [junit4]   2> 143092 T1026 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 143093 T1026 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 143093 T1026 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 143094 T1026 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 143094 T1026 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 143094 T1026 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 143098 T1026 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 143099 T1026 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 143099 T1026 oasc.CoreContainer.load Host Name: 
   [junit4]   2> 143102 T1027 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> 143103 T1027 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> 143103 T1027 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> 143104 T1027 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> 143140 T1027 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 143171 T1027 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-basic.xml
   [junit4]   2> 143172 T1027 oass.IndexSchema.readSchema Reading Solr Schema from schema-analytics.xml
   [junit4]   2> 143176 T1027 oass.IndexSchema.readSchema [collection1] Schema name=schema-docValues
   [junit4]   2> 143201 T1027 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 143202 T1027 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 143203 T1027 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-1391641915602/
   [junit4]   2> 143203 T1027 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
   [junit4]   2> 143204 T1027 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-ExpressionTest-1391641915602
   [junit4]   2> 143204 T1027 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-1391641915602/index/
   [junit4]   2> 143204 T1027 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-ExpressionTest-1391641915602/index' doesn't exist. Creating new index...
   [junit4]   2> 143205 T1027 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-ExpressionTest-1391641915602/index
   [junit4]   2> 143205 T1027 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=43, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
   [junit4]   2> 143206 T1027 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_1,generation=1}
   [junit4]   2> 143207 T1027 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 143208 T1027 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 143208 T1027 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 143208 T1027 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 143210 T1027 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 143212 T1027 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 143212 T1027 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 143212 T1027 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=48, maxMergeAtOnceExplicit=27, maxMergedSegmentMB=76.9697265625, floorSegmentMB=1.9716796875, forceMergeDeletesPctAllowed=27.867245170492737, segmentsPerTier=36.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4354662987845006
   [junit4]   2> 143213 T1027 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_1,generation=1}
   [junit4]   2> 143213 T1027 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 143214 T1027 oass.SolrIndexSearcher.<init> Opening Searcher@a1b505ad main
   [junit4]   2> 143214 T1027 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 143214 T1028 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@a1b505ad main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 143217 T1026 oas.SolrTestCaseJ4.initCore ####initCore end
   [junit4]   2> ASYNC  NEW_CORE C94 name=collection1 org.apache.solr.core.SolrCore@c4e621a3
   [junit4]   2> 143217 T1026 C94 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 143218 T1026 C94 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
   [junit4]   2> 143223 T1026 C94 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10000]} 0 4
   [junit4]   2> 143227 T1026 C94 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 143239 T1026 C94 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_1,generation=1}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_2,generation=2}
   [junit4]   2> 143240 T1026 C94 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
   [junit4]   2> 143294 T1026 C94 oass.SolrIndexSearcher.<init> Opening Searcher@aa2f6d7d main
   [junit4]   2> 143295 T1026 C94 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 143295 T1028 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@aa2f6d7d main{StandardDirectoryReader(segments_2:4:nrt _0(5.0):C1)}
   [junit4]   2> 143296 T1026 C94 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 69
   [junit4]   2> 143300 T1026 C94 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10001]} 0 2
   [junit4]   2> 143302 T1026 C94 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 143306 T1026 C94 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_2,generation=2}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_3,generation=3}
   [junit4]   2> 143307 T1026 C94 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
   [junit4]   2> 143309 T1026 C94 oass.SolrIndexSearcher.<init> Opening Searcher@ff981e83 main
   [junit4]   2> 143310 T1026 C94 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 143310 T1028 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@ff981e83 main{StandardDirectoryReader(segments_3:6:nrt _0(5.0):C1 _1(5.0):C1)}
   [junit4]   2> 143311 T1026 C94 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 9
   [junit4]   2> 143315 T1026 C94 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10002]} 0 2
   [junit4]   2> 143318 T1026 C94 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 143321 T1026 C94 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_3,generation=3}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_4,generation=4}
   [junit4]   2> 143322 T1026 C94 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
   [junit4]   2> 143324 T1026 C94 oass.SolrIndexSearcher.<init> Opening Searcher@5091e3cc main
   [junit4]   2> 143325 T1026 C94 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 143325 T1028 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5091e3cc main{StandardDirectoryReader(segments_4:8:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1)}
   [junit4]   2> 143326 T1026 C94 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 9
   [junit4]   2> 143330 T1026 C94 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10003]} 0 2
   [junit4]   2> 143332 T1026 C94 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 143336 T1026 C94 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_4,generation=4}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_5,generation=5}
   [junit4]   2> 143337 T1026 C94 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 5
   [junit4]   2> 143339 T1026 C94 oass.SolrIndexSearcher.<init> Opening Searcher@3a803b58 main
   [junit4]   2> 143340 T1026 C94 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 143341 T1028 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3a803b58 main{StandardDirectoryReader(segments_5:10:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1 _3(5.0):C1)}
   [junit4]   2> 143342 T1026 C94 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 10
   [junit4]   2> 143345 T1026 C94 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10004]} 0 2
   [junit4]   2> 143347 T1026 C94 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 143351 T1026 C94 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_5,generation=5}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_6,generation=6}
   [junit4]   2> 143352 T1026 C94 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 6
   [junit4]   2> 143354 T1026 C94 oass.SolrIndexSearcher.<init> Opening Searcher@600d9658 main
   [junit4]   2> 143355 T1026 C94 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 143356 T1028 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@600d9658 main{StandardDirectoryReader(segments_6:12:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1 _3(5.0):C1 _4(5.0):C1)}
   [junit4]   2> 143357 T1026 C94 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 10
   [junit4]   2> 143361 T1026 C94 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10005]} 0 2
   [junit4]   2> 143362 T1026 C94 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 143366 T1026 C94 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_6,generation=6}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_7,generation=7}
   [junit4]   2> 143366 T1026 C94 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 7
   [junit4]   2> 143368 T1026 C94 oass.SolrIndexSearcher.<init> Opening Searcher@97551749 main
   [junit4]   2> 143369 T1026 C94 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 143369 T1028 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@97551749 main{StandardDirectoryReader(segments_7:14:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1 _3(5.0):C1 _4(5.0):C1 _5(5.0):C1)}
   [junit4]   2> 143370 T1026 C94 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 8
   [junit4]   2> 143373 T1026 C94 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10006]} 0 2
   [junit4]   2> 143374 T1026 C94 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 143378 T1026 C94 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_7,generation=7}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_8,generation=8}
   [junit4]   2> 143380 T1026 C94 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 8
   [junit4]   2> 143381 T1026 C94 oass.SolrIndexSearcher.<init> Opening Searcher@710d40ac main
   [junit4]   2> 143382 T1026 C94 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 143382 T1028 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@710d40ac main{StandardDirectoryReader(segments_8:16:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1 _3(5.0):C1 _4(5.0):C1 _5(5.0):C1 _6(5.0):C1)}
   [junit4]   2> 143383 T1026 C94 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 9
   [junit4]   2> 143386 T1026 C94 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10007]} 0 1
   [junit4]   2> 143387 T1026 C94 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 143391 T1026 C94 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_8,generation=8}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_9,generation=9}
   [junit4]   2> 143391 T1026 C94 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 9
   [junit4]   2> 143393 T1026 C94 oass.SolrIndexSearcher.<init> Opening Searcher@6222192a main
   [junit4]   2> 143393 T1026 C94 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 143393 T1028 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6222192a main{StandardDirectoryReader(segments_9:18:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1 _3(5.0):C1 _4(5.0):C1 _5(5.0):C1 _6(5.0):C1 _7(5.0):C1)}
   [junit4]   2> 143394 T1026 C94 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 7
   [junit4]   2> 143397 T1026 C94 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10008]} 0 1
   [junit4]   2> 143398 T1026 C94 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 143402 T1026 C94 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_9,generation=9}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_a,generation=10}
   [junit4]   2> 143402 T1026 C94 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 10
   [junit4]   2> 143404 T1026 C94 oass.SolrIndexSearcher.<init> Opening Searcher@7f27acfb main
   [junit4]   2> 143404 T1026 C94 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 143404 T1028 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7f27acfb main{StandardDirectoryReader(segments_a:20:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1 _3(5.0):C1 _4(5.0):C1 _5(5.0):C1 _6(5.0):C1 _7(5.0):C1 _8(5.0):C1)}
   [junit4]   2> 143405 T1026 C94 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 7
   [junit4]   2> ASYNC  NEW_CORE C95 name=collection1 org.apache.solr.core.SolrCore@c4e621a3
   [junit4]   2> 143413 T1026 C95 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10009]} 0 6
   [junit4]   2> 143415 T1026 C95 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 143420 T1026 C95 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_a,generation=10}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_b,generation=11}
   [junit4]   2> 143421 T1026 C95 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 11
   [junit4]   2> 143423 T1026 C95 oass.SolrIndexSearcher.<init> Opening Searcher@6519e703 main
   [junit4]   2> 143425 T1026 C95 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 143425 T1028 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6519e703 main{StandardDirectoryReader(segments_b:22:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1 _3(5.0):C1 _4(5.0):C1 _5(5.0):C1 _6(5.0):C1 _7(5.0):C1 _8(5.0):C1 _9(5.0):C1)}
   [junit4]   2> 143426 T1026 C95 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 11
   [junit4]   2> 143432 T1026 C95 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100010]} 0 3
   [junit4]   2> 143434 T1026 C95 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 143439 T1026 C95 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_b,generation=11}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_c,generation=12}
   [junit4]   2> 143440 T1026 C95 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 12
   [junit4]   2> 143443 T1026 C95 oass.SolrIndexSearcher.<init> Opening Searcher@552f1e77 main
   [junit4]   2> 143444 T1026 C95 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 143444 T1028 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@552f1e77 main{StandardDirectoryReader(segments_c:24:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1 _3(5.0):C1 _4(5.0):C1 _5(5.0):C1 _6(5.0):C1 _7(5.0):C1 _8(5.0):C1 _9(5.0):C1 _a(5.0):C1)}
   [junit4]   2> 143445 T1026 C95 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 11
   [junit4]   2> 143449 T1026 C95 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100011]} 0 2
   [junit4]   2> 143451 T1026 C95 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 143456 T1026 C95 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_c,generation=12}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_d,generation=13}
   [junit4]   2> 143457 T1026 C95 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 13
   [junit4]   2> 143459 T1026 C95 oass.SolrIndexSearcher.<init> Opening Searcher@690d851b main
   [junit4]   2> 143461 T1026 C95 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 143461 T1028 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@690d851b main{StandardDirectoryReader(segments_d:26:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1 _3(5.0):C1 _4(5.0):C1 _5(5.0):C1 _6(5.0):C1 _7(5.0):C1 _8(5.0):C1 _9(5.0):C1 _a(5.0):C1 _b(5.0):C1)}
   [junit4]   2> 143462 T1026 C95 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 11
   [junit4]   2> 143466 T1026 C95 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100012]} 0 2
   [junit4]   2> 143468 T1026 C95 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 143473 T1026 C95 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_d,generation=13}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_e,generation=14}
   [junit4]   2> 143474 T1026 C95 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 14
   [junit4]   2> 143476 T1026 C95 oass.SolrIndexSearcher.<init> Opening Searcher@9d06aa99 main
   [junit4]   2> 143477 T1026 C95 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 143478 T1028 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@9d06aa99 main{StandardDirectoryReader(segments_e:28:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1 _3(5.0):C1 _4(5.0):C1 _5(5.0):C1 _6(5.0):C1 _7(5.0):C1 _8(5.0):C1 _9(5.0):C1 _a(5.0):C1 _b(5.0):C1 _c(5.0):C1)}
   [junit4]   2> 143479 T1026 C95 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 11
   [junit4]   2> 143483 T1026 C95 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100013]} 0 2
   [junit4]   2> 143485 T1026 C95 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 143490 T1026 C95 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_e,generation=14}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_f,generation=15}
   [junit4]   2> 143491 T1026 C95 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 15
   [junit4]   2> 143494 T1026 C95 oass.SolrIndexSearcher.<init> Opening Searcher@4cd8156b main
   [junit4]   2> 143495 T1026 C95 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 143495 T1028 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4cd8156b main{StandardDirectoryReader(segments_f:30:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1 _3(5.0):C1 _4(5.0):C1 _5(5.0):C1 _6(5.0):C1 _7(5.0):C1 _8(5.0):C1 _9(5.0):C1 _a(5.0):C1 _b(5.0):C1 _c(5.0):C1 _d(5.0):C1)}
   [junit4]   2> 143496 T1026 C95 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 11
   [junit4]   2> 143500 T1026 C95 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100014]} 0 2
   [junit4]   2> 143502 T1026 C95 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 143505 T1026 C95 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_f,generation=15}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_g,generation=16}
   [junit4]   2> 143506 T1026 C95 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 16
   [junit4]   2> 143507 T1026 C95 oass.SolrIndexSearcher.<init> Opening Searcher@4e223ac6 main
   [junit4]   2> 143508 T1026 C95 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 143509 T1028 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4e223ac6 main{StandardDirectoryReader(segments_g:32:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1 _3(5.0):C1 _4(5.0):C1 _5(5.0):C1 _6(5.0):C1 _7(5.0):C1 _8(5.0):C1 _9(5.0):C1 _a(5.0):C1 _b(5.0):C1 _c(5.0):C1 _d(5.0):C1 _e(5.0):C1)}
   [junit4]   2> 143514 T1026 C95 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 13
   [junit4]   2> 143518 T1026 C95 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100015]} 0 2
   [junit4]   2> 143520 T1026 C95 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 143525 T1026 C95 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_g,generation=16}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_h,generation=17}
   [junit4]   2> 143526 T1026 C95 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 17
   [junit4]   2> 143527 T1026 C95 oass.SolrIndexSearcher.<init> Opening Searcher@c44a4ed5 main
   [junit4]   2> 143528 T1026 C95 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 143528 T1028 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c44a4ed5 main{StandardDirectoryReader(segments_h:34:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1 _3(5.0):C1 _4(5.0):C1 _5(5.0):C1 _6(5.0):C1 _7(5.0):C1 _8(5.0):C1 _9(5.0):C1 _a(5.0):C1 _b(5.0):C1 _c(5.0):C1 _d(5.0):C1 _e(5.0):C1 _f(5.0):C1)}
   [junit4]   2> 143529 T1026 C95 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 9
   [junit4]   2> 143531 T1026 C95 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100016]} 0 1
   [junit4]   2> 143533 T1026 C95 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 143537 T1026 C95 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_h,generation=17}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_i,generation=18}
   [junit4]   2> 143538 T1026 C95 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 18
   [junit4]   2> 143539 T1026 C95 oass.SolrIndexSearcher.<init> Opening Searcher@17cc06d4 main
   [junit4]   2> 143540 T1026 C95 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 143540 T1028 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@17cc06d4 main{StandardDirectoryReader(segments_i:36:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1 _3(5.0):C1 _4(5.0):C1 _5(5.0):C1 _6(5.0):C1 _7(5.0):C1 _8(5.0):C1 _9(5.0):C1 _a(5.0):C1 _b(5.0):C1 _c(5.0):C1 _d(5.0):C1 _e(5.0):C1 _f(5.0):C1 _g(5.0):C1)}
   [junit4]   2> 143541 T1026 C95 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 8
   [junit4]   2> 143544 T1026 C95 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100017]} 0 2
   [junit4]   2> 143545 T1026 C95 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 143549 T1026 C95 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_i,generation=18}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_j,generation=19}
   [junit4]   2> 143550 T1026 C95 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 19
   [junit4]   2> 143551 T1026 C95 oass.SolrIndexSearcher.<init> Opening Searcher@585a06b3 main
   [junit4]   2> 143552 T1026 C95 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 143552 T1028 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@585a06b3 main{StandardDirectoryReader(segments_j:38:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1 _3(5.0):C1 _4(5.0):C1 _5(5.0):C1 _6(5.0):C1 _7(5.0):C1 _8(5.0):C1 _9(5.0):C1 _a(5.0):C1 _b(5.0):C1 _c(5.0):C1 _d(5.0):C1 _e(5.0):C1 _f(5.0):C1 _g(5.0):C1 _h(5.0):C1)}
   [junit4]   2> 143553 T1026 C95 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 8
   [junit4]   2> 143556 T1026 C95 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100018]} 0 2
   [junit4]   2> 143557 T1026 C95 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 143561 T1026 C95 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_j,generation=19}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_k,generation=20}
   [junit4]   2> 143562 T1026 C95 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 20
   [junit4]   2> 143563 T1026 C95 oass.SolrIndexSearcher.<init> Opening Searcher@a2f02e56 main
   [junit4]   2> 143564 T1026 C95 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 143564 T1028 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@a2f02e56 main{StandardDirectoryReader(segments_k:40:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1 _3(5.0):C1 _4(5.0):C1 _5(5.0):C1 _6(5.0):C1 _7(5.0):C1 _8(5.0):C1 _9(5.0):C1 _a(5.0):C1 _b(5.0):C1 _c(5.0):C1 _d(5.0):C1 _e(5.0):C1 _f(5.0):C1 _g(5.0):C1 _h(5.0):C1 _i(5.0):C1)}
   [junit4]   2> 143565 T1026 C95 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 8
   [junit4]   2> 143568 T1026 C95 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100019]} 0 1
   [junit4]   2> 143569 T1026 C95 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 143574 T1026 C95 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_k,generation=20}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_l,generation=21}
   [junit4]   2> 143574 T1026 C95 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 21
   [junit4]   2> 143576 T1026 C95 oass.SolrIndexSearcher.<init> Opening Searcher@f17e7de2 main
   [junit4]   2> 143576 T1026 C95 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 143576 T1028 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@f17e7de2 main{StandardDirectoryReader(segments_l:42:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1 _3(5.0):C1 _4(5.0):C1 _5(5.0):C1 _6(5.0):C1 _7(5.0):C1 _8(5.0):C1 _9(5.0):C1 _a(5.0):C1 _b(5.0):C1 _c(5.0):C1 _d(5.0):C1 _e(5.0):C1 _f(5.0):C1 _g(5.0):C1 _h(5.0):C1 _i(5.0):C1 _j(5.0):C1)}
   [junit4]   2> 143577 T1026 C95 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 8
   [junit4]   2> 143580 T1026 C95 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100020]} 0 1
   [junit4]   2> 143581 T1026 C95 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 143586 T1026 C95 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_l,generation=21}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_m,generation=22}
   [junit4]   2> 143586 T1026 C95 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 22
   [junit4]   2> 143589 T1026 C95 oass.SolrIndexSearcher.<init> Opening Searcher@b07ec784 main
   [junit4]   2> 143590 T1026 C95 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 143590 T1028 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@b07ec784 main{StandardDirectoryReader(segments_m:44:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1 _3(5.0):C1 _4(5.0):C1 _5(5.0):C1 _6(5.0):C1 _7(5.0):C1 _8(5.0):C1 _9(5.0):C1 _a(5.0):C1 _b(5.0):C1 _c(5.0):C1 _d(5.0):C1 _e(5.0):C1 _f(5.0):C1 _g(5.0):C1 _h(5.0):C1 _i(5.0):C1 _j(5.0):C1 _k(5.0):C1)}
   [junit4]   2> 143591 T1026 C95 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 10
   [junit4]   2> 143594 T1026 C95 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100021]} 0 1
   [junit4]   2> 143595 T1026 C95 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 143600 T1026 C95 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_m,generation=22}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_n,generation=23}
   [junit4]   2> 143601 T1026 C95 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 23
   [junit4]   2> 143602 T1026 C95 oass.SolrIndexSearcher.<init> Opening Searcher@2abdfad8 main
   [junit4]   2> 143603 T1026 C95 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 143603 T1028 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2abdfad8 main{StandardDirectoryReader(segments_n:46:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1 _3(5.0):C1 _4(5.0):C1 _5(5.0):C1 _6(5.0):C1 _7(5.0):C1 _8(5.0):C1 _9(5.0):C1 _a(5.0):C1 _b(5.0):C1 _c(5.0):C1 _d(5.0):C1 _e(5.0):C1 _f(5.0):C1 _g(5.0):C1 _h(5.0):C1 _i(5.0):C1 _j(5.0):C1 _k(5.0):C1 _l(5.0):C1)}
   [junit4]   2> 143604 T1026 C95 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 9
   [junit4]   2> 143606 T1026 C95 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100022]} 0 1
   [junit4]   2> 143608 T1026 C95 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 143613 T1026 C95 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_n,generation=23}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_o,generation=24}
   [junit4]   2> 143613 T1026 C95 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 24
   [junit4]   2> 143615 T1026 C95 oass.SolrIndexSearcher.<init> Opening Searcher@c4b3d5d5 main
   [junit4]   2> 143616 T1026 C95 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 143616 T1028 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c4b3d5d5 main{StandardDirectoryReader(segments_o:48:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1 _3(5.0):C1 _4(5.0):C1 _5(5.0):C1 _6(5.0):C1 _7(5.0):C1 _8(5.0):C1 _9(5.0):C1 _a(5.0):C1 _b(5.0):C1 _c(5.0):C1 _d(5.0):C1 _e(5.0):C1 _f(5.0):C1 _g(5.0):C1 _h(5.0):C1 _i(5.0):C1 _j(5.0):C1 _k(5.0):C1 _l(5.0):C1 _m(5.0):C1)}
   [junit4]   2> 143617 T1026 C95 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 9
   [junit4]   2> 143619 T1026 C95 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100023]} 0 1
   [junit4]   2> 143621 T1026 C95 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 143626 T1026 C95 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_o,generation=24}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_p,generation=25}
   [junit4]   2> 143627 T1026 C95 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 25
   [junit4]   2> 143628 T1026 C95 oass.SolrIndexSearcher.<init> Opening Searcher@942c0b11 main
   [junit4]   2> 143629 T1026 C95 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 143629 T1028 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@942c0b11 main{StandardDirectoryReader(segments_p:50:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1 _3(5.0):C1 _4(5.0):C1 _5(5.0):C1 _6(5.0):C1 _7(5.0):C1 _8(5.0):C1 _9(5.0):C1 _a(5.0):C1 _b(5.0):C1 _c(5.0):C1 _d(5.0):C1 _e(5.0):C1 _f(5.0):C1 _g(5.0):C1 _h(5.0):C1 _i(5.0):C1 _j(5.0):C1 _k(5.0):C1 _l(5.0):C1 _m(5.0):C1 _n(5.0):C1)}
   [junit4]   2> 143630 T1026 C95 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 9
   [junit4]   2> 143633 T1026 C95 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100024]} 0 2
   [junit4]   2> 143635 T1026 C95 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 143642 T1026 C95 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_p,generation=25}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_q,generation=26}
   [junit4]   2> 143643 T1026 C95 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 26
   [junit4]   2> 143645 T1026 C95 oass.SolrIndexSearcher.<init> Opening Searcher@4b425434 main
   [junit4]   2> 143645 T1026 C95 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 143645 T1028 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4b425434 main{StandardDirectoryReader(segments_q:52:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1 _3(5.0):C1 _4(5.0):C1 _5(5.0):C1 _6(5.0):C1 _7(5.0):C1 _8(5.0):C1 _9(5.0):C1 _a(5.0):C1 _b(5.0):C1 _c(5.0):C1 _d(5.0):C1 _e(5.0):C1 _f(5.0):C1 _g(5.0):C1 _h(5.0):C1 _i(5.0):C1 _j(5.0):C1 _k(5.0):C1 _l(5.0):C1 _m(5.0):C1 _n(5.0):C1 _o(5.0):C1)}
   [junit4]   2> 143646 T1026 C95 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 11
   [junit4]   2> 143649 T1026 C95 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100025]} 0 1
   [junit4]   2> 143650 T1026 C95 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 143656 T1026 C95 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_q,generation=26}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_r,generation=27}
   [junit4]   2> 143657 T1026 C95 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 27
   [junit4]   2> 143658 T1026 C95 oass.SolrIndexSearcher.<init> Opening Searcher@8580904 main
   [junit4]   2> 143659 T1026 C95 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 143659 T1028 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@8580904 main{StandardDirectoryReader(segments_r:54:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1 _3(5.0):C1 _4(5.0):C1 _5(5.0):C1 _6(5.0):C1 _7(5.0):C1 _8(5.0):C1 _9(5.0):C1 _a(5.0):C1 _b(5.0):C1 _c(5.0):C1 _d(5.0):C1 _e(5.0):C1 _f(5.0):C1 _g(5.0):C1 _h(5.0):C1 _i(5.0):C1 _j(5.0):C1 _k(5.0):C1 _l(5.0):C1 _m(5.0):C1 _n(5.0):C1 _o(5.0):C1 _p(5.0):C1)}
   [junit4]   2> 143660 T1026 C95 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 10
   [junit4]   2> ASYNC  NEW_CORE C96 name=collection1 org.apache.solr.core.SolrCore@c4e621a3
   [junit4]   2> 143669 T1026 C96 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100026]} 0 8
   [junit4]   2> 143671 T1026 C96 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 143676 T1026 C96 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_r,generation=27}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_s,generation=28}
   [junit4]   2> 143677 T1026 C96 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 28
   [junit4]   2> 143679 T1026 C96 oass.SolrIndexSearcher.<init> Opening Searcher@d41f263 main
   [junit4]   2> 143680 T1026 C96 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 143680 T1028 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@d41f263 main{StandardDirectoryReader(segments_s:56:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1 _3(5.0):C1 _4(5.0):C1 _5(5.0):C1 _6(5.0):C1 _7(5.0):C1 _8(5.0):C1 _9(5.0):C1 _a(5.0):C1 _b(5.0):C1 _c(5.0):C1 _d(5.0):C1 _e(5.0):C1 _f(5.0):C1 _g(5.0):C1 _h(5.0):C1 _i(5.0):C1 _j(5.0):C1 _k(5.0):C1 _l(5.0):C1 _m(5.0):C1 _n(5.0):C1 _o(5.0):C1 _p(5.0):C1 _q(5.0):C1)}
   [junit4]   2> 143681 T1026 C96 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 10
   [junit4]   2> 143683 T1026 C96 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100027]} 0 1
   [junit4]   2> 143685 T1026 C96 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 143691 T1026 C96 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_s,generation=28}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_t,generation=29}
   [junit4]   2> 143691 T1026 C96 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 29
   [junit4]   2> 143693 T1026 C96 oass.SolrIndexSearcher.<init> Opening Searcher@60b37294 main
   [junit4]   2> 143693 T1026 C96 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 143694 T1028 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@60b37294 main{StandardDirectoryReader(segments_t:58:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1 _3(5.0):C1 _4(5.0):C1 _5(5.0):C1 _6(5.0):C1 _7(5.0):C1 _8(5.0):C1 _9(5.0):C1 _a(5.0):C1 _b(5.0):C1 _c(5.0):C1 _d(5.0):C1 _e(5.0):C1 _f(5.0):C1 _g(5.0):C1 _h(5.0):C1 _i(5.0):C1 _j(5.0):C1 _k(5.0):C1 _l(5.0):C1 _m(5.0):C1 _n(5.0):C1 _o(5.0):C1 _p(5.0):C1 _q(5.0):C1 _r(5.0):C1)}
   [junit4]   2> 143695 T1026 C96 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 10
   [junit4]   2> 143697 T1026 C96 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100028]} 0 1
   [junit4]   2> 143699 T1026 C96 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 143704 T1026 C96 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_t,generation=29}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@36861e90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39fdef68),segFN=segments_u,generation=30}
   [junit4]   2> 143705 T1026 C96 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 30
   [junit4]   2> 143707 T1026 C96 oass.SolrIndexSearcher.<init> Opening Searcher@c00cfe90 main
   [junit4]   2> 143707 T1026 C96 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 143707 T1028 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c00cfe90 main{StandardDirectoryReader(segments_u:60:nrt _0(5.0):C1 _1(5.0):C1 _2(5.0):C1 _3(5.0):C1 _4(5.0):C1 _5(5.0):C1 _6(5.0):C1 _7(5.0):C1 _8(5.0):C1 _9(5.0):C1 _a(5.0):C1 _b(5.0):C1 _c(5.0):C1 _d(5.0):C1 _e(5.0):C1 _f(5.0):C1 _g(5.0):C1 _h(5.0):C1 _i(5.0):C1 _j(5.0):C1 _k(5.0):C1 _l(5.0):C1 _m(5.0):C1 _n(5.0):C1 _o(5.0):C1 _p(5.0):C1 _q(5.0):C1 _r(5.0):C1 _s(5.0):C1)}
   [junit4]   2> 143708 T1026 C96 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 10
   [junit4]   2> 143711 T1026 C96 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100029]} 0 1
   [junit4]   2> 143712 T1026 C96 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 143718 T1026 C96 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWr

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

371 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
   [junit4]   2> 1066342 T5371 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
   [junit4]   2> 1066343 T5371 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 1066343 T5371 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.component.DistributedQueryElevationComponentTest-1391642837253/shard1/data/index [CachedDir<<refCount=0;path=./org.apache.solr.handler.component.DistributedQueryElevationComponentTest-1391642837253/shard1/data/index;done=false>>]
   [junit4]   2> 1066344 T5371 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.component.DistributedQueryElevationComponentTest-1391642837253/shard1/data/index
   [junit4]   2> 1066345 T5371 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.component.DistributedQueryElevationComponentTest-1391642837253/shard1/data [CachedDir<<refCount=0;path=./org.apache.solr.handler.component.DistributedQueryElevationComponentTest-1391642837253/shard1/data;done=false>>]
   [junit4]   2> 1066345 T5371 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.component.DistributedQueryElevationComponentTest-1391642837253/shard1/data
   [junit4]   2> 1066347 T5371 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/,null}
   [junit4]   2> 1066420 T5371 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=-1378269312
   [junit4]   2> 1066421 T5371 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@d5b50c94
   [junit4]   2> 1066427 T5371 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=3,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
   [junit4]   2> 1066427 T5371 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
   [junit4]   2> 1066428 T5371 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 1066429 T5371 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
   [junit4]   2> 1066429 T5371 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
   [junit4]   2> 1066430 T5371 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 1066431 T5371 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.component.DistributedQueryElevationComponentTest-1391642837253/shard2/data [CachedDir<<refCount=0;path=./org.apache.solr.handler.component.DistributedQueryElevationComponentTest-1391642837253/shard2/data;done=false>>]
   [junit4]   2> 1066432 T5371 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.component.DistributedQueryElevationComponentTest-1391642837253/shard2/data
   [junit4]   2> 1066432 T5371 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.handler.component.DistributedQueryElevationComponentTest-1391642837253/shard2/data/index [CachedDir<<refCount=0;path=./org.apache.solr.handler.component.DistributedQueryElevationComponentTest-1391642837253/shard2/data/index;done=false>>]
   [junit4]   2> 1066433 T5371 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.handler.component.DistributedQueryElevationComponentTest-1391642837253/shard2/data/index
   [junit4]   2> 1066434 T5371 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/,null}
   [junit4]   2> 1066487 T5371 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=DistributedQueryElevationComponentTest -Dtests.method=testDistribSearch -Dtests.seed=80077084E3E0D614 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=el_CY -Dtests.timezone=America/Barbados -Dtests.file.encoding=US-ASCII
   [junit4] FAILURE 1.90s J1 | DistributedQueryElevationComponentTest.testDistribSearch <<<
   [junit4]    > Throwable #1: junit.framework.AssertionFailedError: .responseHeader.params.version!=sort (unordered or missing)
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([80077084E3E0D614:1E1FE9C94BFB628]:0)
   [junit4]    > 	at junit.framework.Assert.fail(Assert.java:50)
   [junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase.compareSolrResponses(BaseDistributedSearchTestCase.java:840)
   [junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase.compareResponses(BaseDistributedSearchTestCase.java:859)
   [junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:562)
   [junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:542)
   [junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:521)
   [junit4]    > 	at org.apache.solr.handler.component.DistributedQueryElevationComponentTest.doTest(DistributedQueryElevationComponentTest.java:83)
   [junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:867)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:780)
   [junit4]   2> 1066493 T5371 oas.SolrTestCaseJ4.deleteCore ###deleteCore
   [junit4]   2> NOTE: test params are: codec=Lucene45, sim=RandomSimilarityProvider(queryNorm=true,coord=crazy): {}, locale=el_CY, timezone=America/Barbados
   [junit4]   2> NOTE: Linux 3.8.0-35-generic x86/IBM Corporation 1.7.0 (32-bit)/cpus=8,threads=1,free=22151968,total=290914304
   [junit4]   2> NOTE: All tests run in this JVM: [HighlighterTest, CurrencyFieldXmlFileTest, TestStressRecovery, ShardRoutingCustomTest, TestStressReorder, CustomCollectionTest, TestReloadAndDeleteDocs, DocumentAnalysisRequestHandlerTest, ConvertedLegacyTest, TestSolrJ, DistributedDebugComponentTest, TestXIncludeConfig, TestDistributedGrouping, TestFileDictionaryLookup, TestFuzzyAnalyzedSuggestions, SpellCheckComponentTest, PluginInfoTest, FileUtilsTest, TestZkChroot, DefaultValueUpdateProcessorTest, TestFastWriter, TestFastLRUCache, TestInitQParser, AnalysisErrorHandlingTest, FunctionTest, TestSolr4Spatial, TestQuerySenderNoQuery, TestCodecSupport, TestHashPartitioner, TestDocBasedVersionConstraints, XmlUpdateRequestHandlerTest, TestCSVLoader, TestImplicitCoreProperties, AssignTest, TestSolrXml, QueryFacetTest, TestCoreDiscovery, SuggesterWFSTTest, TermVectorComponentDistributedTest, HdfsDirectoryTest, ChangedSchemaMergeTest, IndexBasedSpellCheckerTest, DirectSolrSpellCheckerTest, DebugComponentTest, BlockDirectoryTest, MinimalSchemaTest, TestRandomFaceting, TimeZoneUtilsTest, TestCSVResponseWriter, TestPartialUpdateDeduplication, TestFieldResource, TestQuerySenderListener, TestFastOutputStream, TestLazyCores, UniqFieldsUpdateProcessorFactoryTest, SpellingQueryConverterTest, ConnectionManagerTest, HdfsChaosMonkeySafeLeaderTest, DocValuesMultiTest, TestOmitPositions, TestDistributedMissingSort, TestComponentsName, AddSchemaFieldsUpdateProcessorFactoryTest, CollectionsAPIDistributedZkTest, HighlighterConfigTest, TestStandardQParsers, TestRangeQuery, SolrCoreCheckLockOnStartupTest, TestFiltering, NotRequiredUniqueKeyTest, TestReload, BadIndexSchemaTest, TestRecovery, TestUpdate, TestCursorMarkWithoutUniqueKey, AlternateDirectoryTest, TestBinaryResponseWriter, TestArbitraryIndexDir, TestAddFieldRealTimeGet, TestFoldingMultitermQuery, TestSweetSpotSimilarityFactory, NumericFieldsTest, TriLevelCompositeIdRoutingTest, TestBinaryField, TestSolrQueryParserDefaultOperatorResource, TestIndexSearcher, RequiredFieldsTest, SignatureUpdateProcessorFactoryTest, TestDefaultSimilarityFactory, CachingDirectoryFactoryTest, ChaosMonkeySafeLeaderTest, UnloadDistributedZkTest, OpenCloseCoreStressTest, LeaderElectionIntegrationTest, ShardRoutingTest, FullSolrCloudDistribCmdsTest, LeaderElectionTest, TestDistributedSearch, DistributedSpellCheckComponentTest, TestMultiCoreConfBootstrap, HardAutoCommitTest, StatsComponentTest, SolrCmdDistributorTest, QueryElevationComponentTest, TestSort, TestFunctionQuery, BasicFunctionalityTest, DirectUpdateHandlerTest, OverseerCollectionProcessorTest, DistributedQueryElevationComponentTest]
   [junit4] Completed on J1 in 1.92s, 1 test, 1 failure <<< FAILURES!

[...truncated 482 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: 368 suites, 1593 tests, 5 failures, 35 ignored (7 assumptions)

Total time: 53 minutes 14 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.7.0_60-ea-b04) - Build # 9375 - Still Failing!

Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/9375/
Java: 32bit/jdk1.7.0_60-ea-b04 -client -XX:+UseG1GC

1 tests failed.
FAILED:  junit.framework.TestSuite.org.apache.solr.schema.NotRequiredUniqueKeyTest

Error Message:
Suite timeout exceeded (>= 7200000 msec).

Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
	at __randomizedtesting.SeedInfo.seed([B5C79F46D11F2A91]:0)




Build Log:
[...truncated 11123 lines...]
   [junit4] Suite: org.apache.solr.schema.NotRequiredUniqueKeyTest
   [junit4]   2> 960062 T5510 oas.SolrTestCaseJ4.initCore ####initCore
   [junit4]   2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-NotRequiredUniqueKeyTest-1391648802515
   [junit4]   2> 960063 T5510 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> 960063 T5510 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> 960064 T5510 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> 960107 T5510 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 960154 T5510 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 960154 T5510 oass.IndexSchema.readSchema Reading Solr Schema from schema-not-required-unique-key.xml
   [junit4]   2> 960158 T5510 oass.IndexSchema.readSchema [null] Schema name=test
   [junit4]   2> 960168 T5510 oass.IndexSchema.readSchema default search field in schema is subject
   [junit4]   2> 960170 T5510 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 960171 T5510 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 960171 T5510 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> 960171 T5510 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/'
   [junit4]   2> 960182 T5510 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 960182 T5510 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> 960182 T5510 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/'
   [junit4]   2> 960237 T5510 oasc.CoreContainer.<init> New CoreContainer 13151307
   [junit4]   2> 960237 T5510 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> 960238 T5510 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
   [junit4]   2> 960238 T5510 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 960238 T5510 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
   [junit4]   2> 960239 T5510 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 960239 T5510 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 960239 T5510 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 960239 T5510 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 960240 T5510 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 960240 T5510 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 960242 T5510 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 960242 T5510 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 960242 T5510 oasc.CoreContainer.load Host Name: 
   [junit4]   2> 960246 T5511 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> 960246 T5511 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> 960246 T5511 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> 960247 T5511 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> 960293 T5511 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 960339 T5511 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 960340 T5511 oass.IndexSchema.readSchema Reading Solr Schema from schema-not-required-unique-key.xml
   [junit4]   2> 960344 T5511 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 960354 T5511 oass.IndexSchema.readSchema default search field in schema is subject
   [junit4]   2> 960355 T5511 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 960356 T5511 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 960356 T5511 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-NotRequiredUniqueKeyTest-1391648802515/
   [junit4]   2> 960357 T5511 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@a4e66d
   [junit4]   2> 960357 T5511 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-NotRequiredUniqueKeyTest-1391648802515
   [junit4]   2> 960357 T5511 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-NotRequiredUniqueKeyTest-1391648802515/index/
   [junit4]   2> 960358 T5511 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-NotRequiredUniqueKeyTest-1391648802515/index' doesn't exist. Creating new index...
   [junit4]   2> 960358 T5511 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-NotRequiredUniqueKeyTest-1391648802515/index
   [junit4]   2> 960358 T5511 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=14, maxMergeAtOnceExplicit=19, maxMergedSegmentMB=72.8984375, floorSegmentMB=0.625, forceMergeDeletesPctAllowed=28.43308219426028, segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 960359 T5511 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8c0b1 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@a4ad43)),segFN=segments_1,generation=1}
   [junit4]   2> 960359 T5511 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 960360 T5511 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
   [junit4]   2> 960362 T5511 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 960363 T5511 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 960363 T5511 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 960363 T5511 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 960364 T5511 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 960364 T5511 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 960364 T5511 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 960364 T5511 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 960365 T5511 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 960365 T5511 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 960365 T5511 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 960365 T5511 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 960366 T5511 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 960366 T5511 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 960366 T5511 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 960367 T5511 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 960367 T5511 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
   [junit4]   2> 960368 T5511 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
   [junit4]   2> 960368 T5511 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 960369 T5511 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 960369 T5511 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
   [junit4]   2> 960370 T5511 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
   [junit4]   2> 960370 T5511 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
   [junit4]   2> 960370 T5511 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
   [junit4]   2> 960371 T5511 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 960371 T5511 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 960371 T5511 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 960372 T5511 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 960372 T5511 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 960373 T5511 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 960373 T5511 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 960374 T5511 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 960374 T5511 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 960375 T5511 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
   [junit4]   2> 960375 T5511 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
   [junit4]   2> 960378 T5511 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 960379 T5511 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
   [junit4]   2> 960380 T5511 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 960380 T5511 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 960381 T5511 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@ec8be6
   [junit4]   2> 960381 T5511 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8c0b1 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@a4ad43)),segFN=segments_1,generation=1}
   [junit4]   2> 960382 T5511 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 960382 T5511 oass.SolrIndexSearcher.<init> Opening Searcher@438db0 main
   [junit4]   2> 960382 T5511 oashc.SpellCheckComponent.inform Initializing spell checkers
   [junit4]   2> 960521 T5511 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 960537 T5512 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@438db0 main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 960547 T5511 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 960551 T5510 oas.SolrTestCaseJ4.initCore ####initCore end
   [junit4]   2> 960555 T5510 oas.SolrTestCaseJ4.setUp ###Starting testSchemaLoading
   [junit4]   2> 960555 T5510 oas.SolrTestCaseJ4.tearDown ###Ending testSchemaLoading
   [junit4]   2> 960557 T5510 oas.SolrTestCaseJ4.deleteCore ###deleteCore
   [junit4]   2> 960557 T5510 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=13151307
   [junit4]   2> 7200048 T5509 ccr.ThreadLeakControl$2.evaluate WARNING Suite execution timed out: org.apache.solr.schema.NotRequiredUniqueKeyTest
   [junit4]   2> 	==== jstack at approximately timeout time ====
   [junit4]   2> 	"Thread-2493" ID=5513 WAITING on java.lang.Object@4b9c5b
   [junit4]   2> 		at java.lang.Object.wait(Native Method)
   [junit4]   2> 		- waiting on java.lang.Object@4b9c5b
   [junit4]   2> 		at java.lang.Object.wait(Object.java:503)
   [junit4]   2> 		at org.apache.solr.core.CloserThread.run(CoreContainer.java:1014)
   [junit4]   2> 	
   [junit4]   2> 	"searcherExecutor-3477-thread-1" ID=5512 WAITING on java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@6394a
   [junit4]   2> 		at sun.misc.Unsafe.park(Native Method)
   [junit4]   2> 		- waiting on java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@6394a
   [junit4]   2> 		at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
   [junit4]   2> 		at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
   [junit4]   2> 		at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
   [junit4]   2> 		at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
   [junit4]   2> 		at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
   [junit4]   2> 		at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
   [junit4]   2> 		at java.lang.Thread.run(Thread.java:744)
   [junit4]   2> 	
   [junit4]   2> 	"SUITE-NotRequiredUniqueKeyTest-seed#[B5C79F46D11F2A91]-worker" ID=5510 WAITING on org.apache.solr.core.CloserThread@1700bcc
   [junit4]   2> 		at java.lang.Object.wait(Native Method)
   [junit4]   2> 		- waiting on org.apache.solr.core.CloserThread@1700bcc
   [junit4]   2> 		at java.lang.Thread.join(Thread.java:1280)
   [junit4]   2> 		at java.lang.Thread.join(Thread.java:1354)
   [junit4]   2> 		at org.apache.solr.core.CoreContainer.shutdown(CoreContainer.java:377)
   [junit4]   2> 		at org.apache.solr.util.TestHarness.close(TestHarness.java:337)
   [junit4]   2> 		at org.apache.solr.SolrTestCaseJ4.deleteCore(SolrTestCaseJ4.java:560)
   [junit4]   2> 		at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:140)
   [junit4]   2> 		at sun.reflect.GeneratedMethodAccessor22.invoke(Unknown Source)
   [junit4]   2> 		at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]   2> 		at java.lang.reflect.Method.invoke(Method.java:606)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:700)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
   [junit4]   2> 		at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
   [junit4]   2> 		at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 		at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
   [junit4]   2> 		at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
   [junit4]   2> 		at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
   [junit4]   2> 		at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
   [junit4]   2> 		at java.lang.Thread.run(Thread.java:744)
   [junit4]   2> 	
   [junit4]   2> 	"SUITE-NotRequiredUniqueKeyTest-seed#[B5C79F46D11F2A91]" ID=5509 RUNNABLE
   [junit4]   2> 		at sun.management.ThreadImpl.dumpThreads0(Native Method)
   [junit4]   2> 		at sun.management.ThreadImpl.dumpAllThreads(ThreadImpl.java:446)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.ThreadLeakControl.formatThreadStacksFull(ThreadLeakControl.java:655)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.ThreadLeakControl.access$800(ThreadLeakControl.java:60)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.ThreadLeakControl$2.evaluate(ThreadLeakControl.java:396)
   [junit4]   2> 		- locked java.lang.Object@9b2d63
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner.runSuite(RandomizedRunner.java:556)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner.access$200(RandomizedRunner.java:79)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$1.run(RandomizedRunner.java:492)
   [junit4]   2> 	
   [junit4]   2> 	"RMI TCP Accept-0" ID=2813 RUNNABLE (in native code)
   [junit4]   2> 		at java.net.PlainSocketImpl.socketAccept(Native Method)
   [junit4]   2> 		at java.net.AbstractPlainSocketImpl.accept(AbstractPlainSocketImpl.java:398)
   [junit4]   2> 		at java.net.ServerSocket.implAccept(ServerSocket.java:530)
   [junit4]   2> 		at java.net.ServerSocket.accept(ServerSocket.java:498)
   [junit4]   2> 		at sun.rmi.transport.tcp.TCPTransport$AcceptLoop.executeAcceptLoop(TCPTransport.java:388)
   [junit4]   2> 		at sun.rmi.transport.tcp.TCPTransport$AcceptLoop.run(TCPTransport.java:360)
   [junit4]   2> 		at java.lang.Thread.run(Thread.java:744)
   [junit4]   2> 	
   [junit4]   2> 	"RMI RenewClean-[127.0.0.1:47081]" ID=2809 TIMED_WAITING on java.lang.ref.ReferenceQueue$Lock@1c0e7ec
   [junit4]   2> 		at java.lang.Object.wait(Native Method)
   [junit4]   2> 		- timed waiting on java.lang.ref.ReferenceQueue$Lock@1c0e7ec
   [junit4]   2> 		at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:135)
   [junit4]   2> 		at sun.rmi.transport.DGCClient$EndpointEntry$RenewCleanThread.run(DGCClient.java:535)
   [junit4]   2> 		at java.lang.Thread.run(Thread.java:744)
   [junit4]   2> 	
   [junit4]   2> 	"RMI Scheduler(0)" ID=2808 TIMED_WAITING on java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@1ba766d
   [junit4]   2> 		at sun.misc.Unsafe.park(Native Method)
   [junit4]   2> 		- timed waiting on java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@1ba766d
   [junit4]   2> 		at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:226)
   [junit4]   2> 		at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2082)
   [junit4]   2> 		at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1090)
   [junit4]   2> 		at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807)
   [junit4]   2> 		at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
   [junit4]   2> 		at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
   [junit4]   2> 		at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
   [junit4]   2> 		at java.lang.Thread.run(Thread.java:744)
   [junit4]   2> 	
   [junit4]   2> 	"GC Daemon" ID=2806 TIMED_WAITING on sun.misc.GC$LatencyLock@b509a9
   [junit4]   2> 		at java.lang.Object.wait(Native Method)
   [junit4]   2> 		- timed waiting on sun.misc.GC$LatencyLock@b509a9
   [junit4]   2> 		at sun.misc.GC$Daemon.run(GC.java:117)
   [junit4]   2> 	
   [junit4]   2> 	"RMI Reaper" ID=2805 WAITING on java.lang.ref.ReferenceQueue$Lock@18d69e6
   [junit4]   2> 		at java.lang.Object.wait(Native Method)
   [junit4]   2> 		- waiting on java.lang.ref.ReferenceQueue$Lock@18d69e6
   [junit4]   2> 		at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:135)
   [junit4]   2> 		at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:151)
   [junit4]   2> 		at sun.rmi.transport.ObjectTable$Reaper.run(ObjectTable.java:351)
   [junit4]   2> 		at java.lang.Thread.run(Thread.java:744)
   [junit4]   2> 	
   [junit4]   2> 	"RMI TCP Accept-0" ID=2804 RUNNABLE (in native code)
   [junit4]   2> 		at java.net.PlainSocketImpl.socketAccept(Native Method)
   [junit4]   2> 		at java.net.AbstractPlainSocketImpl.accept(AbstractPlainSocketImpl.java:398)
   [junit4]   2> 		at java.net.ServerSocket.implAccept(ServerSocket.java:530)
   [junit4]   2> 		at java.net.ServerSocket.accept(ServerSocket.java:498)
   [junit4]   2> 		at sun.rmi.transport.tcp.TCPTransport$AcceptLoop.executeAcceptLoop(TCPTransport.java:388)
   [junit4]   2> 		at sun.rmi.transport.tcp.TCPTransport$AcceptLoop.run(TCPTransport.java:360)
   [junit4]   2> 		at java.lang.Thread.run(Thread.java:744)
   [junit4]   2> 	
   [junit4]   2> 	"RMI TCP Accept-0" ID=2803 RUNNABLE (in native code)
   [junit4]   2> 		at java.net.PlainSocketImpl.socketAccept(Native Method)
   [junit4]   2> 		at java.net.AbstractPlainSocketImpl.accept(AbstractPlainSocketImpl.java:398)
   [junit4]   2> 		at java.net.ServerSocket.implAccept(ServerSocket.java:530)
   [junit4]   2> 		at java.net.ServerSocket.accept(ServerSocket.java:498)
   [junit4]   2> 		at sun.rmi.transport.tcp.TCPTransport$AcceptLoop.executeAcceptLoop(TCPTransport.java:388)
   [junit4]   2> 		at sun.rmi.transport.tcp.TCPTransport$AcceptLoop.run(TCPTransport.java:360)
   [junit4]   2> 		at java.lang.Thread.run(Thread.java:744)
   [junit4]   2> 	
   [junit4]   2> 	"TimeLimitedCollector timer thread" ID=2518 TIMED_WAITING
   [junit4]   2> 		at java.lang.Thread.sleep(Native Method)
   [junit4]   2> 		at org.apache.lucene.search.TimeLimitingCollector$TimerThread.run(TimeLimitingCollector.java:267)
   [junit4]   2> 	
   [junit4]   2> 	"Signal Dispatcher" ID=5 RUNNABLE
   [junit4]   2> 	
   [junit4]   2> 	"Finalizer" ID=3 WAITING on java.lang.ref.ReferenceQueue$Lock@123905a
   [junit4]   2> 		at java.lang.Object.wait(Native Method)
   [junit4]   2> 		- waiting on java.lang.ref.ReferenceQueue$Lock@123905a
   [junit4]   2> 		at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:135)
   [junit4]   2> 		at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:151)
   [junit4]   2> 		at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:209)
   [junit4]   2> 	
   [junit4]   2> 	"Reference Handler" ID=2 WAITING on java.lang.ref.Reference$Lock@1da0652
   [junit4]   2> 		at java.lang.Object.wait(Native Method)
   [junit4]   2> 		- waiting on java.lang.ref.Reference$Lock@1da0652
   [junit4]   2> 		at java.lang.Object.wait(Object.java:503)
   [junit4]   2> 		at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:133)
   [junit4]   2> 	
   [junit4]   2> 	"main" ID=1 WAITING on com.carrotsearch.randomizedtesting.RandomizedRunner$1@6a1ca2
   [junit4]   2> 		at java.lang.Object.wait(Native Method)
   [junit4]   2> 		- waiting on com.carrotsearch.randomizedtesting.RandomizedRunner$1@6a1ca2
   [junit4]   2> 		at java.lang.Thread.join(Thread.java:1280)
   [junit4]   2> 		at java.lang.Thread.join(Thread.java:1354)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner.runSuite(RandomizedRunner.java:502)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner.run(RandomizedRunner.java:399)
   [junit4]   2> 		at com.carrotsearch.ant.tasks.junit4.slave.SlaveMain.execute(SlaveMain.java:180)
   [junit4]   2> 		at com.carrotsearch.ant.tasks.junit4.slave.SlaveMain.main(SlaveMain.java:275)
   [junit4]   2> 		at com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe.main(SlaveMainSafe.java:12)
   [junit4]   2> 	
   [junit4]   2> 	^^==============================================
   [junit4]   2> 	
   [junit4]   2> 7200052 T5509 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 3 leaked thread(s).
   [junit4]   2> 7220203 T5509 ccr.ThreadLeakControl.checkThreadLeaks SEVERE 3 threads leaked from SUITE scope at org.apache.solr.schema.NotRequiredUniqueKeyTest: 
   [junit4]   2> 	   1) Thread[id=5512, name=searcherExecutor-3477-thread-1, state=WAITING, group=TGRP-NotRequiredUniqueKeyTest]
   [junit4]   2> 	        at sun.misc.Unsafe.park(Native Method)
   [junit4]   2> 	        at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
   [junit4]   2> 	        at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
   [junit4]   2> 	        at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
   [junit4]   2> 	        at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
   [junit4]   2> 	        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
   [junit4]   2> 	        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
   [junit4]   2> 	        at java.lang.Thread.run(Thread.java:744)
   [junit4]   2> 	   2) Thread[id=5513, name=Thread-2493, state=WAITING, group=TGRP-NotRequiredUniqueKeyTest]
   [junit4]   2> 	        at java.lang.Object.wait(Native Method)
   [junit4]   2> 	        at java.lang.Object.wait(Object.java:503)
   [junit4]   2> 	        at org.apache.solr.core.CloserThread.run(CoreContainer.java:1014)
   [junit4]   2> 	   3) Thread[id=5510, name=SUITE-NotRequiredUniqueKeyTest-seed#[B5C79F46D11F2A91]-worker, state=WAITING, group=TGRP-NotRequiredUniqueKeyTest]
   [junit4]   2> 	        at java.lang.Object.wait(Native Method)
   [junit4]   2> 	        at java.lang.Thread.join(Thread.java:1280)
   [junit4]   2> 	        at java.lang.Thread.join(Thread.java:1354)
   [junit4]   2> 	        at org.apache.solr.core.CoreContainer.shutdown(CoreContainer.java:377)
   [junit4]   2> 	        at org.apache.solr.util.TestHarness.close(TestHarness.java:337)
   [junit4]   2> 	        at org.apache.solr.SolrTestCaseJ4.deleteCore(SolrTestCaseJ4.java:560)
   [junit4]   2> 	        at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:140)
   [junit4]   2> 	        at sun.reflect.GeneratedMethodAccessor22.invoke(Unknown Source)
   [junit4]   2> 	        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]   2> 	        at java.lang.reflect.Method.invoke(Method.java:606)
   [junit4]   2> 	        at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
   [junit4]   2> 	        at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
   [junit4]   2> 	        at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:700)
   [junit4]   2> 	        at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	        at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
   [junit4]   2> 	        at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
   [junit4]   2> 	        at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
   [junit4]   2> 	        at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
   [junit4]   2> 	        at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
   [junit4]   2> 	        at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
   [junit4]   2> 	        at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	        at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
   [junit4]   2> 	        at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
   [junit4]   2> 	        at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
   [junit4]   2> 	        at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
   [junit4]   2> 	        at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	        at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
   [junit4]   2> 	        at java.lang.Thread.run(Thread.java:744)
   [junit4]   2> 7220204 T5509 ccr.ThreadLeakControl.tryToInterruptAll Starting to interrupt leaked threads:
   [junit4]   2> 	   1) Thread[id=5512, name=searcherExecutor-3477-thread-1, state=WAITING, group=TGRP-NotRequiredUniqueKeyTest]
   [junit4]   2> 	   2) Thread[id=5513, name=Thread-2493, state=WAITING, group=TGRP-NotRequiredUniqueKeyTest]
   [junit4]   2> 	   3) Thread[id=5510, name=SUITE-NotRequiredUniqueKeyTest-seed#[B5C79F46D11F2A91]-worker, state=WAITING, group=TGRP-NotRequiredUniqueKeyTest]
   [junit4]   2> 8180266 T5510 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@1abfcd9
   [junit4]   2> 8180300 T5510 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
   [junit4]   2> 8180301 T5510 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
   [junit4]   2> 8180301 T5510 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 8180301 T5510 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
   [junit4]   2> 8180302 T5510 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
   [junit4]   2> 8180302 T5510 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 8180303 T5510 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-NotRequiredUniqueKeyTest-1391648802515/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-NotRequiredUniqueKeyTest-1391648802515/index;done=false>>]
   [junit4]   2> 8180303 T5510 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-NotRequiredUniqueKeyTest-1391648802515/index
   [junit4]   2> 8180304 T5510 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-NotRequiredUniqueKeyTest-1391648802515 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-NotRequiredUniqueKeyTest-1391648802515;done=false>>]
   [junit4]   2> 8180304 T5510 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-NotRequiredUniqueKeyTest-1391648802515
   [junit4]   2> 7220249 T5509 ccr.ThreadLeakControl.tryToInterruptAll All leaked threads terminated.
   [junit4]   2> NOTE: test params are: codec=Lucene42, sim=RandomSimilarityProvider(queryNorm=true,coord=no): {}, locale=iw, timezone=Africa/Accra
   [junit4]   2> NOTE: Linux 3.8.0-35-generic i386/Oracle Corporation 1.7.0_60-ea (32-bit)/cpus=8,threads=1,free=141730880,total=373293056
   [junit4]   2> NOTE: All tests run in this JVM: [SimpleFacetsTest, TestAnalyzeInfixSuggestions, CursorPagingTest, DateMathParserTest, SolrInfoMBeanTest, AnalysisAfterCoreReloadTest, TestReversedWildcardFilterFactory, CSVRequestHandlerTest, TestPHPSerializedResponseWriter, TestCloudManagedSchema, TestFieldCollectionResource, LukeRequestHandlerTest, TestConfig, JsonLoaderTest, TestSearchPerf, AddBlockUpdateTest, TestFaceting, SuggesterFSTTest, TestRandomDVFaceting, TestSolrDeletionPolicy1, SolrCoreTest, FastVectorHighlighterTest, TermsComponentTest, TestTrie, TestMergePolicyConfig, MoreLikeThisHandlerTest, TestSolrXmlPersistor, SampleTest, PeerSyncTest, TestFileDictionaryLookup, DeleteShardTest, TestClassNameShortening, TestSolrXmlPersistence, OverseerTest, SchemaVersionSpecificBehaviorTest, DistributedSpellCheckComponentTest, SpatialFilterTest, TestExtendedDismaxParser, TestHighFrequencyDictionaryFactory, SynonymTokenizerTest, TestRandomMergePolicy, TestPostingsSolrHighlighter, SliceStateUpdateTest, IndexSchemaTest, SpellPossibilityIteratorTest, TestSchemaVersionResource, ExternalFileFieldSortTest, FieldMutatingUpdateProcessorTest, TestLMDirichletSimilarityFactory, CustomCollectionTest, TestStressUserVersions, TestInitQParser, TestValueSourceCache, DistributedQueryElevationComponentTest, AutoCommitTest, RegexBoostProcessorTest, HdfsRecoveryZkTest, DistributedSuggestComponentTest, RecoveryZkTest, TestSolrXMLSerializer, TestSurroundQueryParser, TestSolrQueryParserResource, TestPseudoReturnFields, TestDefaultSearchFieldResource, TestSerializedLuceneMatchVersion, TestSuggestSpellingConverter, ShowFileRequestHandlerTest, LoggingHandlerTest, BadComponentTest, TestGroupingSearch, TestMaxScoreQueryParser, TestRealTimeGet, PreAnalyzedUpdateProcessorTest, ScriptEngineTest, AliasIntegrationTest, CircularListTest, SuggesterTest, TestCopyFieldCollectionResource, QueryEqualityTest, SolrTestCaseJ4Test, TestLFUCache, PolyFieldTest, TestJmxMonitoredMap, TestLMJelinekMercerSimilarityFactory, TestElisionMultitermQuery, AddSchemaFieldsUpdateProcessorFactoryTest, FileBasedSpellCheckerTest, BasicDistributedZkTest, ChaosMonkeySafeLeaderTest, SyncSliceTest, TestReplicationHandler, ClusterStateUpdateTest, ZkSolrClientTest, ZkCLITest, TestDistributedSearch, TestHashPartitioner, TestJoin, TestSolr4Spatial, SolrCmdDistributorTest, TestSort, TestFunctionQuery, TestLazyCores, OverseerCollectionProcessorTest, SoftAutoCommitTest, TestCSVLoader, SolrCoreCheckLockOnStartupTest, DirectUpdateHandlerOptimizeTest, StatelessScriptUpdateProcessorFactoryTest, TestRemoteStreaming, DistanceFunctionTest, CacheHeaderTest, TestQueryUtils, DirectSolrSpellCheckerTest, DocumentBuilderTest, TestIndexingPerformance, TestSolrQueryParser, SolrPluginUtilsTest, ReturnFieldsTest, MBeansHandlerTest, BinaryUpdateRequestHandlerTest, TestComponentsName, SearchHandlerTest, UpdateParamsTest, TestSolrIndexConfig, ResponseLogComponentTest, TestSolrDeletionPolicy2, TestBinaryField, TestFuzzyAnalyzedSuggestions, NotRequiredUniqueKeyTest]
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=NotRequiredUniqueKeyTest -Dtests.seed=B5C79F46D11F2A91 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=iw -Dtests.timezone=Africa/Accra -Dtests.file.encoding=US-ASCII
   [junit4] ERROR   0.00s J0 | NotRequiredUniqueKeyTest (suite) <<<
   [junit4]    > Throwable #1: java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([B5C79F46D11F2A91]:0)
   [junit4] Completed on J0 in 7220.27s, 1 test, 1 error <<< FAILURES!

[...truncated 10 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: 368 suites, 1593 tests, 1 suite-level error, 35 ignored (7 assumptions)

Total time: 162 minutes 52 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/jdk1.7.0_60-ea-b04 -client -XX:+UseG1GC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure