Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/12310/
Java: 32bit/jdk1.8.0_40 -server -XX:+UseConcMarkSweepGC

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='b59d79}]}} '

Stack Trace:
org.noggit.JSONParser$ParseException: Expected ',' or '}': char=@,position=286 
BEFORE='","json_s":org.apache.lucene.document.LazyDocument$LazyField@' 
AFTER='b59d79}]}} '
        at 
__randomizedtesting.SeedInfo.seed([874DF3298D848C31:BCED3DF310936A2C]: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:497)
        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='14733ee}]}} '

Stack Trace:
org.noggit.JSONParser$ParseException: Expected ',' or '}': char=@,position=286 
BEFORE='","json_s":org.apache.lucene.document.LazyDocument$LazyField@' 
AFTER='14733ee}]}} '
        at 
__randomizedtesting.SeedInfo.seed([874DF3298D848C31:BCED3DF310936A2C]: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:497)
        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='562c4c}]}} '

Stack Trace:
org.noggit.JSONParser$ParseException: Expected ',' or '}': char=@,position=286 
BEFORE='","json_s":org.apache.lucene.document.LazyDocument$LazyField@' 
AFTER='562c4c}]}} '
        at 
__randomizedtesting.SeedInfo.seed([874DF3298D848C31:BCED3DF310936A2C]: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:497)
        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='1bd78d0}]}} '

Stack Trace:
org.noggit.JSONParser$ParseException: Expected ',' or '}': char=@,position=286 
BEFORE='","json_s":org.apache.lucene.document.LazyDocument$LazyField@' 
AFTER='1bd78d0}]}} '
        at 
__randomizedtesting.SeedInfo.seed([874DF3298D848C31:BCED3DF310936A2C]: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:497)
        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='1a7c09f}]}} '

Stack Trace:
org.noggit.JSONParser$ParseException: Expected ',' or '}': char=@,position=286 
BEFORE='","json_s":org.apache.lucene.document.LazyDocument$LazyField@' 
AFTER='1a7c09f}]}} '
        at 
__randomizedtesting.SeedInfo.seed([874DF3298D848C31:BCED3DF310936A2C]: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:497)
        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 10955 lines...]
   [junit4] Suite: org.apache.solr.client.solrj.embedded.SolrExampleJettyTest
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.embedded.SolrExampleJettyTest
 874DF3298D848C31-001/init-core-data-001
   [junit4]   2> 9450 T148 oas.SolrTestCaseJ4.initCore ####initCore
   [junit4]   2> 9451 T148 oas.SolrTestCaseJ4.initCore ####initCore end
   [junit4]   2> 9451 T148 oas.SolrTestCaseJ4.writeCoreProperties Writing 
core.properties file to 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.embedded.SolrExampleJettyTest
 874DF3298D848C31-001/tempDir-002/cores/core
   [junit4]   2> 9452 T148 oejs.Server.doStart jetty-9.2.9.v20150224
   [junit4]   2> 9454 T148 oejsh.ContextHandler.doStart Started 
o.e.j.s.ServletContextHandler@11c66b5{/solr,null,AVAILABLE}
   [junit4]   2> 9456 T148 oejs.AbstractConnector.doStart Started 
ServerConnector@717387{HTTP/1.1}{127.0.0.1:53759}
   [junit4]   2> 9456 T148 oejs.Server.doStart Started @11079ms
   [junit4]   2> 9456 T148 oascse.JettySolrRunner$1.lifeCycleStarted Jetty 
properties: {hostContext=/solr, hostPort=53759, 
configSetBaseDir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.embedded.SolrExampleJettyTest
 874DF3298D848C31-001/tempDir-001, 
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.embedded.SolrExampleJettyTest
 874DF3298D848C31-001/tempDir-002/cores}
   [junit4]   2> 9457 T148 oass.SolrDispatchFilter.init 
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@19e0bfd
   [junit4]   2> 9457 T148 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.embedded.SolrExampleJettyTest
 874DF3298D848C31-001/tempDir-001/'
   [junit4]   2> 9468 T148 oasc.SolrXmlConfig.fromFile Loading container 
configuration from 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.embedded.SolrExampleJettyTest
 874DF3298D848C31-001/tempDir-001/solr.xml
   [junit4]   2> 9473 T148 oasc.CorePropertiesLocator.<init> Config-defined 
core root directory: 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.embedded.SolrExampleJettyTest
 874DF3298D848C31-001/tempDir-001
   [junit4]   2> 9473 T148 oasc.CoreContainer.<init> New CoreContainer 25571799
   [junit4]   2> 9473 T148 oasc.CoreContainer.load Loading cores into 
CoreContainer 
[instanceDir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.embedded.SolrExampleJettyTest
 874DF3298D848C31-001/tempDir-001/]
   [junit4]   2> 9474 T148 oasc.CoreContainer.load loading shared library: 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.embedded.SolrExampleJettyTest
 874DF3298D848C31-001/tempDir-001/lib
   [junit4]   2> 9474 T148 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/J2/temp/solr.client.solrj.embedded.SolrExampleJettyTest
 874DF3298D848C31-001/tempDir-001/lib).
   [junit4]   2> 9479 T148 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> 9488 T148 oasu.UpdateShardHandler.<init> Creating 
UpdateShardHandler HTTP client with params: 
socketTimeout=600000&connTimeout=60000&retry=true
   [junit4]   2> 9489 T148 oasl.LogWatcher.createWatcher SLF4J impl is 
org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 9490 T148 oasl.LogWatcher.newRegisteredLogWatcher Registering 
Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 9490 T148 oasc.CoreContainer.load Node Name: 
   [junit4]   2> 9491 T148 oasc.CorePropertiesLocator.discover Looking for core 
definitions underneath 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.embedded.SolrExampleJettyTest
 874DF3298D848C31-001/tempDir-001
   [junit4]   2> 9492 T148 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: 
{name=collection1, config=solrconfig.xml, 
instanceDir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.embedded.SolrExampleJettyTest
 874DF3298D848C31-001/tempDir-001/collection1, loadOnStartup=true, 
absoluteInstDir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.embedded.SolrExampleJettyTest
 874DF3298D848C31-001/tempDir-001/collection1/, schema=schema.xml, 
transient=false, dataDir=data/}
   [junit4]   2> 9492 T148 oasc.CorePropertiesLocator.discoverUnder Found core 
collection1 in 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.embedded.SolrExampleJettyTest
 874DF3298D848C31-001/tempDir-001/collection1/
   [junit4]   2> 9492 T148 oasc.CorePropertiesLocator.discover Found 1 core 
definitions
   [junit4]   2> 9493 T160 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.embedded.SolrExampleJettyTest
 874DF3298D848C31-001/tempDir-001/collection1/'
   [junit4]   2> 9523 T160 oasc.SolrConfig.refreshRequestParams current version 
of requestparams : -1
   [junit4]   2> 9524 T160 oasc.SolrConfig.initLibs Adding specified lib dirs 
to ClassLoader
   [junit4]   2> 9524 T160 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/J2/temp/solr.client.solrj.embedded.SolrExampleJettyTest
 
874DF3298D848C31-001/tempDir-001/collection1/../../../../contrib/extraction/lib).
   [junit4]   2> 9525 T160 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/J2/temp/solr.client.solrj.embedded.SolrExampleJettyTest
 874DF3298D848C31-001/tempDir-001/collection1/../../../../dist).
   [junit4]   2> 9525 T160 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/J2/temp/solr.client.solrj.embedded.SolrExampleJettyTest
 
874DF3298D848C31-001/tempDir-001/collection1/../../../../contrib/clustering/lib).
   [junit4]   2> 9525 T160 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/J2/temp/solr.client.solrj.embedded.SolrExampleJettyTest
 874DF3298D848C31-001/tempDir-001/collection1/../../../../dist).
   [junit4]   2> 9526 T160 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/J2/temp/solr.client.solrj.embedded.SolrExampleJettyTest
 874DF3298D848C31-001/tempDir-001/collection1/../../../../contrib/langid/lib).
   [junit4]   2> 9526 T160 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/J2/temp/solr.client.solrj.embedded.SolrExampleJettyTest
 874DF3298D848C31-001/tempDir-001/collection1/../../../../dist).
   [junit4]   2> 9526 T160 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/J2/temp/solr.client.solrj.embedded.SolrExampleJettyTest
 874DF3298D848C31-001/tempDir-001/collection1/../../../../contrib/velocity/lib).
   [junit4]   2> 9527 T160 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/J2/temp/solr.client.solrj.embedded.SolrExampleJettyTest
 874DF3298D848C31-001/tempDir-001/collection1/../../../../dist).
   [junit4]   2> 9549 T160 oasu.SolrIndexConfig.<init> IndexWriter infoStream 
