Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/12311/
Java: 64bit/jdk1.9.0-ea-b54 -XX:-UseCompressedOops -XX:+UseG1GC

5 tests failed.
FAILED:  org.apache.solr.client.solrj.SolrExampleBinaryTest.testRawFields

Error Message:
Expected ',' or '}': char=@,position=286 
BEFORE='","json_s":org.apache.lucene.document.LazyDocument$LazyField@' 
AFTER='5b86d21d}]}} '

Stack Trace:
org.noggit.JSONParser$ParseException: Expected ',' or '}': char=@,position=286 
BEFORE='","json_s":org.apache.lucene.document.LazyDocument$LazyField@' 
AFTER='5b86d21d}]}} '
        at 
__randomizedtesting.SeedInfo.seed([7F8FA793B2B70717:442F69492FA0E10A]:0)
        at org.noggit.JSONParser.err(JSONParser.java:356)
        at org.noggit.JSONParser.nextEvent(JSONParser.java:958)
        at 
org.apache.solr.client.solrj.SolrExampleTests.testRawFields(SolrExampleTests.java:595)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:502)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
        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:365)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
        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 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
        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:365)
        at java.lang.Thread.run(Thread.java:745)


FAILED:  org.apache.solr.client.solrj.SolrExampleXMLTest.testRawFields

Error Message:
Expected ',' or '}': char=@,position=286 
BEFORE='","json_s":org.apache.lucene.document.LazyDocument$LazyField@' 
AFTER='3d5a1b53}]}} '

Stack Trace:
org.noggit.JSONParser$ParseException: Expected ',' or '}': char=@,position=286 
BEFORE='","json_s":org.apache.lucene.document.LazyDocument$LazyField@' 
AFTER='3d5a1b53}]}} '
        at 
__randomizedtesting.SeedInfo.seed([7F8FA793B2B70717:442F69492FA0E10A]:0)
        at org.noggit.JSONParser.err(JSONParser.java:356)
        at org.noggit.JSONParser.nextEvent(JSONParser.java:958)
        at 
org.apache.solr.client.solrj.SolrExampleTests.testRawFields(SolrExampleTests.java:595)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:502)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
        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:365)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
        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 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
        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:365)
        at java.lang.Thread.run(Thread.java:745)


FAILED:  
org.apache.solr.client.solrj.embedded.SolrExampleJettyTest.testRawFields

Error Message:
Expected ',' or '}': char=@,position=286 
BEFORE='","json_s":org.apache.lucene.document.LazyDocument$LazyField@' 
AFTER='4d6bbdf3}]}} '

Stack Trace:
org.noggit.JSONParser$ParseException: Expected ',' or '}': char=@,position=286 
BEFORE='","json_s":org.apache.lucene.document.LazyDocument$LazyField@' 
AFTER='4d6bbdf3}]}} '
        at 
__randomizedtesting.SeedInfo.seed([7F8FA793B2B70717:442F69492FA0E10A]:0)
        at org.noggit.JSONParser.err(JSONParser.java:356)
        at org.noggit.JSONParser.nextEvent(JSONParser.java:958)
        at 
org.apache.solr.client.solrj.SolrExampleTests.testRawFields(SolrExampleTests.java:595)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:502)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
        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:365)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
        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 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
        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:365)
        at java.lang.Thread.run(Thread.java:745)


FAILED:  
org.apache.solr.client.solrj.embedded.SolrExampleStreamingBinaryTest.testRawFields

Error Message:
Expected ',' or '}': char=@,position=286 
BEFORE='","json_s":org.apache.lucene.document.LazyDocument$LazyField@' 
AFTER='634c174a}]}} '

Stack Trace:
org.noggit.JSONParser$ParseException: Expected ',' or '}': char=@,position=286 
BEFORE='","json_s":org.apache.lucene.document.LazyDocument$LazyField@' 
AFTER='634c174a}]}} '
        at 
__randomizedtesting.SeedInfo.seed([7F8FA793B2B70717:442F69492FA0E10A]:0)
        at org.noggit.JSONParser.err(JSONParser.java:356)
        at org.noggit.JSONParser.nextEvent(JSONParser.java:958)
        at 
org.apache.solr.client.solrj.SolrExampleTests.testRawFields(SolrExampleTests.java:595)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:502)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
        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:365)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
        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 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
        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:365)
        at java.lang.Thread.run(Thread.java:745)


FAILED:  
org.apache.solr.client.solrj.embedded.SolrExampleStreamingTest.testRawFields

Error Message:
Expected ',' or '}': char=@,position=286 
BEFORE='","json_s":org.apache.lucene.document.LazyDocument$LazyField@' 
AFTER='1720f567}]}} '

Stack Trace:
org.noggit.JSONParser$ParseException: Expected ',' or '}': char=@,position=286 
BEFORE='","json_s":org.apache.lucene.document.LazyDocument$LazyField@' 
AFTER='1720f567}]}} '
        at 
__randomizedtesting.SeedInfo.seed([7F8FA793B2B70717:442F69492FA0E10A]:0)
        at org.noggit.JSONParser.err(JSONParser.java:356)
        at org.noggit.JSONParser.nextEvent(JSONParser.java:958)
        at 
org.apache.solr.client.solrj.SolrExampleTests.testRawFields(SolrExampleTests.java:595)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:502)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
        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:365)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
        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 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
        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:365)
        at java.lang.Thread.run(Thread.java:745)




Build Log:
[...truncated 10944 lines...]
   [junit4] Suite: 
org.apache.solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
 7F8FA793B2B70717-001/init-core-data-001
   [junit4]   2> 6323 T40 oas.SolrTestCaseJ4.initCore ####initCore
   [junit4]   2> 6323 T40 oas.SolrTestCaseJ4.initCore ####initCore end
   [junit4]   2> 6324 T40 oas.SolrTestCaseJ4.writeCoreProperties Writing 
core.properties file to 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
 7F8FA793B2B70717-001/tempDir-002/cores/core
   [junit4]   2> 6345 T40 oejul.Log.initialized Logging initialized @7511ms
   [junit4]   2> 6469 T40 oejs.Server.doStart jetty-9.2.9.v20150224
   [junit4]   2> 6539 T40 oejsh.ContextHandler.doStart Started 
o.e.j.s.ServletContextHandler@4e39cceb{/solr,null,AVAILABLE}
   [junit4]   2> 6553 T40 oejs.AbstractConnector.doStart Started 
ServerConnector@8613e2c{HTTP/1.1}{127.0.0.1:55741}
   [junit4]   2> 6554 T40 oejs.Server.doStart Started @7722ms
   [junit4]   2> 6555 T40 oascse.JettySolrRunner$1.lifeCycleStarted Jetty 
properties: {hostContext=/solr, hostPort=55741, 
configSetBaseDir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
 7F8FA793B2B70717-001/tempDir-001, 
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
 7F8FA793B2B70717-001/tempDir-002/cores}
   [junit4]   2> 6570 T40 oass.SolrDispatchFilter.init 
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@484b61fc
   [junit4]   2> 6570 T40 oasc.SolrResourceLoader.<init> new SolrResourceLoader 
for directory: 
'/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
 7F8FA793B2B70717-001/tempDir-001/'
   [junit4]   2> 6583 T40 oasc.SolrXmlConfig.fromFile Loading container 
configuration from 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
 7F8FA793B2B70717-001/tempDir-001/solr.xml
   [junit4]   2> 6592 T40 oasc.CorePropertiesLocator.<init> Config-defined core 
root directory: 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
 7F8FA793B2B70717-001/tempDir-001
   [junit4]   2> 6593 T40 oasc.CoreContainer.<init> New CoreContainer 1871845424
   [junit4]   2> 6594 T40 oasc.CoreContainer.load Loading cores into 
CoreContainer 
[instanceDir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
 7F8FA793B2B70717-001/tempDir-001/]
   [junit4]   2> 6594 T40 oasc.CoreContainer.load loading shared library: 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
 7F8FA793B2B70717-001/tempDir-001/lib
   [junit4]   2> 6594 T40 oasc.SolrResourceLoader.addToClassLoader WARN Can't 
find (or read) directory to add to classloader: lib (resolved as: 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
 7F8FA793B2B70717-001/tempDir-001/lib).
   [junit4]   2> 6606 T40 oashc.HttpShardHandlerFactory.init created with 
socketTimeout : 600000,connTimeout : 60000,maxConnectionsPerHost : 
20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 
2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : 
false,useRetries : false,
   [junit4]   2> 6621 T40 oasu.UpdateShardHandler.<init> Creating 
UpdateShardHandler HTTP client with params: 
socketTimeout=600000&connTimeout=60000&retry=true
   [junit4]   2> 6622 T40 oasl.LogWatcher.createWatcher SLF4J impl is 
org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 6622 T40 oasl.LogWatcher.newRegisteredLogWatcher Registering 
Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 6623 T40 oasc.CoreContainer.load Node Name: 
   [junit4]   2> 6623 T40 oasc.CorePropertiesLocator.discover Looking for core 
definitions underneath 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
 7F8FA793B2B70717-001/tempDir-001
   [junit4]   2> 6625 T40 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: 
{name=collection1, config=solrconfig.xml, 
instanceDir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
 7F8FA793B2B70717-001/tempDir-001/collection1, loadOnStartup=true, 
absoluteInstDir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
 7F8FA793B2B70717-001/tempDir-001/collection1/, schema=schema.xml, 
transient=false, dataDir=data/}
   [junit4]   2> 6625 T40 oasc.CorePropertiesLocator.discoverUnder Found core 
