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/07/05 05:57:55 UTC
[JENKINS] Lucene-Solr-trunk-MacOSX (64bit/jdk1.8.0) - Build # 1691
- Still Failing!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/1691/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseParallelGC
4 tests failed.
FAILED: org.apache.solr.client.solrj.embedded.SolrExampleEmbeddedTest.testChildDoctransformer
Error Message:
Stack Trace:
java.lang.AssertionError
at __randomizedtesting.SeedInfo.seed([EAE765ED855F3E3F:993D7A7709474939]: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:150)
at org.apache.solr.response.BinaryResponseWriter$Resolver.writeResults(BinaryResponseWriter.java:174)
at org.apache.solr.response.BinaryResponseWriter$Resolver.resolve(BinaryResponseWriter.java:87)
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:247)
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)
FAILED: 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([EAE765ED855F3E3F:993D7A7709474939]:0)
at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:516)
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)
FAILED: org.apache.solr.client.solrj.embedded.SolrExampleStreamingBinaryTest.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([EAE765ED855F3E3F:993D7A7709474939]:0)
at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:516)
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.impl.ConcurrentUpdateSolrServer.request(ConcurrentUpdateSolrServer.java:280)
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)
FAILED: org.apache.solr.client.solrj.embedded.SolrExampleStreamingTest.testChildDoctransformer
Error Message:
java.lang.AssertionError
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: java.lang.AssertionError
at __randomizedtesting.SeedInfo.seed([EAE765ED855F3E3F:993D7A7709474939]:0)
at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:554)
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.impl.ConcurrentUpdateSolrServer.request(ConcurrentUpdateSolrServer.java:280)
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 12291 lines...]
[junit4] Suite: org.apache.solr.client.solrj.embedded.SolrExampleJettyTest
[junit4] 2> SLF4J: Class path contains multiple SLF4J bindings.
[junit4] 2> SLF4J: Found binding in [jar:file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/test-lib/slf4j-log4j12-1.7.6.jar!/org/slf4j/impl/StaticLoggerBinder.class]
[junit4] 2> SLF4J: Found binding in [jar:file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/lib/slf4j-log4j12-1.7.6.jar!/org/slf4j/impl/StaticLoggerBinder.class]
[junit4] 2> SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation.
[junit4] 2> SLF4J: Actual binding is of type [org.slf4j.impl.Log4jLoggerFactory]
[junit4] 2> log4j:WARN No such property [conversionPattern] in org.apache.solr.util.SolrLogLayout.
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.embedded.SolrExampleJettyTest-EAE765ED855F3E3F-001/init-core-data-001
[junit4] 2> 3309 T11 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> 3330 T11 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> 4095 T11 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 4528 T11 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:53580
[junit4] 2> 4832 T11 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 4962 T11 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 4963 T11 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr
[junit4] 2> 4964 T11 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/'
[junit4] 2> 5635 T11 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/solr.xml
[junit4] 2> 5970 T11 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr
[junit4] 2> 6011 T11 oasc.CoreContainer.<init> New CoreContainer 792171065
[junit4] 2> 6019 T11 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/]
[junit4] 2> 6187 T11 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 6188 T11 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null
[junit4] 2> 6201 T11 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 6202 T11 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 6204 T11 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 6204 T11 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 6205 T11 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 6206 T11 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 6207 T11 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 6803 T11 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 6809 T11 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 6818 T11 oasc.CoreContainer.load Host Name:
[junit4] 2> 7209 T11 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr
[junit4] 2> 7351 T11 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/collection1/
[junit4] 2> 7352 T11 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 7361 T23 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/collection1/'
[junit4] 2> 7595 T23 oasc.SolrConfig.initLibs Adding specified lib dirs to ClassLoader
[junit4] 2> 7604 T23 oasc.SolrResourceLoader.addToClassLoader WARN No files added to classloader from lib: ../../../contrib/extraction/lib (resolved as: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/collection1/../../../contrib/extraction/lib).
[junit4] 2> 7606 T23 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/collection1/../../../dist).
[junit4] 2> 7607 T23 oasc.SolrResourceLoader.addToClassLoader WARN No files added to classloader from lib: ../../../contrib/clustering/lib/ (resolved as: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/collection1/../../../contrib/clustering/lib).
[junit4] 2> 7608 T23 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/collection1/../../../dist).
[junit4] 2> 7608 T23 oasc.SolrResourceLoader.addToClassLoader WARN No files added to classloader from lib: ../../../contrib/langid/lib/ (resolved as: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/collection1/../../../contrib/langid/lib).
[junit4] 2> 7609 T23 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/collection1/../../../dist).
[junit4] 2> 7610 T23 oasc.SolrResourceLoader.addToClassLoader WARN No files added to classloader from lib: ../../../contrib/velocity/lib (resolved as: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/collection1/../../../contrib/velocity/lib).
[junit4] 2> 7611 T23 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/collection1/../../../dist).
[junit4] 2> 7687 T23 oasu.SolrIndexConfig.<init> IndexWriter infoStream solr logging is enabled
[junit4] 2> 7759 T23 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 8621 T23 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 8709 T23 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 8816 T23 oass.IndexSchema.readSchema [collection1] Schema name=example
[junit4] 2> 12084 T23 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 12220 T23 oasr.RestManager$Registry.registerManagedResource Registered ManagedResource impl org.apache.solr.rest.schema.analysis.ManagedWordSetResource for path /schema/analysis/stopwords/english
[junit4] 2> 12228 T23 oasr.RestManager$Registry.registerManagedResource Registered ManagedResource impl org.apache.solr.rest.schema.analysis.ManagedSynonymFilterFactory$SynonymManager for path /schema/analysis/synonyms/english
[junit4] 2> 13117 T23 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 13131 T23 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 13450 T23 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/collection1/
[junit4] 2> 13619 T23 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 13687 T23 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-EAE765ED855F3E3F-001/tempDir-001/
[junit4] 2> 13700 T23 oasc.JmxMonitoredMap.<init> No JMX servers found, not exposing Solr information with JMX.
[junit4] 2> 13753 T23 oasc.SolrCore.initListeners [collection1] Added SolrEventListener for newSearcher: org.apache.solr.core.QuerySenderListener{queries=[]}
[junit4] 2> 13754 T23 oasc.SolrCore.initListeners [collection1] Added SolrEventListener for firstSearcher: org.apache.solr.core.QuerySenderListener{queries=[{q=static firstSearcher warming in solrconfig.xml}]}
[junit4] 2> 13917 T23 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-EAE765ED855F3E3F-001/tempDir-001
[junit4] 2> 13918 T23 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-EAE765ED855F3E3F-001/tempDir-001/index/
[junit4] 2> 13920 T23 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-EAE765ED855F3E3F-001/tempDir-001/index' doesn't exist. Creating new index...
[junit4] 2> 13949 T23 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-EAE765ED855F3E3F-001/tempDir-001/index
[junit4] 2> 14168 T23 oasu.LoggingInfoStream.message [IFD][coreLoadExecutor-5-thread-1]: init: current segments file is "null"; deletionPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper@1299e361
[junit4] 2> 14174 T23 oasu.LoggingInfoStream.message [IFD][coreLoadExecutor-5-thread-1]: now checkpoint "" [0 segments ; isCommit = false]
[junit4] 2> 14174 T23 oasu.LoggingInfoStream.message [IFD][coreLoadExecutor-5-thread-1]: 0 msec to checkpoint
[junit4] 2> 14175 T23 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-5-thread-1]: init: create=true
[junit4] 2> 14178 T23 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-5-thread-1]:
[junit4] 2> dir=MockDirectoryWrapper(RAMDirectory@71d7098 lockFactory=NativeFSLockFactory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-EAE765ED855F3E3F-001/tempDir-001/index)
[junit4] 2> index=
[junit4] 2> version=5.0-SNAPSHOT
[junit4] 2> matchVersion=LUCENE_5_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=Lucene49
[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@15dd43c8
[junit4] 2> readerPooling=false
[junit4] 2> perThreadHardLimitMB=1945
[junit4] 2> useCompoundFile=false
[junit4] 2> checkIntegrityAtMerge=false
[junit4] 2> writer=org.apache.lucene.util.SetOnce@287217d3
[junit4] 2>
[junit4] 2> 14181 T23 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-5-thread-1]: now flush at shutdown
[junit4] 2> 14181 T23 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-5-thread-1]: start flush: applyAllDeletes=true
[junit4] 2> 14183 T23 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-5-thread-1]: index before flush
[junit4] 2> 14184 T23 oasu.LoggingInfoStream.message [DW][coreLoadExecutor-5-thread-1]: startFullFlush
[junit4] 2> 14185 T23 oasu.LoggingInfoStream.message [DW][coreLoadExecutor-5-thread-1]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 14186 T23 oasu.LoggingInfoStream.message [DW][coreLoadExecutor-5-thread-1]: coreLoadExecutor-5-thread-1 finishFullFlush success=true
[junit4] 2> 14188 T23 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-5-thread-1]: apply all deletes during flush
[junit4] 2> 14193 T23 oasu.LoggingInfoStream.message [BD][coreLoadExecutor-5-thread-1]: prune sis=null: minGen=9223372036854775807 packetCount=0
[junit4] 2> 14193 T23 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-5-thread-1]: waitForMerges
[junit4] 2> 14194 T23 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-5-thread-1]: waitForMerges done
[junit4] 2> 14194 T23 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-5-thread-1]: commit: start
[junit4] 2> 14195 T23 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-5-thread-1]: commit: enter lock
[junit4] 2> 14195 T23 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-5-thread-1]: commit: now prepare
[junit4] 2> 14196 T23 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-5-thread-1]: prepareCommit: flush
[junit4] 2> 14196 T23 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-5-thread-1]: index before flush
[junit4] 2> 14196 T23 oasu.LoggingInfoStream.message [DW][coreLoadExecutor-5-thread-1]: startFullFlush
[junit4] 2> 14196 T23 oasu.LoggingInfoStream.message [DW][coreLoadExecutor-5-thread-1]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 14197 T23 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-5-thread-1]: apply all deletes during flush
[junit4] 2> 14197 T23 oasu.LoggingInfoStream.message [BD][coreLoadExecutor-5-thread-1]: prune sis=null: minGen=9223372036854775807 packetCount=0
[junit4] 2> 14197 T23 oasu.LoggingInfoStream.message [DW][coreLoadExecutor-5-thread-1]: coreLoadExecutor-5-thread-1 finishFullFlush success=true
[junit4] 2> 14198 T23 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-5-thread-1]: startCommit(): start
[junit4] 2> 14198 T23 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-5-thread-1]: startCommit index= changeCount=1
[junit4] 2> 14225 T23 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-5-thread-1]: done all syncs: []
[junit4] 2> 14225 T23 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-5-thread-1]: commit: pendingCommit != null
[junit4] 2> 14226 T23 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-5-thread-1]: commit: wrote segments file "segments_1"
[junit4] 2> 14226 T23 oasu.LoggingInfoStream.message [IFD][coreLoadExecutor-5-thread-1]: now checkpoint "" [0 segments ; isCommit = true]
[junit4] 2> 14255 T23 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@71d7098 lockFactory=NativeFSLockFactory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-EAE765ED855F3E3F-001/tempDir-001/index),segFN=segments_1,generation=1}
[junit4] 2> 14262 T23 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 14263 T23 oasu.LoggingInfoStream.message [IFD][coreLoadExecutor-5-thread-1]: 36 msec to checkpoint
[junit4] 2> 14267 T23 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-5-thread-1]: commit: took 68.2 msec
[junit4] 2> 14268 T23 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-5-thread-1]: commit: done
[junit4] 2> 14268 T23 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-5-thread-1]: rollback
[junit4] 2> 14269 T23 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-5-thread-1]: all running merges have aborted
[junit4] 2> 14269 T23 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-5-thread-1]: rollback: done finish merges
[junit4] 2> 14270 T23 oasu.LoggingInfoStream.message [DW][coreLoadExecutor-5-thread-1]: abort
[junit4] 2> 14280 T23 oasu.LoggingInfoStream.message [DW][coreLoadExecutor-5-thread-1]: done abort; abortedFiles=[] success=true
[junit4] 2> 14281 T23 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-5-thread-1]: rollback: infos=
[junit4] 2> 14281 T23 oasu.LoggingInfoStream.message [IFD][coreLoadExecutor-5-thread-1]: now checkpoint "" [0 segments ; isCommit = false]
[junit4] 2> 14282 T23 oasu.LoggingInfoStream.message [IFD][coreLoadExecutor-5-thread-1]: 0 msec to checkpoint
[junit4] 2> 14331 T23 oasc.SolrCore.initWriters created json: solr.JSONResponseWriter
[junit4] 2> 14332 T23 oasc.SolrCore.initWriters adding lazy queryResponseWriter: solr.VelocityResponseWriter
[junit4] 2> 14338 T23 oasc.SolrCore.initWriters created velocity: solr.VelocityResponseWriter
[junit4] 2> 14383 T23 oasc.SolrCore.initWriters created xslt: solr.XSLTResponseWriter
[junit4] 2> 14383 T23 oasr.XSLTResponseWriter.init xsltCacheLifetimeSeconds=5
[junit4] 2> 16057 T23 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 16183 T23 oasc.RequestHandlers.initHandlersFromConfig created /select: solr.SearchHandler
[junit4] 2> 16184 T23 oasc.RequestHandlers.initHandlersFromConfig created /query: solr.SearchHandler
[junit4] 2> 16205 T23 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 16206 T23 oasc.RequestHandlers.initHandlersFromConfig created /browse: solr.SearchHandler
[junit4] 2> 16246 T23 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 16246 T23 oasc.RequestHandlers.initHandlersFromConfig created /update/json: solr.UpdateRequestHandler
[junit4] 2> 16247 T23 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: solr.UpdateRequestHandler
[junit4] 2> 16247 T23 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.extraction.ExtractingRequestHandler
[junit4] 2> 16254 T23 oasc.RequestHandlers.initHandlersFromConfig created /update/extract: solr.extraction.ExtractingRequestHandler
[junit4] 2> 16255 T23 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.FieldAnalysisRequestHandler
[junit4] 2> 16255 T23 oasc.RequestHandlers.initHandlersFromConfig created /analysis/field: solr.FieldAnalysisRequestHandler
[junit4] 2> 16255 T23 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.DocumentAnalysisRequestHandler
[junit4] 2> 16256 T23 oasc.RequestHandlers.initHandlersFromConfig created /analysis/document: solr.DocumentAnalysisRequestHandler
[junit4] 2> 16286 T23 oasc.RequestHandlers.initHandlersFromConfig created /admin/: solr.admin.AdminHandlers
[junit4] 2> 16313 T23 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: solr.PingRequestHandler
[junit4] 2> 16350 T23 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 16421 T23 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 16422 T23 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
[junit4] 2> 16422 T23 oasc.RequestHandlers.initHandlersFromConfig created /spell: solr.SearchHandler
[junit4] 2> 16422 T23 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
[junit4] 2> 16423 T23 oasc.RequestHandlers.initHandlersFromConfig created /suggest: solr.SearchHandler
[junit4] 2> 16423 T23 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
[junit4] 2> 16423 T23 oasc.RequestHandlers.initHandlersFromConfig created /tvrh: solr.SearchHandler
[junit4] 2> 16423 T23 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
[junit4] 2> 16424 T23 oasc.RequestHandlers.initHandlersFromConfig created /terms: solr.SearchHandler
[junit4] 2> 16424 T23 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
[junit4] 2> 16425 T23 oasc.RequestHandlers.initHandlersFromConfig created /elevate: solr.SearchHandler
[junit4] 2> 16594 T23 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 16632 T23 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 16646 T23 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 16829 T23 oasu.CommitTracker.<init> Hard AutoCommit: if uncommited for 15000ms;
[junit4] 2> 16831 T23 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 16846 T23 oasu.LoggingInfoStream.message [IFD][coreLoadExecutor-5-thread-1]: init: current segments file is "segments_1"; deletionPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper@1299e361
[junit4] 2> 16849 T23 oasu.LoggingInfoStream.message [IFD][coreLoadExecutor-5-thread-1]: init: load commit "segments_1"
[junit4] 2> 16849 T23 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@71d7098 lockFactory=NativeFSLockFactory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-EAE765ED855F3E3F-001/tempDir-001/index),segFN=segments_1,generation=1}
[junit4] 2> 16850 T23 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 16851 T23 oasu.LoggingInfoStream.message [IFD][coreLoadExecutor-5-thread-1]: now checkpoint "" [0 segments ; isCommit = false]
[junit4] 2> 16851 T23 oasu.LoggingInfoStream.message [IFD][coreLoadExecutor-5-thread-1]: 0 msec to checkpoint
[junit4] 2> 16851 T23 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-5-thread-1]: init: create=false
[junit4] 2> 16852 T23 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-5-thread-1]:
[junit4] 2> dir=MockDirectoryWrapper(RAMDirectory@71d7098 lockFactory=NativeFSLockFactory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-EAE765ED855F3E3F-001/tempDir-001/index)
[junit4] 2> index=
[junit4] 2> version=5.0-SNAPSHOT
[junit4] 2> matchVersion=LUCENE_5_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=Lucene49
[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@26da252e
[junit4] 2> readerPooling=false
[junit4] 2> perThreadHardLimitMB=1945
[junit4] 2> useCompoundFile=false
[junit4] 2> checkIntegrityAtMerge=false
[junit4] 2> writer=org.apache.lucene.util.SetOnce@217173ec
[junit4] 2>
[junit4] 2> 16876 T23 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-5-thread-1]: flush at getReader
[junit4] 2> 16876 T23 oasu.LoggingInfoStream.message [DW][coreLoadExecutor-5-thread-1]: startFullFlush
[junit4] 2> 16877 T23 oasu.LoggingInfoStream.message [DW][coreLoadExecutor-5-thread-1]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 16877 T23 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-5-thread-1]: apply all deletes during flush
[junit4] 2> 16877 T23 oasu.LoggingInfoStream.message [BD][coreLoadExecutor-5-thread-1]: prune sis=segments_1: minGen=9223372036854775807 packetCount=0
[junit4] 2> 16893 T23 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-5-thread-1]: return reader version=1 reader=StandardDirectoryReader(segments_1:1:nrt)
[junit4] 2> 16894 T23 oasu.LoggingInfoStream.message [DW][coreLoadExecutor-5-thread-1]: coreLoadExecutor-5-thread-1 finishFullFlush success=true
[junit4] 2> 16894 T23 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-5-thread-1]: getReader took 18 msec
[junit4] 2> 16998 T23 oass.SolrIndexSearcher.<init> Opening Searcher@10036d23[collection1] main
[junit4] 2> 17099 T23 oasr.ManagedResourceStorage.newStorageIO WARN Cannot write to config directory /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 17110 T23 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 17121 T23 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 17123 T23 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 17135 T23 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using InMemoryStorage
[junit4] 2> 17137 T23 oasr.RestManager.init Initializing 2 registered ManagedResources
[junit4] 2> 17138 T23 oasr.ManagedResourceStorage.load Reading _schema_analysis_stopwords_english.json using InMemoryStorage
[junit4] 2> 17139 T23 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /schema/analysis/stopwords/english
[junit4] 2> 17168 T23 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _schema_analysis_stopwords_english.json using InMemoryStorage
[junit4] 2> 17172 T23 oasrsa.ManagedWordSetResource.onManagedDataLoadedFromStorage Loaded 0 words for /schema/analysis/stopwords/english
[junit4] 2> 17173 T23 oasr.ManagedResource.notifyObserversDuringInit Notified 1 observers of /schema/analysis/stopwords/english
[junit4] 2> 17175 T23 oasr.ManagedResourceStorage.load Reading _schema_analysis_synonyms_english.json using InMemoryStorage
[junit4] 2> 17176 T23 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /schema/analysis/synonyms/english
[junit4] 2> 17176 T23 oasrsa.ManagedSynonymFilterFactory$SynonymManager.onManagedDataLoadedFromStorage Loaded 0 synonym mappings for /schema/analysis/synonyms/english
[junit4] 2> 17185 T23 oasr.ManagedResource.notifyObserversDuringInit Notified 1 observers of /schema/analysis/synonyms/english
[junit4] 2> 17186 T23 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 17308 T23 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> 17415 T23 oashc.SpellCheckComponent.inform No queryConverter defined, using default converter
[junit4] 2> 17424 T23 oashc.SuggestComponent.inform Initializing SuggestComponent
[junit4] 2> 17441 T23 oasss.SolrSuggester.init init: {name=mySuggester,lookupImpl=FuzzyLookupFactory,dictionaryImpl=DocumentDictionaryFactory,field=cat,weightField=price,suggestAnalyzerFieldType=string}
[junit4] 2> 17745 T23 oasss.SolrSuggester.init Dictionary loaded with params: {name=mySuggester,lookupImpl=FuzzyLookupFactory,dictionaryImpl=DocumentDictionaryFactory,field=cat,weightField=price,suggestAnalyzerFieldType=string}
[junit4] 2> 17763 T23 oashc.QueryElevationComponent.inform Loading QueryElevation from: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/collection1/conf/elevate.xml
[junit4] 2> 18066 T23 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 18073 T23 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 18073 T24 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@10036d23[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 18086 T11 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0
[junit4] 2> 18087 T11 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 18088 T11 oas.SolrJettyTestBase.createJetty Jetty Assigned Port#53580
[junit4] 2> 18155 T11 oas.SolrTestCaseJ4.setUp ###Starting testUpdateField
[junit4] 2> ASYNC NEW_CORE C0 name=collection1 org.apache.solr.core.SolrCore@344791dc
[junit4] 2> 18614 T24 C0 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=526
[junit4] 2> 18624 T24 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 18625 T24 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default
[junit4] 2> 18626 T24 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak
[junit4] 2> 18626 T24 oashc.SuggestComponent$SuggesterListener.newSearcher Loading suggester index for: mySuggester
[junit4] 2> 18627 T24 oasss.SolrSuggester.reload reload()
[junit4] 2> 18628 T24 oasss.SolrSuggester.build build()
[junit4] 2> 18780 T24 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@10036d23[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> ASYNC NEW_CORE C1 name=collection1 org.apache.solr.core.SolrCore@344791dc
[junit4] 2> 20227 T21 C1 oasu.LoggingInfoStream.message [DW][qtp1769747735-21]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 20230 T21 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-21]: nrtIsCurrent: infoVersion matches: true; DW changes: true; BD changes: false
[junit4] 2> 20231 T21 C1 oasu.LoggingInfoStream.message [DW][qtp1769747735-21]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 20232 T21 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-21]: flush at getReader
[junit4] 2> 20233 T21 C1 oasu.LoggingInfoStream.message [DW][qtp1769747735-21]: startFullFlush
[junit4] 2> 20234 T21 C1 oasu.LoggingInfoStream.message [DW][qtp1769747735-21]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 20235 T21 C1 oasu.LoggingInfoStream.message [DW][qtp1769747735-21]: qtp1769747735-21: flush naked frozen global deletes
[junit4] 2> 20289 T21 C1 oasu.LoggingInfoStream.message [BD][qtp1769747735-21]: push deletes 1 deleted queries bytesUsed=64 delGen=2 packetCount=1 totBytesUsed=64
[junit4] 2> 20291 T21 C1 oasu.LoggingInfoStream.message [DW][qtp1769747735-21]: flush: push buffered updates: 1 deleted queries bytesUsed=64
[junit4] 2> 20291 T21 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-21]: apply all deletes during flush
[junit4] 2> 20292 T21 C1 oasu.LoggingInfoStream.message [BD][qtp1769747735-21]: prune sis=segments_1: minGen=9223372036854775807 packetCount=1
[junit4] 2> 20293 T21 C1 oasu.LoggingInfoStream.message [BD][qtp1769747735-21]: pruneDeletes: prune 1 packets; 0 packets remain
[junit4] 2> 20294 T21 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-21]: return reader version=1 reader=StandardDirectoryReader(segments_1:1:nrt)
[junit4] 2> 20294 T21 C1 oasu.LoggingInfoStream.message [DW][qtp1769747735-21]: qtp1769747735-21 finishFullFlush success=true
[junit4] 2> 20295 T21 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-21]: getReader took 63 msec
[junit4] 2> 20310 T21 C1 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1472758989717504000)} 0 626
[junit4] 2> 20490 T17 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 20492 T17 C1 oasu.LoggingInfoStream.message [DW][qtp1769747735-17]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 20493 T17 C1 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 20494 T17 C1 oasu.LoggingInfoStream.message [DW][qtp1769747735-17]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 20494 T17 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-17]: nrtIsCurrent: infoVersion matches: true; DW changes: false; BD changes: false
[junit4] 2> 20495 T17 C1 oasu.LoggingInfoStream.message [DW][qtp1769747735-17]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 20495 T17 C1 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
[junit4] 2> 20498 T17 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 20498 T17 C1 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 8
[junit4] 2> 20905 T15 C1 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[unique (1472758990169440256)]} 0 393
[junit4] 2> 20911 T19 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 20913 T19 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-19]: commit: start
[junit4] 2> 20914 T19 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-19]: commit: enter lock
[junit4] 2> 20914 T19 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-19]: commit: now prepare
[junit4] 2> 20915 T19 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-19]: prepareCommit: flush
[junit4] 2> 20916 T19 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-19]: index before flush
[junit4] 2> 20916 T19 C1 oasu.LoggingInfoStream.message [DW][qtp1769747735-19]: startFullFlush
[junit4] 2> 20917 T19 C1 oasu.LoggingInfoStream.message [DW][qtp1769747735-19]: anyChanges? numDocsInRam=1 deletes=true hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 20918 T19 C1 oasu.LoggingInfoStream.message [DWFC][qtp1769747735-19]: addFlushableState DocumentsWriterPerThread [pendingDeletes=gen=0, segment=_0, aborting=false, numDocsInRAM=1, deleteQueue=DWDQ: [ generation: 2 ]]
[junit4] 2> 20930 T19 C1 oasu.LoggingInfoStream.message [DWPT][qtp1769747735-19]: flush postings as segment _0 numDocs=1
[junit4] 2> 21144 T19 C1 oasu.LoggingInfoStream.message [DWPT][qtp1769747735-19]: new segment has 0 deleted docs
[junit4] 2> 21144 T19 C1 oasu.LoggingInfoStream.message [DWPT][qtp1769747735-19]: new segment has no vectors; norms; no docValues; prox; freqs
[junit4] 2> 21146 T19 C1 oasu.LoggingInfoStream.message [DWPT][qtp1769747735-19]: flushedFiles=[_0_Lucene41_0.tip, _0_Lucene41_0.doc, _0_Lucene41_0.tim, _0.nvd, _0.fdx, _0.fdt, _0_Lucene41_0.pos, _0.nvm, _0.fnm]
[junit4] 2> 21150 T19 C1 oasu.LoggingInfoStream.message [DWPT][qtp1769747735-19]: flushed codec=Lucene49
[junit4] 2> 21159 T19 C1 oasu.LoggingInfoStream.message [DWPT][qtp1769747735-19]: flushed: segment=_0 ramUsed=0.079 MB newFlushedSize(includes docstores)=0.001 MB docs/MB=757.642
[junit4] 2> 21175 T19 C1 oasu.LoggingInfoStream.message [DW][qtp1769747735-19]: publishFlushedSegment seg-private updates=null
[junit4] 2> 21177 T19 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-19]: publishFlushedSegment
[junit4] 2> 21177 T19 C1 oasu.LoggingInfoStream.message [BD][qtp1769747735-19]: push deletes 1 deleted terms (unique count=1) bytesUsed=1056 delGen=4 packetCount=1 totBytesUsed=1056
[junit4] 2> 21177 T19 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-19]: publish sets newSegment delGen=5 seg=_0(5.0):C1
[junit4] 2> 21178 T19 C1 oasu.LoggingInfoStream.message [IFD][qtp1769747735-19]: now checkpoint "_0(5.0):C1" [1 segments ; isCommit = false]
[junit4] 2> 21179 T19 C1 oasu.LoggingInfoStream.message [IFD][qtp1769747735-19]: 0 msec to checkpoint
[junit4] 2> 21179 T19 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-19]: apply all deletes during flush
[junit4] 2> 21179 T19 C1 oasu.LoggingInfoStream.message [BD][qtp1769747735-19]: applyDeletes: infos=[_0(5.0):C1] packetCount=1
[junit4] 2> 21180 T19 C1 oasu.LoggingInfoStream.message [BD][qtp1769747735-19]: applyDeletes took 1 msec
[junit4] 2> 21180 T19 C1 oasu.LoggingInfoStream.message [BD][qtp1769747735-19]: prune sis=segments_1: _0(5.0):C1 minGen=6 packetCount=1
[junit4] 2> 21181 T19 C1 oasu.LoggingInfoStream.message [BD][qtp1769747735-19]: pruneDeletes: prune 1 packets; 0 packets remain
[junit4] 2> 21182 T19 C1 oasu.LoggingInfoStream.message [DW][qtp1769747735-19]: qtp1769747735-19 finishFullFlush success=true
[junit4] 2> 21188 T19 C1 oasu.LoggingInfoStream.message [TMP][qtp1769747735-19]: findMerges: 1 segments
[junit4] 2> 21194 T19 C1 oasu.LoggingInfoStream.message [TMP][qtp1769747735-19]: seg=_0(5.0):C1 size=0.001 MB [floored]
[junit4] 2> 21195 T19 C1 oasu.LoggingInfoStream.message [TMP][qtp1769747735-19]: allowedSegmentCount=1 vs count=1 (eligible count=1) tooBigCount=0
[junit4] 2> 21195 T19 C1 oasu.LoggingInfoStream.message [CMS][qtp1769747735-19]: now merge
[junit4] 2> 21196 T19 C1 oasu.LoggingInfoStream.message [CMS][qtp1769747735-19]: index: _0(5.0):C1
[junit4] 2> 21196 T19 C1 oasu.LoggingInfoStream.message [CMS][qtp1769747735-19]: no more merges pending; now return
[junit4] 2> 21196 T19 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-19]: startCommit(): start
[junit4] 2> 21197 T19 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-19]: startCommit index=_0(5.0):C1 changeCount=3
[junit4] 2> 21203 T19 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-19]: done all syncs: [_0_Lucene41_0.tip, _0_Lucene41_0.doc, _0.si, _0_Lucene41_0.tim, _0.nvd, _0.fdx, _0.fdt, _0_Lucene41_0.pos, _0.nvm, _0.fnm]
[junit4] 2> 21204 T19 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-19]: commit: pendingCommit != null
[junit4] 2> 21204 T19 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-19]: commit: wrote segments file "segments_2"
[junit4] 2> 21205 T19 C1 oasu.LoggingInfoStream.message [IFD][qtp1769747735-19]: now checkpoint "_0(5.0):C1" [1 segments ; isCommit = true]
[junit4] 2> 21205 T19 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@71d7098 lockFactory=NativeFSLockFactory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-EAE765ED855F3E3F-001/tempDir-001/index),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@71d7098 lockFactory=NativeFSLockFactory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-EAE765ED855F3E3F-001/tempDir-001/index),segFN=segments_2,generation=2}
[junit4] 2> 21208 T19 C1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 21208 T19 C1 oasu.LoggingInfoStream.message [IFD][qtp1769747735-19]: deleteCommits: now decRef commit "segments_1"
[junit4] 2> 21208 T19 C1 oasu.LoggingInfoStream.message [IFD][qtp1769747735-19]: delete "segments_1"
[junit4] 2> 21209 T19 C1 oasu.LoggingInfoStream.message [IFD][qtp1769747735-19]: 4 msec to checkpoint
[junit4] 2> 21210 T19 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-19]: commit: took 295.0 msec
[junit4] 2> 21210 T19 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-19]: commit: done
[junit4] 2> 21211 T19 C1 oasu.LoggingInfoStream.message [DW][qtp1769747735-19]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 21211 T19 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-19]: nrtIsCurrent: infoVersion matches: false; DW changes: false; BD changes: false
[junit4] 2> 21211 T19 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-19]: flush at getReader
[junit4] 2> 21212 T19 C1 oasu.LoggingInfoStream.message [DW][qtp1769747735-19]: startFullFlush
[junit4] 2> 21212 T19 C1 oasu.LoggingInfoStream.message [DW][qtp1769747735-19]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 21213 T19 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-19]: apply all deletes during flush
[junit4] 2> 21213 T19 C1 oasu.LoggingInfoStream.message [BD][qtp1769747735-19]: applyDeletes: no deletes; skipping
[junit4] 2> 21214 T19 C1 oasu.LoggingInfoStream.message [BD][qtp1769747735-19]: prune sis=segments_2: _0(5.0):C1 minGen=6 packetCount=0
[junit4] 2> 21349 T19 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-19]: return reader version=3 reader=StandardDirectoryReader(segments_2:3:nrt _0(5.0):C1)
[junit4] 2> 21350 T19 C1 oasu.LoggingInfoStream.message [DW][qtp1769747735-19]: qtp1769747735-19 finishFullFlush success=true
[junit4] 2> 21351 T19 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-19]: getReader took 140 msec
[junit4] 2> 21357 T19 C1 oass.SolrIndexSearcher.<init> Opening Searcher@161deb49[collection1] main
[junit4] 2> 21379 T19 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 21379 T24 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@161deb49[collection1] main{UninvertingDirectoryReader(Uninverting(_0(5.0):C1))}
[junit4] 2> 21381 T24 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 21381 T24 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@161deb49[collection1] main{UninvertingDirectoryReader(Uninverting(_0(5.0):C1))}
[junit4] 2> 21383 T19 C1 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 472
[junit4] 2> 21552 T21 C1 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&fl=id,price_f,name,_version_&wt=javabin&version=2} hits=1 status=0 QTime=110
[junit4] 2> 21808 T17 C1 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {} 0 236
[junit4] 2> 21824 T22 C1 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[unique (1472758991532589056)]} 0 6
[junit4] 2> 21831 T18 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 21831 T18 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-18]: commit: start
[junit4] 2> 21832 T18 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-18]: commit: enter lock
[junit4] 2> 21832 T18 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-18]: commit: now prepare
[junit4] 2> 21832 T18 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-18]: prepareCommit: flush
[junit4] 2> 21834 T18 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-18]: index before flush _0(5.0):C1
[junit4] 2> 21834 T18 C1 oasu.LoggingInfoStream.message [DW][qtp1769747735-18]: startFullFlush
[junit4] 2> 21835 T18 C1 oasu.LoggingInfoStream.message [DW][qtp1769747735-18]: anyChanges? numDocsInRam=1 deletes=true hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 21836 T18 C1 oasu.LoggingInfoStream.message [DWFC][qtp1769747735-18]: addFlushableState DocumentsWriterPerThread [pendingDeletes=gen=0, segment=_1, aborting=false, numDocsInRAM=1, deleteQueue=DWDQ: [ generation: 4 ]]
[junit4] 2> 21836 T18 C1 oasu.LoggingInfoStream.message [DWPT][qtp1769747735-18]: flush postings as segment _1 numDocs=1
[junit4] 2> 21841 T18 C1 oasu.LoggingInfoStream.message [DWPT][qtp1769747735-18]: new segment has 0 deleted docs
[junit4] 2> 21842 T18 C1 oasu.LoggingInfoStream.message [DWPT][qtp1769747735-18]: new segment has no vectors; norms; no docValues; prox; freqs
[junit4] 2> 21842 T18 C1 oasu.LoggingInfoStream.message [DWPT][qtp1769747735-18]: flushedFiles=[_1.nvd, _1_Lucene41_0.tip, _1.fdx, _1_Lucene41_0.doc, _1_Lucene41_0.tim, _1.nvm, _1.fnm, _1.fdt, _1_Lucene41_0.pos]
[junit4] 2> 21842 T18 C1 oasu.LoggingInfoStream.message [DWPT][qtp1769747735-18]: flushed codec=Lucene49
[junit4] 2> 21843 T18 C1 oasu.LoggingInfoStream.message [DWPT][qtp1769747735-18]: flushed: segment=_1 ramUsed=0.079 MB newFlushedSize(includes docstores)=0.001 MB docs/MB=757.642
[junit4] 2> 21844 T18 C1 oasu.LoggingInfoStream.message [DW][qtp1769747735-18]: publishFlushedSegment seg-private updates=null
[junit4] 2> 21845 T18 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-18]: publishFlushedSegment
[junit4] 2> 21845 T18 C1 oasu.LoggingInfoStream.message [BD][qtp1769747735-18]: push deletes 1 deleted terms (unique count=1) bytesUsed=1056 delGen=8 packetCount=1 totBytesUsed=1056
[junit4] 2> 21845 T18 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-18]: publish sets newSegment delGen=9 seg=_1(5.0):C1
[junit4] 2> 21846 T18 C1 oasu.LoggingInfoStream.message [IFD][qtp1769747735-18]: now checkpoint "_0(5.0):C1 _1(5.0):C1" [2 segments ; isCommit = false]
[junit4] 2> 21847 T18 C1 oasu.LoggingInfoStream.message [IFD][qtp1769747735-18]: 1 msec to checkpoint
[junit4] 2> 21848 T18 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-18]: apply all deletes during flush
[junit4] 2> 21849 T18 C1 oasu.LoggingInfoStream.message [BD][qtp1769747735-18]: applyDeletes: infos=[_0(5.0):C1, _1(5.0):C1] packetCount=1
[junit4] 2> 21981 T18 C1 oasu.LoggingInfoStream.message [BD][qtp1769747735-18]: seg=_0(5.0):C1 segGen=6 coalesced deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=1 100% deleted
[junit4] 2> 21983 T18 C1 oasu.LoggingInfoStream.message [BD][qtp1769747735-18]: applyDeletes took 134 msec
[junit4] 2> 21983 T18 C1 oasu.LoggingInfoStream.message [IFD][qtp1769747735-18]: now checkpoint "_0(5.0):C1/1 _1(5.0):C1" [2 segments ; isCommit = false]
[junit4] 2> 21984 T18 C1 oasu.LoggingInfoStream.message [IFD][qtp1769747735-18]: 0 msec to checkpoint
[junit4] 2> 21984 T18 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-18]: drop 100% deleted segments: _0(5.0):C1/1
[junit4] 2> 21984 T18 C1 oasu.LoggingInfoStream.message [IFD][qtp1769747735-18]: now checkpoint "_1(5.0):C1" [1 segments ; isCommit = false]
[junit4] 2> 21986 T18 C1 oasu.LoggingInfoStream.message [IFD][qtp1769747735-18]: 1 msec to checkpoint
[junit4] 2> 21986 T18 C1 oasu.LoggingInfoStream.message [BD][qtp1769747735-18]: prune sis=segments_2: _1(5.0):C1 minGen=10 packetCount=1
[junit4] 2> 21986 T18 C1 oasu.LoggingInfoStream.message [BD][qtp1769747735-18]: pruneDeletes: prune 1 packets; 0 packets remain
[junit4] 2> 21989 T18 C1 oasu.LoggingInfoStream.message [DW][qtp1769747735-18]: qtp1769747735-18 finishFullFlush success=true
[junit4] 2> 21990 T18 C1 oasu.LoggingInfoStream.message [TMP][qtp1769747735-18]: findMerges: 1 segments
[junit4] 2> 21991 T18 C1 oasu.LoggingInfoStream.message [TMP][qtp1769747735-18]: seg=_1(5.0):C1 size=0.001 MB [floored]
[junit4] 2> 21992 T18 C1 oasu.LoggingInfoStream.message [TMP][qtp1769747735-18]: allowedSegmentCount=1 vs count=1 (eligible count=1) tooBigCount=0
[junit4] 2> 21993 T18 C1 oasu.LoggingInfoStream.message [CMS][qtp1769747735-18]: now merge
[junit4] 2> 21993 T18 C1 oasu.LoggingInfoStream.message [CMS][qtp1769747735-18]: index: _1(5.0):C1
[junit4] 2> 21994 T18 C1 oasu.LoggingInfoStream.message [CMS][qtp1769747735-18]: no more merges pending; now return
[junit4] 2> 21994 T18 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-18]: startCommit(): start
[junit4] 2> 21994 T18 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-18]: startCommit index=_1(5.0):C1 changeCount=8
[junit4] 2> 21996 T18 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-18]: done all syncs: [_1.si, _1.nvd, _1_Lucene41_0.tip, _1.fdx, _1_Lucene41_0.doc, _1_Lucene41_0.tim, _1.nvm, _1.fnm, _1.fdt, _1_Lucene41_0.pos]
[junit4] 2> 21996 T18 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-18]: commit: pendingCommit != null
[junit4] 2> 21998 T18 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-18]: commit: wrote segments file "segments_3"
[junit4] 2> 21998 T18 C1 oasu.LoggingInfoStream.message [IFD][qtp1769747735-18]: now checkpoint "_1(5.0):C1" [1 segments ; isCommit = true]
[junit4] 2> 21999 T18 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@71d7098 lockFactory=NativeFSLockFactory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-EAE765ED855F3E3F-001/tempDir-001/index),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@71d7098 lockFactory=NativeFSLockFactory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-EAE765ED855F3E3F-001/tempDir-001/index),segFN=segments_3,generation=3}
[junit4] 2> 21999 T18 C1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 22000 T18 C1 oasu.LoggingInfoStream.message [IFD][qtp1769747735-18]: deleteCommits: now decRef commit "segments_2"
[junit4] 2> 22000 T18 C1 oasu.LoggingInfoStream.message [IFD][qtp1769747735-18]: delete "segments_2"
[junit4] 2> 22003 T18 C1 oasu.LoggingInfoStream.message [IFD][qtp1769747735-18]: 5 msec to checkpoint
[junit4] 2> 22004 T18 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-18]: commit: took 171.6 msec
[junit4] 2> 22004 T18 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-18]: commit: done
[junit4] 2> 22005 T18 C1 oasu.LoggingInfoStream.message [DW][qtp1769747735-18]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 22005 T18 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-18]: nrtIsCurrent: infoVersion matches: false; DW changes: false; BD changes: false
[junit4] 2> 22006 T18 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-18]: flush at getReader
[junit4] 2> 22006 T18 C1 oasu.LoggingInfoStream.message [DW][qtp1769747735-18]: startFullFlush
[junit4] 2> 22007 T18 C1 oasu.LoggingInfoStream.message [DW][qtp1769747735-18]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 22007 T18 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-18]: apply all deletes during flush
[junit4] 2> 22008 T18 C1 oasu.LoggingInfoStream.message [BD][qtp1769747735-18]: applyDeletes: no deletes; skipping
[junit4] 2> 22008 T18 C1 oasu.LoggingInfoStream.message [BD][qtp1769747735-18]: prune sis=segments_3: _1(5.0):C1 minGen=10 packetCount=0
[junit4] 2> 22014 T18 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-18]: return reader version=7 reader=StandardDirectoryReader(segments_3:7:nrt _1(5.0):C1)
[junit4] 2> 22015 T18 C1 oasu.LoggingInfoStream.message [DW][qtp1769747735-18]: qtp1769747735-18 finishFullFlush success=true
[junit4] 2> 22016 T18 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-18]: getReader took 10 msec
[junit4] 2> 22016 T18 C1 oass.SolrIndexSearcher.<init> Opening Searcher@5c4a4b3e[collection1] main
[junit4] 2> 22021 T18 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 22021 T24 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@5c4a4b3e[collection1] main{UninvertingDirectoryReader(Uninverting(_1(5.0):C1))}
[junit4] 2> 22021 T24 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 22022 T24 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5c4a4b3e[collection1] main{UninvertingDirectoryReader(Uninverting(_1(5.0):C1))}
[junit4] 2> 22024 T24 oasu.LoggingInfoStream.message [IFD][searcherExecutor-6-thread-1]: delete "_0_Lucene41_0.tip"
[junit4] 2> 22027 T24 oasu.LoggingInfoStream.message [IFD][searcherExecutor-6-thread-1]: delete "_0_Lucene41_0.doc"
[junit4] 2> 22027 T24 oasu.LoggingInfoStream.message [IFD][searcherExecutor-6-thread-1]: delete "_0.si"
[junit4] 2> 22028 T24 oasu.LoggingInfoStream.message [IFD][searcherExecutor-6-thread-1]: delete "_0_Lucene41_0.tim"
[junit4] 2> 22028 T24 oasu.LoggingInfoStream.message [IFD][searcherExecutor-6-thread-1]: delete "_0.nvd"
[junit4] 2> 22031 T24 oasu.LoggingInfoStream.message [IFD][searcherExecutor-6-thread-1]: delete "_0.fdx"
[junit4] 2> 22032 T24 oasu.LoggingInfoStream.message [IFD][searcherExecutor-6-thread-1]: delete "_0.fdt"
[junit4] 2> 22032 T24 oasu.LoggingInfoStream.message [IFD][searcherExecutor-6-thread-1]: delete "_0_Lucene41_0.pos"
[junit4] 2> 22032 T24 oasu.LoggingInfoStream.message [IFD][searcherExecutor-6-thread-1]: delete "_0.nvm"
[junit4] 2> 22032 T24 oasu.LoggingInfoStream.message [IFD][searcherExecutor-6-thread-1]: delete "_0.fnm"
[junit4] 2> 22033 T18 C1 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 202
[junit4] 2> 22041 T19 C1 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={q=*:*&fl=id,price_f,name,_version_&wt=javabin&version=2} hits=1 status=0 QTime=2
[junit4] 2> 22051 T21 C1 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[unique (1472758991771664384)]} 0 4
[junit4] 2> 22059 T17 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 22060 T17 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-17]: commit: start
[junit4] 2> 22061 T17 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-17]: commit: enter lock
[junit4] 2> 22061 T17 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-17]: commit: now prepare
[junit4] 2> 22062 T17 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-17]: prepareCommit: flush
[junit4] 2> 22065 T17 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-17]: index before flush _1(5.0):C1
[junit4] 2> 22065 T17 C1 oasu.LoggingInfoStream.message [DW][qtp1769747735-17]: startFullFlush
[junit4] 2> 22066 T17 C1 oasu.LoggingInfoStream.message [DW][qtp1769747735-17]: anyChanges? numDocsInRam=1 deletes=true hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 22066 T17 C1 oasu.LoggingInfoStream.message [DWFC][qtp1769747735-17]: addFlushableState DocumentsWriterPerThread [pendingDeletes=gen=0, segment=_2, aborting=false, numDocsInRAM=1, deleteQueue=DWDQ: [ generation: 6 ]]
[junit4] 2> 22067 T17 C1 oasu.LoggingInfoStream.message [DWPT][qtp1769747735-17]: flush postings as segment _2 numDocs=1
[junit4] 2> 22074 T17 C1 oasu.LoggingInfoStream.message [DWPT][qtp1769747735-17]: new segment has 0 deleted docs
[junit4] 2> 22076 T17 C1 oasu.LoggingInfoStream.message [DWPT][qtp1769747735-17]: new segment has no vectors; norms; no docValues; prox; freqs
[junit4] 2> 22076 T17 C1 oasu.LoggingInfoStream.message [DWPT][qtp1769747735-17]: flushedFiles=[_2.nvd, _2_Lucene41_0.tip, _2.fdt, _2_Lucene41_0.doc, _2_Lucene41_0.tim, _2.nvm, _2.fnm, _2.fdx, _2_Lucene41_0.pos]
[junit4] 2> 22077 T17 C1 oasu.LoggingInfoStream.message [DWPT][qtp1769747735-17]: flushed codec=Lucene49
[junit4] 2> 22079 T17 C1 oasu.LoggingInfoStream.message [DWPT][qtp1769747735-17]: flushed: segment=_2 ramUsed=0.079 MB newFlushedSize(includes docstores)=0.001 MB docs/MB=757.642
[junit4] 2> 22083 T17 C1 oasu.LoggingInfoStream.message [DW][qtp1769747735-17]: publishFlushedSegment seg-private updates=null
[junit4] 2> 22086 T17 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-17]: publishFlushedSegment
[junit4] 2> 22087 T17 C1 oasu.LoggingInfoStream.message [BD][qtp1769747735-17]: push deletes 1 deleted terms (unique count=1) bytesUsed=1056 delGen=12 packetCount=1 totBytesUsed=1056
[junit4] 2> 22097 T17 C1 oasu.LoggingInfoStream.message [IW][qtp1769747735-17]: publish sets newSegment delGen=13 seg=_2(5.0):C1
[junit4] 2> ASYNC NEW_CORE C2 name=collection1 org.apache.solr.core.SolrCore@344791dc
[junit4] 2> 22125 T17 C2 oasu.LoggingInfoStream.message [IFD][qtp1769747735-17]: now checkpoint "_1(5.0):C1 _2(5.0):C1" [2 segments ; isCommit = false]
[junit4] 2> 22129 T17 C2 oasu.LoggingInfoStream.message [IFD][qtp1769747735-17]: 4 msec to checkpoint
[junit4] 2> 22130 T17 C2 oasu.LoggingInfoStream.message [IW][qtp1769747735-17]: apply all deletes during flush
[junit4] 2> 22131 T17 C2 oasu.LoggingInfoStream.message [BD][qtp1769747735-17]: applyDeletes: infos=[_1(5.0):C1, _2(5.0):C1] packetCount=1
[junit4] 2> 22132 T17 C2 oasu.LoggingInfoStream.message [BD][qtp1769747735-17]: seg=_1(5.0):C1 segGen=10 coalesced deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=1 100% deleted
[junit4] 2> 22132 T17 C2 oasu.LoggingInfoStream.message [BD][qtp1769747735-17]: applyDeletes took 1 msec
[junit4] 2> 22133 T17 C2 oasu.LoggingInfoStream.message [IFD][qtp1769747735-17]: now checkpoint "_1(5.0):C1/1 _2(5.0):C1" [2 segments ; isCommit = false]
[junit4] 2> 22133 T17 C2 oasu.LoggingInfoStream.message [IFD][qtp1769747735-17]: 0 msec to checkpoint
[junit4] 2> 22134 T17 C2 oasu.LoggingInfoStream.message [IW][qtp1769747735-17]: drop 100% deleted segments: _1(5.0):C1/1
[junit4] 2> 22135 T17 C2 oasu.LoggingInfoStream.message [IFD][qtp1769747735-17]: now checkpoint "_2(5.0):C1" [1 segments ; isCommit = false]
[junit4] 2> 22136 T17 C2 oasu.LoggingInfoStream.message [IFD][qtp1769747735-17]: 0 msec to checkpoint
[junit4] 2> 22136 T17 C2 oasu.LoggingInfoStream.message [BD][qtp1769747735-17]: prune sis=segments_3: _2(5.0):C1 minGen=14 packetCount=1
[junit4] 2> 22137 T17 C2 oasu.LoggingInfoStream.message [BD][qtp1769747735-17]: pruneDeletes: prune 1 packets; 0 packets remain
[junit4] 2> 22138 T17 C2 oasu.LoggingInfoStream.message [DW][qtp1769747735-17]: qtp1769747735-17 finishFullFlush success=true
[junit4] 2> 22138 T17 C2 oasu.LoggingInfoStream.message [TMP][qtp1769747735-17]: findMerges: 1 segments
[junit4] 2> 22144 T17 C2 oasu.LoggingInfoStream.message [TMP][qtp1769747735-17]: seg=_2(5.0):C1 size=0.001 MB [floored]
[junit4] 2> 22144 T17 C2 oasu.LoggingInfoStream.message [TMP][qtp1769747735-17]: allowedSegmentCount=1 vs count=1 (eligible count=1) tooBigCount=0
[junit4] 2> 22145 T17 C2 oasu.LoggingInfoStream.message [CMS][qtp1769747735-17]: now merge
[junit4] 2> 22145 T17 C2 oasu.LoggingInfoStream.message [CMS][qtp1769747735-17]: index: _2(5.0):C1
[junit4] 2> 22146 T17 C2 oasu.LoggingInfoStream.message [CMS][qtp1769747735-17]: no more merges pending; now return
[junit4] 2> 22146 T17 C2 oasu.LoggingInfoStream.message [IW][qtp1769747735-17]: startCommit(): start
[junit4] 2> 22146 T17 C2 oasu.LoggingInfoStream.message [IW][qtp1769747735-17]: startCommit index=_2(5.0):C1 changeCount=13
[junit4] 2> 22148 T17 C2 oasu.LoggingInfoStream.message [IW][qtp1769747735-17]: done all syncs: [_2.nvd, _2.si, _2_Lucene41_0.tip, _2.fdt, _2_Lucene41_0.doc, _2_Lucene41_0.tim, _2.nvm, _2.fnm, _2.fdx, _2_Lucene41_0.pos]
[junit4] 2> 22148 T17 C2 oasu.LoggingInfoStream.message [IW][qtp1769747735-17]: commit: pendingCommit != null
[junit4] 2> 22149 T17 C2 oasu.LoggingInfoStream.message [IW][qtp1769747735-17]: commit: wrote segments file "segments_4"
[junit4] 2> 22149 T17 C2 oasu.LoggingInfoStream.message [IFD][qtp1769747735-17]: now checkpoint "_2(5.0):C1" [1 segments ; isCommit = true]
[junit4] 2> 22149 T17 C2 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@71d7098 lockFactory=NativeFSLockFactory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-EAE765ED855F3E3F-001/tempDir-001/index),segFN=segments_3,generation=3}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@71d7098 lockFactory=NativeFSLockFactory@/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-EAE765ED855F3E3F-001/tempDir-001/index),segFN=segments_4,generation=4}
[junit4] 2> 22150 T17 C2 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
[junit4] 2> 22151 T17 C2 oasu.LoggingInfoStream.message [IFD][qtp1769747735-17]: deleteCommits: now decRef commit "segments_3"
[junit4] 2> 22152 T17 C2 oasu.LoggingInfoStream.message [IFD][qtp1769747735-17]: delete "segments_3"
[junit4] 2> 22152 T17 C2 oasu.LoggingInfoStream.message [IFD][qtp1769747735-17]: 3 msec to checkpoint
[junit4] 2> 22153 T17 C2 oasu.LoggingInfoStream.message [IW][qtp1769747735-17]: commit: took 90.1 msec
[junit4] 2> 22153 T17 C2 oasu.LoggingInfoStream.message [IW][qtp1769747735-17]: commit: done
[junit4] 2> 221
[...truncated too long message...]
]]: flush at getReader
[junit4] 2> 183159 T557 C68 oasu.LoggingInfoStream.message [DW][TEST-SolrExampleEmbeddedTest.testMultiContentStreamRequest-seed#[EAE765ED855F3E3F]]: startFullFlush
[junit4] 2> 183159 T557 C68 oasu.LoggingInfoStream.message [DW][TEST-SolrExampleEmbeddedTest.testMultiContentStreamRequest-seed#[EAE765ED855F3E3F]]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 183159 T557 C68 oasu.LoggingInfoStream.message [IW][TEST-SolrExampleEmbeddedTest.testMultiContentStreamRequest-seed#[EAE765ED855F3E3F]]: apply all deletes during flush
[junit4] 2> 183160 T557 C68 oasu.LoggingInfoStream.message [BD][TEST-SolrExampleEmbeddedTest.testMultiContentStreamRequest-seed#[EAE765ED855F3E3F]]: applyDeletes: no deletes; skipping
[junit4] 2> 183160 T557 C68 oasu.LoggingInfoStream.message [BD][TEST-SolrExampleEmbeddedTest.testMultiContentStreamRequest-seed#[EAE765ED855F3E3F]]: prune sis=segments_1n: _15(5.0):C5 minGen=246 packetCount=0
[junit4] 2> 183161 T557 C68 oasu.LoggingInfoStream.message [IW][TEST-SolrExampleEmbeddedTest.testMultiContentStreamRequest-seed#[EAE765ED855F3E3F]]: return reader version=155 reader=StandardDirectoryReader(segments_1n:155:nrt _15(5.0):C5)
[junit4] 2> 183162 T557 C68 oasu.LoggingInfoStream.message [DW][TEST-SolrExampleEmbeddedTest.testMultiContentStreamRequest-seed#[EAE765ED855F3E3F]]: TEST-SolrExampleEmbeddedTest.testMultiContentStreamRequest-seed#[EAE765ED855F3E3F] finishFullFlush success=true
[junit4] 2> 183163 T557 C68 oasu.LoggingInfoStream.message [IW][TEST-SolrExampleEmbeddedTest.testMultiContentStreamRequest-seed#[EAE765ED855F3E3F]]: getReader took 5 msec
[junit4] 2> 183163 T557 C68 oass.SolrIndexSearcher.<init> Opening Searcher@7f1e3d03[collection1] main
[junit4] 2> 183164 T559 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@7f1e3d03[collection1] main{UninvertingDirectoryReader(Uninverting(_15(5.0):C5))}
[junit4] 2> 183165 T559 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 183165 T557 C68 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 183166 T559 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7f1e3d03[collection1] main{UninvertingDirectoryReader(Uninverting(_15(5.0):C5))}
[junit4] 2> 183167 T557 C68 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={a=%E1%88%B4&echoParams=ALL&commit=true&softCommit=false&waitSearcher=true} {add=[SP2514N (1472759160421482496), 6H500F0 (1472759160424628224), TWINX2048-3200PRO (1472759160425676800), VS1GB400C3 (1472759160426725376), VDBDB1A16 (1472759160428822528)],commit=} 0 283
[junit4] 2> 183168 T557 C68 oasc.SolrCore.execute [collection1] webapp=null path=/select params={q=*%3A*} hits=5 status=0 QTime=1
[junit4] 2> 183169 T557 oas.SolrTestCaseJ4.tearDown ###Ending testMultiContentStreamRequest
[junit4] 2> 183170 T557 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=445746363
[junit4] 2> 183171 T557 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@61cac3ab
[junit4] 2> 183193 T557 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=62,autocommit maxTime=15000ms,autocommits=0,soft autocommits=3,optimizes=2,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=1,cumulative_adds=192,cumulative_deletesById=5,cumulative_deletesByQuery=29,cumulative_errors=1,transaction_logs_total_size=3808,transaction_logs_total_number=10}
[junit4] 2> 183195 T557 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 183195 T557 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 183195 T557 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 183199 T557 C68 oasu.LoggingInfoStream.message [IW][SUITE-SolrExampleEmbeddedTest-seed#[EAE765ED855F3E3F]-worker]: waitForMerges
[junit4] 2> 183199 T557 C68 oasu.LoggingInfoStream.message [IW][SUITE-SolrExampleEmbeddedTest-seed#[EAE765ED855F3E3F]-worker]: waitForMerges done
[junit4] 2> 183199 T557 C68 oasu.LoggingInfoStream.message [IW][SUITE-SolrExampleEmbeddedTest-seed#[EAE765ED855F3E3F]-worker]: rollback
[junit4] 2> 183200 T557 C68 oasu.LoggingInfoStream.message [IW][SUITE-SolrExampleEmbeddedTest-seed#[EAE765ED855F3E3F]-worker]: all running merges have aborted
[junit4] 2> 183200 T557 C68 oasu.LoggingInfoStream.message [IW][SUITE-SolrExampleEmbeddedTest-seed#[EAE765ED855F3E3F]-worker]: rollback: done finish merges
[junit4] 2> 183200 T557 C68 oasu.LoggingInfoStream.message [DW][SUITE-SolrExampleEmbeddedTest-seed#[EAE765ED855F3E3F]-worker]: abort
[junit4] 2> 183200 T557 C68 oasu.LoggingInfoStream.message [DW][SUITE-SolrExampleEmbeddedTest-seed#[EAE765ED855F3E3F]-worker]: done abort; abortedFiles=[] success=true
[junit4] 2> 183201 T557 C68 oasu.LoggingInfoStream.message [IW][SUITE-SolrExampleEmbeddedTest-seed#[EAE765ED855F3E3F]-worker]: rollback: infos=_15(5.0):C5
[junit4] 2> 183201 T557 C68 oasu.LoggingInfoStream.message [IFD][SUITE-SolrExampleEmbeddedTest-seed#[EAE765ED855F3E3F]-worker]: now checkpoint "_15(5.0):C5" [1 segments ; isCommit = false]
[junit4] 2> 183202 T557 C68 oasu.LoggingInfoStream.message [IFD][SUITE-SolrExampleEmbeddedTest-seed#[EAE765ED855F3E3F]-worker]: 0 msec to checkpoint
[junit4] 2> 183206 T557 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 183207 T557 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 183207 T557 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.embedded.SolrExampleEmbeddedTest-EAE765ED855F3E3F-001/init-core-data-001/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.embedded.SolrExampleEmbeddedTest-EAE765ED855F3E3F-001/init-core-data-001/index;done=false>>]
[junit4] 2> 183207 T557 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.embedded.SolrExampleEmbeddedTest-EAE765ED855F3E3F-001/init-core-data-001/index
[junit4] 2> 183207 T557 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.embedded.SolrExampleEmbeddedTest-EAE765ED855F3E3F-001/init-core-data-001 [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.embedded.SolrExampleEmbeddedTest-EAE765ED855F3E3F-001/init-core-data-001;done=false>>]
[junit4] 2> 183208 T557 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.embedded.SolrExampleEmbeddedTest-EAE765ED855F3E3F-001/init-core-data-001
[junit4] 2> 183208 T557 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 183208 T557 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=445746363
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.embedded.SolrExampleEmbeddedTest-EAE765ED855F3E3F-001
[junit4] 2> NOTE: test params are: codec=Lucene40, sim=DefaultSimilarity, locale=cs, timezone=Asia/Dili
[junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.8.0_05 (64-bit)/cpus=2,threads=1,free=165758496,total=202375168
[junit4] 2> NOTE: All tests run in this JVM: [SolrExampleJettyTest, TestBatchUpdate, MergeIndexesEmbeddedTest, BasicHttpSolrServerTest, TestSpellCheckResponse, TestEmbeddedSolrServer, SolrExampleStreamingBinaryTest, TestRetryUtil, LargeVolumeJettyTest, TestLBHttpSolrServer, AnlysisResponseBaseTest, TestDocumentObjectBinder, TestHash, SolrDocumentTest, TestNamedListCodec, ModifiableSolrParamsTest, ContentStreamTest, SolrExampleXMLTest, TestFastInputStream, SolrExampleBinaryTest, NamedListTest, SolrParamTest, JettyWebappTest, CloudSolrServerTest, SolrExampleStreamingTest, SolrExampleEmbeddedTest]
[junit4] Completed in 8.08s, 27 tests, 1 failure <<< FAILURES!
[...truncated 75 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:467: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:447: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:45: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/extra-targets.xml:37: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build.xml:202: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/common-build.xml:496: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:1298: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:922: There were test failures: 49 suites, 295 tests, 3 errors, 1 failure
Total time: 105 minutes 2 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseParallelGC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any