solr logging is enabled
   [junit4]   2> 9551 T160 oasc.SolrConfig.<init> Using Lucene MatchVersion: 
6.0.0
   [junit4]   2> 9592 T160 oasc.SolrConfig.<init> Loaded SolrConfig: 
solrconfig.xml
   [junit4]   2> 9592 T160 oass.IndexSchema.readSchema Reading Solr Schema from 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.embedded.SolrExampleJettyTest
 874DF3298D848C31-001/tempDir-001/collection1/conf/schema.xml
   [junit4]   2> 9605 T160 oass.IndexSchema.readSchema [collection1] Schema 
name=example
   [junit4]   2> 9812 T160 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 9813 T160 oasr.RestManager$Registry.registerManagedResource 
Registered ManagedResource impl 
org.apache.solr.rest.schema.analysis.ManagedWordSetResource for path 
/schema/analysis/stopwords/english
   [junit4]   2> 9814 T160 oasr.RestManager$Registry.registerManagedResource 
Registered ManagedResource impl 
org.apache.solr.rest.schema.analysis.ManagedSynonymFilterFactory$SynonymManager 
for path /schema/analysis/synonyms/english
   [junit4]   2> 9821 T160 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
   [junit4]   2> 9824 T160 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
   [junit4]   2> 9855 T160 oasc.CoreContainer.create Creating SolrCore 
'collection1' using configuration from instancedir 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.embedded.SolrExampleJettyTest
 874DF3298D848C31-001/tempDir-001/collection1/
   [junit4]   2> 9856 T160 c:collection1 oasc.SolrCore.initDirectoryFactory 
org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 9856 T160 c:collection1 oasc.SolrCore.<init> [collection1] 
Opening new SolrCore at 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.embedded.SolrExampleJettyTest
 874DF3298D848C31-001/tempDir-001/collection1/, 
dataDir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.embedded.SolrExampleJettyTest
 874DF3298D848C31-001/tempDir-001/collection1/data/
   [junit4]   2> 9856 T160 c:collection1 oasc.JmxMonitoredMap.<init> JMX 
monitoring is enabled. Adding Solr mbeans to JMX Server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@4a0519
   [junit4]   2> 9871 T160 c:collection1 oasc.SolrCore.initListeners 
[collection1] Added SolrEventListener for newSearcher: 
org.apache.solr.core.QuerySenderListener{queries=[]}
   [junit4]   2> 9871 T160 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> 9872 T160 c:collection1 oasc.CachingDirectoryFactory.get 
return new directory for 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.embedded.SolrExampleJettyTest
 874DF3298D848C31-001/tempDir-001/collection1/data
   [junit4]   2> 9872 T160 c:collection1 oasc.SolrCore.getNewIndexDir New index 
directory detected: old=null 
new=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.embedded.SolrExampleJettyTest
 874DF3298D848C31-001/tempDir-001/collection1/data/index/
   [junit4]   2> 9872 T160 c:collection1 oasc.SolrCore.initIndex WARN 
[collection1] Solr index directory 
'/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.embedded.SolrExampleJettyTest
 874DF3298D848C31-001/tempDir-001/collection1/data/index' doesn't exist. 
Creating new index...
   [junit4]   2> 9872 T160 c:collection1 oasc.CachingDirectoryFactory.get 
return new directory for 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.embedded.SolrExampleJettyTest
 874DF3298D848C31-001/tempDir-001/collection1/data/index
   [junit4]   2> 9872 T160 c:collection1 oasu.LoggingInfoStream.message 
[IFD][coreLoadExecutor-37-thread-1]: init: current segments file is "segments"; 
deletionPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper@c8b8f2
   [junit4]   2> 9873 T160 c:collection1 oasu.LoggingInfoStream.message 
[IFD][coreLoadExecutor-37-thread-1]: now checkpoint "" [0 segments ; isCommit = 
false]
   [junit4]   2> 9873 T160 c:collection1 oasu.LoggingInfoStream.message 
[IFD][coreLoadExecutor-37-thread-1]: 0 msec to checkpoint
   [junit4]   2> 9873 T160 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-37-thread-1]: init: create=true
   [junit4]   2> 9873 T160 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-37-thread-1]: 
   [junit4]   2>        dir=MockDirectoryWrapper(RAMDirectory@400e9d 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@fc8ff8)
   [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@109dc23
   [junit4]   2>        readerPooling=false
   [junit4]   2>        perThreadHardLimitMB=1945
   [junit4]   2>        useCompoundFile=false
   [junit4]   2>        commitOnClose=true
   [junit4]   2>        writer=org.apache.solr.update.SolrIndexWriter@18316f9
   [junit4]   2>        
   [junit4]   2> 9873 T160 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-37-thread-1]: now flush at close
   [junit4]   2> 9873 T160 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-37-thread-1]:   start flush: applyAllDeletes=true
   [junit4]   2> 9874 T160 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-37-thread-1]:   index before flush 
   [junit4]   2> 9874 T160 c:collection1 oasu.LoggingInfoStream.message 
[DW][coreLoadExecutor-37-thread-1]: startFullFlush
   [junit4]   2> 9874 T160 c:collection1 oasu.LoggingInfoStream.message 
[DW][coreLoadExecutor-37-thread-1]: coreLoadExecutor-37-thread-1 
finishFullFlush success=true
   [junit4]   2> 9874 T160 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-37-thread-1]: apply all deletes during flush
   [junit4]   2> 9874 T160 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-37-thread-1]: now apply all deletes for all segments 
maxDoc=0
   [junit4]   2> 9874 T160 c:collection1 oasu.LoggingInfoStream.message 
[BD][coreLoadExecutor-37-thread-1]: prune sis=segments:  
minGen=9223372036854775807 packetCount=0
   [junit4]   2> 9874 T160 c:collection1 oasu.LoggingInfoStream.message 
[MS][coreLoadExecutor-37-thread-1]: initDynamicDefaults spins=false 
maxThreadCount=4 maxMergeCount=9
   [junit4]   2> 9875 T160 c:collection1 oasu.LoggingInfoStream.message 
[MS][coreLoadExecutor-37-thread-1]: updateMergeThreads ioThrottle=true 
targetMBPerSec=10240.0 MB/sec
   [junit4]   2> 9875 T160 c:collection1 oasu.LoggingInfoStream.message 
[MS][coreLoadExecutor-37-thread-1]: now merge
   [junit4]   2> 9875 T160 c:collection1 oasu.LoggingInfoStream.message 
[MS][coreLoadExecutor-37-thread-1]:   index: 
   [junit4]   2> 9875 T160 c:collection1 oasu.LoggingInfoStream.message 
[MS][coreLoadExecutor-37-thread-1]:   no more merges pending; now return
   [junit4]   2> 9875 T160 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-37-thread-1]: waitForMerges
   [junit4]   2> 9875 T160 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-37-thread-1]: waitForMerges done
   [junit4]   2> 9875 T160 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-37-thread-1]: commit: start
   [junit4]   2> 9876 T160 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-37-thread-1]: commit: enter lock
   [junit4]   2> 9876 T160 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-37-thread-1]: commit: now prepare
   [junit4]   2> 9876 T160 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-37-thread-1]: prepareCommit: flush
   [junit4]   2> 9876 T160 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-37-thread-1]:   index before flush 
   [junit4]   2> 9876 T160 c:collection1 oasu.LoggingInfoStream.message 
[DW][coreLoadExecutor-37-thread-1]: startFullFlush
   [junit4]   2> 9876 T160 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-37-thread-1]: apply all deletes during flush
   [junit4]   2> 9876 T160 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-37-thread-1]: now apply all deletes for all segments 
maxDoc=0
   [junit4]   2> 9876 T160 c:collection1 oasu.LoggingInfoStream.message 
[BD][coreLoadExecutor-37-thread-1]: prune sis=segments:  
minGen=9223372036854775807 packetCount=0
   [junit4]   2> 9877 T160 c:collection1 oasu.LoggingInfoStream.message 
[DW][coreLoadExecutor-37-thread-1]: coreLoadExecutor-37-thread-1 
finishFullFlush success=true
   [junit4]   2> 9877 T160 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-37-thread-1]: startCommit(): start
   [junit4]   2> 9877 T160 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-37-thread-1]: startCommit index= changeCount=1
   [junit4]   2> 9877 T160 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-37-thread-1]: done all syncs: []
   [junit4]   2> 9877 T160 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-37-thread-1]: commit: pendingCommit != null
   [junit4]   2> 9877 T160 c:collection1 oasu.LoggingInfoStream.message 