collection1 in 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
 7F8FA793B2B70717-001/tempDir-001/collection1/
   [junit4]   2> 6625 T40 oasc.CorePropertiesLocator.discover Found 1 core 
definitions
   [junit4]   2> 6629 T53 oasc.SolrResourceLoader.<init> new SolrResourceLoader 
for directory: 
'/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
 7F8FA793B2B70717-001/tempDir-001/collection1/'
   [junit4]   2> 6662 T53 oasc.SolrConfig.refreshRequestParams current version 
of requestparams : -1
   [junit4]   2> 6664 T53 oasc.SolrConfig.initLibs Adding specified lib dirs to 
ClassLoader
   [junit4]   2> 6664 T53 oasc.SolrResourceLoader.addToClassLoader WARN Can't 
find (or read) directory to add to classloader: 
../../../../contrib/extraction/lib (resolved as: 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
 
7F8FA793B2B70717-001/tempDir-001/collection1/../../../../contrib/extraction/lib).
   [junit4]   2> 6666 T53 oasc.SolrResourceLoader.addToClassLoader WARN Can't 
find (or read) directory to add to classloader: ../../../../dist/ (resolved as: 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
 7F8FA793B2B70717-001/tempDir-001/collection1/../../../../dist).
   [junit4]   2> 6666 T53 oasc.SolrResourceLoader.addToClassLoader WARN Can't 
find (or read) directory to add to classloader: 
../../../../contrib/clustering/lib/ (resolved as: 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
 
7F8FA793B2B70717-001/tempDir-001/collection1/../../../../contrib/clustering/lib).
   [junit4]   2> 6666 T53 oasc.SolrResourceLoader.addToClassLoader WARN Can't 
find (or read) directory to add to classloader: ../../../../dist/ (resolved as: 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
 7F8FA793B2B70717-001/tempDir-001/collection1/../../../../dist).
   [junit4]   2> 6666 T53 oasc.SolrResourceLoader.addToClassLoader WARN Can't 
find (or read) directory to add to classloader: ../../../../contrib/langid/lib/ 
(resolved as: 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
 7F8FA793B2B70717-001/tempDir-001/collection1/../../../../contrib/langid/lib).
   [junit4]   2> 6667 T53 oasc.SolrResourceLoader.addToClassLoader WARN Can't 
find (or read) directory to add to classloader: ../../../../dist/ (resolved as: 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
 7F8FA793B2B70717-001/tempDir-001/collection1/../../../../dist).
   [junit4]   2> 6667 T53 oasc.SolrResourceLoader.addToClassLoader WARN Can't 
find (or read) directory to add to classloader: 
../../../../contrib/velocity/lib (resolved as: 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
 7F8FA793B2B70717-001/tempDir-001/collection1/../../../../contrib/velocity/lib).
   [junit4]   2> 6667 T53 oasc.SolrResourceLoader.addToClassLoader WARN Can't 
find (or read) directory to add to classloader: ../../../../dist/ (resolved as: 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
 7F8FA793B2B70717-001/tempDir-001/collection1/../../../../dist).
   [junit4]   2> 6691 T53 oasu.SolrIndexConfig.<init> IndexWriter infoStream 
solr logging is enabled
   [junit4]   2> 6694 T53 oasc.SolrConfig.<init> Using Lucene MatchVersion: 
6.0.0
   [junit4]   2> 6753 T53 oasc.SolrConfig.<init> Loaded SolrConfig: 
solrconfig.xml
   [junit4]   2> 6757 T53 oass.IndexSchema.readSchema Reading Solr Schema from 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
 7F8FA793B2B70717-001/tempDir-001/collection1/conf/schema.xml
   [junit4]   2> 6770 T53 oass.IndexSchema.readSchema [collection1] Schema 
name=example
   [junit4]   2> 7052 T53 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 7054 T53 oasr.RestManager$Registry.registerManagedResource 
Registered ManagedResource impl 
org.apache.solr.rest.schema.analysis.ManagedWordSetResource for path 
/schema/analysis/stopwords/english
   [junit4]   2> 7054 T53 oasr.RestManager$Registry.registerManagedResource 
Registered ManagedResource impl 
org.apache.solr.rest.schema.analysis.ManagedSynonymFilterFactory$SynonymManager 
for path /schema/analysis/synonyms/english
   [junit4]   2> 7081 T53 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
   [junit4]   2> 7085 T53 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
   [junit4]   2> 7109 T53 oasc.CoreContainer.create Creating SolrCore 
'collection1' using configuration from instancedir 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
 7F8FA793B2B70717-001/tempDir-001/collection1/
   [junit4]   2> 7109 T53 c:collection1 oasc.SolrCore.initDirectoryFactory 
org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 7110 T53 c:collection1 oasc.SolrCore.<init> [collection1] 
Opening new SolrCore at 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
 7F8FA793B2B70717-001/tempDir-001/collection1/, 
dataDir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
 7F8FA793B2B70717-001/tempDir-001/collection1/data/
   [junit4]   2> 7110 T53 c:collection1 oasc.JmxMonitoredMap.<init> No JMX 
servers found, not exposing Solr information with JMX.
   [junit4]   2> 7111 T53 c:collection1 oasc.SolrCore.initListeners 
[collection1] Added SolrEventListener for newSearcher: 
org.apache.solr.core.QuerySenderListener{queries=[]}
   [junit4]   2> 7111 T53 c:collection1 oasc.SolrCore.initListeners 
[collection1] Added SolrEventListener for firstSearcher: 
org.apache.solr.core.QuerySenderListener{queries=[{q=static firstSearcher 
warming in solrconfig.xml}]}
   [junit4]   2> 7112 T53 c:collection1 oasc.CachingDirectoryFactory.get return 
new directory for 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
 7F8FA793B2B70717-001/tempDir-001/collection1/data
   [junit4]   2> 7112 T53 c:collection1 oasc.SolrCore.getNewIndexDir New index 
directory detected: old=null 
new=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
 7F8FA793B2B70717-001/tempDir-001/collection1/data/index/
   [junit4]   2> 7113 T53 c:collection1 oasc.SolrCore.initIndex WARN 
[collection1] Solr index directory 
'/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
 7F8FA793B2B70717-001/tempDir-001/collection1/data/index' doesn't exist. 
Creating new index...
   [junit4]   2> 7113 T53 c:collection1 oasc.CachingDirectoryFactory.get return 
new directory for 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
 7F8FA793B2B70717-001/tempDir-001/collection1/data/index
   [junit4]   2> 7117 T53 c:collection1 oasu.LoggingInfoStream.message 
[IFD][coreLoadExecutor-48-thread-1]: init: current segments file is "segments"; 
deletionPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper@45c79efe
   [junit4]   2> 7117 T53 c:collection1 oasu.LoggingInfoStream.message 
[IFD][coreLoadExecutor-48-thread-1]: now checkpoint "" [0 segments ; isCommit = 
false]
   [junit4]   2> 7118 T53 c:collection1 oasu.LoggingInfoStream.message 
[IFD][coreLoadExecutor-48-thread-1]: 0 msec to checkpoint
   [junit4]   2> 7118 T53 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-48-thread-1]: init: create=true
   [junit4]   2> 7118 T53 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-48-thread-1]: 
   [junit4]   2>        dir=MockDirectoryWrapper(RAMDirectory@7e6026d2 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ef89d23)
   [junit4]   2>        index=
   [junit4]   2>        version=6.0.0
   [junit4]   2>        
analyzer=org.apache.solr.update.SolrIndexConfig$DelayedSchemaAnalyzer
   [junit4]   2>        ramBufferSizeMB=100.0
   [junit4]   2>        maxBufferedDocs=-1
   [junit4]   2>        maxBufferedDeleteTerms=-1
   [junit4]   2>        mergedSegmentWarmer=null
   [junit4]   2>        
delPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper
   [junit4]   2>        commit=null
   [junit4]   2>        openMode=CREATE
   [junit4]   2>        
similarity=org.apache.lucene.search.similarities.DefaultSimilarity
   [junit4]   2>        mergeScheduler=ConcurrentMergeScheduler: 
maxThreadCount=-1, maxMergeCount=-1, ioThrottle=true
   [junit4]   2>        default WRITE_LOCK_TIMEOUT=1000
   [junit4]   2>        writeLockTimeout=1000
   [junit4]   2>        codec=Lucene50
   [junit4]   2>        infoStream=org.apache.solr.update.LoggingInfoStream
   [junit4]   2>        mergePolicy=[TieredMergePolicy: maxMergeAtOnce=10, 
maxMergeAtOnceExplicit=30, maxMergedSegmentMB=5120.0, floorSegmentMB=2.0, 
forceMergeDeletesPctAllowed=10.0, segmentsPerTier=10.0, 
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2>        
indexerThreadPool=org.apache.lucene.index.DocumentsWriterPerThreadPool@1be2774c
   [junit4]   2>        readerPooling=false
   [junit4]   2>        perThreadHardLimitMB=1945
   [junit4]   2>        useCompoundFile=false
   [junit4]   2>        commitOnClose=true
   [junit4]   2>        writer=org.apache.solr.update.SolrIndexWriter@64cb8454
   [junit4]   2>        
   [junit4]   2> 7118 T53 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-48-thread-1]: now flush at close
   [junit4]   2> 7118 T53 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-48-thread-1]:   start flush: applyAllDeletes=true
   [junit4]   2> 7118 T53 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-48-thread-1]:   index before flush 
   [junit4]   2> 7118 T53 c:collection1 oasu.LoggingInfoStream.message 
