You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2014/09/04 06:44:14 UTC
[JENKINS] Lucene-Solr-trunk-Linux (32bit/jdk1.8.0_20) - Build #
11173 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/11173/
Java: 32bit/jdk1.8.0_20 -client -XX:+UseConcMarkSweepGC
4 tests failed.
REGRESSION: org.apache.solr.client.solrj.SolrExampleBinaryTest.testChildDoctransformer
Error Message:
Expected mime type application/octet-stream but got text/html. <html> <head> <meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/> <title>Error 500 Server Error</title> </head> <body> <h2>HTTP ERROR: 500</h2> <p>Problem accessing /solr/collection1/select. Reason: <pre> Server Error</pre></p> <hr /><i><small>Powered by Jetty://</small></i> </body> </html>
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: Expected mime type application/octet-stream but got text/html. <html>
<head>
<meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/>
<title>Error 500 Server Error</title>
</head>
<body>
<h2>HTTP ERROR: 500</h2>
<p>Problem accessing /solr/collection1/select. Reason:
<pre> Server Error</pre></p>
<hr /><i><small>Powered by Jetty://</small></i>
</body>
</html>
at __randomizedtesting.SeedInfo.seed([E905AA174BC81859:9ADFB58DC7D06F5F]:0)
at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:512)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:210)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:206)
at org.apache.solr.client.solrj.request.QueryRequest.process(QueryRequest.java:91)
at org.apache.solr.client.solrj.SolrServer.query(SolrServer.java:301)
at org.apache.solr.client.solrj.SolrExampleTests.testChildDoctransformer(SolrExampleTests.java:1373)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:483)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java: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:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at 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:43)
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)
REGRESSION: org.apache.solr.client.solrj.SolrExampleXMLTest.testChildDoctransformer
Error Message:
java.lang.AssertionError
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: java.lang.AssertionError
at __randomizedtesting.SeedInfo.seed([E905AA174BC81859:9ADFB58DC7D06F5F]:0)
at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:550)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:210)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:206)
at org.apache.solr.client.solrj.request.QueryRequest.process(QueryRequest.java:91)
at org.apache.solr.client.solrj.SolrServer.query(SolrServer.java:301)
at org.apache.solr.client.solrj.SolrExampleTests.testChildDoctransformer(SolrExampleTests.java:1373)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:483)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java: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:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at 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:43)
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)
REGRESSION: org.apache.solr.client.solrj.embedded.SolrExampleEmbeddedTest.testChildDoctransformer
Error Message:
Stack Trace:
java.lang.AssertionError
at __randomizedtesting.SeedInfo.seed([E905AA174BC81859:9ADFB58DC7D06F5F]:0)
at org.apache.lucene.search.join.ToChildBlockJoinQuery$ToChildBlockJoinScorer.advance(ToChildBlockJoinQuery.java:286)
at org.apache.lucene.search.FilteredQuery$LeapFrogScorer.advanceToNextCommonDoc(FilteredQuery.java:274)
at org.apache.lucene.search.FilteredQuery$LeapFrogScorer.nextDoc(FilteredQuery.java:286)
at org.apache.lucene.search.Weight$DefaultBulkScorer.scoreAll(Weight.java:192)
at org.apache.lucene.search.Weight$DefaultBulkScorer.score(Weight.java:163)
at org.apache.lucene.search.BulkScorer.score(BulkScorer.java:35)
at org.apache.lucene.search.IndexSearcher.search(IndexSearcher.java:614)
at org.apache.lucene.search.IndexSearcher.search(IndexSearcher.java:289)
at org.apache.solr.search.SolrIndexSearcher.buildAndRunCollectorChain(SolrIndexSearcher.java:224)
at org.apache.solr.search.SolrIndexSearcher.getDocListNC(SolrIndexSearcher.java:1631)
at org.apache.solr.search.SolrIndexSearcher.getDocListC(SolrIndexSearcher.java:1454)
at org.apache.solr.search.SolrIndexSearcher.search(SolrIndexSearcher.java:535)
at org.apache.solr.search.SolrIndexSearcher.getDocList(SolrIndexSearcher.java:1289)
at org.apache.solr.response.transform.ChildDocTransformer.transform(ChildDocTransformerFactory.java:144)
at org.apache.solr.response.BinaryResponseWriter$Resolver.writeResultsBody(BinaryResponseWriter.java:159)
at org.apache.solr.response.BinaryResponseWriter$Resolver.writeResults(BinaryResponseWriter.java:183)
at org.apache.solr.response.BinaryResponseWriter$Resolver.resolve(BinaryResponseWriter.java:88)
at org.apache.solr.common.util.JavaBinCodec.writeVal(JavaBinCodec.java:158)
at org.apache.solr.common.util.JavaBinCodec.writeNamedList(JavaBinCodec.java:148)
at org.apache.solr.common.util.JavaBinCodec.writeKnownType(JavaBinCodec.java:242)
at org.apache.solr.common.util.JavaBinCodec.writeVal(JavaBinCodec.java:153)
at org.apache.solr.common.util.JavaBinCodec.marshal(JavaBinCodec.java:96)
at org.apache.solr.response.BinaryResponseWriter.getParsedResponse(BinaryResponseWriter.java:256)
at org.apache.solr.client.solrj.embedded.EmbeddedSolrServer.getParsedResponse(EmbeddedSolrServer.java:241)
at org.apache.solr.client.solrj.embedded.EmbeddedSolrServer.request(EmbeddedSolrServer.java:213)
at org.apache.solr.client.solrj.request.QueryRequest.process(QueryRequest.java:91)
at org.apache.solr.client.solrj.SolrServer.query(SolrServer.java:301)
at org.apache.solr.client.solrj.SolrExampleTests.testChildDoctransformer(SolrExampleTests.java:1373)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:483)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java: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:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at 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:43)
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)
REGRESSION: org.apache.solr.client.solrj.embedded.SolrExampleJettyTest.testChildDoctransformer
Error Message:
Expected mime type application/octet-stream but got text/html. <html> <head> <meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/> <title>Error 500 Server Error</title> </head> <body> <h2>HTTP ERROR: 500</h2> <p>Problem accessing /solr/collection1/select. Reason: <pre> Server Error</pre></p> <hr /><i><small>Powered by Jetty://</small></i> </body> </html>
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: Expected mime type application/octet-stream but got text/html. <html>
<head>
<meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/>
<title>Error 500 Server Error</title>
</head>
<body>
<h2>HTTP ERROR: 500</h2>
<p>Problem accessing /solr/collection1/select. Reason:
<pre> Server Error</pre></p>
<hr /><i><small>Powered by Jetty://</small></i>
</body>
</html>
at __randomizedtesting.SeedInfo.seed([E905AA174BC81859:9ADFB58DC7D06F5F]:0)
at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:512)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:210)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:206)
at org.apache.solr.client.solrj.request.QueryRequest.process(QueryRequest.java:91)
at org.apache.solr.client.solrj.SolrServer.query(SolrServer.java:301)
at org.apache.solr.client.solrj.SolrExampleTests.testChildDoctransformer(SolrExampleTests.java:1373)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:483)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java: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:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at 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:43)
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 12961 lines...]
[junit4] Suite: org.apache.solr.client.solrj.SolrExampleXMLTest
[junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.SolrExampleXMLTest-E905AA174BC81859-001/init-core-data-001
[junit4] 2> 78945 T265 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> 78945 T265 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> 78946 T265 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 78951 T265 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:60643
[junit4] 2> 78952 T265 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 78952 T265 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 78953 T265 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr
[junit4] 2> 78953 T265 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/'
[junit4] 2> 78984 T265 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/solr.xml
[junit4] 2> 79014 T265 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr
[junit4] 2> 79015 T265 oasc.CoreContainer.<init> New CoreContainer 31724897
[junit4] 2> 79015 T265 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/]
[junit4] 2> 79016 T265 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 79017 T265 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null
[junit4] 2> 79017 T265 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 79018 T265 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 79018 T265 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 79018 T265 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 79019 T265 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 79019 T265 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 79020 T265 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 79020 T265 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 79021 T265 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 79021 T265 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 79022 T265 oasc.CoreContainer.load Host Name:
[junit4] 2> 79024 T265 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr
[junit4] 2> 79028 T265 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/
[junit4] 2> 79029 T265 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 79030 T275 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/'
[junit4] 2> 79122 T275 oasc.SolrConfig.initLibs Adding specified lib dirs to ClassLoader
[junit4] 2> 79123 T275 oasc.SolrResourceLoader.addToClassLoader WARN No files added to classloader from lib: ../../../contrib/extraction/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/../../../contrib/extraction/lib).
[junit4] 2> 79123 T275 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/../../../dist).
[junit4] 2> 79124 T275 oasc.SolrResourceLoader.addToClassLoader WARN No files added to classloader from lib: ../../../contrib/clustering/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/../../../contrib/clustering/lib).
[junit4] 2> 79124 T275 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/../../../dist).
[junit4] 2> 79124 T275 oasc.SolrResourceLoader.addToClassLoader WARN No files added to classloader from lib: ../../../contrib/langid/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/../../../contrib/langid/lib).
[junit4] 2> 79124 T275 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/../../../dist).
[junit4] 2> 79125 T275 oasc.SolrResourceLoader.addToClassLoader WARN No files added to classloader from lib: ../../../contrib/velocity/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/../../../contrib/velocity/lib).
[junit4] 2> 79125 T275 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/../../../dist).
[junit4] 2> 79234 T275 oasu.SolrIndexConfig.<init> IndexWriter infoStream solr logging is enabled
[junit4] 2> 79238 T275 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.0.0
[junit4] 2> 79320 T275 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 79322 T275 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 79339 T275 oass.IndexSchema.readSchema [collection1] Schema name=example
[junit4] 2> 79807 T275 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 79810 T275 oasr.RestManager$Registry.registerManagedResource Registered ManagedResource impl org.apache.solr.rest.schema.analysis.ManagedWordSetResource for path /schema/analysis/stopwords/english
[junit4] 2> 79810 T275 oasr.RestManager$Registry.registerManagedResource Registered ManagedResource impl org.apache.solr.rest.schema.analysis.ManagedSynonymFilterFactory$SynonymManager for path /schema/analysis/synonyms/english
[junit4] 2> 79838 T275 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 79843 T275 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 79901 T275 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/
[junit4] 2> 79902 T275 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 79902 T275 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrExampleXMLTest-E905AA174BC81859-001/tempDir-001/
[junit4] 2> 79902 T275 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1435945
[junit4] 2> 79904 T275 oasc.SolrCore.initListeners [collection1] Added SolrEventListener for newSearcher: org.apache.solr.core.QuerySenderListener{queries=[]}
[junit4] 2> 79904 T275 oasc.SolrCore.initListeners [collection1] Added SolrEventListener for firstSearcher: org.apache.solr.core.QuerySenderListener{queries=[{q=static firstSearcher warming in solrconfig.xml}]}
[junit4] 2> 79905 T275 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrExampleXMLTest-E905AA174BC81859-001/tempDir-001
[junit4] 2> 79905 T275 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrExampleXMLTest-E905AA174BC81859-001/tempDir-001/index/
[junit4] 2> 79906 T275 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrExampleXMLTest-E905AA174BC81859-001/tempDir-001/index' doesn't exist. Creating new index...
[junit4] 2> 79926 T275 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrExampleXMLTest-E905AA174BC81859-001/tempDir-001/index
[junit4] 2> 79927 T275 oasu.LoggingInfoStream.message [IFD][coreLoadExecutor-97-thread-1]: init: current segments file is "null"; deletionPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper@1222829
[junit4] 2> 79928 T275 oasu.LoggingInfoStream.message [IFD][coreLoadExecutor-97-thread-1]: now checkpoint "" [0 segments ; isCommit = false]
[junit4] 2> 79928 T275 oasu.LoggingInfoStream.message [IFD][coreLoadExecutor-97-thread-1]: 0 msec to checkpoint
[junit4] 2> 79929 T275 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-97-thread-1]: init: create=true
[junit4] 2> 79929 T275 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-97-thread-1]:
[junit4] 2> dir=MockDirectoryWrapper(RateLimitedDirectoryWrapper(RAMDirectory@8212d0 lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrExampleXMLTest-E905AA174BC81859-001/tempDir-001/index))
[junit4] 2> index=
[junit4] 2> version=5.0.0
[junit4] 2> analyzer=null
[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=2, mergeThreadPriority=-1
[junit4] 2> default WRITE_LOCK_TIMEOUT=1000
[junit4] 2> writeLockTimeout=1000
[junit4] 2> codec=Lucene410
[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@18abecf
[junit4] 2> readerPooling=false
[junit4] 2> perThreadHardLimitMB=1945
[junit4] 2> useCompoundFile=false
[junit4] 2> checkIntegrityAtMerge=false
[junit4] 2> commitOnClose=true
[junit4] 2> writer=org.apache.lucene.util.SetOnce@1319786
[junit4] 2>
[junit4] 2> 79930 T275 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-97-thread-1]: now flush at close
[junit4] 2> 79930 T275 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-97-thread-1]: start flush: applyAllDeletes=true
[junit4] 2> 79930 T275 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-97-thread-1]: index before flush
[junit4] 2> 79931 T275 oasu.LoggingInfoStream.message [DW][coreLoadExecutor-97-thread-1]: startFullFlush
[junit4] 2> 79931 T275 oasu.LoggingInfoStream.message [DW][coreLoadExecutor-97-thread-1]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 79931 T275 oasu.LoggingInfoStream.message [DW][coreLoadExecutor-97-thread-1]: coreLoadExecutor-97-thread-1 finishFullFlush success=true
[junit4] 2> 79932 T275 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-97-thread-1]: apply all deletes during flush
[junit4] 2> 79932 T275 oasu.LoggingInfoStream.message [BD][coreLoadExecutor-97-thread-1]: prune sis=null: minGen=9223372036854775807 packetCount=0
[junit4] 2> 79932 T275 oasu.LoggingInfoStream.message [CMS][coreLoadExecutor-97-thread-1]: now merge
[junit4] 2> 79933 T275 oasu.LoggingInfoStream.message [CMS][coreLoadExecutor-97-thread-1]: index:
[junit4] 2> 79933 T275 oasu.LoggingInfoStream.message [CMS][coreLoadExecutor-97-thread-1]: no more merges pending; now return
[junit4] 2> 79933 T275 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-97-thread-1]: waitForMerges
[junit4] 2> 79934 T275 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-97-thread-1]: waitForMerges done
[junit4] 2> 79934 T275 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-97-thread-1]: commit: start
[junit4] 2> 79934 T275 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-97-thread-1]: commit: enter lock
[junit4] 2> 79935 T275 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-97-thread-1]: commit: now prepare
[junit4] 2> 79935 T275 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-97-thread-1]: prepareCommit: flush
[junit4] 2> 79935 T275 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-97-thread-1]: index before flush
[junit4] 2> 79936 T275 oasu.LoggingInfoStream.message [DW][coreLoadExecutor-97-thread-1]: startFullFlush
[junit4] 2> 79936 T275 oasu.LoggingInfoStream.message [DW][coreLoadExecutor-97-thread-1]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 79936 T275 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-97-thread-1]: apply all deletes during flush
[junit4] 2> 79937 T275 oasu.LoggingInfoStream.message [BD][coreLoadExecutor-97-thread-1]: prune sis=null: minGen=9223372036854775807 packetCount=0
[junit4] 2> 79937 T275 oasu.LoggingInfoStream.message [DW][coreLoadExecutor-97-thread-1]: coreLoadExecutor-97-thread-1 finishFullFlush success=true
[junit4] 2> 79937 T275 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-97-thread-1]: startCommit(): start
[junit4] 2> 79938 T275 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-97-thread-1]: startCommit index= changeCount=1
[junit4] 2> 79938 T275 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-97-thread-1]: done all syncs: []
[junit4] 2> 79939 T275 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-97-thread-1]: commit: pendingCommit != null
[junit4] 2> 79939 T275 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-97-thread-1]: commit: wrote segments file "segments_1"
[junit4] 2> 79940 T275 oasu.LoggingInfoStream.message [IFD][coreLoadExecutor-97-thread-1]: now checkpoint "" [0 segments ; isCommit = true]
[junit4] 2> 79940 T275 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RateLimitedDirectoryWrapper(RAMDirectory@8212d0 lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrExampleXMLTest-E905AA174BC81859-001/tempDir-001/index)),segFN=segments_1,generation=1}
[junit4] 2> 79940 T275 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 79941 T275 oasu.LoggingInfoStream.message [IFD][coreLoadExecutor-97-thread-1]: 1 msec to checkpoint
[junit4] 2> 79941 T275 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-97-thread-1]: commit: took 6.1 msec
[junit4] 2> 79942 T275 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-97-thread-1]: commit: done
[junit4] 2> 79942 T275 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-97-thread-1]: rollback
[junit4] 2> 79942 T275 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-97-thread-1]: all running merges have aborted
[junit4] 2> 79943 T275 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-97-thread-1]: rollback: done finish merges
[junit4] 2> 79943 T275 oasu.LoggingInfoStream.message [DW][coreLoadExecutor-97-thread-1]: abort
[junit4] 2> 79943 T275 oasu.LoggingInfoStream.message [DW][coreLoadExecutor-97-thread-1]: done abort; abortedFiles=[] success=true
[junit4] 2> 79944 T275 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-97-thread-1]: rollback: infos=
[junit4] 2> 79944 T275 oasu.LoggingInfoStream.message [IFD][coreLoadExecutor-97-thread-1]: now checkpoint "" [0 segments ; isCommit = false]
[junit4] 2> 79944 T275 oasu.LoggingInfoStream.message [IFD][coreLoadExecutor-97-thread-1]: 0 msec to checkpoint
[junit4] 2> 79945 T275 oasc.SolrCore.initWriters created json: solr.JSONResponseWriter
[junit4] 2> 79945 T275 oasc.SolrCore.initWriters adding lazy queryResponseWriter: solr.VelocityResponseWriter
[junit4] 2> 79945 T275 oasc.SolrCore.initWriters created velocity: solr.VelocityResponseWriter
[junit4] 2> 79946 T275 oasc.SolrCore.initWriters created xslt: solr.XSLTResponseWriter
[junit4] 2> 79946 T275 oasr.XSLTResponseWriter.init xsltCacheLifetimeSeconds=5
[junit4] 2> 79955 T275 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 79956 T275 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 79957 T275 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 79958 T275 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 79959 T275 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 79960 T275 oasc.RequestHandlers.initHandlersFromConfig created /select: solr.SearchHandler
[junit4] 2> 79961 T275 oasc.RequestHandlers.initHandlersFromConfig created /query: solr.SearchHandler
[junit4] 2> 79962 T275 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 79963 T275 oasc.RequestHandlers.initHandlersFromConfig created /export: solr.SearchHandler
[junit4] 2> 79964 T275 oasc.RequestHandlers.initHandlersFromConfig created /browse: solr.SearchHandler
[junit4] 2> 79964 T275 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.extraction.ExtractingRequestHandler
[junit4] 2> 79965 T275 oasc.RequestHandlers.initHandlersFromConfig created /update/extract: solr.extraction.ExtractingRequestHandler
[junit4] 2> 79965 T275 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.FieldAnalysisRequestHandler
[junit4] 2> 79965 T275 oasc.RequestHandlers.initHandlersFromConfig created /analysis/field: solr.FieldAnalysisRequestHandler
[junit4] 2> 79966 T275 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.DocumentAnalysisRequestHandler
[junit4] 2> 79966 T275 oasc.RequestHandlers.initHandlersFromConfig created /analysis/document: solr.DocumentAnalysisRequestHandler
[junit4] 2> 79967 T275 oasc.RequestHandlers.initHandlersFromConfig created /admin/: solr.admin.AdminHandlers
[junit4] 2> 79978 T275 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: solr.PingRequestHandler
[junit4] 2> 79979 T275 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 79980 T275 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 79981 T275 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
[junit4] 2> 79981 T275 oasc.RequestHandlers.initHandlersFromConfig created /spell: solr.SearchHandler
[junit4] 2> 79981 T275 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
[junit4] 2> 79982 T275 oasc.RequestHandlers.initHandlersFromConfig created /suggest: solr.SearchHandler
[junit4] 2> 79982 T275 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
[junit4] 2> 79982 T275 oasc.RequestHandlers.initHandlersFromConfig created /tvrh: solr.SearchHandler
[junit4] 2> 79983 T275 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
[junit4] 2> 79983 T275 oasc.RequestHandlers.initHandlersFromConfig created /terms: solr.SearchHandler
[junit4] 2> 79983 T275 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
[junit4] 2> 79984 T275 oasc.RequestHandlers.initHandlersFromConfig created /elevate: solr.SearchHandler
[junit4] 2> 79987 T275 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 79989 T275 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 79990 T275 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 79992 T275 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 79997 T275 oasu.CommitTracker.<init> Hard AutoCommit: if uncommited for 15000ms;
[junit4] 2> 79998 T275 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 79999 T275 oasu.LoggingInfoStream.message [IFD][coreLoadExecutor-97-thread-1]: init: current segments file is "segments_1"; deletionPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper@1222829
[junit4] 2> 80000 T275 oasu.LoggingInfoStream.message [IFD][coreLoadExecutor-97-thread-1]: init: load commit "segments_1"
[junit4] 2> 80000 T275 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RateLimitedDirectoryWrapper(RAMDirectory@8212d0 lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrExampleXMLTest-E905AA174BC81859-001/tempDir-001/index)),segFN=segments_1,generation=1}
[junit4] 2> 80001 T275 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 80001 T275 oasu.LoggingInfoStream.message [IFD][coreLoadExecutor-97-thread-1]: now checkpoint "" [0 segments ; isCommit = false]
[junit4] 2> 80001 T275 oasu.LoggingInfoStream.message [IFD][coreLoadExecutor-97-thread-1]: 0 msec to checkpoint
[junit4] 2> 80002 T275 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-97-thread-1]: init: create=false
[junit4] 2> 80002 T275 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-97-thread-1]:
[junit4] 2> dir=MockDirectoryWrapper(RateLimitedDirectoryWrapper(RAMDirectory@8212d0 lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrExampleXMLTest-E905AA174BC81859-001/tempDir-001/index))
[junit4] 2> index=
[junit4] 2> version=5.0.0
[junit4] 2> analyzer=null
[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=2, mergeThreadPriority=-1
[junit4] 2> default WRITE_LOCK_TIMEOUT=1000
[junit4] 2> writeLockTimeout=1000
[junit4] 2> codec=Lucene410
[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@1b0028
[junit4] 2> readerPooling=false
[junit4] 2> perThreadHardLimitMB=1945
[junit4] 2> useCompoundFile=false
[junit4] 2> checkIntegrityAtMerge=false
[junit4] 2> commitOnClose=true
[junit4] 2> writer=org.apache.lucene.util.SetOnce@98fb1a
[junit4] 2>
[junit4] 2> 80003 T275 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-97-thread-1]: flush at getReader
[junit4] 2> 80003 T275 oasu.LoggingInfoStream.message [DW][coreLoadExecutor-97-thread-1]: startFullFlush
[junit4] 2> 80003 T275 oasu.LoggingInfoStream.message [DW][coreLoadExecutor-97-thread-1]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 80004 T275 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-97-thread-1]: apply all deletes during flush
[junit4] 2> 80004 T275 oasu.LoggingInfoStream.message [BD][coreLoadExecutor-97-thread-1]: prune sis=segments_1: minGen=9223372036854775807 packetCount=0
[junit4] 2> 80004 T275 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-97-thread-1]: return reader version=1 reader=StandardDirectoryReader(segments_1:1:nrt)
[junit4] 2> 80005 T275 oasu.LoggingInfoStream.message [DW][coreLoadExecutor-97-thread-1]: coreLoadExecutor-97-thread-1 finishFullFlush success=true
[junit4] 2> 80005 T275 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-97-thread-1]: getReader took 2 msec
[junit4] 2> 80005 T275 oass.SolrIndexSearcher.<init> Opening Searcher@1041ebd[collection1] main
[junit4] 2> 80006 T275 oasr.ManagedResourceStorage.newStorageIO WARN Cannot write to config directory /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 80006 T275 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 80006 T275 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 80007 T275 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 80007 T275 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using InMemoryStorage
[junit4] 2> 80007 T275 oasr.RestManager.init Initializing 2 registered ManagedResources
[junit4] 2> 80008 T275 oasr.ManagedResourceStorage.load Reading _schema_analysis_stopwords_english.json using InMemoryStorage
[junit4] 2> 80008 T275 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /schema/analysis/stopwords/english
[junit4] 2> 80008 T275 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _schema_analysis_stopwords_english.json using InMemoryStorage
[junit4] 2> 80009 T275 oasrsa.ManagedWordSetResource.onManagedDataLoadedFromStorage Loaded 0 words for /schema/analysis/stopwords/english
[junit4] 2> 80009 T275 oasr.ManagedResource.notifyObserversDuringInit Notified 1 observers of /schema/analysis/stopwords/english
[junit4] 2> 80009 T275 oasr.ManagedResourceStorage.load Reading _schema_analysis_synonyms_english.json using InMemoryStorage
[junit4] 2> 80009 T275 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /schema/analysis/synonyms/english
[junit4] 2> 80010 T275 oasrsa.ManagedSynonymFilterFactory$SynonymManager.onManagedDataLoadedFromStorage Loaded 0 synonym mappings for /schema/analysis/synonyms/english
[junit4] 2> 80010 T275 oasr.ManagedResource.notifyObserversDuringInit Notified 1 observers of /schema/analysis/synonyms/english
[junit4] 2> 80010 T275 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 80011 T275 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> 80011 T275 oashc.SpellCheckComponent.inform No queryConverter defined, using default converter
[junit4] 2> 80012 T275 oashc.SuggestComponent.inform Initializing SuggestComponent
[junit4] 2> 80012 T275 oasss.SolrSuggester.init init: {name=mySuggester,lookupImpl=FuzzyLookupFactory,dictionaryImpl=DocumentDictionaryFactory,field=cat,weightField=price,suggestAnalyzerFieldType=string}
[junit4] 2> 80013 T275 oasss.SolrSuggester.init Dictionary loaded with params: {name=mySuggester,lookupImpl=FuzzyLookupFactory,dictionaryImpl=DocumentDictionaryFactory,field=cat,weightField=price,suggestAnalyzerFieldType=string}
[junit4] 2> 80013 T275 oashc.QueryElevationComponent.inform Loading QueryElevation from: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/conf/elevate.xml
[junit4] 2> 80036 T275 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 80036 T276 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1041ebd[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> ASYNC NEW_CORE C106 name=collection1 org.apache.solr.core.SolrCore@14d7025
[junit4] 2> 80037 T276 C106 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> 80038 T276 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 80038 T276 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default
[junit4] 2> 80038 T276 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak
[junit4] 2> 80038 T276 oashc.SuggestComponent$SuggesterListener.newSearcher Loading suggester index for: mySuggester
[junit4] 2> 80038 T276 oasss.SolrSuggester.reload reload()
[junit4] 2> 80039 T276 oasss.SolrSuggester.build build()
[junit4] 2> 80046 T276 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1041ebd[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 80054 T275 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 80055 T265 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0
[junit4] 2> 80055 T265 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 80056 T265 oas.SolrJettyTestBase.createJetty Jetty Assigned Port#60643
[junit4] 2> 80068 T265 oas.SolrTestCaseJ4.setUp ###Starting testMultiContentStreamRequest
[junit4] 2> ASYNC NEW_CORE C107 name=collection1 org.apache.solr.core.SolrCore@14d7025
[junit4] 2> 80076 T272 C107 oasu.LoggingInfoStream.message [DW][qtp9428032-272]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 80076 T272 C107 oasu.LoggingInfoStream.message [IW][qtp9428032-272]: nrtIsCurrent: infoVersion matches: true; DW changes: true; BD changes: false
[junit4] 2> 80077 T272 C107 oasu.LoggingInfoStream.message [DW][qtp9428032-272]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 80077 T272 C107 oasu.LoggingInfoStream.message [IW][qtp9428032-272]: flush at getReader
[junit4] 2> ASYNC NEW_CORE C108 name=collection1 org.apache.solr.core.SolrCore@14d7025
[junit4] 2> 80079 T272 C108 oasu.LoggingInfoStream.message [DW][qtp9428032-272]: startFullFlush
[junit4] 2> 80080 T272 C108 oasu.LoggingInfoStream.message [DW][qtp9428032-272]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 80080 T272 C108 oasu.LoggingInfoStream.message [DW][qtp9428032-272]: qtp9428032-272: flush naked frozen global deletes
[junit4] 2> 80081 T272 C108 oasu.LoggingInfoStream.message [BD][qtp9428032-272]: push deletes 1 deleted queries bytesUsed=64 delGen=2 packetCount=1 totBytesUsed=64
[junit4] 2> 80082 T272 C108 oasu.LoggingInfoStream.message [DW][qtp9428032-272]: flush: push buffered updates: 1 deleted queries bytesUsed=64
[junit4] 2> 80082 T272 C108 oasu.LoggingInfoStream.message [IW][qtp9428032-272]: apply all deletes during flush
[junit4] 2> 80082 T272 C108 oasu.LoggingInfoStream.message [BD][qtp9428032-272]: prune sis=segments_1: minGen=9223372036854775807 packetCount=1
[junit4] 2> 80082 T272 C108 oasu.LoggingInfoStream.message [BD][qtp9428032-272]: pruneDeletes: prune 1 packets; 0 packets remain
[junit4] 2> 80083 T272 C108 oasu.LoggingInfoStream.message [IW][qtp9428032-272]: return reader version=1 reader=StandardDirectoryReader(segments_1:1:nrt)
[junit4] 2> 80083 T272 C108 oasu.LoggingInfoStream.message [DW][qtp9428032-272]: qtp9428032-272 finishFullFlush success=true
[junit4] 2> 80083 T272 C108 oasu.LoggingInfoStream.message [IW][qtp9428032-272]: getReader took 6 msec
[junit4] 2> 80084 T272 C108 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1478288460782501888)} 0 10
[junit4] 2> ASYNC NEW_CORE C109 name=collection1 org.apache.solr.core.SolrCore@14d7025
[junit4] 2> 80193 T273 C109 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 80195 T273 C109 oasu.LoggingInfoStream.message [DW][qtp9428032-273]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 80195 T273 C109 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 80196 T273 C109 oasu.LoggingInfoStream.message [DW][qtp9428032-273]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 80196 T273 C109 oasu.LoggingInfoStream.message [IW][qtp9428032-273]: nrtIsCurrent: infoVersion matches: true; DW changes: false; BD changes: false
[junit4] 2> 80197 T273 C109 oasu.LoggingInfoStream.message [DW][qtp9428032-273]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 80197 T273 C109 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
[junit4] 2> 80198 T273 C109 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 80199 T273 C109 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=xml&version=2.2} {commit=} 0 6
[junit4] 2> 80203 T271 C109 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 80295 T272 C109 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 80296 T272 C109 oasu.LoggingInfoStream.message [IW][qtp9428032-272]: commit: start
[junit4] 2> 80297 T272 C109 oasu.LoggingInfoStream.message [IW][qtp9428032-272]: commit: enter lock
[junit4] 2> 80297 T272 C109 oasu.LoggingInfoStream.message [IW][qtp9428032-272]: commit: now prepare
[junit4] 2> 80298 T272 C109 oasu.LoggingInfoStream.message [IW][qtp9428032-272]: prepareCommit: flush
[junit4] 2> 80298 T272 C109 oasu.LoggingInfoStream.message [IW][qtp9428032-272]: index before flush
[junit4] 2> 80299 T272 C109 oasu.LoggingInfoStream.message [DW][qtp9428032-272]: startFullFlush
[junit4] 2> 80299 T272 C109 oasu.LoggingInfoStream.message [DW][qtp9428032-272]: anyChanges? numDocsInRam=5 deletes=true hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 80300 T272 C109 oasu.LoggingInfoStream.message [DWFC][qtp9428032-272]: addFlushableState DocumentsWriterPerThread [pendingDeletes=gen=0 4 deleted terms (unique count=4) bytesUsed=587, segment=_0, aborting=false, numDocsInRAM=5, deleteQueue=DWDQ: [ generation: 2 ]]
[junit4] 2> 80301 T272 C109 oasu.LoggingInfoStream.message [DWPT][qtp9428032-272]: flush postings as segment _0 numDocs=5
[junit4] 2> 80322 T272 C109 oasu.LoggingInfoStream.message [DWPT][qtp9428032-272]: new segment has 0 deleted docs
[junit4] 2> 80323 T272 C109 oasu.LoggingInfoStream.message [DWPT][qtp9428032-272]: new segment has no vectors; norms; no docValues; prox; freqs
[junit4] 2> 80323 T272 C109 oasu.LoggingInfoStream.message [DWPT][qtp9428032-272]: flushedFiles=[_0_Lucene41_0.tip, _0_Lucene41_0.doc, _0_Lucene41_0.pay, _0_Lucene41_0.tim, _0.nvd, _0.fdx, _0.fdt, _0_Lucene41_0.pos, _0.nvm, _0.fnm]
[junit4] 2> 80324 T272 C109 oasu.LoggingInfoStream.message [DWPT][qtp9428032-272]: flushed codec=Lucene410
[junit4] 2> 80324 T272 C109 oasu.LoggingInfoStream.message [DWPT][qtp9428032-272]: flushed: segment=_0 ramUsed=0.106 MB newFlushedSize(includes docstores)=0.008 MB docs/MB=641.096
[junit4] 2> 80325 T272 C109 oasu.LoggingInfoStream.message [DW][qtp9428032-272]: publishFlushedSegment seg-private updates=null
[junit4] 2> 80325 T272 C109 oasu.LoggingInfoStream.message [IW][qtp9428032-272]: publishFlushedSegment
[junit4] 2> 80326 T272 C109 oasu.LoggingInfoStream.message [BD][qtp9428032-272]: push deletes 5 deleted terms (unique count=5) bytesUsed=1056 delGen=4 packetCount=1 totBytesUsed=1056
[junit4] 2> 80326 T272 C109 oasu.LoggingInfoStream.message [IW][qtp9428032-272]: publish sets newSegment delGen=5 seg=_0(5.0.0):C5
[junit4] 2> 80327 T272 C109 oasu.LoggingInfoStream.message [IFD][qtp9428032-272]: now checkpoint "_0(5.0.0):C5" [1 segments ; isCommit = false]
[junit4] 2> 80327 T272 C109 oasu.LoggingInfoStream.message [IFD][qtp9428032-272]: 0 msec to checkpoint
[junit4] 2> 80328 T272 C109 oasu.LoggingInfoStream.message [IW][qtp9428032-272]: apply all deletes during flush
[junit4] 2> 80328 T272 C109 oasu.LoggingInfoStream.message [BD][qtp9428032-272]: applyDeletes: infos=[_0(5.0.0):C5] packetCount=1
[junit4] 2> 80329 T272 C109 oasu.LoggingInfoStream.message [BD][qtp9428032-272]: applyDeletes took 1 msec
[junit4] 2> 80329 T272 C109 oasu.LoggingInfoStream.message [BD][qtp9428032-272]: prune sis=segments_1: _0(5.0.0):C5 minGen=6 packetCount=1
[junit4] 2> 80330 T272 C109 oasu.LoggingInfoStream.message [BD][qtp9428032-272]: pruneDeletes: prune 1 packets; 0 packets remain
[junit4] 2> 80330 T272 C109 oasu.LoggingInfoStream.message [DW][qtp9428032-272]: qtp9428032-272 finishFullFlush success=true
[junit4] 2> 80331 T272 C109 oasu.LoggingInfoStream.message [TMP][qtp9428032-272]: findMerges: 1 segments
[junit4] 2> 80331 T272 C109 oasu.LoggingInfoStream.message [TMP][qtp9428032-272]: seg=_0(5.0.0):C5 size=0.008 MB [floored]
[junit4] 2> 80332 T272 C109 oasu.LoggingInfoStream.message [TMP][qtp9428032-272]: allowedSegmentCount=1 vs count=1 (eligible count=1) tooBigCount=0
[junit4] 2> 80332 T272 C109 oasu.LoggingInfoStream.message [CMS][qtp9428032-272]: now merge
[junit4] 2> 80333 T272 C109 oasu.LoggingInfoStream.message [CMS][qtp9428032-272]: index: _0(5.0.0):C5
[junit4] 2> 80333 T272 C109 oasu.LoggingInfoStream.message [CMS][qtp9428032-272]: no more merges pending; now return
[junit4] 2> 80333 T272 C109 oasu.LoggingInfoStream.message [IW][qtp9428032-272]: startCommit(): start
[junit4] 2> 80334 T272 C109 oasu.LoggingInfoStream.message [IW][qtp9428032-272]: startCommit index=_0(5.0.0):C5 changeCount=3
[junit4] 2> 80335 T272 C109 oasu.LoggingInfoStream.message [IW][qtp9428032-272]: done all syncs: [_0_Lucene41_0.tip, _0_Lucene41_0.doc, _0_Lucene41_0.pay, _0.si, _0_Lucene41_0.tim, _0.nvd, _0.fdx, _0.fdt, _0_Lucene41_0.pos, _0.nvm, _0.fnm]
[junit4] 2> 80335 T272 C109 oasu.LoggingInfoStream.message [IW][qtp9428032-272]: commit: pendingCommit != null
[junit4] 2> 80336 T272 C109 oasu.LoggingInfoStream.message [IW][qtp9428032-272]: commit: wrote segments file "segments_2"
[junit4] 2> 80336 T272 C109 oasu.LoggingInfoStream.message [IFD][qtp9428032-272]: now checkpoint "_0(5.0.0):C5" [1 segments ; isCommit = true]
[junit4] 2> 80337 T272 C109 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RateLimitedDirectoryWrapper(RAMDirectory@8212d0 lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrExampleXMLTest-E905AA174BC81859-001/tempDir-001/index)),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RateLimitedDirectoryWrapper(RAMDirectory@8212d0 lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrExampleXMLTest-E905AA174BC81859-001/tempDir-001/index)),segFN=segments_2,generation=2}
[junit4] 2> 80338 T272 C109 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> ASYNC NEW_CORE C110 name=collection1 org.apache.solr.core.SolrCore@14d7025
[junit4] 2> 80338 T272 C110 oasu.LoggingInfoStream.message [IFD][qtp9428032-272]: deleteCommits: now decRef commit "segments_1"
[junit4] 2> 80350 T272 C110 oasu.LoggingInfoStream.message [IFD][qtp9428032-272]: delete "segments_1"
[junit4] 2> 80350 T272 C110 oasu.LoggingInfoStream.message [IFD][qtp9428032-272]: 13 msec to checkpoint
[junit4] 2> 80351 T272 C110 oasu.LoggingInfoStream.message [IW][qtp9428032-272]: commit: took 52.8 msec
[junit4] 2> 80351 T272 C110 oasu.LoggingInfoStream.message [IW][qtp9428032-272]: commit: done
[junit4] 2> 80352 T272 C110 oasu.LoggingInfoStream.message [DW][qtp9428032-272]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 80352 T272 C110 oasu.LoggingInfoStream.message [IW][qtp9428032-272]: nrtIsCurrent: infoVersion matches: false; DW changes: false; BD changes: false
[junit4] 2> 80353 T272 C110 oasu.LoggingInfoStream.message [IW][qtp9428032-272]: flush at getReader
[junit4] 2> 80353 T272 C110 oasu.LoggingInfoStream.message [DW][qtp9428032-272]: startFullFlush
[junit4] 2> 80354 T272 C110 oasu.LoggingInfoStream.message [DW][qtp9428032-272]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 80354 T272 C110 oasu.LoggingInfoStream.message [IW][qtp9428032-272]: apply all deletes during flush
[junit4] 2> 80354 T272 C110 oasu.LoggingInfoStream.message [BD][qtp9428032-272]: applyDeletes: no deletes; skipping
[junit4] 2> 80355 T272 C110 oasu.LoggingInfoStream.message [BD][qtp9428032-272]: prune sis=segments_2: _0(5.0.0):C5 minGen=6 packetCount=0
[junit4] 2> 80359 T272 C110 oasu.LoggingInfoStream.message [IW][qtp9428032-272]: return reader version=3 reader=StandardDirectoryReader(segments_2:3:nrt _0(5.0.0):C5)
[junit4] 2> 80359 T272 C110 oasu.LoggingInfoStream.message [DW][qtp9428032-272]: qtp9428032-272 finishFullFlush success=true
[junit4] 2> 80360 T272 C110 oasu.LoggingInfoStream.message [IW][qtp9428032-272]: getReader took 7 msec
[junit4] 2> 80360 T272 C110 oass.SolrIndexSearcher.<init> Opening Searcher@60a59f[collection1] main
[junit4] 2> 80361 T272 C110 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 80361 T276 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@60a59f[collection1] main{UninvertingDirectoryReader(Uninverting(_0(5.0.0):C5))}
[junit4] 2> 80362 T276 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 80365 T276 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@60a59f[collection1] main{UninvertingDirectoryReader(Uninverting(_0(5.0.0):C5))}
[junit4] 2> 80366 T272 C110 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={a=?&echoParams=ALL&waitSearcher=true&commit=true&softCommit=false&wt=xml&version=2.2} {add=[SP2514N (1478288460955516928), 6H500F0 (1478288460988022784), TWINX2048-3200PRO (1478288460993265664), VS1GB400C3 (1478288461011091456), VDBDB1A16 (1478288461013188608)],commit=} 0 127
[junit4] 2> 80373 T273 C110 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=5 status=0 QTime=0
[junit4] 2> 80383 T265 oas.SolrTestCaseJ4.tearDown ###Ending testMultiContentStreamRequest
[junit4] 2> 80391 T265 oas.SolrTestCaseJ4.setUp ###Starting testUpdateRequestWithParameters
[junit4] 2> 80395 T271 C110 oasu.LoggingInfoStream.message [DW][qtp9428032-271]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 80395 T271 C110 oasu.LoggingInfoStream.message [IW][qtp9428032-271]: nrtIsCurrent: infoVersion matches: true; DW changes: true; BD changes: false
[junit4] 2> 80396 T271 C110 oasu.LoggingInfoStream.message [DW][qtp9428032-271]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 80396 T271 C110 oasu.LoggingInfoStream.message [IW][qtp9428032-271]: flush at getReader
[junit4] 2> 80396 T271 C110 oasu.LoggingInfoStream.message [DW][qtp9428032-271]: startFullFlush
[junit4] 2> 80396 T271 C110 oasu.LoggingInfoStream.message [DW][qtp9428032-271]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 80397 T271 C110 oasu.LoggingInfoStream.message [DW][qtp9428032-271]: qtp9428032-271: flush naked frozen global deletes
[junit4] 2> 80397 T271 C110 oasu.LoggingInfoStream.message [BD][qtp9428032-271]: push deletes 1 deleted queries bytesUsed=64 delGen=8 packetCount=1 totBytesUsed=64
[junit4] 2> 80397 T271 C110 oasu.LoggingInfoStream.message [DW][qtp9428032-271]: flush: push buffered updates: 1 deleted queries bytesUsed=64
[junit4] 2> 80398 T271 C110 oasu.LoggingInfoStream.message [IW][qtp9428032-271]: apply all deletes during flush
[junit4] 2> 80398 T271 C110 oasu.LoggingInfoStream.message [BD][qtp9428032-271]: applyDeletes: infos=[_0(5.0.0):C5] packetCount=1
[junit4] 2> 80400 T271 C110 oasu.LoggingInfoStream.message [BD][qtp9428032-271]: seg=_0(5.0.0):C5 segGen=6 coalesced deletes=[CoalescedUpdates(termSets=1,queries=1,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=5 100% deleted
[junit4] 2> 80400 T271 C110 oasu.LoggingInfoStream.message [BD][qtp9428032-271]: applyDeletes took 2 msec
[junit4] 2> 80400 T271 C110 oasu.LoggingInfoStream.message [IFD][qtp9428032-271]: now checkpoint "_0(5.0.0):C5/5" [1 segments ; isCommit = false]
[junit4] 2> 80401 T271 C110 oasu.LoggingInfoStream.message [IFD][qtp9428032-271]: 0 msec to checkpoint
[junit4] 2> 80401 T271 C110 oasu.LoggingInfoStream.message [IW][qtp9428032-271]: drop 100% deleted segments: _0(5.0.0):C5/5
[junit4] 2> 80401 T271 C110 oasu.LoggingInfoStream.message [IFD][qtp9428032-271]: now checkpoint "" [0 segments ; isCommit = false]
[junit4] 2> 80402 T271 C110 oasu.LoggingInfoStream.message [IFD][qtp9428032-271]: 0 msec to checkpoint
[junit4] 2> ASYNC NEW_CORE C111 name=collection1 org.apache.solr.core.SolrCore@14d7025
[junit4] 2> 80404 T271 C111 oasu.LoggingInfoStream.message [BD][qtp9428032-271]: prune sis=segments_2: minGen=9223372036854775807 packetCount=1
[junit4] 2> 80404 T271 C111 oasu.LoggingInfoStream.message [BD][qtp9428032-271]: pruneDeletes: prune 1 packets; 0 packets remain
[junit4] 2> 80404 T271 C111 oasu.LoggingInfoStream.message [IW][qtp9428032-271]: return reader version=5 reader=StandardDirectoryReader(segments_2:5:nrt)
[junit4] 2> 80405 T271 C111 oasu.LoggingInfoStream.message [DW][qtp9428032-271]: qtp9428032-271 finishFullFlush success=true
[junit4] 2> 80405 T271 C111 oasu.LoggingInfoStream.message [IW][qtp9428032-271]: getReader took 9 msec
[junit4] 2> 80405 T271 C111 oass.SolrIndexSearcher.<init> Opening Searcher@1c2978a[collection1] realtime
[junit4] 2> 80406 T271 C111 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1478288461116997632)} 0 13
[junit4] 2> 80411 T272 C111 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 80412 T272 C111 oasu.LoggingInfoStream.message [IW][qtp9428032-272]: commit: start
[junit4] 2> 80412 T272 C111 oasu.LoggingInfoStream.message [IW][qtp9428032-272]: commit: enter lock
[junit4] 2> 80413 T272 C111 oasu.LoggingInfoStream.message [IW][qtp9428032-272]: commit: now prepare
[junit4] 2> 80413 T272 C111 oasu.LoggingInfoStream.message [IW][qtp9428032-272]: prepareCommit: flush
[junit4] 2> 80413 T272 C111 oasu.LoggingInfoStream.message [IW][qtp9428032-272]: index before flush
[junit4] 2> 80414 T272 C111 oasu.LoggingInfoStream.message [DW][qtp9428032-272]: startFullFlush
[junit4] 2> 80414 T272 C111 oasu.LoggingInfoStream.message [DW][qtp9428032-272]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 80415 T272 C111 oasu.LoggingInfoStream.message [IW][qtp9428032-272]: apply all deletes during flush
[junit4] 2> 80415 T272 C111 oasu.LoggingInfoStream.message [BD][qtp9428032-272]: prune sis=segments_2: minGen=9223372036854775807 packetCount=0
[junit4] 2> 80416 T272 C111 oasu.LoggingInfoStream.message [DW][qtp9428032-272]: qtp9428032-272 finishFullFlush success=true
[junit4] 2> 80416 T272 C111 oasu.LoggingInfoStream.message [IW][qtp9428032-272]: startCommit(): start
[junit4] 2> 80417 T272 C111 oasu.LoggingInfoStream.message [IW][qtp9428032-272]: startCommit index= changeCount=6
[junit4] 2> 80417 T272 C111 oasu.LoggingInfoStream.message [IW][qtp9428032-272]: done all syncs: []
[junit4] 2> 80418 T272 C111 oasu.LoggingInfoStream.message [IW][qtp9428032-272]: commit: pendingCommit != null
[junit4] 2> 80418 T272 C111 oasu.LoggingInfoStream.message [IW][qtp9428032-272]: commit: wrote segments file "segments_3"
[junit4] 2> 80419 T272 C111 oasu.LoggingInfoStream.message [IFD][qtp9428032-272]: now checkpoint "" [0 segments ; isCommit = true]
[junit4] 2> 80419 T272 C111 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RateLimitedDirectoryWrapper(RAMDirectory@8212d0 lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrExampleXMLTest-E905AA174BC81859-001/tempDir-001/index)),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RateLimitedDirectoryWrapper(RAMDirectory@8212d0 lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrExampleXMLTest-E905AA174BC81859-001/tempDir-001/index)),segFN=segments_3,generation=3}
[junit4] 2> 80420 T272 C111 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 80420 T272 C111 oasu.LoggingInfoStream.message [IFD][qtp9428032-272]: deleteCommits: now decRef commit "segments_2"
[junit4] 2> 80421 T272 C111 oasu.LoggingInfoStream.message [IFD][qtp9428032-272]: delete "segments_2"
[junit4] 2> 80421 T272 C111 oasu.LoggingInfoStream.message [IFD][qtp9428032-272]: 2 msec to checkpoint
[junit4] 2> 80422 T272 C111 oasu.LoggingInfoStream.message [IW][qtp9428032-272]: commit: took 8.3 msec
[junit4] 2> 80422 T272 C111 oasu.LoggingInfoStream.message [IW][qtp9428032-272]: commit: done
[junit4] 2> 80422 T272 C111 oasu.LoggingInfoStream.message [DW][qtp9428032-272]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 80423 T272 C111 oasu.LoggingInfoStream.message [IW][qtp9428032-272]: nrtIsCurrent: infoVersion matches: true; DW changes: false; BD changes: false
[junit4] 2> 80423 T272 C111 oasu.LoggingInfoStream.message [DW][qtp9428032-272]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 80424 T272 C111 oass.SolrIndexSearcher.<init> Opening Searcher@1d00095[collection1] main
[junit4] 2> 80425 T276 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1d00095[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 80425 T276 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 80425 T272 C111 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 80429 T276 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1d00095[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 80430 T276 oasu.LoggingInfoStream.message [IFD][searcherExecutor-98-thread-1]: delete "_0_Lucene41_0.tip"
[junit4] 2> 80431 T276 oasu.LoggingInfoStream.message [IFD][searcherExecutor-98-thread-1]: delete "_0_Lucene41_0.doc"
[junit4] 2> 80431 T276 oasu.LoggingInfoStream.message [IFD][searcherExecutor-98-thread-1]: delete "_0_Lucene41_0.pay"
[junit4] 2> 80432 T276 oasu.LoggingInfoStream.message [IFD][searcherExecutor-98-thread-1]: delete "_0.si"
[junit4] 2> 80432 T276 oasu.LoggingInfoStream.message [IFD][searcherExecutor-98-thread-1]: delete "_0_Lucene41_0.tim"
[junit4] 2> 80432 T276 oasu.LoggingInfoStream.message [IFD][searcherExecutor-98-thread-1]: delete "_0.nvd"
[junit4] 2> 80432 T276 oasu.LoggingInfoStream.message [IFD][searcherExecutor-98-thread-1]: delete "_0.fdx"
[junit4] 2> 80432 T276 oasu.LoggingInfoStream.message [IFD][searcherExecutor-98-thread-1]: delete "_0.fdt"
[junit4] 2> 80433 T276 oasu.LoggingInfoStream.message [IFD][searcherExecutor-98-thread-1]: delete "_0_Lucene41_0.pos"
[junit4] 2> 80433 T276 oasu.LoggingInfoStream.message [IFD][searcherExecutor-98-thread-1]: delete "_0.nvm"
[junit4] 2> 80433 T276 oasu.LoggingInfoStream.message [IFD][searcherExecutor-98-thread-1]: delete "_0.fnm"
[junit4] 2> 80433 T272 C111 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=xml&version=2.2} {commit=} 0 22
[junit4] 2> 80439 T273 C111 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={overwrite=false&wt=xml&version=2.2} {add=[id1 (1478288461163134976)]} 0 2
[junit4] 2> 80444 T274 C111 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={overwrite=false&wt=xml&version=2.2} {add=[id1 (1478288461169426432)]} 0 1
[junit4] 2> 80448 T271 C111 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 80448 T271 C111 oasu.LoggingInfoStream.message [IW][qtp9428032-271]: commit: start
[junit4] 2> 80448 T271 C111 oasu.LoggingInfoStream.message [IW][qtp9428032-271]: commit: enter lock
[junit4] 2> 80448 T271 C111 oasu.LoggingInfoStream.message [IW][qtp9428032-271]: commit: now prepare
[junit4] 2> 80449 T271 C111 oasu.LoggingInfoStream.message [IW][qtp9428032-271]: prepareCommit: flush
[junit4] 2> 80449 T271 C111 oasu.LoggingInfoStream.message [IW][qtp9428032-271]: index before flush
[junit4] 2> 80449 T271 C111 oasu.LoggingInfoStream.message [DW][qtp9428032-271]: startFullFlush
[junit4] 2> 80450 T271 C111 oasu.LoggingInfoStream.message [DW][qtp9428032-271]: anyChanges? numDocsInRam=2 deletes=false hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 80450 T271 C111 oasu.LoggingInfoStream.message [DWFC][qtp9428032-271]: addFlushableState DocumentsWriterPerThread [pendingDeletes=gen=0, segment=_1, aborting=false, numDocsInRAM=2, deleteQueue=DWDQ: [ generation: 6 ]]
[junit4] 2> 80450 T271 C111 oasu.LoggingInfoStream.message [DWPT][qtp9428032-271]: flush postings as segment _1 numDocs=2
[junit4] 2> 80452 T271 C111 oasu.LoggingInfoStream.message [DWPT][qtp9428032-271]: new segment has 0 deleted docs
[junit4] 2> 80452 T271 C111 oasu.LoggingInfoStream.message [DWPT][qtp9428032-271]: new segment has no vectors; no norms; no docValues; no prox; no freqs
[junit4] 2> ASYNC NEW_CORE C112 name=collection1 org.apache.solr.core.SolrCore@14d7025
[junit4] 2> 80454 T271 C112 oasu.LoggingInfoStream.message [DWPT][qtp9428032-271]: flushedFiles=[_1_Lucene41_0.tip, _1.fdx, _1_Lucene41_0.doc, _1_Lucene41_0.tim, _1.fnm, _1.fdt]
[junit4] 2> 80454 T271 C112 oasu.LoggingInfoStream.message [DWPT][qtp9428032-271]: flushed codec=Lucene410
[junit4] 2> 80454 T271 C112 oasu.LoggingInfoStream.message [DWPT][qtp9428032-271]: flushed: segment=_1 ramUsed=0.063 MB newFlushedSize(includes docstores)=0.001 MB docs/MB=2,788.766
[junit4] 2> 80455 T271 C112 oasu.LoggingInfoStream.message [DW][qtp9428032-271]: publishFlushedSegment seg-private updates=null
[junit4] 2> 80455 T271 C112 oasu.LoggingInfoStream.message [IW][qtp9428032-271]: publishFlushedSegment
[junit4] 2> 80455 T271 C112 oasu.LoggingInfoStream.message [IW][qtp9428032-271]: publish sets newSegment delGen=11 seg=_1(5.0.0):C2
[junit4] 2> 80456 T271 C112 oasu.LoggingInfoStream.message [IFD][qtp9428032-271]: now checkpoint "_1(5.0.0):C2" [1 segments ; isCommit = false]
[junit4] 2> 80456 T271 C112 oasu.LoggingInfoStream.message [IFD][qtp9428032-271]: 0 msec to checkpoint
[junit4] 2> 80456 T271 C112 oasu.LoggingInfoStream.message [IW][qtp9428032-271]: apply all deletes during flush
[junit4] 2> 80457 T271 C112 oasu.LoggingInfoStream.message [BD][qtp9428032-271]: applyDeletes: no deletes; skipping
[junit4] 2> 80457 T271 C112 oasu.LoggingInfoStream.message [BD][qtp9428032-271]: prune sis=segments_3: _1(5.0.0):C2 minGen=11 packetCount=0
[junit4] 2> 80457 T271 C112 oasu.LoggingInfoStream.message [DW][qtp9428032-271]: qtp9428032-271 finishFullFlush success=true
[junit4] 2> 80457 T271 C112 oasu.LoggingInfoStream.message [TMP][qtp9428032-271]: findMerges: 1 segments
[junit4] 2> 80458 T271 C112 oasu.LoggingInfoStream.message [TMP][qtp9428032-271]: seg=_1(5.0.0):C2 size=0.001 MB [floored]
[junit4] 2> 80458 T271 C112 oasu.LoggingInfoStream.message [TMP][qtp9428032-271]: allowedSegmentCount=1 vs count=1 (eligible count=1) tooBigCount=0
[junit4] 2> 80459 T271 C112 oasu.LoggingInfoStream.message [CMS][qtp9428032-271]: now merge
[junit4] 2> 80459 T271 C112 oasu.LoggingInfoStream.message [CMS][qtp9428032-271]: index: _1(5.0.0):C2
[junit4] 2> 80459 T271 C112 oasu.LoggingInfoStream.message [CMS][qtp9428032-271]: no more merges pending; now return
[junit4] 2> 80460 T271 C112 oasu.LoggingInfoStream.message [IW][qtp9428032-271]: startCommit(): start
[junit4] 2> 80460 T271 C112 oasu.LoggingInfoStream.message [IW][qtp9428032-271]: startCommit index=_1(5.0.0):C2 changeCount=9
[junit4] 2> 80460 T271 C112 oasu.LoggingInfoStream.message [IW][qtp9428032-271]: done all syncs: [_1.si, _1_Lucene41_0.tip, _1.fdx, _1_Lucene41_0.doc, _1_Lucene41_0.tim, _1.fnm, _1.fdt]
[junit4] 2> 80461 T271 C112 oasu.LoggingInfoStream.message [IW][qtp9428032-271]: commit: pendingCommit != null
[junit4] 2> 80461 T271 C112 oasu.LoggingInfoStream.message [IW][qtp9428032-271]: commit: wrote segments file "segments_4"
[junit4] 2> 80461 T271 C112 oasu.LoggingInfoStream.message [IFD][qtp9428032-271]: now checkpoint "_1(5.0.0):C2" [1 segments ; isCommit = true]
[junit4] 2> 80462 T271 C112 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RateLimitedDirectoryWrapper(RAMDirectory@8212d0 lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrExampleXMLTest-E905AA174BC81859-001/tempDir-001/index)),segFN=segments_3,generation=3}
[junit4] 2> commit{dir=MockDirectoryWrapper(RateLimitedDirectoryWrapper(RAMDirectory@8212d0 lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrExampleXMLTest-E905AA174BC81859-001/tempDir-001/index)),segFN=segments_4,generation=4}
[junit4] 2> 80463 T271 C112 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
[junit4] 2> 80463 T271 C112 oasu.LoggingInfoStream.message [IFD][qtp9428032-271]: deleteCommits: now decRef commit "segments_3"
[junit4] 2> 80463 T271 C112 oasu.LoggingInfoStream.message [IFD][qtp9428032-271]: delete "segments_3"
[junit4] 2> 80463 T271 C112 oasu.LoggingInfoStream.message [IFD][qtp9428032-271]: 2 msec to checkpoint
[junit4] 2> 80464 T271 C112 oasu.LoggingInfoStream.message [IW][qtp9428032-271]: commit: took 14.9 msec
[junit4] 2> 80464 T271 C112 oasu.LoggingInfoStream.message [IW][qtp9428032-271]: commit: done
[junit4] 2> 80464 T271 C112 oasu.LoggingInfoStream.message [DW][qtp9428032-271]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 80465 T271 C112 oasu.LoggingInfoStream.message [IW][qtp9428032-271]: nrtIsCurrent: infoVersion matches: false; DW changes: false; BD changes: false
[junit4] 2> 80465 T271 C112 oasu.LoggingInfoStream.message [IW][qtp9428032-271]: flush at getReader
[junit4] 2> 80465 T271 C112 oasu.LoggingInfoStream.message [DW][qtp9428032-271]: startFullFlush
[junit4] 2> 80466 T271 C112 oasu.LoggingInfoStream.message [DW][qtp9428032-271]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 80466 T271 C112 oasu.LoggingInfoStream.message [IW][qtp9428032-271]: apply all deletes during flush
[junit4] 2> 80466 T271 C112 oasu.LoggingInfoStream.message [BD][qtp9428032-271]: applyDeletes: no deletes; skipping
[junit4] 2> 80466 T271 C112 oasu.LoggingInfoStream.message [BD][qtp9428032-271]: prune sis=segments_4: _1(5.0.0):C2 minGen=11 packetCount=0
[junit4] 2> 80467 T271 C112 oasu.LoggingInfoStream.message [IW][qtp9428032-271]: return reader version=7 reader=StandardDirectoryReader(segments_4:7:nrt _1(5.0.0):C2)
[junit4] 2> 80468 T271 C112 oasu.LoggingInfoStream.message [DW][qtp9428032-271]: qtp9428032-271 finishFullFlush success=true
[junit4] 2> 80468 T271 C112 oasu.LoggingInfoStream.message [IW][qtp9428032-271]: getReader took 3 msec
[junit4] 2> 80468 T271 C112 oass.SolrIndexSearcher.<init> Opening Searcher@be2f98[collection1] main
[junit4] 2> 80469 T271 C112 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 80469 T276 oasc.QuerySenderListener.newSearcher QuerySenderListener se
[...truncated too long message...]
SolrExampleEmbeddedTest-seed#[E905AA174BC81859]-worker]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 159459 T298 C1423 oasu.LoggingInfoStream.message [DW][SUITE-SolrExampleEmbeddedTest-seed#[E905AA174BC81859]-worker]: SUITE-SolrExampleEmbeddedTest-seed#[E905AA174BC81859]-worker finishFullFlush success=true
[junit4] 2> 159460 T298 C1423 oasu.LoggingInfoStream.message [IW][SUITE-SolrExampleEmbeddedTest-seed#[E905AA174BC81859]-worker]: apply all deletes during flush
[junit4] 2> 159460 T298 C1423 oasu.LoggingInfoStream.message [BD][SUITE-SolrExampleEmbeddedTest-seed#[E905AA174BC81859]-worker]: applyDeletes: no deletes; skipping
[junit4] 2> ASYNC NEW_CORE C1424 name=collection1 org.apache.solr.core.SolrCore@96f6
[junit4] 2> 159467 T298 C1424 oasu.LoggingInfoStream.message [BD][SUITE-SolrExampleEmbeddedTest-seed#[E905AA174BC81859]-worker]: prune sis=segments_1t: _19(5.0.0):C2 _1c(5.0.0):C1 minGen=287 packetCount=0
[junit4] 2> 159468 T298 C1424 oasu.LoggingInfoStream.message [CMS][SUITE-SolrExampleEmbeddedTest-seed#[E905AA174BC81859]-worker]: now merge
[junit4] 2> 159468 T298 C1424 oasu.LoggingInfoStream.message [CMS][SUITE-SolrExampleEmbeddedTest-seed#[E905AA174BC81859]-worker]: index: _19(5.0.0):C2 _1c(5.0.0):C1
[junit4] 2> 159469 T298 C1424 oasu.LoggingInfoStream.message [CMS][SUITE-SolrExampleEmbeddedTest-seed#[E905AA174BC81859]-worker]: no more merges pending; now return
[junit4] 2> 159469 T298 C1424 oasu.LoggingInfoStream.message [IW][SUITE-SolrExampleEmbeddedTest-seed#[E905AA174BC81859]-worker]: waitForMerges
[junit4] 2> 159469 T298 C1424 oasu.LoggingInfoStream.message [IW][SUITE-SolrExampleEmbeddedTest-seed#[E905AA174BC81859]-worker]: waitForMerges done
[junit4] 2> 159470 T298 C1424 oasu.LoggingInfoStream.message [IW][SUITE-SolrExampleEmbeddedTest-seed#[E905AA174BC81859]-worker]: commit: start
[junit4] 2> 159470 T298 C1424 oasu.LoggingInfoStream.message [IW][SUITE-SolrExampleEmbeddedTest-seed#[E905AA174BC81859]-worker]: commit: enter lock
[junit4] 2> 159470 T298 C1424 oasu.LoggingInfoStream.message [IW][SUITE-SolrExampleEmbeddedTest-seed#[E905AA174BC81859]-worker]: commit: now prepare
[junit4] 2> 159471 T298 C1424 oasu.LoggingInfoStream.message [IW][SUITE-SolrExampleEmbeddedTest-seed#[E905AA174BC81859]-worker]: prepareCommit: flush
[junit4] 2> 159471 T298 C1424 oasu.LoggingInfoStream.message [IW][SUITE-SolrExampleEmbeddedTest-seed#[E905AA174BC81859]-worker]: index before flush _19(5.0.0):C2 _1c(5.0.0):C1
[junit4] 2> 159471 T298 C1424 oasu.LoggingInfoStream.message [DW][SUITE-SolrExampleEmbeddedTest-seed#[E905AA174BC81859]-worker]: startFullFlush
[junit4] 2> 159472 T298 C1424 oasu.LoggingInfoStream.message [DW][SUITE-SolrExampleEmbeddedTest-seed#[E905AA174BC81859]-worker]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 159472 T298 C1424 oasu.LoggingInfoStream.message [IW][SUITE-SolrExampleEmbeddedTest-seed#[E905AA174BC81859]-worker]: apply all deletes during flush
[junit4] 2> 159472 T298 C1424 oasu.LoggingInfoStream.message [BD][SUITE-SolrExampleEmbeddedTest-seed#[E905AA174BC81859]-worker]: applyDeletes: no deletes; skipping
[junit4] 2> 159473 T298 C1424 oasu.LoggingInfoStream.message [BD][SUITE-SolrExampleEmbeddedTest-seed#[E905AA174BC81859]-worker]: prune sis=segments_1t: _19(5.0.0):C2 _1c(5.0.0):C1 minGen=287 packetCount=0
[junit4] 2> 159473 T298 C1424 oasu.LoggingInfoStream.message [DW][SUITE-SolrExampleEmbeddedTest-seed#[E905AA174BC81859]-worker]: SUITE-SolrExampleEmbeddedTest-seed#[E905AA174BC81859]-worker finishFullFlush success=true
[junit4] 2> 159473 T298 C1424 oasu.LoggingInfoStream.message [IW][SUITE-SolrExampleEmbeddedTest-seed#[E905AA174BC81859]-worker]: startCommit(): start
[junit4] 2> 159474 T298 C1424 oasu.LoggingInfoStream.message [IW][SUITE-SolrExampleEmbeddedTest-seed#[E905AA174BC81859]-worker]: skip startCommit(): no changes pending
[junit4] 2> 159474 T298 C1424 oasu.LoggingInfoStream.message [IW][SUITE-SolrExampleEmbeddedTest-seed#[E905AA174BC81859]-worker]: commit: pendingCommit == null; skip
[junit4] 2> 159475 T298 C1424 oasu.LoggingInfoStream.message [IW][SUITE-SolrExampleEmbeddedTest-seed#[E905AA174BC81859]-worker]: commit: done
[junit4] 2> 159475 T298 C1424 oasu.LoggingInfoStream.message [IW][SUITE-SolrExampleEmbeddedTest-seed#[E905AA174BC81859]-worker]: rollback
[junit4] 2> 159475 T298 C1424 oasu.LoggingInfoStream.message [IW][SUITE-SolrExampleEmbeddedTest-seed#[E905AA174BC81859]-worker]: all running merges have aborted
[junit4] 2> 159476 T298 C1424 oasu.LoggingInfoStream.message [IW][SUITE-SolrExampleEmbeddedTest-seed#[E905AA174BC81859]-worker]: rollback: done finish merges
[junit4] 2> 159476 T298 C1424 oasu.LoggingInfoStream.message [DW][SUITE-SolrExampleEmbeddedTest-seed#[E905AA174BC81859]-worker]: abort
[junit4] 2> 159476 T298 C1424 oasu.LoggingInfoStream.message [DW][SUITE-SolrExampleEmbeddedTest-seed#[E905AA174BC81859]-worker]: done abort; abortedFiles=[] success=true
[junit4] 2> 159477 T298 C1424 oasu.LoggingInfoStream.message [IW][SUITE-SolrExampleEmbeddedTest-seed#[E905AA174BC81859]-worker]: rollback: infos=_19(5.0.0):C2 _1c(5.0.0):C1
[junit4] 2> 159477 T298 C1424 oasu.LoggingInfoStream.message [IFD][SUITE-SolrExampleEmbeddedTest-seed#[E905AA174BC81859]-worker]: now checkpoint "_19(5.0.0):C2 _1c(5.0.0):C1" [2 segments ; isCommit = false]
[junit4] 2> 159477 T298 C1424 oasu.LoggingInfoStream.message [IFD][SUITE-SolrExampleEmbeddedTest-seed#[E905AA174BC81859]-worker]: 0 msec to checkpoint
[junit4] 2> 159479 T298 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 159479 T298 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 159479 T298 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/./temp/solr.client.solrj.embedded.SolrExampleEmbeddedTest-E905AA174BC81859-001/init-core-data-001/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/./temp/solr.client.solrj.embedded.SolrExampleEmbeddedTest-E905AA174BC81859-001/init-core-data-001/index;done=false>>]
[junit4] 2> 159480 T298 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/./temp/solr.client.solrj.embedded.SolrExampleEmbeddedTest-E905AA174BC81859-001/init-core-data-001/index
[junit4] 2> 159480 T298 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/./temp/solr.client.solrj.embedded.SolrExampleEmbeddedTest-E905AA174BC81859-001/init-core-data-001 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/./temp/solr.client.solrj.embedded.SolrExampleEmbeddedTest-E905AA174BC81859-001/init-core-data-001;done=false>>]
[junit4] 2> 159481 T298 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/./temp/solr.client.solrj.embedded.SolrExampleEmbeddedTest-E905AA174BC81859-001/init-core-data-001
[junit4] 2> 159481 T298 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 159481 T298 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=8206083
[junit4] 2> NOTE: leaving temporary files on disk at: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/./temp/solr.client.solrj.embedded.SolrExampleEmbeddedTest-E905AA174BC81859-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene410), sim=RandomSimilarityProvider(queryNorm=false,coord=yes): {}, locale=es_CL, timezone=America/Los_Angeles
[junit4] 2> NOTE: Linux 3.13.0-35-generic i386/Oracle Corporation 1.8.0_20 (32-bit)/cpus=8,threads=1,free=92585464,total=211435520
[junit4] 2> NOTE: All tests run in this JVM: [MergeIndexesEmbeddedTest, MultiCoreExampleJettyTest, FieldAnalysisResponseTest, TestSolrProperties, NamedListTest, TestJsonRecordReader, BasicHttpSolrServerTest, TestLBHttpSolrServer, IteratorChainTest, SolrExampleJettyTest, TestNamedListCodec, ConcurrentUpdateSolrServerTest, FacetFieldTest, SolrExampleEmbeddedTest]
[junit4] Completed on J1 in 7.64s, 27 tests, 1 failure <<< FAILURES!
[...truncated 76 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:491: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:471: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:60: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/extra-targets.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:202: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:496: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1347: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:959: There were test failures: 52 suites, 302 tests, 3 errors, 1 failure
Total time: 107 minutes 35 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 32bit/jdk1.8.0_20 -client -XX:+UseConcMarkSweepGC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any