[IFD][coreLoadExecutor-37-thread-1]: now checkpoint "" [0 segments ; isCommit = 
true]
   [junit4]   2> 9877 T160 c:collection1 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@400e9d 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@fc8ff8),segFN=segments_1,generation=1}
   [junit4]   2> 9878 T160 c:collection1 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 1
   [junit4]   2> 9878 T160 c:collection1 oasu.LoggingInfoStream.message 
[IFD][coreLoadExecutor-37-thread-1]: 0 msec to checkpoint
   [junit4]   2> 9878 T160 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-37-thread-1]: commit: wrote segments file "segments_1"
   [junit4]   2> 9878 T160 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-37-thread-1]: commit: took 2.1 msec
   [junit4]   2> 9878 T160 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-37-thread-1]: commit: done
   [junit4]   2> 9878 T160 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-37-thread-1]: rollback
   [junit4]   2> 9878 T160 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-37-thread-1]: all running merges have aborted
   [junit4]   2> 9879 T160 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-37-thread-1]: rollback: done finish merges
   [junit4]   2> 9879 T160 c:collection1 oasu.LoggingInfoStream.message 
[DW][coreLoadExecutor-37-thread-1]: abort
   [junit4]   2> 9879 T160 c:collection1 oasu.LoggingInfoStream.message 
[DW][coreLoadExecutor-37-thread-1]: done abort success=true
   [junit4]   2> 9879 T160 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-37-thread-1]: rollback: infos=
   [junit4]   2> 9879 T160 c:collection1 oasu.LoggingInfoStream.message 
[IFD][coreLoadExecutor-37-thread-1]: now checkpoint "" [0 segments ; isCommit = 
false]
   [junit4]   2> 9879 T160 c:collection1 oasu.LoggingInfoStream.message 
[IFD][coreLoadExecutor-37-thread-1]: 0 msec to checkpoint
   [junit4]   2> 9879 T160 c:collection1 oasc.PluginBag.createPlugin 
queryResponseWriter : 'velocity' created with startup=lazy 
   [junit4]   2> 9880 T160 c:collection1 oasr.XSLTResponseWriter.init 
xsltCacheLifetimeSeconds=5
   [junit4]   2> 9885 T160 c:collection1 
oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined 
as default, creating implicit default
   [junit4]   2> 9887 T160 c:collection1 oashl.XMLLoader.init 
xsltCacheLifetimeSeconds=60
   [junit4]   2> 9890 T160 c:collection1 oashl.XMLLoader.init 
xsltCacheLifetimeSeconds=60
   [junit4]   2> 9891 T160 c:collection1 oashl.XMLLoader.init 
xsltCacheLifetimeSeconds=60
   [junit4]   2> 9892 T160 c:collection1 oashl.XMLLoader.init 
xsltCacheLifetimeSeconds=60
   [junit4]   2> 9899 T160 c:collection1 oasc.PluginBag.createPlugin 
requestHandler : '/update/extract' created with startup=lazy 
   [junit4]   2> 9899 T160 c:collection1 oasc.PluginBag.createPlugin 
requestHandler : '/analysis/field' created with startup=lazy 
   [junit4]   2> 9900 T160 c:collection1 oasc.PluginBag.createPlugin 
requestHandler : '/analysis/document' created with startup=lazy 
   [junit4]   2> 9900 T160 c:collection1 oasc.PluginBag.createPlugin 
requestHandler : '/spell' created with startup=lazy 
   [junit4]   2> 9900 T160 c:collection1 oasc.PluginBag.createPlugin 
requestHandler : '/suggest' created with startup=lazy 
   [junit4]   2> 9900 T160 c:collection1 oasc.PluginBag.createPlugin 
requestHandler : '/tvrh' created with startup=lazy 
   [junit4]   2> 9900 T160 c:collection1 oasc.PluginBag.createPlugin 
requestHandler : '/terms' created with startup=lazy 
   [junit4]   2> 9901 T160 c:collection1 oasc.PluginBag.createPlugin 
requestHandler : '/elevate' created with startup=lazy 
   [junit4]   2> 9901 T160 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> 9902 T160 c:collection1 oasc.SolrCore.initStatsCache Using 
default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 9902 T160 c:collection1 oasu.UpdateHandler.<init> Using 
UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 9903 T160 c:collection1 oasu.UpdateLog.init Initializing 
UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10
   [junit4]   2> 9903 T160 c:collection1 oasu.CommitTracker.<init> Hard 
AutoCommit: if uncommited for 15000ms; 
   [junit4]   2> 9903 T160 c:collection1 oasu.CommitTracker.<init> Soft 
AutoCommit: disabled
   [junit4]   2> 9904 T160 c:collection1 oasu.LoggingInfoStream.message 
[IFD][coreLoadExecutor-37-thread-1]: init: current segments file is 
"segments_1"; 
deletionPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper@c8b8f2
   [junit4]   2> 9904 T160 c:collection1 oasu.LoggingInfoStream.message 
[IFD][coreLoadExecutor-37-thread-1]: init: load commit "segments_1"
   [junit4]   2> 9904 T160 c:collection1 oasc.SolrDeletionPolicy.onInit 
SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@400e9d 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@fc8ff8),segFN=segments_1,generation=1}
   [junit4]   2> 9904 T160 c:collection1 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 1
   [junit4]   2> 9905 T160 c:collection1 oasu.LoggingInfoStream.message 
[IFD][coreLoadExecutor-37-thread-1]: now checkpoint "" [0 segments ; isCommit = 
false]
   [junit4]   2> 9905 T160 c:collection1 oasu.LoggingInfoStream.message 
[IFD][coreLoadExecutor-37-thread-1]: 0 msec to checkpoint
   [junit4]   2> 9905 T160 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-37-thread-1]: init: create=false
   [junit4]   2> 9905 T160 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-37-thread-1]: 
   [junit4]   2>        dir=MockDirectoryWrapper(RAMDirectory@400e9d 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@fc8ff8)
   [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@11ecc77
   [junit4]   2>        readerPooling=false
   [junit4]   2>        perThreadHardLimitMB=1945
   [junit4]   2>        useCompoundFile=false
   [junit4]   2>        commitOnClose=true
   [junit4]   2>        writer=org.apache.solr.update.SolrIndexWriter@5ac6df
   [junit4]   2>        
   [junit4]   2> 9905 T160 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-37-thread-1]: flush at getReader
   [junit4]   2> 9905 T160 c:collection1 oasu.LoggingInfoStream.message 
[DW][coreLoadExecutor-37-thread-1]: startFullFlush
   [junit4]   2> 9906 T160 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-37-thread-1]: apply all deletes during flush
   [junit4]   2> 9906 T160 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-37-thread-1]: now apply all deletes for all segments 
maxDoc=0
   [junit4]   2> 9906 T160 c:collection1 oasu.LoggingInfoStream.message 
[BD][coreLoadExecutor-37-thread-1]: prune sis=segments_1:  
minGen=9223372036854775807 packetCount=0
   [junit4]   2> 9906 T160 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-37-thread-1]: return reader version=1 
reader=StandardDirectoryReader(segments_1:1:nrt)
   [junit4]   2> 9906 T160 c:collection1 oasu.LoggingInfoStream.message 
[DW][coreLoadExecutor-37-thread-1]: coreLoadExecutor-37-thread-1 
finishFullFlush success=true
   [junit4]   2> 9906 T160 c:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-37-thread-1]: getReader took 1 msec
   [junit4]   2> 9906 T160 c:collection1 oass.SolrIndexSearcher.<init> Opening 
Searcher@1aa1728[collection1] main
   [junit4]   2> 9907 T160 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/J2/temp/solr.client.solrj.embedded.SolrExampleJettyTest
 874DF3298D848C31-001/tempDir-001/collection1/conf
   [junit4]   2> 9907 T160 c:collection1 oasr.RestManager.init Initializing 
RestManager with initArgs: 
{storageDir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.embedded.SolrExampleJettyTest
 874DF3298D848C31-001/tempDir-001/collection1/conf}
   [junit4]   2> 9907 T160 c:collection1 oasr.ManagedResourceStorage.load 