[DW][coreLoadExecutor-48-thread-1]: startFullFlush
   [junit4]   2> 7119 T53 c:collection1 oasu.LoggingInfoStream.message 
[DW][coreLoadExecutor-48-thread-1]: coreLoadExecutor-48-thread-1 
finishFullFlush success=true
   [junit4]   2> 7119 T53 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-48-thread-1]: apply all deletes during flush
   [junit4]   2> 7119 T53 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-48-thread-1]: now apply all deletes for all segments 
maxDoc=0
   [junit4]   2> 7119 T53 c:collection1 oasu.LoggingInfoStream.message 
[BD][coreLoadExecutor-48-thread-1]: prune sis=segments:  
minGen=9223372036854775807 packetCount=0
   [junit4]   2> 7119 T53 c:collection1 oasu.LoggingInfoStream.message 
[MS][coreLoadExecutor-48-thread-1]: initDynamicDefaults spins=false 
maxThreadCount=4 maxMergeCount=9
   [junit4]   2> 7119 T53 c:collection1 oasu.LoggingInfoStream.message 
[MS][coreLoadExecutor-48-thread-1]: updateMergeThreads ioThrottle=true 
targetMBPerSec=10240.0 MB/sec
   [junit4]   2> 7119 T53 c:collection1 oasu.LoggingInfoStream.message 
[MS][coreLoadExecutor-48-thread-1]: now merge
   [junit4]   2> 7119 T53 c:collection1 oasu.LoggingInfoStream.message 
[MS][coreLoadExecutor-48-thread-1]:   index: 
   [junit4]   2> 7120 T53 c:collection1 oasu.LoggingInfoStream.message 
[MS][coreLoadExecutor-48-thread-1]:   no more merges pending; now return
   [junit4]   2> 7120 T53 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-48-thread-1]: waitForMerges
   [junit4]   2> 7120 T53 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-48-thread-1]: waitForMerges done
   [junit4]   2> 7120 T53 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-48-thread-1]: commit: start
   [junit4]   2> 7120 T53 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-48-thread-1]: commit: enter lock
   [junit4]   2> 7120 T53 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-48-thread-1]: commit: now prepare
   [junit4]   2> 7120 T53 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-48-thread-1]: prepareCommit: flush
   [junit4]   2> 7120 T53 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-48-thread-1]:   index before flush 
   [junit4]   2> 7120 T53 c:collection1 oasu.LoggingInfoStream.message 
[DW][coreLoadExecutor-48-thread-1]: startFullFlush
   [junit4]   2> 7121 T53 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-48-thread-1]: apply all deletes during flush
   [junit4]   2> 7121 T53 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-48-thread-1]: now apply all deletes for all segments 
maxDoc=0
   [junit4]   2> 7121 T53 c:collection1 oasu.LoggingInfoStream.message 
[BD][coreLoadExecutor-48-thread-1]: prune sis=segments:  
minGen=9223372036854775807 packetCount=0
   [junit4]   2> 7121 T53 c:collection1 oasu.LoggingInfoStream.message 
[DW][coreLoadExecutor-48-thread-1]: coreLoadExecutor-48-thread-1 
finishFullFlush success=true
   [junit4]   2> 7121 T53 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-48-thread-1]: startCommit(): start
   [junit4]   2> 7121 T53 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-48-thread-1]: startCommit index= changeCount=1
   [junit4]   2> 7122 T53 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-48-thread-1]: done all syncs: []
   [junit4]   2> 7122 T53 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-48-thread-1]: commit: pendingCommit != null
   [junit4]   2> 7122 T53 c:collection1 oasu.LoggingInfoStream.message 
[IFD][coreLoadExecutor-48-thread-1]: now checkpoint "" [0 segments ; isCommit = 
true]
   [junit4]   2> 7122 T53 c:collection1 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@7e6026d2 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ef89d23),segFN=segments_1,generation=1}
   [junit4]   2> 7122 T53 c:collection1 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 1
   [junit4]   2> 7122 T53 c:collection1 oasu.LoggingInfoStream.message 
[IFD][coreLoadExecutor-48-thread-1]: 0 msec to checkpoint
   [junit4]   2> 7123 T53 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-48-thread-1]: commit: wrote segments file "segments_1"
   [junit4]   2> 7123 T53 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-48-thread-1]: commit: took 2.6 msec
   [junit4]   2> 7123 T53 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-48-thread-1]: commit: done
   [junit4]   2> 7123 T53 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-48-thread-1]: rollback
   [junit4]   2> 7123 T53 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-48-thread-1]: all running merges have aborted
   [junit4]   2> 7123 T53 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-48-thread-1]: rollback: done finish merges
   [junit4]   2> 7123 T53 c:collection1 oasu.LoggingInfoStream.message 
[DW][coreLoadExecutor-48-thread-1]: abort
   [junit4]   2> 7123 T53 c:collection1 oasu.LoggingInfoStream.message 
[DW][coreLoadExecutor-48-thread-1]: done abort success=true
   [junit4]   2> 7124 T53 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-48-thread-1]: rollback: infos=
   [junit4]   2> 7124 T53 c:collection1 oasu.LoggingInfoStream.message 
[IFD][coreLoadExecutor-48-thread-1]: now checkpoint "" [0 segments ; isCommit = 
false]
   [junit4]   2> 7124 T53 c:collection1 oasu.LoggingInfoStream.message 
[IFD][coreLoadExecutor-48-thread-1]: 0 msec to checkpoint
   [junit4]   2> 7124 T53 c:collection1 oasc.PluginBag.createPlugin 
queryResponseWriter : 'velocity' created with startup=lazy 
   [junit4]   2> 7124 T53 c:collection1 oasr.XSLTResponseWriter.init 
xsltCacheLifetimeSeconds=5
   [junit4]   2> 7125 T53 c:collection1 oasc.SolrCore.loadUpdateProcessorChains 
no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 7128 T53 c:collection1 oashl.XMLLoader.init 
xsltCacheLifetimeSeconds=60
   [junit4]   2> 7129 T53 c:collection1 oashl.XMLLoader.init 
xsltCacheLifetimeSeconds=60
   [junit4]   2> 7130 T53 c:collection1 oashl.XMLLoader.init 
xsltCacheLifetimeSeconds=60
   [junit4]   2> 7130 T53 c:collection1 oashl.XMLLoader.init 
xsltCacheLifetimeSeconds=60
   [junit4]   2> 7132 T53 c:collection1 oasc.PluginBag.createPlugin 
requestHandler : '/update/extract' created with startup=lazy 
   [junit4]   2> 7133 T53 c:collection1 oasc.PluginBag.createPlugin 
requestHandler : '/analysis/field' created with startup=lazy 
   [junit4]   2> 7133 T53 c:collection1 oasc.PluginBag.createPlugin 
requestHandler : '/analysis/document' created with startup=lazy 
   [junit4]   2> 7133 T53 c:collection1 oasc.PluginBag.createPlugin 
requestHandler : '/spell' created with startup=lazy 
   [junit4]   2> 7134 T53 c:collection1 oasc.PluginBag.createPlugin 
requestHandler : '/suggest' created with startup=lazy 
   [junit4]   2> 7134 T53 c:collection1 oasc.PluginBag.createPlugin 
requestHandler : '/tvrh' created with startup=lazy 
   [junit4]   2> 7134 T53 c:collection1 oasc.PluginBag.createPlugin 
requestHandler : '/terms' created with startup=lazy 
   [junit4]   2> 7134 T53 c:collection1 oasc.PluginBag.createPlugin 
requestHandler : '/elevate' created with startup=lazy 
   [junit4]   2> 7134 T53 c:collection1 
oasc.RequestHandlers.initHandlersFromConfig Registered paths: 
/admin/mbeans,/browse,/update/json/docs,/admin/luke,/export,/get,/admin/properties,/elevate,/update/json,/admin/threads,/query,/analysis/field,/analysis/document,/spell,/update/csv,/tvrh,/select,/admin/segments,/admin/system,/suggest,/replication,/config,/stream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/admin/file,/debug/dump,/terms,/update/extract
   [junit4]   2> 7136 T53 c:collection1 oasc.SolrCore.initStatsCache Using 
default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 7141 T53 c:collection1 oasu.UpdateHandler.<init> Using 
UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 7141 T53 c:collection1 oasu.UpdateLog.init Initializing 
UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10
   [junit4]   2> 7141 T53 c:collection1 oasu.CommitTracker.<init> Hard 
AutoCommit: if uncommited for 15000ms; 
   [junit4]   2> 7142 T53 c:collection1 oasu.CommitTracker.<init> Soft 
AutoCommit: disabled
   [junit4]   2> 7142 T53 c:collection1 oasu.LoggingInfoStream.message 