Reading _rest_managed.json using 
file:dir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.embedded.SolrExampleJettyTest
 874DF3298D848C31-001/tempDir-001/collection1/conf
   [junit4]   2> 9907 T160 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/J2/temp/solr.client.solrj.embedded.SolrExampleJettyTest
 874DF3298D848C31-001/tempDir-001/collection1/conf
   [junit4]   2> 9908 T160 c:collection1 oasr.ManagedResource.processStoredData 
Loaded initArgs {} for /rest/managed
   [junit4]   2> 9908 T160 c:collection1 oasr.RestManager.init Initializing 2 
registered ManagedResources
   [junit4]   2> 9908 T160 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/J2/temp/solr.client.solrj.embedded.SolrExampleJettyTest
 874DF3298D848C31-001/tempDir-001/collection1/conf
   [junit4]   2> 9908 T160 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/J2/temp/solr.client.solrj.embedded.SolrExampleJettyTest
 874DF3298D848C31-001/tempDir-001/collection1/conf
   [junit4]   2> 9908 T160 c:collection1 oasr.ManagedResource.processStoredData 
Loaded initArgs {ignoreCase=true} for /schema/analysis/stopwords/english
   [junit4]   2> 9909 T160 c:collection1 
oasrsa.ManagedWordSetResource.onManagedDataLoadedFromStorage Loaded 35 words 
for /schema/analysis/stopwords/english
   [junit4]   2> 9909 T160 c:collection1 
oasr.ManagedResource.notifyObserversDuringInit Notified 1 observers of 
/schema/analysis/stopwords/english
   [junit4]   2> 9909 T160 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/J2/temp/solr.client.solrj.embedded.SolrExampleJettyTest
 874DF3298D848C31-001/tempDir-001/collection1/conf
   [junit4]   2> 9909 T160 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/J2/temp/solr.client.solrj.embedded.SolrExampleJettyTest
 874DF3298D848C31-001/tempDir-001/collection1/conf
   [junit4]   2> 9909 T160 c:collection1 oasr.ManagedResource.processStoredData 
Loaded initArgs {ignoreCase=true,format=solr} for 
/schema/analysis/synonyms/english
   [junit4]   2> 9910 T160 c:collection1 
oasrsa.ManagedSynonymFilterFactory$SynonymManager.onManagedDataLoadedFromStorage
 Loaded 3 synonym mappings for /schema/analysis/synonyms/english
   [junit4]   2> 9910 T160 c:collection1 
oasr.ManagedResource.notifyObserversDuringInit Notified 1 observers of 
/schema/analysis/synonyms/english
   [junit4]   2> 9910 T160 c:collection1 oashc.SpellCheckComponent.inform 
Initializing spell checkers
   [junit4]   2> 9910 T160 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> 9911 T160 c:collection1 oashc.SpellCheckComponent.inform No 
queryConverter defined, using default converter
   [junit4]   2> 9911 T160 c:collection1 oashc.SuggestComponent.inform 
Initializing SuggestComponent
   [junit4]   2> 9911 T160 c:collection1 oasss.SolrSuggester.init init: 
{name=mySuggester,lookupImpl=FuzzyLookupFactory,dictionaryImpl=DocumentDictionaryFactory,field=cat,weightField=price,suggestAnalyzerFieldType=string,buildOnStartup=false}
   [junit4]   2> 9911 T160 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> 9911 T160 c:collection1 oashc.QueryElevationComponent.inform 
Loading QueryElevation from: 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.embedded.SolrExampleJettyTest
 874DF3298D848C31-001/tempDir-001/collection1/conf/elevate.xml
   [junit4]   2> 9916 T160 c:collection1 oash.ReplicationHandler.inform Commits 
will be reserved for  10000
   [junit4]   2> 9916 T161 c:collection1 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@1aa1728[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> ASYNC  NEW_CORE C5 name=collection1 
org.apache.solr.core.SolrCore@11e8dff
   [junit4]   2> 9917 T161 c:collection1 C5 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=1 
   [junit4]   2> 9917 T161 c:collection1 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 9917 T161 c:collection1 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: default
   [junit4]   2> 9917 T161 c:collection1 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: wordbreak
   [junit4]   2> 9918 T160 c:collection1 oasc.CoreContainer.registerCore 
registering core: collection1
   [junit4]   2> 9919 T148 oass.SolrDispatchFilter.init 
user.dir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J2
   [junit4]   2> 9919 T148 oass.SolrDispatchFilter.init 
SolrDispatchFilter.init() done
   [junit4]   2> 9919 T148 oas.SolrJettyTestBase.createJetty Jetty Assigned 
Port#53759
   [junit4]   2> 9920 T161 c:collection1 oasc.SolrCore.registerSearcher 
[collection1] Registered new searcher Searcher@1aa1728[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 9922 T148 oas.SolrTestCaseJ4.setUp ###Starting testAddRetrieve
   [junit4]   2> 9928 T155 c:collection1 C5 oasu.LoggingInfoStream.message 
[DW][qtp23519463-155]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false 
pendingChangesInFullFlush: false
   [junit4]   2> 9929 T155 c:collection1 C5 oasu.LoggingInfoStream.message 
[DW][qtp23519463-155]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false 
pendingChangesInFullFlush: false
   [junit4]   2> 9929 T155 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp23519463-155]: nrtIsCurrent: infoVersion matches: true; DW changes: 
true; BD changes: false
   [junit4]   2> 9929 T155 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp23519463-155]: flush at getReader
   [junit4]   2> 9929 T155 c:collection1 C5 oasu.LoggingInfoStream.message 
[DW][qtp23519463-155]: startFullFlush
   [junit4]   2> 9929 T155 c:collection1 C5 oasu.LoggingInfoStream.message 
[DW][qtp23519463-155]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false 
pendingChangesInFullFlush: false
   [junit4]   2> 9930 T155 c:collection1 C5 oasu.LoggingInfoStream.message 
[DW][qtp23519463-155]: qtp23519463-155: flush naked frozen global deletes
   [junit4]   2> 9930 T155 c:collection1 C5 oasu.LoggingInfoStream.message 
[BD][qtp23519463-155]: push deletes  1 deleted queries bytesUsed=64 
segmentPrivate?=false delGen=2 packetCount=1 totBytesUsed=64
   [junit4]   2> 9930 T155 c:collection1 C5 oasu.LoggingInfoStream.message 
[DW][qtp23519463-155]: flush: push buffered updates:  1 deleted queries 
bytesUsed=64
   [junit4]   2> 9930 T155 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp23519463-155]: apply all deletes during flush
   [junit4]   2> 9931 T155 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp23519463-155]: now apply all deletes for all segments maxDoc=0
   [junit4]   2> 9931 T155 c:collection1 C5 oasu.LoggingInfoStream.message 
[BD][qtp23519463-155]: prune sis=segments_1:  minGen=9223372036854775807 
packetCount=1
   [junit4]   2> 9931 T155 c:collection1 C5 oasu.LoggingInfoStream.message 
[BD][qtp23519463-155]: pruneDeletes: prune 1 packets; 0 packets remain
   [junit4]   2> 9931 T155 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp23519463-155]: return reader version=1 
reader=StandardDirectoryReader(segments_1:1:nrt)
   [junit4]   2> 9932 T155 c:collection1 C5 oasu.LoggingInfoStream.message 
[DW][qtp23519463-155]: qtp23519463-155 finishFullFlush success=true
   [junit4]   2> 9932 T155 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp23519463-155]: getReader took 3 msec
   [junit4]   2> 9932 T155 c:collection1 C5 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/solr path=/update params={wt=javabin&version=2} 
{deleteByQuery=*:* (-1498510269289070592)} 0 5
   [junit4]   2> 9940 T156 c:collection1 C5 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id1 
(1498510269298507776), id2 (1498510269301653504)]} 0 5
   [junit4]   2> 9943 T157 c:collection1 C5 oasu.DirectUpdateHandler2.commit 
start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 9944 T157 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: commit: start
   [junit4]   2> 9944 T157 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: commit: enter lock
   [junit4]   2> 9944 T157 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: commit: now prepare
   [junit4]   2> 9944 T157 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: prepareCommit: flush
   [junit4]   2> 9944 T157 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]:   index before flush 
   [junit4]   2> 9944 T157 c:collection1 C5 oasu.LoggingInfoStream.message 
[DW][qtp23519463-157]: startFullFlush
   [junit4]   2> 9945 T157 c:collection1 C5 oasu.LoggingInfoStream.message 
[DW][qtp23519463-157]: anyChanges? numDocsInRam=2 deletes=true hasTickets:false 
pendingChangesInFullFlush: false
   [junit4]   2> 9945 T157 c:collection1 C5 oasu.LoggingInfoStream.message 
[DWFC][qtp23519463-157]: addFlushableState DocumentsWriterPerThread 
[pendingDeletes=gen=0 1 deleted terms (unique count=1) bytesUsed=139, 
segment=_0, aborted=false, numDocsInRAM=2, deleteQueue=DWDQ: [ generation: 2 ]]
   [junit4]   2> 9945 T157 c:collection1 C5 oasu.LoggingInfoStream.message 
[DWPT][qtp23519463-157]: flush postings as segment _0 numDocs=2
   [junit4]   2> 9948 T157 c:collection1 C5 oasu.LoggingInfoStream.message 
[DWPT][qtp23519463-157]: new segment has 0 deleted docs
   [junit4]   2> 9949 T157 c:collection1 C5 oasu.LoggingInfoStream.message 
[DWPT][qtp23519463-157]: new segment has no vectors; norms; no docValues; prox; 
freqs
   [junit4]   2> 9949 T157 c:collection1 C5 oasu.LoggingInfoStream.message 
[DWPT][qtp23519463-157]: flushedFiles=[_0_Lucene50_0.doc, _0_Lucene50_0.tim, 
_0_Lucene50_0.pos, _0.nvd, _0.fdx, _0_Lucene50_0.tip, _0.fdt, _0.nvm, _0.fnm]
   [junit4]   2> 9949 T157 c:collection1 C5 oasu.LoggingInfoStream.message 
[DWPT][qtp23519463-157]: flushed codec=Lucene50
   [junit4]   2> 9949 T157 c:collection1 C5 oasu.LoggingInfoStream.message 
[DWPT][qtp23519463-157]: flushed: segment=_0 ramUsed=0.12 MB 
newFlushedSize=0.002 MB docs/MB=938.323
   [junit4]   2> 9950 T157 c:collection1 C5 oasu.LoggingInfoStream.message 
[DW][qtp23519463-157]: publishFlushedSegment seg-private updates=null
   [junit4]   2> 9950 T157 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: publishFlushedSegment
   [junit4]   2> 9950 T157 c:collection1 C5 oasu.LoggingInfoStream.message 
[BD][qtp23519463-157]: push deletes  2 deleted terms (unique count=2) 
bytesUsed=1056 segmentPrivate?=false delGen=4 packetCount=1 totBytesUsed=1056
   [junit4]   2> 9950 T157 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: publish sets newSegment delGen=5 seg=_0(6.0.0):C2
   [junit4]   2> 9950 T157 c:collection1 C5 oasu.LoggingInfoStream.message 
[IFD][qtp23519463-157]: now checkpoint "_0(6.0.0):C2" [1 segments ; isCommit = 
false]
   [junit4]   2> 9951 T157 c:collection1 C5 oasu.LoggingInfoStream.message 
[IFD][qtp23519463-157]: 0 msec to checkpoint
   [junit4]   2> 9951 T157 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: apply all deletes during flush
   [junit4]   2> 9951 T157 c:collection1 C5 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: now apply all deletes for all segments maxDoc=2
   [junit4]   2> 9951 T157 c:collection1 C5 oasu.LoggingInfoStream.message 
[BD][qtp23519463-157]: applyDeletes: open segment readers took 0 msec
   [junit4]   2> 9952 T157 c:collection1 C5 oasu.LoggingInfoStream.message 
[BD][qtp23519463-157]: applyDeletes: infos=[_0(6.0.0):C2] packetCount=1
   [junit4]   2> 9952 T157 c:collection1 C5 oasu.LoggingInfoStream.message 
[BD][qtp23519463-157]: applyDeletes took 1 msec for 1 segments, 0 newly deleted 
docs (query deletes), 0 visited terms, allDeleted=null
   [junit4]   2> 9952 T157 c:collection1 C5 oasu.LoggingInfoStream.message 
[BD][qtp23519463-157]: prune sis=segments_1: _0(6.0.0):C2 minGen=5 packetCount=1
   [junit4]   2> 9952 T157 c:collection1 C5 oasu.LoggingInfoStream.message 
[BD][qtp23519463-157]: pruneDeletes: prune 1 packets; 0 packets remain
   [junit4]   2> 9952 T157 c:collection1 C5 oasu.LoggingInfoStream.message 
[DW][qtp23519463-157]: qtp23519463-157 finishFullFlush success=true
   [junit4]   2> 9953 T157 c:collection1 C5 oasu.LoggingInfoStream.message 
[TMP][qtp23519463-157]: findMerges: 1 segments
   [junit4]   2> ASYNC  NEW_CORE C6 name=collection1 
org.apache.solr.core.SolrCore@11e8dff
   [junit4]   2> 9969 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[TMP][qtp23519463-157]:   seg=_0(6.0.0):C2 size=0.002 MB [floored]
   [junit4]   2> 9969 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[TMP][qtp23519463-157]:   allowedSegmentCount=1 vs count=1 (eligible count=1) 
tooBigCount=0
   [junit4]   2> 9969 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[MS][qtp23519463-157]: initDynamicDefaults spins=false maxThreadCount=4 
maxMergeCount=9
   [junit4]   2> 9969 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[MS][qtp23519463-157]: now merge
   [junit4]   2> 9970 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[MS][qtp23519463-157]:   index: _0(6.0.0):C2
   [junit4]   2> 9970 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[MS][qtp23519463-157]:   no more merges pending; now return
   [junit4]   2> 9970 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: startCommit(): start
   [junit4]   2> 9970 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: startCommit index=_0(6.0.0):C2 changeCount=3
   [junit4]   2> 9971 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: done all syncs: [_0.si, _0_Lucene50_0.doc, 
_0_Lucene50_0.tim, _0_Lucene50_0.pos, _0.nvd, _0.fdx, _0_Lucene50_0.tip, 
_0.fdt, _0.nvm, _0.fnm]
   [junit4]   2> 9971 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: commit: pendingCommit != null
   [junit4]   2> 9971 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IFD][qtp23519463-157]: now checkpoint "_0(6.0.0):C2" [1 segments ; isCommit = 
true]
   [junit4]   2> 9972 T157 c:collection1 C6 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@400e9d 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@fc8ff8),segFN=segments_1,generation=1}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@400e9d 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@fc8ff8),segFN=segments_2,generation=2}
   [junit4]   2> 9972 T157 c:collection1 C6 
oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
   [junit4]   2> 9972 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IFD][qtp23519463-157]: deleteCommits: now decRef commit "segments_1"
   [junit4]   2> 9972 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IFD][qtp23519463-157]: delete "segments_1"
   [junit4]   2> 9973 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IFD][qtp23519463-157]: 1 msec to checkpoint
   [junit4]   2> 9973 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: commit: wrote segments file "segments_2"
   [junit4]   2> 9973 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: commit: took 28.8 msec
   [junit4]   2> 9973 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: commit: done
   [junit4]   2> 9973 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: nrtIsCurrent: infoVersion matches: false; DW changes: 
false; BD changes: false
   [junit4]   2> 9974 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: flush at getReader
   [junit4]   2> 9974 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[DW][qtp23519463-157]: startFullFlush
   [junit4]   2> 9974 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: apply all deletes during flush
   [junit4]   2> 9974 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: now apply all deletes for all segments maxDoc=2
   [junit4]   2> 9975 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[BD][qtp23519463-157]: applyDeletes: open segment readers took 0 msec
   [junit4]   2> 9975 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[BD][qtp23519463-157]: applyDeletes: no segments; skipping
   [junit4]   2> 9975 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[BD][qtp23519463-157]: prune sis=segments_1: _0(6.0.0):C2 minGen=5 packetCount=0
   [junit4]   2> 9976 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: return reader version=3 
reader=StandardDirectoryReader(segments_1:3:nrt _0(6.0.0):C2)
   [junit4]   2> 9976 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[DW][qtp23519463-157]: qtp23519463-157 finishFullFlush success=true
   [junit4]   2> 9976 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: getReader took 2 msec
   [junit4]   2> 9977 T157 c:collection1 C6 oass.SolrIndexSearcher.<init> 
Opening Searcher@1f4f4cc[collection1] main
   [junit4]   2> 9977 T157 c:collection1 C6 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 9977 T161 c:collection1 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@1f4f4cc[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):C2)))}
   [junit4]   2> 9978 T161 c:collection1 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 9980 T161 c:collection1 oasc.SolrCore.registerSearcher 
[collection1] Registered new searcher Searcher@1f4f4cc[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):C2)))}
   [junit4]   2> 9981 T157 c:collection1 C6 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/solr path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} 
{commit=} 0 38
   [junit4]   2> 10013 T155 c:collection1 C6 oasc.SolrCore.execute 