[IFD][coreLoadExecutor-48-thread-1]: init: current segments file is 
"segments_1"; 
deletionPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper@45c79efe
   [junit4]   2> 7142 T53 c:collection1 oasu.LoggingInfoStream.message 
[IFD][coreLoadExecutor-48-thread-1]: init: load commit "segments_1"
   [junit4]   2> 7143 T53 c:collection1 oasc.SolrDeletionPolicy.onInit 
SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@7e6026d2 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ef89d23),segFN=segments_1,generation=1}
   [junit4]   2> 7143 T53 c:collection1 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 1
   [junit4]   2> 7143 T53 c:collection1 oasu.LoggingInfoStream.message 
[IFD][coreLoadExecutor-48-thread-1]: now checkpoint "" [0 segments ; isCommit = 
false]
   [junit4]   2> 7143 T53 c:collection1 oasu.LoggingInfoStream.message 
[IFD][coreLoadExecutor-48-thread-1]: 0 msec to checkpoint
   [junit4]   2> 7143 T53 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-48-thread-1]: init: create=false
   [junit4]   2> 7143 T53 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-48-thread-1]: 
   [junit4]   2>        dir=MockDirectoryWrapper(RAMDirectory@7e6026d2 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ef89d23)
   [junit4]   2>        index=
   [junit4]   2>        version=6.0.0
   [junit4]   2>        
analyzer=org.apache.solr.update.SolrIndexConfig$DelayedSchemaAnalyzer
   [junit4]   2>        ramBufferSizeMB=100.0
   [junit4]   2>        maxBufferedDocs=-1
   [junit4]   2>        maxBufferedDeleteTerms=-1
   [junit4]   2>        mergedSegmentWarmer=null
   [junit4]   2>        
delPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper
   [junit4]   2>        commit=null
   [junit4]   2>        openMode=APPEND
   [junit4]   2>        
similarity=org.apache.lucene.search.similarities.DefaultSimilarity
   [junit4]   2>        mergeScheduler=ConcurrentMergeScheduler: 
maxThreadCount=-1, maxMergeCount=-1, ioThrottle=true
   [junit4]   2>        default WRITE_LOCK_TIMEOUT=1000
   [junit4]   2>        writeLockTimeout=1000
   [junit4]   2>        codec=Lucene50
   [junit4]   2>        infoStream=org.apache.solr.update.LoggingInfoStream
   [junit4]   2>        mergePolicy=[TieredMergePolicy: maxMergeAtOnce=10, 
maxMergeAtOnceExplicit=30, maxMergedSegmentMB=5120.0, floorSegmentMB=2.0, 
forceMergeDeletesPctAllowed=10.0, segmentsPerTier=10.0, 
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2>        
indexerThreadPool=org.apache.lucene.index.DocumentsWriterPerThreadPool@2ff09e38
   [junit4]   2>        readerPooling=false
   [junit4]   2>        perThreadHardLimitMB=1945
   [junit4]   2>        useCompoundFile=false
   [junit4]   2>        commitOnClose=true
   [junit4]   2>        writer=org.apache.solr.update.SolrIndexWriter@3efaf47d
   [junit4]   2>        
   [junit4]   2> 7144 T53 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-48-thread-1]: flush at getReader
   [junit4]   2> 7144 T53 c:collection1 oasu.LoggingInfoStream.message 
[DW][coreLoadExecutor-48-thread-1]: startFullFlush
   [junit4]   2> 7144 T53 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-48-thread-1]: apply all deletes during flush
   [junit4]   2> 7144 T53 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-48-thread-1]: now apply all deletes for all segments 
maxDoc=0
   [junit4]   2> 7144 T53 c:collection1 oasu.LoggingInfoStream.message 
[BD][coreLoadExecutor-48-thread-1]: prune sis=segments_1:  
minGen=9223372036854775807 packetCount=0
   [junit4]   2> 7145 T53 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-48-thread-1]: return reader version=1 
reader=StandardDirectoryReader(segments_1:1:nrt)
   [junit4]   2> 7145 T53 c:collection1 oasu.LoggingInfoStream.message 
[DW][coreLoadExecutor-48-thread-1]: coreLoadExecutor-48-thread-1 
finishFullFlush success=true
   [junit4]   2> 7145 T53 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-48-thread-1]: getReader took 1 msec
   [junit4]   2> 7146 T53 c:collection1 oass.SolrIndexSearcher.<init> Opening 
Searcher@68e6d866[collection1] main
   [junit4]   2> 7146 T53 c:collection1 
oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage 
initialized to use dir: 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
 7F8FA793B2B70717-001/tempDir-001/collection1/conf
   [junit4]   2> 7147 T53 c:collection1 oasr.RestManager.init Initializing 
RestManager with initArgs: 
{storageDir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
 7F8FA793B2B70717-001/tempDir-001/collection1/conf}
   [junit4]   2> 7147 T53 c:collection1 oasr.ManagedResourceStorage.load 
Reading _rest_managed.json using 
file:dir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
 7F8FA793B2B70717-001/tempDir-001/collection1/conf
   [junit4]   2> 7147 T53 c:collection1 oasr.ManagedResourceStorage.load Loaded 
LinkedHashMap at path _rest_managed.json using 
file:dir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
 7F8FA793B2B70717-001/tempDir-001/collection1/conf
   [junit4]   2> 7148 T53 c:collection1 oasr.ManagedResource.processStoredData 
Loaded initArgs {} for /rest/managed
   [junit4]   2> 7148 T53 c:collection1 oasr.RestManager.init Initializing 2 
registered ManagedResources
   [junit4]   2> 7148 T53 c:collection1 oasr.ManagedResourceStorage.load 
Reading _schema_analysis_stopwords_english.json using 
file:dir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
 7F8FA793B2B70717-001/tempDir-001/collection1/conf
   [junit4]   2> 7149 T53 c:collection1 oasr.ManagedResourceStorage.load Loaded 
LinkedHashMap at path _schema_analysis_stopwords_english.json using 
file:dir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
 7F8FA793B2B70717-001/tempDir-001/collection1/conf
   [junit4]   2> 7149 T53 c:collection1 oasr.ManagedResource.processStoredData 
Loaded initArgs {ignoreCase=true} for /schema/analysis/stopwords/english
   [junit4]   2> 7149 T53 c:collection1 
oasrsa.ManagedWordSetResource.onManagedDataLoadedFromStorage Loaded 35 words 
for /schema/analysis/stopwords/english
   [junit4]   2> 7149 T53 c:collection1 
oasr.ManagedResource.notifyObserversDuringInit Notified 1 observers of 
/schema/analysis/stopwords/english
   [junit4]   2> 7150 T53 c:collection1 oasr.ManagedResourceStorage.load 
Reading _schema_analysis_synonyms_english.json using 
file:dir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
 7F8FA793B2B70717-001/tempDir-001/collection1/conf
   [junit4]   2> 7150 T53 c:collection1 oasr.ManagedResourceStorage.load Loaded 
LinkedHashMap at path _schema_analysis_synonyms_english.json using 
file:dir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
 7F8FA793B2B70717-001/tempDir-001/collection1/conf
   [junit4]   2> 7150 T53 c:collection1 oasr.ManagedResource.processStoredData 
Loaded initArgs {ignoreCase=true,format=solr} for 
/schema/analysis/synonyms/english
   [junit4]   2> 7151 T53 c:collection1 
oasrsa.ManagedSynonymFilterFactory$SynonymManager.onManagedDataLoadedFromStorage
 Loaded 3 synonym mappings for /schema/analysis/synonyms/english
   [junit4]   2> 7151 T53 c:collection1 
oasr.ManagedResource.notifyObserversDuringInit Notified 1 observers of 
/schema/analysis/synonyms/english
   [junit4]   2> 7151 T53 c:collection1 oashc.SpellCheckComponent.inform 
Initializing spell checkers
   [junit4]   2> 7152 T53 c:collection1 oass.DirectSolrSpellChecker.init init: 
{name=default,field=text,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4,maxQueryFrequency=0.01}
   [junit4]   2> 7152 T53 c:collection1 oashc.SpellCheckComponent.inform No 
queryConverter defined, using default converter
   [junit4]   2> 7153 T53 c:collection1 oashc.SuggestComponent.inform 
Initializing SuggestComponent
   [junit4]   2> 7153 T53 c:collection1 oasss.SolrSuggester.init init: 
{name=mySuggester,lookupImpl=FuzzyLookupFactory,dictionaryImpl=DocumentDictionaryFactory,field=cat,weightField=price,suggestAnalyzerFieldType=string,buildOnStartup=false}
   [junit4]   2> 7153 T53 c:collection1 oasss.SolrSuggester.init Dictionary 
loaded with params: 
{name=mySuggester,lookupImpl=FuzzyLookupFactory,dictionaryImpl=DocumentDictionaryFactory,field=cat,weightField=price,suggestAnalyzerFieldType=string,buildOnStartup=false}
   [junit4]   2> 7154 T53 c:collection1 oashc.QueryElevationComponent.inform 
Loading QueryElevation from: 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
 7F8FA793B2B70717-001/tempDir-001/collection1/conf/elevate.xml
   [junit4]   2> 7160 T53 c:collection1 oash.ReplicationHandler.inform Commits 
will be reserved for  10000
   [junit4]   2> 7161 T53 c:collection1 oasc.CoreContainer.registerCore 