[collection1] webapp=/solr path=/select 
params={q=*:*&sort=price+asc&wt=javabin&version=2} hits=2 status=0 QTime=29 
   [junit4]   2> 10020 T156 c:collection1 C6 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id1 
(1498510269383442432), id2 (1498510269385539584)]} 0 3
   [junit4]   2> 10022 T157 c:collection1 C6 oasu.DirectUpdateHandler2.commit 
start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 10023 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: commit: start
   [junit4]   2> 10023 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: commit: enter lock
   [junit4]   2> 10023 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: commit: now prepare
   [junit4]   2> 10023 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: prepareCommit: flush
   [junit4]   2> 10023 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]:   index before flush _0(6.0.0):C2
   [junit4]   2> 10024 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[DW][qtp23519463-157]: startFullFlush
   [junit4]   2> 10024 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[DW][qtp23519463-157]: anyChanges? numDocsInRam=2 deletes=true hasTickets:false 
pendingChangesInFullFlush: false
   [junit4]   2> 10024 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[DWFC][qtp23519463-157]: addFlushableState DocumentsWriterPerThread 
[pendingDeletes=gen=0 1 deleted terms (unique count=1) bytesUsed=139, 
segment=_1, aborted=false, numDocsInRAM=2, deleteQueue=DWDQ: [ generation: 4 ]]
   [junit4]   2> 10024 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[DWPT][qtp23519463-157]: flush postings as segment _1 numDocs=2
   [junit4]   2> 10026 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[DWPT][qtp23519463-157]: new segment has 0 deleted docs
   [junit4]   2> 10026 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[DWPT][qtp23519463-157]: new segment has no vectors; norms; no docValues; prox; 
freqs
   [junit4]   2> 10027 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[DWPT][qtp23519463-157]: flushedFiles=[_1_Lucene50_0.doc, _1_Lucene50_0.tim, 
_1.nvd, _1_Lucene50_0.pos, _1.fdx, _1.nvm, _1.fnm, _1.fdt, _1_Lucene50_0.tip]
   [junit4]   2> 10027 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[DWPT][qtp23519463-157]: flushed codec=Lucene50
   [junit4]   2> 10027 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[DWPT][qtp23519463-157]: flushed: segment=_1 ramUsed=0.12 MB 
newFlushedSize=0.002 MB docs/MB=938.323
   [junit4]   2> 10027 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[DW][qtp23519463-157]: publishFlushedSegment seg-private updates=null
   [junit4]   2> 10028 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: publishFlushedSegment
   [junit4]   2> 10028 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[BD][qtp23519463-157]: push deletes  2 deleted terms (unique count=2) 
bytesUsed=1056 segmentPrivate?=false delGen=8 packetCount=1 totBytesUsed=1056
   [junit4]   2> 10028 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: publish sets newSegment delGen=9 seg=_1(6.0.0):C2
   [junit4]   2> 10028 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IFD][qtp23519463-157]: now checkpoint "_0(6.0.0):C2 _1(6.0.0):C2" [2 segments 
; isCommit = false]
   [junit4]   2> 10029 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IFD][qtp23519463-157]: 0 msec to checkpoint
   [junit4]   2> 10029 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: apply all deletes during flush
   [junit4]   2> 10029 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: now apply all deletes for all segments maxDoc=4
   [junit4]   2> 10029 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[BD][qtp23519463-157]: applyDeletes: open segment readers took 0 msec
   [junit4]   2> 10029 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[BD][qtp23519463-157]: applyDeletes: infos=[_0(6.0.0):C2, _1(6.0.0):C2] 
packetCount=1
   [junit4]   2> 10036 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[BD][qtp23519463-157]: applyTermDeletes took 0.9 msec for 2 segments and 1 
packets; 2 del terms visited; 5 seg terms visited
   [junit4]   2> 10036 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[BD][qtp23519463-157]: applyDeletes: 2 new deleted documents
   [junit4]   2> 10037 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[BD][qtp23519463-157]: applyDeletes took 8 msec for 2 segments, 0 newly deleted 
docs (query deletes), 2 visited terms, allDeleted=[_0(6.0.0):C2]
   [junit4]   2> 10037 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IFD][qtp23519463-157]: now checkpoint "_0(6.0.0):C2/2 _1(6.0.0):C2" [2 
segments ; isCommit = false]
   [junit4]   2> 10037 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IFD][qtp23519463-157]: 0 msec to checkpoint
   [junit4]   2> 10037 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: drop 100% deleted segments: _0(6.0.0):C2/2
   [junit4]   2> 10038 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IFD][qtp23519463-157]: now checkpoint "_1(6.0.0):C2" [1 segments ; isCommit = 
false]
   [junit4]   2> 10038 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IFD][qtp23519463-157]: 0 msec to checkpoint
   [junit4]   2> 10038 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[BD][qtp23519463-157]: prune sis=segments_1: _1(6.0.0):C2 minGen=10 
packetCount=1
   [junit4]   2> 10038 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[BD][qtp23519463-157]: pruneDeletes: prune 1 packets; 0 packets remain
   [junit4]   2> 10039 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[DW][qtp23519463-157]: qtp23519463-157 finishFullFlush success=true
   [junit4]   2> 10039 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[TMP][qtp23519463-157]: findMerges: 1 segments
   [junit4]   2> 10039 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[TMP][qtp23519463-157]:   seg=_1(6.0.0):C2 size=0.002 MB [floored]
   [junit4]   2> 10039 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[TMP][qtp23519463-157]:   allowedSegmentCount=1 vs count=1 (eligible count=1) 
tooBigCount=0
   [junit4]   2> 10040 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[MS][qtp23519463-157]: now merge
   [junit4]   2> 10040 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[MS][qtp23519463-157]:   index: _1(6.0.0):C2
   [junit4]   2> 10040 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[MS][qtp23519463-157]:   no more merges pending; now return
   [junit4]   2> 10040 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: startCommit(): start
   [junit4]   2> 10040 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: startCommit index=_1(6.0.0):C2 changeCount=8
   [junit4]   2> 10041 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: done all syncs: [_1_Lucene50_0.doc, _1_Lucene50_0.tim, 
_1.si, _1.nvd, _1_Lucene50_0.pos, _1.fdx, _1.nvm, _1.fnm, _1.fdt, 
_1_Lucene50_0.tip]
   [junit4]   2> 10041 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: commit: pendingCommit != null
   [junit4]   2> 10041 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IFD][qtp23519463-157]: now checkpoint "_1(6.0.0):C2" [1 segments ; isCommit = 
true]
   [junit4]   2> 10041 T157 c:collection1 C6 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@400e9d 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@fc8ff8),segFN=segments_2,generation=2}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@400e9d 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@fc8ff8),segFN=segments_3,generation=3}
   [junit4]   2> 10042 T157 c:collection1 C6 
oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
   [junit4]   2> 10042 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IFD][qtp23519463-157]: deleteCommits: now decRef commit "segments_2"
   [junit4]   2> 10042 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IFD][qtp23519463-157]: delete "segments_2"
   [junit4]   2> 10042 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IFD][qtp23519463-157]: 1 msec to checkpoint
   [junit4]   2> 10043 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: commit: wrote segments file "segments_3"
   [junit4]   2> 10043 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: commit: took 19.5 msec
   [junit4]   2> 10043 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: commit: done
   [junit4]   2> 10043 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: nrtIsCurrent: infoVersion matches: false; DW changes: 
false; BD changes: false
   [junit4]   2> 10043 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: flush at getReader
   [junit4]   2> 10044 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[DW][qtp23519463-157]: startFullFlush
   [junit4]   2> 10044 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: apply all deletes during flush
   [junit4]   2> 10044 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: now apply all deletes for all segments maxDoc=2
   [junit4]   2> 10044 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[BD][qtp23519463-157]: applyDeletes: open segment readers took 0 msec
   [junit4]   2> 10044 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[BD][qtp23519463-157]: applyDeletes: no segments; skipping
   [junit4]   2> 10045 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[BD][qtp23519463-157]: prune sis=segments_1: _1(6.0.0):C2 minGen=10 
packetCount=0
   [junit4]   2> 10045 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: return reader version=7 
reader=StandardDirectoryReader(segments_1:7:nrt _1(6.0.0):C2)
   [junit4]   2> 10045 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[DW][qtp23519463-157]: qtp23519463-157 finishFullFlush success=true
   [junit4]   2> 10045 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: getReader took 2 msec
   [junit4]   2> 10046 T157 c:collection1 C6 oass.SolrIndexSearcher.<init> 
Opening Searcher@1de8fe5[collection1] main
   [junit4]   2> 10046 T161 c:collection1 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@1de8fe5[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.0.0):C2)))}
   [junit4]   2> 10046 T157 c:collection1 C6 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 10046 T161 c:collection1 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 10049 T161 c:collection1 oasc.SolrCore.registerSearcher 
[collection1] Registered new searcher Searcher@1de8fe5[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.0.0):C2)))}
   [junit4]   2> 10050 T161 c:collection1 oasu.LoggingInfoStream.message 
[IFD][searcherExecutor-38-thread-1-processing-{core=collection1}]: delete 
"_0.si"
   [junit4]   2> 10050 T161 c:collection1 oasu.LoggingInfoStream.message 
[IFD][searcherExecutor-38-thread-1-processing-{core=collection1}]: delete 
"_0_Lucene50_0.doc"
   [junit4]   2> 10050 T161 c:collection1 oasu.LoggingInfoStream.message 
[IFD][searcherExecutor-38-thread-1-processing-{core=collection1}]: delete 
"_0_Lucene50_0.tim"
   [junit4]   2> 10051 T161 c:collection1 oasu.LoggingInfoStream.message 
[IFD][searcherExecutor-38-thread-1-processing-{core=collection1}]: delete 
"_0_Lucene50_0.pos"
   [junit4]   2> 10051 T161 c:collection1 oasu.LoggingInfoStream.message 
[IFD][searcherExecutor-38-thread-1-processing-{core=collection1}]: delete 
"_0.nvd"
   [junit4]   2> 10051 T161 c:collection1 oasu.LoggingInfoStream.message 
[IFD][searcherExecutor-38-thread-1-processing-{core=collection1}]: delete 
"_0.fdx"
   [junit4]   2> 10051 T161 c:collection1 oasu.LoggingInfoStream.message 
[IFD][searcherExecutor-38-thread-1-processing-{core=collection1}]: delete 
"_0_Lucene50_0.tip"
   [junit4]   2> 10051 T161 c:collection1 oasu.LoggingInfoStream.message 
[IFD][searcherExecutor-38-thread-1-processing-{core=collection1}]: delete 
"_0.fdt"
   [junit4]   2> 10051 T161 c:collection1 oasu.LoggingInfoStream.message 
[IFD][searcherExecutor-38-thread-1-processing-{core=collection1}]: delete 
"_0.nvm"
   [junit4]   2> 10051 T161 c:collection1 oasu.LoggingInfoStream.message 
[IFD][searcherExecutor-38-thread-1-processing-{core=collection1}]: delete 
"_0.fnm"
   [junit4]   2> 10052 T157 c:collection1 C6 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/solr path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} 
{commit=} 0 30
   [junit4]   2> 10055 T155 c:collection1 C6 oasc.SolrCore.execute 
[collection1] webapp=/solr path=/select 
params={q=*:*&sort=price+asc&wt=javabin&version=2} hits=2 status=0 QTime=1 
   [junit4]   2> 10065 T156 c:collection1 C6 oasc.SolrCore.execute 
[collection1] webapp=/solr path=/select 
params={q=name:h𩬅llo&sort=price+asc&wt=javabin&version=2} hits=1 status=0 
QTime=7 
   [junit4]   2> 10067 T148 oas.SolrTestCaseJ4.tearDown ###Ending 
testAddRetrieve
   [junit4]   2> 10070 T148 oas.SolrTestCaseJ4.setUp ###Starting 
testCommitWithinOnDelete
   [junit4]   2> 10073 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[DW][qtp23519463-157]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false 
pendingChangesInFullFlush: false
   [junit4]   2> 10074 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[DW][qtp23519463-157]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false 
pendingChangesInFullFlush: false
   [junit4]   2> 10074 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: nrtIsCurrent: infoVersion matches: true; DW changes: 
true; BD changes: false
   [junit4]   2> 10074 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: flush at getReader
   [junit4]   2> 10074 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[DW][qtp23519463-157]: startFullFlush
   [junit4]   2> 10075 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[DW][qtp23519463-157]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false 
pendingChangesInFullFlush: false
   [junit4]   2> 10075 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[DW][qtp23519463-157]: qtp23519463-157: flush naked frozen global deletes
   [junit4]   2> 10075 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[BD][qtp23519463-157]: push deletes  1 deleted queries bytesUsed=64 
segmentPrivate?=false delGen=12 packetCount=1 totBytesUsed=64
   [junit4]   2> 10075 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[DW][qtp23519463-157]: flush: push buffered updates:  1 deleted queries 
bytesUsed=64
   [junit4]   2> 10076 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: apply all deletes during flush
   [junit4]   2> 10076 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: now apply all deletes for all segments maxDoc=2
   [junit4]   2> 10076 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[BD][qtp23519463-157]: applyDeletes: open segment readers took 0 msec
   [junit4]   2> 10076 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[BD][qtp23519463-157]: applyDeletes: infos=[_1(6.0.0):C2] packetCount=1
   [junit4]   2> 10100 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[BD][qtp23519463-157]: applyDeletes: 2 new deleted documents
   [junit4]   2> 10101 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[BD][qtp23519463-157]: applyDeletes took 25 msec for 1 segments, 2 newly 
deleted docs (query deletes), 0 visited terms, allDeleted=[_1(6.0.0):C2]
   [junit4]   2> 10101 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IFD][qtp23519463-157]: now checkpoint "_1(6.0.0):C2/2" [1 segments ; isCommit 
= false]
   [junit4]   2> 10102 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IFD][qtp23519463-157]: 0 msec to checkpoint
   [junit4]   2> 10102 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: drop 100% deleted segments: _1(6.0.0):C2/2
   [junit4]   2> 10103 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IFD][qtp23519463-157]: now checkpoint "" [0 segments ; isCommit = false]
   [junit4]   2> 10103 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IFD][qtp23519463-157]: 0 msec to checkpoint
   [junit4]   2> 10103 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[BD][qtp23519463-157]: prune sis=segments_1:  minGen=9223372036854775807 
packetCount=1
   [junit4]   2> 10104 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[BD][qtp23519463-157]: pruneDeletes: prune 1 packets; 0 packets remain
   [junit4]   2> 10104 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: return reader version=9 
reader=StandardDirectoryReader(segments_1:9:nrt)
   [junit4]   2> 10105 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[DW][qtp23519463-157]: qtp23519463-157 finishFullFlush success=true
   [junit4]   2> 10105 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[TMP][qtp23519463-157]: findMerges: 0 segments
   [junit4]   2> 10105 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[MS][qtp23519463-157]: now merge
   [junit4]   2> 10106 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[MS][qtp23519463-157]:   index: 
   [junit4]   2> 10106 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[MS][qtp23519463-157]:   no more merges pending; now return
   [junit4]   2> 10106 T157 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-157]: getReader took 32 msec
   [junit4]   2> 10107 T157 c:collection1 C6 oass.SolrIndexSearcher.<init> 
Opening Searcher@52ffde[collection1] realtime
   [junit4]   2> 10107 T157 c:collection1 C6 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/solr path=/update params={wt=javabin&version=2} 
{deleteByQuery=*:* (-1498510269442162688)} 0 35
   [junit4]   2> 10110 T155 c:collection1 C6 oasu.DirectUpdateHandler2.commit 
start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 10111 T155 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-155]: commit: start
   [junit4]   2> 10111 T155 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-155]: commit: enter lock
   [junit4]   2> 10112 T155 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-155]: commit: now prepare
   [junit4]   2> 10112 T155 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-155]: prepareCommit: flush
   [junit4]   2> 10112 T155 c:collection1 C6 oasu.LoggingInfoStream.message 
[IW][qtp23519463-155]:   index before flush 
   [junit4]   2> 10112 T155 c:collection1 C6 oasu.LoggingInfoStream.message 