registering core: collection1
   [junit4]   2> 7161 T54 c:collection1 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@68e6d866[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> ASYNC  NEW_CORE C4 name=collection1 
org.apache.solr.core.SolrCore@61bd0282
   [junit4]   2> 7163 T54 c:collection1 C4 oasc.SolrCore.execute [collection1] 
webapp=null path=null 
params={q=static+firstSearcher+warming+in+solrconfig.xml&distrib=false&event=firstSearcher}
 hits=0 status=0 QTime=2 
   [junit4]   2> 7163 T54 c:collection1 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 7164 T40 oass.SolrDispatchFilter.init 
user.dir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1
   [junit4]   2> 7164 T54 c:collection1 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: default
   [junit4]   2> 7164 T40 oass.SolrDispatchFilter.init 
SolrDispatchFilter.init() done
   [junit4]   2> 7164 T54 c:collection1 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: wordbreak
   [junit4]   2> 7165 T40 oas.SolrJettyTestBase.createJetty Jetty Assigned 
Port#55741
   [junit4]   2> 7165 T54 c:collection1 oasc.SolrCore.registerSearcher 
[collection1] Registered new searcher Searcher@68e6d866[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 7168 T40 oas.SolrTestCaseJ4.setUp ###Starting testAddDelete
   [junit4]   2> ASYNC  NEW_CORE C5 name=collection1 
org.apache.solr.core.SolrCore@61bd0282
   [junit4]   2> 7530 T48 c:collection1 C5 oasu.LoggingInfoStream.message 
[DW][qtp1869245664-48]: anyChanges? numDocsInRam=0 deletes=true 
hasTickets:false pendingChangesInFullFlush: false
   [junit4]   2> 7532 T48 c:collection1 C5 oasu.LoggingInfoStream.message 
[DW][qtp1869245664-48]: anyChanges? numDocsInRam=0 deletes=true 
hasTickets:false pendingChangesInFullFlush: false
   [junit4]   2> 7533 T48 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-48]: nrtIsCurrent: infoVersion matches: true; DW changes: 
true; BD changes: false
   [junit4]   2> 7534 T48 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-48]: flush at getReader
   [junit4]   2> 7534 T48 c:collection1 C5 oasu.LoggingInfoStream.message 
[DW][qtp1869245664-48]: startFullFlush
   [junit4]   2> 7535 T48 c:collection1 C5 oasu.LoggingInfoStream.message 
[DW][qtp1869245664-48]: anyChanges? numDocsInRam=0 deletes=true 
hasTickets:false pendingChangesInFullFlush: false
   [junit4]   2> 7535 T48 c:collection1 C5 oasu.LoggingInfoStream.message 
[DW][qtp1869245664-48]: qtp1869245664-48: flush naked frozen global deletes
   [junit4]   2> 7536 T48 c:collection1 C5 oasu.LoggingInfoStream.message 
[BD][qtp1869245664-48]: push deletes  1 deleted queries bytesUsed=84 
segmentPrivate?=false delGen=2 packetCount=1 totBytesUsed=84
   [junit4]   2> 7537 T48 c:collection1 C5 oasu.LoggingInfoStream.message 
[DW][qtp1869245664-48]: flush: push buffered updates:  1 deleted queries 
bytesUsed=84
   [junit4]   2> 7538 T48 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-48]: apply all deletes during flush
   [junit4]   2> 7538 T48 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-48]: now apply all deletes for all segments maxDoc=0
   [junit4]   2> 7539 T48 c:collection1 C5 oasu.LoggingInfoStream.message 
[BD][qtp1869245664-48]: prune sis=segments_1:  minGen=9223372036854775807 
packetCount=1
   [junit4]   2> 7540 T48 c:collection1 C5 oasu.LoggingInfoStream.message 
[BD][qtp1869245664-48]: pruneDeletes: prune 1 packets; 0 packets remain
   [junit4]   2> 7541 T48 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-48]: return reader version=1 
reader=StandardDirectoryReader(segments_1:1:nrt)
   [junit4]   2> 7541 T48 c:collection1 C5 oasu.LoggingInfoStream.message 
[DW][qtp1869245664-48]: qtp1869245664-48 finishFullFlush success=true
   [junit4]   2> 7542 T48 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-48]: getReader took 8 msec
   [junit4]   2> 7543 T48 c:collection1 C5 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/solr path=/update params={wt=javabin&version=2} 
{deleteByQuery=*:* (-1498516415106252800)} 0 24
   [junit4]   2> 7826 T49 c:collection1 C5 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 & 
222 (1498516415156584448)]} 0 250
   [junit4]   2> 7831 T50 c:collection1 C5 oasu.DirectUpdateHandler2.commit 
start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 7832 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: commit: start
   [junit4]   2> 7832 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: commit: enter lock
   [junit4]   2> 7832 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: commit: now prepare
   [junit4]   2> 7833 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: prepareCommit: flush
   [junit4]   2> 7833 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]:   index before flush 
   [junit4]   2> 7833 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[DW][qtp1869245664-50]: startFullFlush
   [junit4]   2> 7833 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[DW][qtp1869245664-50]: anyChanges? numDocsInRam=1 deletes=true 
hasTickets:false pendingChangesInFullFlush: false
   [junit4]   2> 7833 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[DWFC][qtp1869245664-50]: addFlushableState DocumentsWriterPerThread 
[pendingDeletes=gen=0, segment=_0, aborted=false, numDocsInRAM=1, 
deleteQueue=DWDQ: [ generation: 2 ]]
   [junit4]   2> 7834 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[DWPT][qtp1869245664-50]: flush postings as segment _0 numDocs=1
   [junit4]   2> 7835 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[DWPT][qtp1869245664-50]: new segment has 0 deleted docs
   [junit4]   2> 7835 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[DWPT][qtp1869245664-50]: new segment has no vectors; no norms; no docValues; 
no prox; no freqs
   [junit4]   2> 7835 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[DWPT][qtp1869245664-50]: flushedFiles=[_0_Lucene50_0.doc, _0_Lucene50_0.tim, 
_0.fdx, _0_Lucene50_0.tip, _0.fdt, _0.fnm]
   [junit4]   2> 7835 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[DWPT][qtp1869245664-50]: flushed codec=Lucene50
   [junit4]   2> 7835 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[DWPT][qtp1869245664-50]: flushed: segment=_0 ramUsed=0.079 MB 
newFlushedSize=0.001 MB docs/MB=1,162.501
   [junit4]   2> 7836 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[DW][qtp1869245664-50]: publishFlushedSegment seg-private updates=null
   [junit4]   2> 7836 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: publishFlushedSegment
   [junit4]   2> 7836 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[BD][qtp1869245664-50]: push deletes  1 deleted terms (unique count=1) 
bytesUsed=1072 segmentPrivate?=false delGen=4 packetCount=1 totBytesUsed=1072
   [junit4]   2> 7836 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: publish sets newSegment delGen=5 seg=_0(6.0.0):C1
   [junit4]   2> 7836 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IFD][qtp1869245664-50]: now checkpoint "_0(6.0.0):C1" [1 segments ; isCommit = 
false]
   [junit4]   2> 7837 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IFD][qtp1869245664-50]: 0 msec to checkpoint
   [junit4]   2> 7837 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: apply all deletes during flush
   [junit4]   2> 7837 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: now apply all deletes for all segments maxDoc=1
   [junit4]   2> 7837 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[BD][qtp1869245664-50]: applyDeletes: open segment readers took 0 msec
   [junit4]   2> 7837 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[BD][qtp1869245664-50]: applyDeletes: infos=[_0(6.0.0):C1] packetCount=1
   [junit4]   2> 7838 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[BD][qtp1869245664-50]: applyDeletes took 1 msec for 1 segments, 0 newly 
deleted docs (query deletes), 0 visited terms, allDeleted=null
   [junit4]   2> 7838 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[BD][qtp1869245664-50]: prune sis=segments_1: _0(6.0.0):C1 minGen=5 
packetCount=1
   [junit4]   2> 7838 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[BD][qtp1869245664-50]: pruneDeletes: prune 1 packets; 0 packets remain
   [junit4]   2> 7838 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[DW][qtp1869245664-50]: qtp1869245664-50 finishFullFlush success=true
   [junit4]   2> 7838 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[TMP][qtp1869245664-50]: findMerges: 1 segments
   [junit4]   2> 7839 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[TMP][qtp1869245664-50]:   seg=_0(6.0.0):C1 size=0.001 MB [floored]
   [junit4]   2> 7839 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[TMP][qtp1869245664-50]:   allowedSegmentCount=1 vs count=1 (eligible count=1) 
tooBigCount=0
   [junit4]   2> 7839 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[MS][qtp1869245664-50]: initDynamicDefaults spins=false maxThreadCount=4 