[DW][qtp23519463-155]: startFullFlush
   [juni

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

StreamingBinaryTest-seed#[874DF3298D848C31]-worker]: prune sis=segments_1: 
_2j(6.0.0):C1 minGen=396 packetCount=0
   [junit4]   2> 44830 T195 C66 oasu.LoggingInfoStream.message 
[MS][SUITE-SolrExampleStreamingBinaryTest-seed#[874DF3298D848C31]-worker]: 
updateMergeThreads ioThrottle=true targetMBPerSec=10240.0 MB/sec
   [junit4]   2> 44830 T195 C66 oasu.LoggingInfoStream.message 
[MS][SUITE-SolrExampleStreamingBinaryTest-seed#[874DF3298D848C31]-worker]: now 
merge
   [junit4]   2> 44830 T195 C66 oasu.LoggingInfoStream.message 
[MS][SUITE-SolrExampleStreamingBinaryTest-seed#[874DF3298D848C31]-worker]:   
index: _2j(6.0.0):C1
   [junit4]   2> 44830 T195 C66 oasu.LoggingInfoStream.message 
[MS][SUITE-SolrExampleStreamingBinaryTest-seed#[874DF3298D848C31]-worker]:   no 
more merges pending; now return
   [junit4]   2> 44831 T195 C66 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleStreamingBinaryTest-seed#[874DF3298D848C31]-worker]: 
waitForMerges
   [junit4]   2> 44831 T195 C66 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleStreamingBinaryTest-seed#[874DF3298D848C31]-worker]: 
waitForMerges done
   [junit4]   2> 44832 T195 C66 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleStreamingBinaryTest-seed#[874DF3298D848C31]-worker]: 
commit: start
   [junit4]   2> 44832 T195 C66 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleStreamingBinaryTest-seed#[874DF3298D848C31]-worker]: 
commit: enter lock
   [junit4]   2> 44832 T195 C66 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleStreamingBinaryTest-seed#[874DF3298D848C31]-worker]: 
commit: now prepare
   [junit4]   2> 44832 T195 C66 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleStreamingBinaryTest-seed#[874DF3298D848C31]-worker]: 
prepareCommit: flush
   [junit4]   2> 44832 T195 C66 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleStreamingBinaryTest-seed#[874DF3298D848C31]-worker]:   
index before flush _2j(6.0.0):C1
   [junit4]   2> 44833 T195 C66 oasu.LoggingInfoStream.message 
[DW][SUITE-SolrExampleStreamingBinaryTest-seed#[874DF3298D848C31]-worker]: 
startFullFlush
   [junit4]   2> 44833 T195 C66 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleStreamingBinaryTest-seed#[874DF3298D848C31]-worker]: 
apply all deletes during flush
   [junit4]   2> 44833 T195 C66 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleStreamingBinaryTest-seed#[874DF3298D848C31]-worker]: now 
apply all deletes for all segments maxDoc=1
   [junit4]   2> 44833 T195 C66 oasu.LoggingInfoStream.message 
[BD][SUITE-SolrExampleStreamingBinaryTest-seed#[874DF3298D848C31]-worker]: 
applyDeletes: open segment readers took 0 msec
   [junit4]   2> 44833 T195 C66 oasu.LoggingInfoStream.message 
[BD][SUITE-SolrExampleStreamingBinaryTest-seed#[874DF3298D848C31]-worker]: 
applyDeletes: no segments; skipping
   [junit4]   2> 44834 T195 C66 oasu.LoggingInfoStream.message 
[BD][SUITE-SolrExampleStreamingBinaryTest-seed#[874DF3298D848C31]-worker]: 
prune sis=segments_1: _2j(6.0.0):C1 minGen=396 packetCount=0
   [junit4]   2> 44834 T195 C66 oasu.LoggingInfoStream.message 
[DW][SUITE-SolrExampleStreamingBinaryTest-seed#[874DF3298D848C31]-worker]: 
SUITE-SolrExampleStreamingBinaryTest-seed#[874DF3298D848C31]-worker 
finishFullFlush success=true
   [junit4]   2> 44834 T195 C66 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleStreamingBinaryTest-seed#[874DF3298D848C31]-worker]: 
startCommit(): start
   [junit4]   2> 44834 T195 C66 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleStreamingBinaryTest-seed#[874DF3298D848C31]-worker]:   
skip startCommit(): no changes pending
   [junit4]   2> 44835 T195 C66 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleStreamingBinaryTest-seed#[874DF3298D848C31]-worker]: 
commit: pendingCommit == null; skip
   [junit4]   2> 44835 T195 C66 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleStreamingBinaryTest-seed#[874DF3298D848C31]-worker]: 
commit: wrote segments file "null"
   [junit4]   2> 44835 T195 C66 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleStreamingBinaryTest-seed#[874DF3298D848C31]-worker]: 
commit: took 3.0 msec
   [junit4]   2> 44835 T195 C66 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleStreamingBinaryTest-seed#[874DF3298D848C31]-worker]: 
commit: done
   [junit4]   2> 44836 T195 C66 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleStreamingBinaryTest-seed#[874DF3298D848C31]-worker]: 
rollback
   [junit4]   2> 44836 T195 C66 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleStreamingBinaryTest-seed#[874DF3298D848C31]-worker]: all 
running merges have aborted
   [junit4]   2> 44836 T195 C66 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleStreamingBinaryTest-seed#[874DF3298D848C31]-worker]: 
rollback: done finish merges
   [junit4]   2> 44836 T195 C66 oasu.LoggingInfoStream.message 
[DW][SUITE-SolrExampleStreamingBinaryTest-seed#[874DF3298D848C31]-worker]: abort
   [junit4]   2> 44836 T195 C66 oasu.LoggingInfoStream.message 
[DW][SUITE-SolrExampleStreamingBinaryTest-seed#[874DF3298D848C31]-worker]: done 
abort success=true
   [junit4]   2> 44837 T195 C66 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleStreamingBinaryTest-seed#[874DF3298D848C31]-worker]: 
rollback: infos=_2j(6.0.0):C1
   [junit4]   2> 44837 T195 C66 oasu.LoggingInfoStream.message 
[IFD][SUITE-SolrExampleStreamingBinaryTest-seed#[874DF3298D848C31]-worker]: now 
checkpoint "_2j(6.0.0):C1" [1 segments ; isCommit = false]
   [junit4]   2> 44837 T195 C66 oasu.LoggingInfoStream.message 
[IFD][SUITE-SolrExampleStreamingBinaryTest-seed#[874DF3298D848C31]-worker]: 0 
msec to checkpoint
   [junit4]   2> 44838 T195 oasc.SolrCore.closeSearcher [collection1] Closing 
main searcher on request.
   [junit4]   2> 44852 T195 oasc.CachingDirectoryFactory.close Closing 
MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 44852 T195 oasc.CachingDirectoryFactory.closeCacheValue 
looking to close 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
 874DF3298D848C31-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.embedded.SolrExampleStreamingBinaryTest
 874DF3298D848C31-001/tempDir-001/collection1/data;done=false>>]
   [junit4]   2> 44853 T195 oasc.CachingDirectoryFactory.close Closing 
directory: 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
 874DF3298D848C31-001/tempDir-001/collection1/data
   [junit4]   2> 44853 T195 oasc.CachingDirectoryFactory.closeCacheValue 
looking to close 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
 874DF3298D848C31-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.embedded.SolrExampleStreamingBinaryTest
 874DF3298D848C31-001/tempDir-001/collection1/data/index;done=false>>]
   [junit4]   2> 44853 T195 oasc.CachingDirectoryFactory.close Closing 
directory: 
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
 874DF3298D848C31-001/tempDir-001/collection1/data/index
   [junit4]   2> 44854 T195 oejsh.ContextHandler.doStop Stopped 
o.e.j.s.ServletContextHandler@a90e27{/solr,null,UNAVAILABLE}
   [junit4]   2> 44854 T195 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.embedded.SolrExampleStreamingBinaryTest
 874DF3298D848C31-001
   [junit4]   2> NOTE: test params are: 
codec=FastDecompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST_DECOMPRESSION,
 chunkSize=2466, maxDocsPerChunk=827, blockSize=4), 
termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST_DECOMPRESSION,
 chunkSize=2466, blockSize=4)), sim=DefaultSimilarity, locale=ar_SA, 
timezone=NET
   [junit4]   2> NOTE: Linux 3.13.0-49-generic i386/Oracle Corporation 1.8.0_40 
(32-bit)/cpus=12,threads=1,free=13876224,total=64880640
   [junit4]   2> NOTE: All tests run in this JVM: [ModifiableSolrParamsTest, 
LargeVolumeJettyTest, JettyWebappTest, SolrParamTest, 
TestCloudSolrClientConnections, SolrExampleJettyTest, TestJsonRecordReader, 
ClientUtilsTest, SolrExampleBinaryTest, SolrExampleStreamingBinaryTest]
   [junit4] Completed [24/57] on J2 in 20.12s, 34 tests, 1 error <<< FAILURES!

[...truncated 114 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: 46 minutes 9 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 32bit/jdk1.8.0_40 -server 
-XX:+UseConcMarkSweepGC
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