maxMergeCount=9
   [junit4]   2> 7840 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[MS][qtp1869245664-50]: now merge
   [junit4]   2> 7840 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[MS][qtp1869245664-50]:   index: _0(6.0.0):C1
   [junit4]   2> 7840 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[MS][qtp1869245664-50]:   no more merges pending; now return
   [junit4]   2> 7841 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: startCommit(): start
   [junit4]   2> 7841 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: startCommit index=_0(6.0.0):C1 changeCount=3
   [junit4]   2> 7842 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: done all syncs: [_0.si, _0_Lucene50_0.doc, 
_0_Lucene50_0.tim, _0.fdx, _0_Lucene50_0.tip, _0.fdt, _0.fnm]
   [junit4]   2> 7842 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: commit: pendingCommit != null
   [junit4]   2> 7843 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IFD][qtp1869245664-50]: now checkpoint "_0(6.0.0):C1" [1 segments ; isCommit = 
true]
   [junit4]   2> 7843 T50 c:collection1 C5 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@7e6026d2 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ef89d23),segFN=segments_1,generation=1}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@7e6026d2 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ef89d23),segFN=segments_2,generation=2}
   [junit4]   2> 7843 T50 c:collection1 C5 
oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
   [junit4]   2> 7844 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IFD][qtp1869245664-50]: deleteCommits: now decRef commit "segments_1"
   [junit4]   2> 7844 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IFD][qtp1869245664-50]: delete "segments_1"
   [junit4]   2> 7844 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IFD][qtp1869245664-50]: 1 msec to checkpoint
   [junit4]   2> 7844 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: commit: wrote segments file "segments_2"
   [junit4]   2> 7845 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: commit: took 12.2 msec
   [junit4]   2> 7845 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: commit: done
   [junit4]   2> 7846 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: nrtIsCurrent: infoVersion matches: false; DW changes: 
false; BD changes: false
   [junit4]   2> 7846 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: flush at getReader
   [junit4]   2> 7846 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[DW][qtp1869245664-50]: startFullFlush
   [junit4]   2> 7846 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: apply all deletes during flush
   [junit4]   2> 7847 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: now apply all deletes for all segments maxDoc=1
   [junit4]   2> 7847 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[BD][qtp1869245664-50]: applyDeletes: open segment readers took 0 msec
   [junit4]   2> 7847 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[BD][qtp1869245664-50]: applyDeletes: no segments; skipping
   [junit4]   2> 7847 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[BD][qtp1869245664-50]: prune sis=segments_1: _0(6.0.0):C1 minGen=5 
packetCount=0
   [junit4]   2> 7849 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: return reader version=3 
reader=StandardDirectoryReader(segments_1:3:nrt _0(6.0.0):C1)
   [junit4]   2> 7849 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[DW][qtp1869245664-50]: qtp1869245664-50 finishFullFlush success=true
   [junit4]   2> 7849 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: getReader took 3 msec
   [junit4]   2> 7850 T50 c:collection1 C5 oass.SolrIndexSearcher.<init> 
Opening Searcher@2fa1fd7d[collection1] main
   [junit4]   2> 7850 T50 c:collection1 C5 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 7850 T54 c:collection1 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@2fa1fd7d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):C1)))}
   [junit4]   2> 7851 T54 c:collection1 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 7851 T54 c:collection1 oasc.SolrCore.registerSearcher 
[collection1] Registered new searcher Searcher@2fa1fd7d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):C1)))}
   [junit4]   2> 7851 T50 c:collection1 C5 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/solr path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} 
{commit=} 0 20
   [junit4]   2> 7858 T48 c:collection1 C5 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=1 status=0 
QTime=0 
   [junit4]   2> 7866 T49 c:collection1 C5 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/solr path=/update params={wt=javabin&version=2} 
{delete=[0 & 222 (-1498516415458574336)]} 0 2
   [junit4]   2> 7869 T50 c:collection1 C5 oasu.DirectUpdateHandler2.commit 
start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 7869 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[DW][qtp1869245664-50]: anyChanges? numDocsInRam=0 deletes=true 
hasTickets:false pendingChangesInFullFlush: false
   [junit4]   2> 7870 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: commit: start
   [junit4]   2> 7870 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: commit: enter lock
   [junit4]   2> 7870 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: commit: now prepare
   [junit4]   2> 7870 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: prepareCommit: flush
   [junit4]   2> 7870 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]:   index before flush _0(6.0.0):C1
   [junit4]   2> 7870 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[DW][qtp1869245664-50]: startFullFlush
   [junit4]   2> 7870 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[DW][qtp1869245664-50]: anyChanges? numDocsInRam=0 deletes=true 
hasTickets:false pendingChangesInFullFlush: false
   [junit4]   2> 7871 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[DW][qtp1869245664-50]: qtp1869245664-50: flush naked frozen global deletes
   [junit4]   2> 7871 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[BD][qtp1869245664-50]: push deletes  1 deleted terms (unique count=1) 
bytesUsed=1072 segmentPrivate?=false delGen=8 packetCount=1 totBytesUsed=1072
   [junit4]   2> 7871 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[DW][qtp1869245664-50]: flush: push buffered updates:  1 deleted terms (unique 
count=1) bytesUsed=1072
   [junit4]   2> 7871 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: apply all deletes during flush
   [junit4]   2> 7871 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: now apply all deletes for all segments maxDoc=1
   [junit4]   2> 7871 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[BD][qtp1869245664-50]: applyDeletes: open segment readers took 0 msec
   [junit4]   2> 7872 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[BD][qtp1869245664-50]: applyDeletes: infos=[_0(6.0.0):C1] packetCount=1
   [junit4]   2> 7873 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[BD][qtp1869245664-50]: applyTermDeletes took 1.3 msec for 1 segments and 1 
packets; 1 del terms visited; 1 seg terms visited
   [junit4]   2> 7873 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[BD][qtp1869245664-50]: applyDeletes: 1 new deleted documents
   [junit4]   2> 7874 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[BD][qtp1869245664-50]: applyDeletes took 3 msec for 1 segments, 0 newly 
deleted docs (query deletes), 1 visited terms, allDeleted=[_0(6.0.0):C1]
   [junit4]   2> 7874 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IFD][qtp1869245664-50]: now checkpoint "_0(6.0.0):C1/1" [1 segments ; isCommit 
= false]
   [junit4]   2> 7874 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IFD][qtp1869245664-50]: 0 msec to checkpoint
   [junit4]   2> 7875 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: drop 100% deleted segments: _0(6.0.0):C1/1
   [junit4]   2> 7875 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IFD][qtp1869245664-50]: now checkpoint "" [0 segments ; isCommit = false]
   [junit4]   2> 7875 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IFD][qtp1869245664-50]: 0 msec to checkpoint
   [junit4]   2> 7875 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[BD][qtp1869245664-50]: prune sis=segments_1:  minGen=9223372036854775807 
packetCount=1
   [junit4]   2> 7876 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[BD][qtp1869245664-50]: pruneDeletes: prune 1 packets; 0 packets remain
   [junit4]   2> 7876 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[DW][qtp1869245664-50]: qtp1869245664-50 finishFullFlush success=true
   [junit4]   2> 7876 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: startCommit(): start
   [junit4]   2> 7877 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: startCommit index= changeCount=6
   [junit4]   2> 7877 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: done all syncs: []
   [junit4]   2> 7877 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: commit: pendingCommit != null
   [junit4]   2> 7878 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IFD][qtp1869245664-50]: now checkpoint "" [0 segments ; isCommit = true]
   [junit4]   2> 7878 T50 c:collection1 C5 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@7e6026d2 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ef89d23),segFN=segments_2,generation=2}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@7e6026d2 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ef89d23),segFN=segments_3,generation=3}
   [junit4]   2> 7878 T50 c:collection1 C5 
oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
   [junit4]   2> 7879 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IFD][qtp1869245664-50]: deleteCommits: now decRef commit "segments_2"
   [junit4]   2> 7879 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IFD][qtp1869245664-50]: delete "segments_2"
   [junit4]   2> 7879 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IFD][qtp1869245664-50]: 1 msec to checkpoint
   [junit4]   2> 7879 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: commit: wrote segments file "segments_3"
   [junit4]   2> 7880 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: commit: took 9.4 msec
   [junit4]   2> 7880 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: commit: done
   [junit4]   2> 7880 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: nrtIsCurrent: infoVersion matches: false; DW changes: 
false; BD changes: false
   [junit4]   2> 7880 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: flush at getReader
   [junit4]   2> 7881 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[DW][qtp1869245664-50]: startFullFlush
   [junit4]   2> 7881 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: apply all deletes during flush
   [junit4]   2> 7881 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: now apply all deletes for all segments maxDoc=0
   [junit4]   2> 7881 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[BD][qtp1869245664-50]: prune sis=segments_1:  minGen=9223372036854775807 
packetCount=0
   [junit4]   2> 7882 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: return reader version=5 
reader=StandardDirectoryReader(segments_1:5:nrt)
   [junit4]   2> 7882 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[DW][qtp1869245664-50]: qtp1869245664-50 finishFullFlush success=true
   [junit4]   2> 7882 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: getReader took 2 msec
   [junit4]   2> 7883 T50 c:collection1 C5 oass.SolrIndexSearcher.<init> 
Opening Searcher@2ebb914c[collection1] main
   [junit4]   2> 7883 T54 c:collection1 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@2ebb914c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 7883 T54 c:collection1 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 7883 T50 c:collection1 C5 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 7884 T54 c:collection1 oasc.SolrCore.registerSearcher 
[collection1] Registered new searcher Searcher@2ebb914c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 7884 T54 c:collection1 oasu.LoggingInfoStream.message 
[IFD][searcherExecutor-49-thread-1-processing-{core=collection1}]: delete 
"_0.si"
   [junit4]   2> 7885 T54 c:collection1 oasu.LoggingInfoStream.message 
[IFD][searcherExecutor-49-thread-1-processing-{core=collection1}]: delete 
"_0_Lucene50_0.doc"
   [junit4]   2> 7885 T54 c:collection1 oasu.LoggingInfoStream.message 
[IFD][searcherExecutor-49-thread-1-processing-{core=collection1}]: delete 
"_0_Lucene50_0.tim"
   [junit4]   2> 7885 T54 c:collection1 oasu.LoggingInfoStream.message 
[IFD][searcherExecutor-49-thread-1-processing-{core=collection1}]: delete 
"_0.fdx"
   [junit4]   2> 7885 T54 c:collection1 oasu.LoggingInfoStream.message 
[IFD][searcherExecutor-49-thread-1-processing-{core=collection1}]: delete 
"_0_Lucene50_0.tip"
   [junit4]   2> 7885 T54 c:collection1 oasu.LoggingInfoStream.message 
[IFD][searcherExecutor-49-thread-1-processing-{core=collection1}]: delete 
"_0.fdt"
   [junit4]   2> 7885 T54 c:collection1 oasu.LoggingInfoStream.message 
[IFD][searcherExecutor-49-thread-1-processing-{core=collection1}]: delete 
"_0.fnm"
   [junit4]   2> 7886 T50 c:collection1 C5 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/solr path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} 
{commit=} 0 17
   [junit4]   2> 7889 T48 c:collection1 C5 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 
QTime=0 
   [junit4]   2> 8143 T49 c:collection1 C5 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 & 
222 (1498516415487934464)]} 0 250
   [junit4]   2> 8146 T50 c:collection1 C5 oasu.DirectUpdateHandler2.commit 
start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 8146 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: commit: start
   [junit4]   2> 8147 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: commit: enter lock
   [junit4]   2> 8147 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: commit: now prepare
   [junit4]   2> 8147 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: prepareCommit: flush
   [junit4]   2> 8147 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]:   index before flush 
   [junit4]   2> 8147 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[DW][qtp1869245664-50]: startFullFlush
   [junit4]   2> 8147 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[DW][qtp1869245664-50]: anyChanges? numDocsInRam=1 deletes=true 
hasTickets:false pendingChangesInFullFlush: false
   [junit4]   2> 8147 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[DWFC][qtp1869245664-50]: addFlushableState DocumentsWriterPerThread 
[pendingDeletes=gen=0, segment=_1, aborted=false, numDocsInRAM=1, 
deleteQueue=DWDQ: [ generation: 6 ]]
   [junit4]   2> 8148 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[DWPT][qtp1869245664-50]: flush postings as segment _1 numDocs=1
   [junit4]   2> 8149 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[DWPT][qtp1869245664-50]: new segment has 0 deleted docs
   [junit4]   2> 8149 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[DWPT][qtp1869245664-50]: new segment has no vectors; no norms; no docValues; 
no prox; no freqs
   [junit4]   2> 8149 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[DWPT][qtp1869245664-50]: flushedFiles=[_1_Lucene50_0.doc, _1_Lucene50_0.tim, 
_1.fdx, _1.fnm, _1.fdt, _1_Lucene50_0.tip]
   [junit4]   2> 8149 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[DWPT][qtp1869245664-50]: flushed codec=Lucene50
   [junit4]   2> 8149 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[DWPT][qtp1869245664-50]: flushed: segment=_1 ramUsed=0.079 MB 
newFlushedSize=0.001 MB docs/MB=1,162.501
   [junit4]   2> 8150 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[DW][qtp1869245664-50]: publishFlushedSegment seg-private updates=null
   [junit4]   2> 8150 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: publishFlushedSegment
   [junit4]   2> 8150 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[BD][qtp1869245664-50]: push deletes  1 deleted terms (unique count=1) 
bytesUsed=1072 segmentPrivate?=false delGen=11 packetCount=1 totBytesUsed=1072
   [junit4]   2> 8150 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: publish sets newSegment delGen=12 seg=_1(6.0.0):C1
   [junit4]   2> 8150 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IFD][qtp1869245664-50]: now checkpoint "_1(6.0.0):C1" [1 segments ; isCommit = 
false]
   [junit4]   2> 8151 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IFD][qtp1869245664-50]: 0 msec to checkpoint
   [junit4]   2> 8151 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: apply all deletes during flush
   [junit4]   2> 8151 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: now apply all deletes for all segments maxDoc=1
   [junit4]   2> 8151 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[BD][qtp1869245664-50]: applyDeletes: open segment readers took 0 msec
   [junit4]   2> 8151 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[BD][qtp1869245664-50]: applyDeletes: infos=[_1(6.0.0):C1] packetCount=1
   [junit4]   2> 8151 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[BD][qtp1869245664-50]: applyDeletes took 0 msec for 1 segments, 0 newly 
deleted docs (query deletes), 0 visited terms, allDeleted=null
   [junit4]   2> 8152 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[BD][qtp1869245664-50]: prune sis=segments_1: _1(6.0.0):C1 minGen=12 
packetCount=1
   [junit4]   2> 8152 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[BD][qtp1869245664-50]: pruneDeletes: prune 1 packets; 0 packets remain
   [junit4]   2> 8152 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[DW][qtp1869245664-50]: qtp1869245664-50 finishFullFlush success=true
   [junit4]   2> 8152 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[TMP][qtp1869245664-50]: findMerges: 1 segments
   [junit4]   2> 8153 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[TMP][qtp1869245664-50]:   seg=_1(6.0.0):C1 size=0.001 MB [floored]
   [junit4]   2> 8153 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[TMP][qtp1869245664-50]:   allowedSegmentCount=1 vs count=1 (eligible count=1) 
tooBigCount=0
   [junit4]   2> 8153 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[MS][qtp1869245664-50]: now merge
   [junit4]   2> 8153 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[MS][qtp1869245664-50]:   index: _1(6.0.0):C1
   [junit4]   2> 8153 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[MS][qtp1869245664-50]:   no more merges pending; now return
   [junit4]   2> 8154 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: startCommit(): start
   [junit4]   2> 8154 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: startCommit index=_1(6.0.0):C1 changeCount=9
   [junit4]   2> 8154 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: done all syncs: [_1_Lucene50_0.doc, _1_Lucene50_0.tim, 
_1.si, _1.fdx, _1.fnm, _1.fdt, _1_Lucene50_0.tip]
   [junit4]   2> 8154 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: commit: pendingCommit != null
   [junit4]   2> 8154 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IFD][qtp1869245664-50]: now checkpoint "_1(6.0.0):C1" [1 segments ; isCommit = 
true]
   [junit4]   2> 8155 T50 c:collection1 C5 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@7e6026d2 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ef89d23),segFN=segments_3,generation=3}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@7e6026d2 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ef89d23),segFN=segments_4,generation=4}
   [junit4]   2> 8155 T50 c:collection1 C5 
oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
   [junit4]   2> 8155 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IFD][qtp1869245664-50]: deleteCommits: now decRef commit "segments_3"
   [junit4]   2> 8155 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IFD][qtp1869245664-50]: delete "segments_3"
   [junit4]   2> 8155 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IFD][qtp1869245664-50]: 0 msec to checkpoint
   [junit4]   2> 8155 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: commit: wrote segments file "segments_4"
   [junit4]   2> 8156 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: commit: took 8.6 msec
   [junit4]   2> 8156 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: commit: done
   [junit4]   2> 8156 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: nrtIsCurrent: infoVersion matches: false; DW changes: 
false; BD changes: false
   [junit4]   2> 8156 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: flush at getReader
   [junit4]   2> 8156 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[DW][qtp1869245664-50]: startFullFlush
   [junit4]   2> 8156 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: apply all deletes during flush
   [junit4]   2> 8157 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: now apply all deletes for all segments maxDoc=1
   [junit4]   2> 8157 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[BD][qtp1869245664-50]: applyDeletes: open segment readers took 0 msec
   [junit4]   2> 8157 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[BD][qtp1869245664-50]: applyDeletes: no segments; skipping
   [junit4]   2> 8157 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[BD][qtp1869245664-50]: prune sis=segments_1: _1(6.0.0):C1 minGen=12 
packetCount=0
   [junit4]   2> 8158 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: return reader version=7 
reader=StandardDirectoryReader(segments_1:7:nrt _1(6.0.0):C1)
   [junit4]   2> 8158 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[DW][qtp1869245664-50]: qtp1869245664-50 finishFullFlush success=true
   [junit4]   2> 8158 T50 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp1869245664-50]: getReader took 2 msec
   [junit4]   2> 8158 T50 c:collection1 C5 oass.SolrIndexSearcher.<init> Op

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

5 C56 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleBinaryTest-seed#[7F8FA793B2B70717]-worker]:   index 
before flush 
   [junit4]   2> 80514 T645 C56 oasu.LoggingInfoStream.message 
[DW][SUITE-SolrExampleBinaryTest-seed#[7F8FA793B2B70717]-worker]: startFullFlush
   [junit4]   2> 80514 T645 C56 oasu.LoggingInfoStream.message 
[DW][SUITE-SolrExampleBinaryTest-seed#[7F8FA793B2B70717]-worker]: 
SUITE-SolrExampleBinaryTest-seed#[7F8FA793B2B70717]-worker finishFullFlush 
success=true
   [junit4]   2> 80515 T645 C56 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleBinaryTest-seed#[7F8FA793B2B70717]-worker]: apply all 
deletes during flush
   [junit4]   2> 80515 T645 C56 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleBinaryTest-seed#[7F8FA793B2B70717]-worker]: now apply all 
deletes for all segments maxDoc=0
   [junit4]   2> 80515 T645 C56 oasu.LoggingInfoStream.message 
[BD][SUITE-SolrExampleBinaryTest-seed#[7F8FA793B2B70717]-worker]: prune 
sis=segments_1:  minGen=9223372036854775807 packetCount=0
   [junit4]   2> 80515 T645 C56 oasu.LoggingInfoStream.message 
[MS][SUITE-SolrExampleBinaryTest-seed#[7F8FA793B2B70717]-worker]: 
updateMergeThreads ioThrottle=true targetMBPerSec=10240.0 MB/sec
   [junit4]   2> 80516 T645 C56 oasu.LoggingInfoStream.message 
[MS][SUITE-SolrExampleBinaryTest-seed#[7F8FA793B2B70717]-worker]: now merge
   [junit4]   2> 80516 T645 C56 oasu.LoggingInfoStream.message 
[MS][SUITE-SolrExampleBinaryTest-seed#[7F8FA793B2B70717]-worker]:   index: 
   [junit4]   2> 80516 T645 C56 oasu.LoggingInfoStream.message 
[MS][SUITE-SolrExampleBinaryTest-seed#[7F8FA793B2B70717]-worker]:   no more 
merges pending; now return
   [junit4]   2> 80516 T645 C56 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleBinaryTest-seed#[7F8FA793B2B70717]-worker]: waitForMerges
   [junit4]   2> 80517 T645 C56 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleBinaryTest-seed#[7F8FA793B2B70717]-worker]: waitForMerges 
done
   [junit4]   2> 80517 T645 C56 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleBinaryTest-seed#[7F8FA793B2B70717]-worker]: commit: start
   [junit4]   2> 80517 T645 C56 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleBinaryTest-seed#[7F8FA793B2B70717]-worker]: commit: enter 
lock
   [junit4]   2> 80517 T645 C56 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleBinaryTest-seed#[7F8FA793B2B70717]-worker]: commit: now 
prepare
   [junit4]   2> 80518 T645 C56 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleBinaryTest-seed#[7F8FA793B2B70717]-worker]: 
prepareCommit: flush
   [junit4]   2> 80518 T645 C56 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleBinaryTest-seed#[7F8FA793B2B70717]-worker]:   index 
before flush 
   [junit4]   2> 80518 T645 C56 oasu.LoggingInfoStream.message 
[DW][SUITE-SolrExampleBinaryTest-seed#[7F8FA793B2B70717]-worker]: startFullFlush
   [junit4]   2> 80518 T645 C56 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleBinaryTest-seed#[7F8FA793B2B70717]-worker]: apply all 
deletes during flush
   [junit4]   2> 80519 T645 C56 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleBinaryTest-seed#[7F8FA793B2B70717]-worker]: now apply all 
deletes for all segments maxDoc=0
   [junit4]   2> 80519 T645 C56 oasu.LoggingInfoStream.message 
[BD][SUITE-SolrExampleBinaryTest-seed#[7F8FA793B2B70717]-worker]: prune 
sis=segments_1:  minGen=9223372036854775807 packetCount=0
   [junit4]   2> 80519 T645 C56 oasu.LoggingInfoStream.message 
[DW][SUITE-SolrExampleBinaryTest-seed#[7F8FA793B2B70717]-worker]: 
SUITE-SolrExampleBinaryTest-seed#[7F8FA793B2B70717]-worker finishFullFlush 
success=true
   [junit4]   2> 80519 T645 C56 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleBinaryTest-seed#[7F8FA793B2B70717]-worker]: 
startCommit(): start
   [junit4]   2> 80520 T645 C56 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleBinaryTest-seed#[7F8FA793B2B70717]-worker]:   skip 
startCommit(): no changes pending
   [junit4]   2> 80520 T645 C56 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleBinaryTest-seed#[7F8FA793B2B70717]-worker]: commit: 
pendingCommit == null; skip
   [junit4]   2> 80520 T645 C56 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleBinaryTest-seed#[7F8FA793B2B70717]-worker]: commit: wrote 
segments file "null"
   [junit4]   2> 80521 T645 C56 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleBinaryTest-seed#[7F8FA793B2B70717]-worker]: commit: took 
2.8 msec
   [junit4]   2> 80521 T645 C56 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleBinaryTest-seed#[7F8FA793B2B70717]-worker]: commit: done
   [junit4]   2> 80521 T645 C56 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleBinaryTest-seed#[7F8FA793B2B70717]-worker]: rollback
   [junit4]   2> 80521 T645 C56 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleBinaryTest-seed#[7F8FA793B2B70717]-worker]: all running 
merges have aborted
   [junit4]   2> 80521 T645 C56 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleBinaryTest-seed#[7F8FA793B2B70717]-worker]: rollback: 
done finish merges
   [junit4]   2> 80522 T645 C56 oasu.LoggingInfoStream.message 
[DW][SUITE-SolrExampleBinaryTest-seed#[7F8FA793B2B70717]-worker]: abort
   [junit4]   2> 80522 T645 C56 oasu.LoggingInfoStream.message 
[DW][SUITE-SolrExampleBinaryTest-seed#[7F8FA793B2B70717]-worker]: done abort 
success=true
   [junit4]   2> 80522 T645 C56 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleBinaryTest-seed#[7F8FA793B2B70717]-worker]: rollback: 
infos=
   [junit4]   2> 80523 T645 C56 oasu.LoggingInfoStream.message 
[IFD][SUITE-SolrExampleBinaryTest-seed#[7F8FA793B2B70717]-worker]: now 
checkpoint "" [0 segments ; isCommit = false]
   [junit4]   2> 80523 T645 C56 oasu.LoggingInfoStream.message 
[IFD][SUITE-SolrExampleBinaryTest-seed#[7F8FA793B2B70717]-worker]: 0 msec to 
checkpoint
   [junit4]   2> 80524 T645 oasc.SolrCore.closeSearcher [collection1] Closing 
main searcher on request.
   [junit4]   2> 80554 T645 oasc.CachingDirectoryFactory.close Closing 
MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 80555 T645 oasc.CachingDirectoryFactory.closeCacheValue 
looking to close 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.SolrExampleBinaryTest
 7F8FA793B2B70717-001/tempDir-001/collection1/data/index 
[CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.SolrExampleBinaryTest
 7F8FA793B2B70717-001/tempDir-001/collection1/data/index;done=false>>]
   [junit4]   2> 80555 T645 oasc.CachingDirectoryFactory.close Closing 
directory: 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.SolrExampleBinaryTest
 7F8FA793B2B70717-001/tempDir-001/collection1/data/index
   [junit4]   2> 80555 T645 oasc.CachingDirectoryFactory.closeCacheValue 
looking to close 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.SolrExampleBinaryTest
 7F8FA793B2B70717-001/tempDir-001/collection1/data 
[CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.SolrExampleBinaryTest
 7F8FA793B2B70717-001/tempDir-001/collection1/data;done=false>>]
   [junit4]   2> 80555 T645 oasc.CachingDirectoryFactory.close Closing 
directory: 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.SolrExampleBinaryTest
 7F8FA793B2B70717-001/tempDir-001/collection1/data
   [junit4]   2> 80556 T645 oejsh.ContextHandler.doStop Stopped 
o.e.j.s.ServletContextHandler@6d8366e5{/solr,null,UNAVAILABLE}
   [junit4]   2> 80557 T645 oas.SolrTestCaseJ4.deleteCore ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.SolrExampleBinaryTest
 7F8FA793B2B70717-001
   [junit4]   2> NOTE: test params are: 
codec=DummyCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=DUMMY,
 chunkSize=2, maxDocsPerChunk=1, blockSize=3), 
termVectorsFormat=CompressingTermVectorsFormat(compressionMode=DUMMY, 
chunkSize=2, blockSize=3)), 
sim=RandomSimilarityProvider(queryNorm=false,coord=no): {}, locale=de_CH, 
timezone=Europe/Skopje
   [junit4]   2> NOTE: Linux 3.13.0-49-generic amd64/Oracle Corporation 
1.9.0-ea (64-bit)/cpus=12,threads=1,free=231592264,total=526385152
   [junit4]   2> NOTE: All tests run in this JVM: [TestJavaBinCodec, 
TestBatchUpdate, TestLBHttpSolrClient, FacetFieldTest, StreamingTest, 
TestCoreAdmin, SolrExampleBinaryTest]
   [junit4] Completed [40/57] on J2 in 6.34s, 33 tests, 1 error <<< FAILURES!

[...truncated 66 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:526: The following 
error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:474: The following 
error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:61: The following 
error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/extra-targets.xml:39: The 
following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:242: The 
following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:512: The 
following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1415: 
The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:973: 
There were test failures: 57 suites, 381 tests, 5 errors

Total time: 40 minutes 54 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.9.0-ea-b54 
-XX:-UseCompressedOops -XX:+UseG1GC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any


---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to