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/03/31 00:33:31 UTC

[JENKINS] Lucene-Solr-4.x-Windows (64bit/jdk1.7.0_60-ea-b10) - Build # 3830 - Failure!

Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Windows/3830/
Java: 64bit/jdk1.7.0_60-ea-b10 -XX:+UseCompressedOops -XX:+UseSerialGC

6 tests failed.
REGRESSION:  org.apache.solr.client.solrj.SolrExampleBinaryTest.testStatistics

Error Message:
ERROR: [doc=doc0] unknown field 'val_pi'

Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: ERROR: [doc=doc0] unknown field 'val_pi'
	at __randomizedtesting.SeedInfo.seed([CA47D621EF812E48:3DEEE54543ACF4A0]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:553)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:209)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:205)
	at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:118)
	at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:116)
	at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:102)
	at org.apache.solr.client.solrj.SolrExampleTests.testStatistics(SolrExampleTests.java:638)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1617)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:826)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:862)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:876)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:359)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:783)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:443)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:835)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:771)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:782)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:359)
	at java.lang.Thread.run(Thread.java:744)


REGRESSION:  org.apache.solr.client.solrj.SolrExampleXMLTest.testStatistics

Error Message:
ERROR: [doc=doc0] unknown field 'val_pi'

Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: ERROR: [doc=doc0] unknown field 'val_pi'
	at __randomizedtesting.SeedInfo.seed([CA47D621EF812E48:3DEEE54543ACF4A0]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:553)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:209)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:205)
	at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:118)
	at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:116)
	at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:102)
	at org.apache.solr.client.solrj.SolrExampleTests.testStatistics(SolrExampleTests.java:638)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1617)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:826)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:862)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:876)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:359)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:783)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:443)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:835)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:771)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:782)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:359)
	at java.lang.Thread.run(Thread.java:744)


REGRESSION:  org.apache.solr.client.solrj.embedded.SolrExampleEmbeddedTest.testStatistics

Error Message:
ERROR: [doc=doc0] unknown field 'val_pi'

Stack Trace:
org.apache.solr.common.SolrException: ERROR: [doc=doc0] unknown field 'val_pi'
	at __randomizedtesting.SeedInfo.seed([CA47D621EF812E48:3DEEE54543ACF4A0]:0)
	at org.apache.solr.update.DocumentBuilder.toDocument(DocumentBuilder.java:174)
	at org.apache.solr.update.AddUpdateCommand.getLuceneDocument(AddUpdateCommand.java:77)
	at org.apache.solr.update.DirectUpdateHandler2.addDoc0(DirectUpdateHandler2.java:234)
	at org.apache.solr.update.DirectUpdateHandler2.addDoc(DirectUpdateHandler2.java:160)
	at org.apache.solr.update.processor.RunUpdateProcessor.processAdd(RunUpdateProcessorFactory.java:69)
	at org.apache.solr.update.processor.UpdateRequestProcessor.processAdd(UpdateRequestProcessor.java:51)
	at org.apache.solr.update.processor.DistributedUpdateProcessor.doLocalAdd(DistributedUpdateProcessor.java:704)
	at org.apache.solr.update.processor.DistributedUpdateProcessor.versionAdd(DistributedUpdateProcessor.java:858)
	at org.apache.solr.update.processor.DistributedUpdateProcessor.processAdd(DistributedUpdateProcessor.java:557)
	at org.apache.solr.update.processor.LogUpdateProcessor.processAdd(LogUpdateProcessorFactory.java:100)
	at org.apache.solr.handler.loader.XMLLoader.processUpdate(XMLLoader.java:247)
	at org.apache.solr.handler.loader.XMLLoader.load(XMLLoader.java:174)
	at org.apache.solr.handler.UpdateRequestHandler$1.load(UpdateRequestHandler.java:92)
	at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:74)
	at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:135)
	at org.apache.solr.core.SolrCore.execute(SolrCore.java:1939)
	at org.apache.solr.client.solrj.embedded.EmbeddedSolrServer.request(EmbeddedSolrServer.java:150)
	at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:118)
	at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:116)
	at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:102)
	at org.apache.solr.client.solrj.SolrExampleTests.testStatistics(SolrExampleTests.java:638)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1617)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:826)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:862)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:876)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:359)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:783)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:443)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:835)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:771)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:782)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:359)
	at java.lang.Thread.run(Thread.java:744)


REGRESSION:  org.apache.solr.client.solrj.embedded.SolrExampleJettyTest.testStatistics

Error Message:
ERROR: [doc=doc0] unknown field 'val_pi'

Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: ERROR: [doc=doc0] unknown field 'val_pi'
	at __randomizedtesting.SeedInfo.seed([CA47D621EF812E48:3DEEE54543ACF4A0]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:553)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:209)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:205)
	at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:118)
	at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:116)
	at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:102)
	at org.apache.solr.client.solrj.SolrExampleTests.testStatistics(SolrExampleTests.java:638)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1617)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:826)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:862)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:876)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:359)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:783)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:443)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:835)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:771)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:782)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:359)
	at java.lang.Thread.run(Thread.java:744)


REGRESSION:  org.apache.solr.client.solrj.embedded.SolrExampleStreamingBinaryTest.testStatistics

Error Message:
expected: 10 but had: 0 :: {numFound=0,start=0,docs=[]}

Stack Trace:
java.lang.AssertionError: expected: 10 but had: 0 :: {numFound=0,start=0,docs=[]}
	at __randomizedtesting.SeedInfo.seed([CA47D621EF812E48:3DEEE54543ACF4A0]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.apache.solr.client.solrj.SolrExampleTestsBase.assertNumFound(SolrExampleTestsBase.java:266)
	at org.apache.solr.client.solrj.SolrExampleTests.testStatistics(SolrExampleTests.java:641)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1617)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:826)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:862)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:876)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:359)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:783)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:443)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:835)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:771)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:782)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:359)
	at java.lang.Thread.run(Thread.java:744)


REGRESSION:  org.apache.solr.client.solrj.embedded.SolrExampleStreamingTest.testStatistics

Error Message:
expected: 10 but had: 0 :: {numFound=0,start=0,docs=[]}

Stack Trace:
java.lang.AssertionError: expected: 10 but had: 0 :: {numFound=0,start=0,docs=[]}
	at __randomizedtesting.SeedInfo.seed([CA47D621EF812E48:3DEEE54543ACF4A0]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.apache.solr.client.solrj.SolrExampleTestsBase.assertNumFound(SolrExampleTestsBase.java:266)
	at org.apache.solr.client.solrj.SolrExampleTests.testStatistics(SolrExampleTests.java:641)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1617)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:826)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:862)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:876)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:359)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:783)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:443)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:835)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:771)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:782)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:359)
	at java.lang.Thread.run(Thread.java:744)




Build Log:
[...truncated 12176 lines...]
   [junit4] Suite: org.apache.solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
   [junit4]   2> log4j:WARN No such property [conversionPattern] in org.apache.solr.util.SolrLogLayout.
   [junit4]   2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-SolrExampleStreamingBinaryTest-1396218558289
   [junit4]   2> 1307 T13 oas.SolrTestCaseJ4.initCore ####initCore
   [junit4]   2> 1325 T13 oas.SolrTestCaseJ4.initCore ####initCore end
   [junit4]   2> 2340 T13 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 2634 T13 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:59938
   [junit4]   2> 2859 T13 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 3010 T13 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 3012 T13 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr
   [junit4]   2> 3013 T13 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\'
   [junit4]   2> 3562 T13 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\solr.xml
   [junit4]   2> 4507 T13 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr
   [junit4]   2> 4541 T13 oasc.CoreContainer.<init> New CoreContainer 527755083
   [junit4]   2> 4542 T13 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\]
   [junit4]   2> 4674 T13 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
   [junit4]   2> 4674 T13 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null
   [junit4]   2> 4683 T13 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
   [junit4]   2> 4684 T13 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 4686 T13 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 4687 T13 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 4687 T13 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 4688 T13 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 4689 T13 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 5199 T13 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 5204 T13 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 5218 T13 oasc.CoreContainer.load Host Name: 
   [junit4]   2> 5658 T13 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr
   [junit4]   2> 5818 T13 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\collection1\
   [junit4]   2> 5823 T13 oasc.CorePropertiesLocator.discover Found 1 core definitions
   [junit4]   2> 5837 T25 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\collection1\'
   [junit4]   2> 6042 T25 oasc.SolrConfig.initLibs Adding specified lib dirs to ClassLoader
   [junit4]   2> 6047 T25 oasc.SolrResourceLoader.addToClassLoader WARN No files added to classloader from lib: ../../../contrib/extraction/lib (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\collection1\..\..\..\contrib\extraction\lib).
   [junit4]   2> 6048 T25 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\collection1\..\..\..\dist).
   [junit4]   2> 6050 T25 oasc.SolrResourceLoader.addToClassLoader WARN No files added to classloader from lib: ../../../contrib/clustering/lib/ (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\collection1\..\..\..\contrib\clustering\lib).
   [junit4]   2> 6050 T25 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\collection1\..\..\..\dist).
   [junit4]   2> 6052 T25 oasc.SolrResourceLoader.addToClassLoader WARN No files added to classloader from lib: ../../../contrib/langid/lib/ (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\collection1\..\..\..\contrib\langid\lib).
   [junit4]   2> 6052 T25 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\collection1\..\..\..\dist).
   [junit4]   2> 6054 T25 oasc.SolrResourceLoader.addToClassLoader WARN No files added to classloader from lib: ../../../contrib/velocity/lib (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\collection1\..\..\..\contrib\velocity\lib).
   [junit4]   2> 6055 T25 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\collection1\..\..\..\dist).
   [junit4]   2> 6238 T25 oasu.SolrIndexConfig.<init> IndexWriter infoStream solr logging is enabled
   [junit4]   2> 6243 T25 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_48
   [junit4]   2> 6651 T25 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 6691 T25 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 6800 T25 oass.IndexSchema.readSchema [collection1] Schema name=example
   [junit4]   2> 7276 T25 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [solr.IntField]. Please consult documentation how to replace it accordingly.
   [junit4]   2> 7298 T25 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [solr.LongField]. Please consult documentation how to replace it accordingly.
   [junit4]   2> 7320 T25 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [solr.FloatField]. Please consult documentation how to replace it accordingly.
   [junit4]   2> 7340 T25 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [solr.DoubleField]. Please consult documentation how to replace it accordingly.
   [junit4]   2> 7356 T25 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [solr.DateField]. Please consult documentation how to replace it accordingly.
   [junit4]   2> 8702 T25 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [solr.ThaiWordFilterFactory]. Please consult documentation how to replace it accordingly.
   [junit4]   2> 9039 T25 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 11672 T25 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 11696 T25 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 12048 T25 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\collection1\
   [junit4]   2> 12579 T25 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 12789 T25 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1396218558289\
   [junit4]   2> 12823 T25 oasc.JmxMonitoredMap.<init> No JMX servers found, not exposing Solr information with JMX.
   [junit4]   2> 12918 T25 oasc.SolrCore.initListeners [collection1] Added SolrEventListener for newSearcher: org.apache.solr.core.QuerySenderListener{queries=[]}
   [junit4]   2> 12919 T25 oasc.SolrCore.initListeners [collection1] Added SolrEventListener for firstSearcher: org.apache.solr.core.QuerySenderListener{queries=[{q=static firstSearcher warming in solrconfig.xml}]}
   [junit4]   2> 13505 T25 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1396218558289\
   [junit4]   2> 13508 T25 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1396218558289\index/
   [junit4]   2> 13509 T25 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1396218558289\index' doesn't exist. Creating new index...
   [junit4]   2> 13552 T25 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1396218558289\index
   [junit4]   2> 14227 T25 oasu.LoggingInfoStream.message [IFD][coreLoadExecutor-4-thread-1]: init: current segments file is "null"; deletionPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper@33a515d3
   [junit4]   2> 14243 T25 oasu.LoggingInfoStream.message [IFD][coreLoadExecutor-4-thread-1]: now checkpoint "" [0 segments ; isCommit = false]
   [junit4]   2> 14245 T25 oasu.LoggingInfoStream.message [IFD][coreLoadExecutor-4-thread-1]: 1 msec to checkpoint
   [junit4]   2> 14256 T25 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-4-thread-1]: init: create=true
   [junit4]   2> 14257 T25 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-4-thread-1]: 
   [junit4]   2> 	dir=MockDirectoryWrapper(RAMDirectory@7dc94405 lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1396218558289\index)
   [junit4]   2> 	index=
   [junit4]   2> 	version=4.8-SNAPSHOT
   [junit4]   2> 	matchVersion=LUCENE_48
   [junit4]   2> 	analyzer=null
   [junit4]   2> 	ramBufferSizeMB=100.0
   [junit4]   2> 	maxBufferedDocs=-1
   [junit4]   2> 	maxBufferedDeleteTerms=-1
   [junit4]   2> 	mergedSegmentWarmer=null
   [junit4]   2> 	readerTermsIndexDivisor=1
   [junit4]   2> 	termIndexInterval=32
   [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=Lucene46
   [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.ThreadAffinityDocumentsWriterThreadPool@69f4f294
   [junit4]   2> 	readerPooling=false
   [junit4]   2> 	perThreadHardLimitMB=1945
   [junit4]   2> 	useCompoundFile=false
   [junit4]   2> 	
   [junit4]   2> 14258 T25 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-4-thread-1]: now flush at close waitForMerges=true
   [junit4]   2> 14272 T25 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-4-thread-1]:   start flush: applyAllDeletes=true
   [junit4]   2> 14272 T25 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-4-thread-1]:   index before flush 
   [junit4]   2> 14272 T25 oasu.LoggingInfoStream.message [DW][coreLoadExecutor-4-thread-1]: startFullFlush
   [junit4]   2> 14273 T25 oasu.LoggingInfoStream.message [DW][coreLoadExecutor-4-thread-1]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
   [junit4]   2> 14274 T25 oasu.LoggingInfoStream.message [DW][coreLoadExecutor-4-thread-1]: coreLoadExecutor-4-thread-1 finishFullFlush success=true
   [junit4]   2> 14274 T25 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-4-thread-1]: apply all deletes during flush
   [junit4]   2> 14284 T25 oasu.LoggingInfoStream.message [BD][coreLoadExecutor-4-thread-1]: prune sis=org.apache.lucene.index.SegmentInfos@5435d9f6 minGen=9223372036854775807 packetCount=0
   [junit4]   2> 14298 T25 oasu.LoggingInfoStream.message [CMS][coreLoadExecutor-4-thread-1]: now merge
   [junit4]   2> 14298 T25 oasu.LoggingInfoStream.message [CMS][coreLoadExecutor-4-thread-1]:   index: 
   [junit4]   2> 14299 T25 oasu.LoggingInfoStream.message [CMS][coreLoadExecutor-4-thread-1]:   no more merges pending; now return
   [junit4]   2> 14299 T25 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-4-thread-1]: waitForMerges
   [junit4]   2> 14300 T25 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-4-thread-1]: waitForMerges done
   [junit4]   2> 14300 T25 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-4-thread-1]: now call final commit()
   [junit4]   2> 14301 T25 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-4-thread-1]: commit: start
   [junit4]   2> 14301 T25 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-4-thread-1]: commit: enter lock
   [junit4]   2> 14303 T25 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-4-thread-1]: commit: now prepare
   [junit4]   2> 14303 T25 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-4-thread-1]: prepareCommit: flush
   [junit4]   2> 14304 T25 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-4-thread-1]:   index before flush 
   [junit4]   2> 14307 T25 oasu.LoggingInfoStream.message [DW][coreLoadExecutor-4-thread-1]: startFullFlush
   [junit4]   2> 14307 T25 oasu.LoggingInfoStream.message [DW][coreLoadExecutor-4-thread-1]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
   [junit4]   2> 14307 T25 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-4-thread-1]: apply all deletes during flush
   [junit4]   2> 14309 T25 oasu.LoggingInfoStream.message [BD][coreLoadExecutor-4-thread-1]: prune sis=org.apache.lucene.index.SegmentInfos@5435d9f6 minGen=9223372036854775807 packetCount=0
   [junit4]   2> 14311 T25 oasu.LoggingInfoStream.message [DW][coreLoadExecutor-4-thread-1]: coreLoadExecutor-4-thread-1 finishFullFlush success=true
   [junit4]   2> 14312 T25 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-4-thread-1]: startCommit(): start
   [junit4]   2> 14313 T25 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-4-thread-1]: startCommit index= changeCount=1
   [junit4]   2> 14375 T25 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-4-thread-1]: done all syncs: []
   [junit4]   2> 14376 T25 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-4-thread-1]: commit: pendingCommit != null
   [junit4]   2> 14378 T25 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-4-thread-1]: commit: wrote segments file "segments_1"
   [junit4]   2> 14380 T25 oasu.LoggingInfoStream.message [IFD][coreLoadExecutor-4-thread-1]: now checkpoint "" [0 segments ; isCommit = true]
   [junit4]   2> 14451 T25 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@7dc94405 lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1396218558289\index),segFN=segments_1,generation=1}
   [junit4]   2> 14470 T25 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 14471 T25 oasu.LoggingInfoStream.message [IFD][coreLoadExecutor-4-thread-1]: 83 msec to checkpoint
   [junit4]   2> 14472 T25 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-4-thread-1]: commit: done
   [junit4]   2> 14473 T25 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-4-thread-1]: at close: 
   [junit4]   2> 14543 T25 oasc.SolrCore.initWriters created json: solr.JSONResponseWriter
   [junit4]   2> 14543 T25 oasc.SolrCore.initWriters adding lazy queryResponseWriter: solr.VelocityResponseWriter
   [junit4]   2> 14557 T25 oasc.SolrCore.initWriters created velocity: solr.VelocityResponseWriter
   [junit4]   2> 14632 T25 oasc.SolrCore.initWriters created xslt: solr.XSLTResponseWriter
   [junit4]   2> 14633 T25 oasr.XSLTResponseWriter.init xsltCacheLifetimeSeconds=5
   [junit4]   2> 17035 T25 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 17155 T25 oasc.RequestHandlers.initHandlersFromConfig created /select: solr.SearchHandler
   [junit4]   2> 17156 T25 oasc.RequestHandlers.initHandlersFromConfig created /query: solr.SearchHandler
   [junit4]   2> 17180 T25 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 17181 T25 oasc.RequestHandlers.initHandlersFromConfig created /browse: solr.SearchHandler
   [junit4]   2> 17219 T25 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 17221 T25 oasc.RequestHandlers.initHandlersFromConfig created /update/json: solr.UpdateRequestHandler
   [junit4]   2> 17221 T25 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: solr.UpdateRequestHandler
   [junit4]   2> 17221 T25 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.extraction.ExtractingRequestHandler
   [junit4]   2> 17227 T25 oasc.RequestHandlers.initHandlersFromConfig created /update/extract: solr.extraction.ExtractingRequestHandler
   [junit4]   2> 17227 T25 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.FieldAnalysisRequestHandler
   [junit4]   2> 17227 T25 oasc.RequestHandlers.initHandlersFromConfig created /analysis/field: solr.FieldAnalysisRequestHandler
   [junit4]   2> 17228 T25 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.DocumentAnalysisRequestHandler
   [junit4]   2> 17228 T25 oasc.RequestHandlers.initHandlersFromConfig created /analysis/document: solr.DocumentAnalysisRequestHandler
   [junit4]   2> 17256 T25 oasc.RequestHandlers.initHandlersFromConfig created /admin/: solr.admin.AdminHandlers
   [junit4]   2> 17277 T25 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: solr.PingRequestHandler
   [junit4]   2> 17300 T25 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
   [junit4]   2> 17361 T25 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 17361 T25 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
   [junit4]   2> 17362 T25 oasc.RequestHandlers.initHandlersFromConfig created /spell: solr.SearchHandler
   [junit4]   2> 17362 T25 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
   [junit4]   2> 17362 T25 oasc.RequestHandlers.initHandlersFromConfig created /suggest: solr.SearchHandler
   [junit4]   2> 17363 T25 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
   [junit4]   2> 17363 T25 oasc.RequestHandlers.initHandlersFromConfig created /tvrh: solr.SearchHandler
   [junit4]   2> 17364 T25 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
   [junit4]   2> 17364 T25 oasc.RequestHandlers.initHandlersFromConfig created /terms: solr.SearchHandler
   [junit4]   2> 17366 T25 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
   [junit4]   2> 17366 T25 oasc.RequestHandlers.initHandlersFromConfig created /elevate: solr.SearchHandler
   [junit4]   2> 17479 T25 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 17500 T25 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 17502 T25 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 17635 T25 oasu.CommitTracker.<init> Hard AutoCommit: if uncommited for 15000ms; 
   [junit4]   2> 17635 T25 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 17639 T25 oasu.LoggingInfoStream.message [IFD][coreLoadExecutor-4-thread-1]: init: current segments file is "segments_1"; deletionPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper@33a515d3
   [junit4]   2> 17641 T25 oasu.LoggingInfoStream.message [IFD][coreLoadExecutor-4-thread-1]: init: load commit "segments_1"
   [junit4]   2> 17642 T25 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@7dc94405 lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1396218558289\index),segFN=segments_1,generation=1}
   [junit4]   2> 17642 T25 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 17642 T25 oasu.LoggingInfoStream.message [IFD][coreLoadExecutor-4-thread-1]: now checkpoint "" [0 segments ; isCommit = false]
   [junit4]   2> 17643 T25 oasu.LoggingInfoStream.message [IFD][coreLoadExecutor-4-thread-1]: 0 msec to checkpoint
   [junit4]   2> 17643 T25 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-4-thread-1]: init: create=false
   [junit4]   2> 17644 T25 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-4-thread-1]: 
   [junit4]   2> 	dir=MockDirectoryWrapper(RAMDirectory@7dc94405 lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1396218558289\index)
   [junit4]   2> 	index=
   [junit4]   2> 	version=4.8-SNAPSHOT
   [junit4]   2> 	matchVersion=LUCENE_48
   [junit4]   2> 	analyzer=null
   [junit4]   2> 	ramBufferSizeMB=100.0
   [junit4]   2> 	maxBufferedDocs=-1
   [junit4]   2> 	maxBufferedDeleteTerms=-1
   [junit4]   2> 	mergedSegmentWarmer=null
   [junit4]   2> 	readerTermsIndexDivisor=1
   [junit4]   2> 	termIndexInterval=32
   [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=Lucene46
   [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.ThreadAffinityDocumentsWriterThreadPool@59217628
   [junit4]   2> 	readerPooling=false
   [junit4]   2> 	perThreadHardLimitMB=1945
   [junit4]   2> 	useCompoundFile=false
   [junit4]   2> 	
   [junit4]   2> 17658 T25 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-4-thread-1]: flush at getReader
   [junit4]   2> 17659 T25 oasu.LoggingInfoStream.message [DW][coreLoadExecutor-4-thread-1]: startFullFlush
   [junit4]   2> 17659 T25 oasu.LoggingInfoStream.message [DW][coreLoadExecutor-4-thread-1]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
   [junit4]   2> 17660 T25 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-4-thread-1]: apply all deletes during flush
   [junit4]   2> 17660 T25 oasu.LoggingInfoStream.message [BD][coreLoadExecutor-4-thread-1]: prune sis=org.apache.lucene.index.SegmentInfos@4b936059 minGen=9223372036854775807 packetCount=0
   [junit4]   2> 17673 T25 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-4-thread-1]: return reader version=1 reader=StandardDirectoryReader(segments_1:1:nrt)
   [junit4]   2> 17674 T25 oasu.LoggingInfoStream.message [DW][coreLoadExecutor-4-thread-1]: coreLoadExecutor-4-thread-1 finishFullFlush success=true
   [junit4]   2> 17674 T25 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-4-thread-1]: getReader took 16 msec
   [junit4]   2> 17711 T25 oass.SolrIndexSearcher.<init> Opening Searcher@2943ebbf[collection1] main
   [junit4]   2> 17780 T25 oashc.SpellCheckComponent.inform Initializing spell checkers
   [junit4]   2> 17873 T25 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> 17946 T25 oashc.SpellCheckComponent.inform No queryConverter defined, using default converter
   [junit4]   2> 17952 T25 oashc.SuggestComponent.inform Initializing SuggestComponent
   [junit4]   2> 17963 T25 oasss.SolrSuggester.init init: {name=mySuggester,lookupImpl=FuzzyLookupFactory,dictionaryImpl=DocumentDictionaryFactory,field=cat,weightField=price,suggestAnalyzerFieldType=string}
   [junit4]   2> 18199 T25 oasss.SolrSuggester.init Dictionary loaded with params: {name=mySuggester,lookupImpl=FuzzyLookupFactory,dictionaryImpl=DocumentDictionaryFactory,field=cat,weightField=price,suggestAnalyzerFieldType=string}
   [junit4]   2> 18218 T25 oashc.QueryElevationComponent.inform Loading QueryElevation from: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\collection1\conf\elevate.xml
   [junit4]   2> 18528 T25 oash.ReplicationHandler.inform Commits will be reserved for  10000
   [junit4]   2> 18529 T26 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@2943ebbf[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 18534 T25 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 18546 T13 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0
   [junit4]   2> 18546 T13 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 18547 T13 oas.SolrJettyTestBase.createJetty Jetty Assigned Port#59938
   [junit4]   2> 18572 T13 oas.SolrTestCaseJ4.setUp ###Starting testChineseDefaults
   [junit4]   2> ASYNC  NEW_CORE C0 name=collection1 org.apache.solr.core.SolrCore@29ba29b3
   [junit4]   2> 19096 T26 C0 oasc.SolrCore.execute [collection1] webapp=null path=null params={distrib=false&event=firstSearcher&q=static+firstSearcher+warming+in+solrconfig.xml} hits=0 status=0 QTime=551 
   [junit4]   2> 19112 T26 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
   [junit4]   2> 19112 T26 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default
   [junit4]   2> 19112 T26 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak
   [junit4]   2> 19113 T26 oashc.SuggestComponent$SuggesterListener.newSearcher Loading suggester index for: mySuggester
   [junit4]   2> 19113 T26 oasss.SolrSuggester.reload reload()
   [junit4]   2> 19113 T26 oasss.SolrSuggester.build build()
   [junit4]   2> 19331 T26 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2943ebbf[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> ASYNC  NEW_CORE C1 name=collection1 org.apache.solr.core.SolrCore@29ba29b3
   [junit4]   2> 19923 T19 C1 oasu.LoggingInfoStream.message [DW][qtp1686223675-19]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false pendingChangesInFullFlush: false
   [junit4]   2> 19925 T19 C1 oasu.LoggingInfoStream.message [IW][qtp1686223675-19]: nrtIsCurrent: infoVersion matches: true; DW changes: true; BD changes: false
   [junit4]   2> 19926 T19 C1 oasu.LoggingInfoStream.message [DW][qtp1686223675-19]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false pendingChangesInFullFlush: false
   [junit4]   2> 19928 T19 C1 oasu.LoggingInfoStream.message [IW][qtp1686223675-19]: flush at getReader
   [junit4]   2> 19929 T19 C1 oasu.LoggingInfoStream.message [DW][qtp1686223675-19]: startFullFlush
   [junit4]   2> 19931 T19 C1 oasu.LoggingInfoStream.message [DW][qtp1686223675-19]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false pendingChangesInFullFlush: false
   [junit4]   2> 19931 T19 C1 oasu.LoggingInfoStream.message [DW][qtp1686223675-19]: qtp1686223675-19: flush naked frozen global deletes
   [junit4]   2> 19995 T19 C1 oasu.LoggingInfoStream.message [BD][qtp1686223675-19]: push deletes  1 deleted queries bytesUsed=32 delGen=2 packetCount=1 totBytesUsed=32
   [junit4]   2> 19996 T19 C1 oasu.LoggingInfoStream.message [DW][qtp1686223675-19]: flush: push buffered updates:  1 deleted queries bytesUsed=32
   [junit4]   2> 19997 T19 C1 oasu.LoggingInfoStream.message [IW][qtp1686223675-19]: apply all deletes during flush
   [junit4]   2> 19997 T19 C1 oasu.LoggingInfoStream.message [BD][qtp1686223675-19]: prune sis=org.apache.lucene.index.SegmentInfos@4b936059 minGen=9223372036854775807 packetCount=1
   [junit4]   2> 19998 T19 C1 oasu.LoggingInfoStream.message [BD][qtp1686223675-19]: pruneDeletes: prune 1 packets; 0 packets remain
   [junit4]   2> 19999 T19 C1 oasu.LoggingInfoStream.message [IW][qtp1686223675-19]: return reader version=1 reader=StandardDirectoryReader(segments_1:1:nrt)
   [junit4]   2> 19999 T19 C1 oasu.LoggingInfoStream.message [DW][qtp1686223675-19]: qtp1686223675-19 finishFullFlush success=true
   [junit4]   2> 20001 T19 C1 oasu.LoggingInfoStream.message [IW][qtp1686223675-19]: getReader took 73 msec
   [junit4]   2> 20013 T19 C1 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1464041291352375296)} 0 267
   [junit4]   2> 20171 T18 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 20174 T18 C1 oasu.LoggingInfoStream.message [DW][qtp1686223675-18]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
   [junit4]   2> 20176 T18 C1 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 20177 T18 C1 oasu.LoggingInfoStream.message [DW][qtp1686223675-18]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
   [junit4]   2> 20177 T18 C1 oasu.LoggingInfoStream.message [IW][qtp1686223675-18]: nrtIsCurrent: infoVersion matches: true; DW changes: false; BD changes: false
   [junit4]   2> 20177 T18 C1 oasu.LoggingInfoStream.message [DW][qtp1686223675-18]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
   [junit4]   2> 20180 T18 C1 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
   [junit4]   2> 20181 T18 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 20182 T18 C1 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={commit=true&version=2&softCommit=false&waitSearcher=true&wt=javabin} {commit=} 0 11
   [junit4]   2> 20213 T23 C1 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={version=2&wt=javabin&q=*:*} hits=0 status=0 QTime=7 
   [junit4]   2> 20228 T13 oascsi.ConcurrentUpdateSolrServer.request blocking for commit/optimize
   [junit4]   2> ASYNC  NEW_CORE C2 name=collection1 org.apache.solr.core.SolrCore@29ba29b3
   [junit4]   2> 20626 T21 C2 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 20636 T21 C2 oasu.LoggingInfoStream.message [IW][qtp1686223675-21]: commit: start
   [junit4]   2> 20636 T21 C2 oasu.LoggingInfoStream.message [IW][qtp1686223675-21]: commit: enter lock
   [junit4]   2> 20638 T21 C2 oasu.LoggingInfoStream.message [IW][qtp1686223675-21]: commit: now prepare
   [junit4]   2> 20639 T21 C2 oasu.LoggingInfoStream.message [IW][qtp1686223675-21]: prepareCommit: flush
   [junit4]   2> 20640 T21 C2 oasu.LoggingInfoStream.message [IW][qtp1686223675-21]:   index before flush 
   [junit4]   2> 20642 T21 C2 oasu.LoggingInfoStream.message [DW][qtp1686223675-21]: startFullFlush
   [junit4]   2> 20648 T21 C2 oasu.LoggingInfoStream.message [DW][qtp1686223675-21]: anyChanges? numDocsInRam=1 deletes=true hasTickets:false pendingChangesInFullFlush: false
   [junit4]   2> 20650 T21 C2 oasu.LoggingInfoStream.message [DWFC][qtp1686223675-21]: addFlushableState DocumentsWriterPerThread [pendingDeletes=gen=0, segment=_0, aborting=false, numDocsInRAM=1, deleteQueue=DWDQ: [ generation: 2 ]]
   [junit4]   2> 20669 T21 C2 oasu.LoggingInfoStream.message [DWPT][qtp1686223675-21]: flush postings as segment _0 numDocs=1
   [junit4]   2> 20865 T21 C2 oasu.LoggingInfoStream.message [DWPT][qtp1686223675-21]: new segment has 0 deleted docs
   [junit4]   2> 20866 T21 C2 oasu.LoggingInfoStream.message [DWPT][qtp1686223675-21]: new segment has no vectors; norms; no docValues; prox; freqs
   [junit4]   2> 20866 T21 C2 oasu.LoggingInfoStream.message [DWPT][qtp1686223675-21]: flushedFiles=[_0_Lucene41_0.tim, _0.nvm, _0_Lucene41_0.pos, _0.fnm, _0_Lucene41_0.doc, _0.fdt, _0.fdx, _0_Lucene41_0.tip, _0.nvd]
   [junit4]   2> 20867 T21 C2 oasu.LoggingInfoStream.message [DWPT][qtp1686223675-21]: flushed codec=Lucene46
   [junit4]   2> 20893 T21 C2 oasu.LoggingInfoStream.message [DWPT][qtp1686223675-21]: flushed: segment=_0 ramUsed=0.071 MB newFlushedSize(includes docstores)=0.001 MB docs/MB=1,178.175
   [junit4]   2> 20910 T21 C2 oasu.LoggingInfoStream.message [DW][qtp1686223675-21]: publishFlushedSegment seg-private updates=null
   [junit4]   2> 20911 T21 C2 oasu.LoggingInfoStream.message [IW][qtp1686223675-21]: publishFlushedSegment
   [junit4]   2> 20912 T21 C2 oasu.LoggingInfoStream.message [BD][qtp1686223675-21]: push deletes  1 deleted terms (unique count=1) bytesUsed=1024 delGen=4 packetCount=1 totBytesUsed=1024
   [junit4]   2> 20912 T21 C2 oasu.LoggingInfoStream.message [IW][qtp1686223675-21]: publish sets newSegment delGen=5 seg=_0(4.8):C1
   [junit4]   2> 20913 T21 C2 oasu.LoggingInfoStream.message [IFD][qtp1686223675-21]: now checkpoint "_0(4.8):C1" [1 segments ; isCommit = false]
   [junit4]   2> 20915 T21 C2 oasu.LoggingInfoStream.message [IFD][qtp1686223675-21]: 1 msec to checkpoint
   [junit4]   2> 20916 T21 C2 oasu.LoggingInfoStream.message [IW][qtp1686223675-21]: apply all deletes during flush
   [junit4]   2> 20916 T21 C2 oasu.LoggingInfoStream.message [BD][qtp1686223675-21]: applyDeletes: infos=[_0(4.8):C1] packetCount=1
   [junit4]   2> 20918 T21 C2 oasu.LoggingInfoStream.message [BD][qtp1686223675-21]: applyDeletes took 2 msec
   [junit4]   2> 20919 T21 C2 oasu.LoggingInfoStream.message [BD][qtp1686223675-21]: prune sis=org.apache.lucene.index.SegmentInfos@4b936059 minGen=6 packetCount=1
   [junit4]   2> 20920 T21 C2 oasu.LoggingInfoStream.message [BD][qtp1686223675-21]: pruneDeletes: prune 1 packets; 0 packets remain
   [junit4]   2> 20921 T21 C2 oasu.LoggingInfoStream.message [DW][qtp1686223675-21]: qtp1686223675-21 finishFullFlush success=true
   [junit4]   2> 20922 T21 C2 oasu.LoggingInfoStream.message [TMP][qtp1686223675-21]: findMerges: 1 segments
   [junit4]   2> 20935 T21 C2 oasu.LoggingInfoStream.message [TMP][qtp1686223675-21]:   seg=_0(4.8):C1 size=0.001 MB [floored]
   [junit4]   2> 20936 T21 C2 oasu.LoggingInfoStream.message [TMP][qtp1686223675-21]:   allowedSegmentCount=1 vs count=1 (eligible count=1) tooBigCount=0
   [junit4]   2> 20936 T21 C2 oasu.LoggingInfoStream.message [CMS][qtp1686223675-21]: now merge
   [junit4]   2> 20936 T21 C2 oasu.LoggingInfoStream.message [CMS][qtp1686223675-21]:   index: _0(4.8):C1
   [junit4]   2> 20939 T21 C2 oasu.LoggingInfoStream.message [CMS][qtp1686223675-21]:   no more merges pending; now return
   [junit4]   2> 20940 T21 C2 oasu.LoggingInfoStream.message [IW][qtp1686223675-21]: startCommit(): start
   [junit4]   2> 20940 T21 C2 oasu.LoggingInfoStream.message [IW][qtp1686223675-21]: startCommit index=_0(4.8):C1 changeCount=3
   [junit4]   2> 20952 T21 C2 oasu.LoggingInfoStream.message [IW][qtp1686223675-21]: done all syncs: [_0_Lucene41_0.tim, _0_Lucene41_0.pos, _0.nvm, _0.fnm, _0_Lucene41_0.doc, _0.fdt, _0.fdx, _0.si, _0_Lucene41_0.tip, _0.nvd]
   [junit4]   2> 20952 T21 C2 oasu.LoggingInfoStream.message [IW][qtp1686223675-21]: commit: pendingCommit != null
   [junit4]   2> 20953 T21 C2 oasu.LoggingInfoStream.message [IW][qtp1686223675-21]: commit: wrote segments file "segments_2"
   [junit4]   2> 20954 T21 C2 oasu.LoggingInfoStream.message [IFD][qtp1686223675-21]: now checkpoint "_0(4.8):C1" [1 segments ; isCommit = true]
   [junit4]   2> 20954 T21 C2 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@7dc94405 lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1396218558289\index),segFN=segments_1,generation=1}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@7dc94405 lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1396218558289\index),segFN=segments_2,generation=2}
   [junit4]   2> 20954 T21 C2 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
   [junit4]   2> 20957 T21 C2 oasu.LoggingInfoStream.message [IFD][qtp1686223675-21]: deleteCommits: now decRef commit "segments_1"
   [junit4]   2> 20957 T21 C2 oasu.LoggingInfoStream.message [IFD][qtp1686223675-21]: delete "segments_1"
   [junit4]   2> 20958 T21 C2 oasu.LoggingInfoStream.message [IFD][qtp1686223675-21]: 4 msec to checkpoint
   [junit4]   2> 20959 T21 C2 oasu.LoggingInfoStream.message [IW][qtp1686223675-21]: commit: done
   [junit4]   2> 20960 T21 C2 oasu.LoggingInfoStream.message [DW][qtp1686223675-21]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
   [junit4]   2> 20960 T21 C2 oasu.LoggingInfoStream.message [IW][qtp1686223675-21]: nrtIsCurrent: infoVersion matches: false; DW changes: false; BD changes: false
   [junit4]   2> 20960 T21 C2 oasu.LoggingInfoStream.message [IW][qtp1686223675-21]: flush at getReader
   [junit4]   2> 20961 T21 C2 oasu.LoggingInfoStream.message [DW][qtp1686223675-21]: startFullFlush
   [junit4]   2> 20962 T21 C2 oasu.LoggingInfoStream.message [DW][qtp1686223675-21]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
   [junit4]   2> 20962 T21 C2 oasu.LoggingInfoStream.message [IW][qtp1686223675-21]: apply all deletes during flush
   [junit4]   2> 20963 T21 C2 oasu.LoggingInfoStream.message [BD][qtp1686223675-21]: applyDeletes: no deletes; skipping
   [junit4]   2> 20963 T21 C2 oasu.LoggingInfoStream.message [BD][qtp1686223675-21]: prune sis=org.apache.lucene.index.SegmentInfos@4b936059 minGen=6 packetCount=0
   [junit4]   2> ASYNC  NEW_CORE C3 name=collection1 org.apache.solr.core.SolrCore@29ba29b3
   [junit4]   2> 21151 T21 C3 oasu.LoggingInfoStream.message [IW][qtp1686223675-21]: return reader version=3 reader=StandardDirectoryReader(segments_2:3:nrt _0(4.8):C1)
   [junit4]   2> 21151 T21 C3 oasu.LoggingInfoStream.message [DW][qtp1686223675-21]: qtp1686223675-21 finishFullFlush success=true
   [junit4]   2> 21152 T21 C3 oasu.LoggingInfoStream.message [IW][qtp1686223675-21]: getReader took 192 msec
   [junit4]   2> 21153 T21 C3 oass.SolrIndexSearcher.<init> Opening Searcher@4d169bef[collection1] main
   [junit4]   2> 21165 T21 C3 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 21165 T26 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@4d169bef[collection1] main{StandardDirectoryReader(segments_2:3:nrt _0(4.8):C1)}
   [junit4]   2> 21166 T26 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
   [junit4]   2> 21166 T26 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4d169bef[collection1] main{StandardDirectoryReader(segments_2:3:nrt _0(4.8):C1)}
   [junit4]   2> 21168 T21 C3 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={commit=true&version=2&softCommit=false&waitSearcher=true&wt=javabin} {add=[42 (1464041291804311552)],commit=} 0 934
   [junit4]   2> 21255 T19 C3 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={version=2&wt=javabin&q=????} hits=1 status=0 QTime=82 
   [junit4]   2> 21279 T13 oas.SolrTestCaseJ4.tearDown ###Ending testChineseDefaults
   [junit4]   2> 21304 T13 oas.SolrTestCaseJ4.setUp ###Starting testSetNullUpdateOrder
   [junit4]   2> 21571 T18 C3 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[testSetNullUpdateOrder (1464041292938870784)]} 0 246
   [junit4]   2> 21580 T22 C3 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 21581 T22 C3 oasu.LoggingInfoStream.message [IW][qtp1686223675-22]: commit: start
   [junit4]   2> 21582 T22 C3 oasu.LoggingInfoStream.message [IW][qtp1686223675-22]: commit: enter lock
   [junit4]   2> 21582 T22 C3 oasu.LoggingInfoStream.message [IW][qtp1686223675-22]: commit: now prepare
   [junit4]   2> 21583 T22 C3 oasu.LoggingInfoStream.message [IW][qtp1686223675-22]: prepareCommit: flush
   [junit4]   2> 21585 T22 C3 oasu.LoggingInfoStream.message [IW][qtp1686223675-22]:   index before flush _0(4.8):C1
   [junit4]   2> 21586 T22 C3 oasu.LoggingInfoStream.message [DW][qtp1686223675-22]: startFullFlush
   [junit4]   2> 21587 T22 C3 oasu.LoggingInfoStream.message [DW][qtp1686223675-22]: anyChanges? numDocsInRam=1 deletes=true hasTickets:false pendingChangesInFullFlush: false
   [junit4]   2> 21588 T22 C3 oasu.LoggingInfoStream.message [DWFC][qtp1686223675-22]: addFlushableState DocumentsWriterPerThread [pendingDeletes=gen=0, segment=_1, aborting=false, numDocsInRAM=1, deleteQueue=DWDQ: [ generation: 4 ]]
   [junit4]   2> 21588 T22 C3 oasu.LoggingInfoStream.message [DWPT][qtp1686223675-22]: flush postings as segment _1 numDocs=1
   [junit4]   2> 21592 T22 C3 oasu.LoggingInfoStream.message [DWPT][qtp1686223675-22]: new segment has 0 deleted docs
   [junit4]   2> 21593 T22 C3 oasu.LoggingInfoStream.message [DWPT][qtp1686223675-22]: new segment has no vectors; no norms; no docValues; no prox; no freqs
   [junit4]   2> 21593 T22 C3 oasu.LoggingInfoStream.message [DWPT][qtp1686223675-22]: flushedFiles=[_1.fdt, _1.fnm, _1_Lucene41_0.doc, _1.fdx, _1_Lucene41_0.tim, _1_Lucene41_0.tip]
   [junit4]   2> 21594 T22 C3 oasu.LoggingInfoStream.message [DWPT][qtp1686223675-22]: flushed codec=Lucene46
   [junit4]   2> 21595 T22 C3 oasu.LoggingInfoStream.message [DWPT][qtp1686223675-22]: flushed: segment=_1 ramUsed=0.063 MB newFlushedSize(includes docstores)=0.001 MB docs/MB=1,092.267
   [junit4]   2> 21596 T22 C3 oasu.LoggingInfoStream.message [DW][qtp1686223675-22]: publishFlushedSegment seg-private updates=null
   [junit4]   2> 21596 T22 C3 oasu.LoggingInfoStream.message [IW][qtp1686223675-22]: publishFlushedSegment
   [junit4]   2> 21596 T22 C3 oasu.LoggingInfoStream.message [BD][qtp1686223675-22]: push deletes  1 deleted terms (unique count=1) bytesUsed=1024 delGen=8 packetCount=1 totBytesUsed=1024
   [junit4]   2> 21597 T22 C3 oasu.LoggingInfoStream.message [IW][qtp1686223675-22]: publish sets newSegment delGen=9 seg=_1(4.8):C1
   [junit4]   2> 21598 T22 C3 oasu.LoggingInfoStream.message [IFD][qtp1686223675-22]: now checkpoint "_0(4.8):C1 _1(4.8):C1" [2 segments ; isCommit = false]
   [junit4]   2> 21598 T22 C3 oasu.LoggingInfoStream.message [IFD][qtp1686223675-22]: 0 msec to checkpoint
   [junit4]   2> 21599 T22 C3 oasu.LoggingInfoStream.message [IW][qtp1686223675-22]: apply all deletes during flush
   [junit4]   2> 21599 T22 C3 oasu.LoggingInfoStream.message [BD][qtp1686223675-22]: applyDeletes: infos=[_0(4.8):C1, _1(4.8):C1] packetCount=1
   [junit4]   2> 21665 T22 C3 oasu.LoggingInfoStream.message [BD][qtp1686223675-22]: seg=_0(4.8):C1 segGen=6 coalesced deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=0
   [junit4]   2> 21666 T22 C3 oasu.LoggingInfoStream.message [BD][qtp1686223675-22]: applyDeletes took 67 msec
   [junit4]   2> 21666 T22 C3 oasu.LoggingInfoStream.message [BD][qtp1686223675-22]: prune sis=org.apache.lucene.index.SegmentInfos@4b936059 minGen=10 packetCount=1
   [junit4]   2> 21666 T22 C3 oasu.LoggingInfoStream.message [BD][qtp1686223675-22]: pruneDeletes: prune 1 packets; 0 packets remain
   [junit4]   2> 21667 T22 C3 oasu.LoggingInfoStream.message [DW][qtp1686223675-22]: qtp1686223675-22 finishFullFlush success=true
   [junit4]   2> 21667 T22 C3 oasu.LoggingInfoStream.message [TMP][qtp1686223675-22]: findMerges: 2 segments
   [junit4]   2> 21672 T22 C3 oasu.LoggingInfoStream.message [TMP][qtp1686223675-22]:   seg=_1(4.8):C1 size=0.001 MB [floored]
   [junit4]   2> 21673 T22 C3 oasu.LoggingInfoStream.message [TMP][qtp1686223675-22]:   seg=_0(4.8):C1 size=0.001 MB [floored]
   [junit4]   2> 21673 T22 C3 oasu.LoggingInfoStream.message [TMP][qtp1686223675-22]:   allowedSegmentCount=1 vs count=2 (eligible count=2) tooBigCount=0
   [junit4]   2> 21674 T22 C3 oasu.LoggingInfoStream.message [CMS][qtp1686223675-22]: now merge
   [junit4]   2> 21675 T22 C3 oasu.LoggingInfoStream.message [CMS][qtp1686223675-22]:   index: _0(4.8):C1 _1(4.8):C1
   [junit4]   2> 21676 T22 C3 oasu.LoggingInfoStream.message [CMS][qtp1686223675-22]:   no more merges pending; now return
   [junit4]   2> 21676 T22 C3 oasu.LoggingInfoStream.message [IW][qtp1686223675-22]: startCommit(): start
   [junit4]   2> 21676 T22 C3 oasu.LoggingInfoStream.message [IW][qtp1686223675-22]: startCommit index=_0(4.8):C1 _1(4.8):C1 changeCount=6
   [junit4]   2> 21676 T22 C3 oasu.LoggingInfoStream.message [IW][qtp1686223675-22]: done all syncs: [_0_Lucene41_0.tim, _1.fdt, _0.fnm, _1.fdx, _1_Lucene41_0.tim, _0.fdt, _1.si, _0.fdx, _0_Lucene41_0.tip, _0.si, _0.nvd, _0.nvm, _0_Lucene41_0.pos, _0_Lucene41_0.doc, _1.fnm, _1_Lucene41_0.doc, _1_Lucene41_0.tip]
   [junit4]   2> 21676 T22 C3 oasu.LoggingInfoStream.message [IW][qtp1686223675-22]: commit: pendingCommit != null
   [junit4]   2> 21678 T22 C3 oasu.LoggingInfoStream.message [IW][qtp1686223675-22]: commit: wrote segments file "segments_3"
   [junit4]   2> 21679 T22 C3 oasu.LoggingInfoStream.message [IFD][qtp1686223675-22]: now checkpoint "_0(4.8):C1 _1(4.8):C1" [2 segments ; isCommit = true]
   [junit4]   2> 21679 T22 C3 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@7dc94405 lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1396218558289\index),segFN=segments_2,generation=2}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@7dc94405 lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1396218558289\index),segFN=segments_3,generation=3}
   [junit4]   2> 21680 T22 C3 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
   [junit4]   2> 21680 T22 C3 oasu.LoggingInfoStream.message [IFD][qtp1686223675-22]: deleteCommits: now decRef commit "segments_2"
   [junit4]   2> 21680 T22 C3 oasu.LoggingInfoStream.message [IFD][qtp1686223675-22]: delete "segments_2"
   [junit4]   2> 21681 T22 C3 oasu.LoggingInfoStream.message [IFD][qtp1686223675-22]: 2 msec to checkpoint
   [junit4]   2> 21681 T22 C3 oasu.LoggingInfoStream.message [IW][qtp1686223675-22]: commit: done
   [junit4]   2> 21683 T22 C3 oasu.LoggingInfoStream.message [DW][qtp1686223675-22]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
   [junit4]   2> 21684 T22 C3 oasu.LoggingInfoStream.message [IW][qtp1686223675-22]: nrtIsCurrent: infoVersion matches: false; DW changes: false; BD changes: false
   [junit4]   2> 21686 T22 C3 oasu.LoggingInfoStream.message [IW][qtp1686223675-22]: flush at getReader
   [junit4]   2> 21686 T22 C3 oasu.LoggingInfoStream.message [DW][qtp1686223675-22]: startFullFlush
   [junit4]   2> 21686 T22 C3 oasu.LoggingInfoStream.message [DW][qtp1686223675-22]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
   [junit4]   2> 21686 T22 C3 oasu.LoggingInfoStream.message [IW][qtp1686223675-22]: apply all deletes during flush
   [junit4]   2> 21688 T22 C3 oasu.LoggingInfoStream.message [BD][qtp1686223675-22]: applyDeletes: no deletes; skipping
   [junit4]   2> 21688 T22 C3 oasu.LoggingInfoStream.message [BD][qtp1686223675-22]: prune sis=org.apache.lucene.index.SegmentInfos@4b936059 minGen=10 packetCount=0
   [junit4]   2> 21692 T22 C3 oasu.LoggingInfoStream.message [IW][qtp1686223675-22]: return reader version=5 reader=StandardDirectoryReader(segments_3:5:nrt _0(4.8):C1 _1(4.8):C1)
   [junit4]   2> 21692 T22 C3 oasu.LoggingInfoStream.message [DW][qtp1686223675-22]: qtp1686223675-22 finishFullFlush success=true
   [junit4]   2> 21693 T22 C3 oasu.LoggingInfoStream.message [IW][qtp1686223675-22]: getReader took 7 msec
   [junit4]   2> 21699 T22 C3 oass.SolrIndexSearcher.<init> Opening Searcher@443725c6[collection1] main
   [junit4]   2> 21701 T26 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@443725c6[collection1] main{StandardDirectoryReader(segments_3:5:nrt _0(4.8):C1 _1(4.8):C1)}
   [junit4]   2> 21701 T26 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
   [junit4]   2> 21701 T26 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@443725c6[collection1] main{StandardDirectoryReader(segments_3:5:nrt _0(4.8):C1 _1(4.8):C1)}
   [junit4]   2> 21703 T22 C3 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 21703 T22 C3 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={commit=true&version=2&softCommit=false&waitSearcher=true&wt=javabin} {commit=} 0 124
   [junit4]   2> 22007 T20 C3 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[testSetNullUpdateOrder (1464041293431701504)]} 0 248
   [junit4]   2> 22012 T19 C3 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 22012 T19 C3 oasu.LoggingInfoStream.message [IW][qtp1686223675-19]: commit: start
   [junit4]   2> 22013 T19 C3 oasu.LoggingInfoStream.message [IW][qtp1686223675-19]: commit: enter lock
   [junit4]   2> 22013 T19 C3 oasu.LoggingInfoStream.message [IW][qtp1686223675-19]: commit: now prepare
   [junit4]   2> 22014 T19 C3 oasu.LoggingInfoStream.message [IW][qtp1686223675-19]: prepareCommit: flush
   [junit4]   2> 22015 T19 C3 oasu.LoggingInfoStream.message [IW][qtp1686223675-19]:   index before flush _0(4.8):C1 _1(4.8):C1
   [junit4]   2> 22015 T19 C3 oasu.LoggingInfoStream.message [DW][qtp1686223675-19]: startFullFlush
   [junit4]   2> 22016 T19 C3 oasu.LoggingInfoStream.message [DW][qtp1686223675-19]: anyChanges? numDocsInRam=1 deletes=true hasTickets:false pendingChangesInFullFlush: false
   [junit4]   2> 22016 T19 C3 oasu.LoggingInfoStream.message [DWFC][qtp1686223675-19]: addFlushableState DocumentsWriterPerThread [pendingDeletes=gen=0, segment=_2, aborting=false, numDocsInRAM=1, deleteQueue=DWDQ: [ generation: 6 ]]
   [junit4]   2> 22017 T19 C3 oasu.LoggingInfoStream.message [DWPT][qtp1686223675-19]: flush postings as segment _2 numDocs=1
   [junit4]   2> 22020 T19 C3 oasu.LoggingInfoStream.message [DWPT][qtp1686223675-19]: new segment has 0 deleted docs
   [junit4]   2> 22021 T19 C3 oasu.LoggingInfoStream.message [DWPT][qtp1686223675-19]: new segment has no vectors; no norms; no docValues; no prox; no freqs
   [junit4]   2> 22021 T19 C3 oasu.LoggingInfoStream.message [DWPT][qtp1686223675-19]: flushedFiles=[_2.fnm, _2.fdt, _2_Lucene41_0.tim, _2_Lucene41_0.doc, _2.fdx, _2_Lucene41_0.tip]
   [junit4]   2> 22022 T19 C3 oasu.LoggingInfoStream.message [DWPT][qtp1686223675-19]: flushed codec=Lucene46
   [junit4]   2> 22023 T19 C3 oasu.LoggingInfoStream.message [DWPT][qtp1686223675-19]: flushed: segment=_2 ramUsed=0.063 MB newFlushedSize(includes docstores)=0.001 MB docs/MB=1,318.964
   [junit4]   2> 22023 T19 C3 oasu.LoggingInfoStream.message [DW][qtp1686223675-19]: publishFlushedSegment seg-private updates=null
   [junit4]   2> 22024 T19 C3 oasu.LoggingInfoStream.message [IW][qtp1686223675-19]: publishFlushedSegment
   [junit4]   2> 22024 T19 C3 oasu.LoggingInfoStream.message [BD][qtp1686223675-19]: push deletes  1 deleted terms (unique count=1) bytesUsed=1024 delGen=12 packetCount=1 totBytesUsed=1024
   [junit4]   2> 22025 T19 C3 oasu.LoggingInfoStream.message [IW][qtp1686223675-19]: publish sets newSegment delGen=13 seg=_2(4.8):C1
   [junit4]   2> 22025 T19 C3 oasu.LoggingInfoStream.message [IFD][qtp1686223675-19]: now checkpoint "_0(4.8):C1 _1(4.8):C1 _2(4.8):C1" [3 segments ; isCommit = false]
   [junit4]   2> 22026 T19 C3 oasu.LoggingInfoStream.message [IFD][qtp1686223675-19]: 0 msec to checkpoint
   [junit4]   2> 22026 T19 C3 oasu.LoggingInfoStream.message [IW][qtp1686223675-19]: apply all deletes during flush
   [junit4]   2> 22027 T19 C3 oasu.LoggingInfoStream.message [BD][qtp1686223675-19]: applyDeletes: infos=[_0(4.8):C1, _1(4.8):C1, _2(4.8):C1] packetCount=1
   [junit4]   2> 22037 T19 C3 oasu.LoggingInfoStream.message [BD][qtp1686223675-19]: seg=_1(4.8):C1 segGen=10 coalesced deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=1 100% deleted
   [junit4]   2> 22037 T19 C3 oasu.LoggingInfoStream.message [BD][qtp1686223675-19]: seg=_0(4.8):C1 segGen=10 coalesced deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=0
   [junit4]   2> 22037 T19 C3 oasu.LoggingInfoStream.message [BD][qtp1686223675-19]: applyDeletes took 10 msec
   [junit4]   2> 22038 T19 C3 oasu.LoggingInfoStream.message [IFD][qtp1686223675-19]: now checkpoint "_0(4.8):C1 _1(4.8):C1/1 _2(4.8):C1" [3 segments ; isCommit = false]
   [junit4]   2> 22038 T19 C3 oasu.LoggingInfoStream.message [IFD][qtp1686223675-19]: 1 msec to checkpoint
   [junit4]   2> 22040 T19 C3 oasu.LoggingInfoStream.message [IW][qtp1686223675-19]: drop 100% deleted segments: _1(4.8):C1/1
   [junit4]   2> 22041 T19 C3 oasu.LoggingInfoStream.message [IFD][qtp1686223675-19]: now checkpoint "_0(4.8):C1 _2(4.8):C1" [2 segments ; isCommit = false]
   [junit4]   2> 22041 T19 C3 oasu.LoggingInfoStream.message [IFD][qtp1686223675-19]: 0 msec to checkpoint
   [junit4]   2> 22043 T19 C3 oasu.LoggingInfoStream.message [BD][qtp1686223675-19]: prune sis=org.apache.lucene.index.SegmentInfos@4b936059 minGen=14 packetCount=1
   [junit4]   2> 22043 T19 C3 oasu.LoggingInfoStream.message [BD][qtp1686223675-19]: pruneDeletes: prune 1 packets; 0 packets remain
   [junit4]   2> 22043 T19 C3 oasu.LoggingInfoStream.message [DW][qtp1686223675-19]: qtp1686223675-19 finishFullFlush success=true
   [junit4]   2> 22046 T19 C3 oasu.LoggingInfoStream.message [TMP][qtp1686223675-19]: findMerges: 2 segments
   [junit4]   2> 22046 T19 C3 oasu.LoggingInfoStream.message [TMP][qtp1686223675-19]:   seg=_0(4.8):C1 size=0.001 MB [floored]
   [junit4]   2> 22046 T19 C3 oasu.LoggingInfoStream.message [TMP][qtp1686223675-19]:   seg=_2(4.8):C1 size=0.001 MB [floored]
   [junit4]   2> 22046 T19 C3 oasu.LoggingInfoStream.message [TMP][qtp1686223675-19]:   allowedSegmentCount=1 vs count=2 (eligible count=2) tooBigCount=0
   [junit4]   2> 22046 T19 C3 oasu.LoggingInfoStream.message [CMS][qtp1686223675-19]: now merge
   [junit4]   2> 22046 T19 C3 oasu.LoggingInfoStream.message [CMS][qtp1686223675-19]:   index: _0(4.8):C1 _2(4.8):C1
   [junit4]   2> 22046 T19 C3 oasu.LoggingInfoStream.message [CMS][qtp1686223675-19]:   no more merges pending; now return
   [junit4]   2> 22046 T19 C3 oasu.LoggingInfoStream.message [IW][qtp1686223675-19]: startCommit(): start
   [junit4]   2> 22046 T19 C3 oasu.LoggingInfoStream.message [IW][qtp1686223675-19]: startCommit index=_0(4.8):C1 _2(4.8):C1 changeCount=11
   [junit4]   2> 22050 T19 C3 oasu.LoggingInfoStream.message [IW][qtp1686223675-19]: done all syncs: [_0_Lucene41_0.tim, _0.fnm, _2.si, _2.fdt, _2_Lucene41_0.tim, _0.fdt, _2_Lucene41_0.doc, _0.fdx, _0_Lucene41_0.tip, _0.si, _2.fdx, _0.nvd, _2_Lucene41_0.tip, _2.fnm, _0.nvm, _0_Lucene41_0.pos, _0_Lucene41_0.doc]
   [junit4]   2> ASYNC  NEW_CORE C4 name=collection1 org.apache.solr.core.SolrCore@29ba29b3
   [junit4]   2> 22063 T19 C4 oasu.LoggingInfoStream.message [IW][qtp1686223675-19]: commit: pendingCommit != null
   [junit4]   2> 22064 T19 C4 oasu.LoggingInfoStream.message [IW][qtp1686223675-19]: commit: wrote segments file "segments_4"
   [junit4]   2> 22064 T19 C4 oasu.LoggingInfoStream.message [IFD][qtp1686223675-19]: now checkpoint "_0(4.8):C1 _2(4.8):C1" [2 segments ; isCommit = true]
   [junit4]   2> 22066 T19 C4 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@7dc94405 lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1396218558289\index),segFN=segments_3,generation=3}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@7dc94405 lockFactory=NativeFSLockFactory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1396218558289\index),segFN=segments_4,generation=4}
   [junit4]   2> 22066 T19 C4 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
   [junit4]   2> 22066 T19 C4 oasu.LoggingInfoStream.message [IFD][qtp1686223675-19]: deleteCommits: now decRef commit "segments_3"
   [junit4]   2> 22066 T19 C4 oasu.LoggingInfoStream.message [IFD][qtp1686223675-19]: delete "segments_3"
   [junit4]   2> 22066 T19 C4 oasu.LoggingInfoStream.message [IFD][qtp1686223675-19]: 3 msec to checkpoint
   [junit4]   2> 22068 T19 C4 oasu.LoggingInfoStream.message [IW][qtp1686223675-19]: commit: done
   [junit4]   2> 22070 T19 C4 oasu.LoggingInfoStream.message [DW][qtp1686223675-19]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
   [junit4]   2> 22070 T19 C4 oasu.LoggingInfoStream.message [IW][qtp1686223675-19]: nrtIsCurrent: infoVersion matches: false; DW changes: false; BD changes: false
   [junit4]   2> 22070 T19 C4 oasu.LoggingInfoStream.message [IW][qtp1686223675-19]: flush at getReader
   [junit4]   2> 22070 T19 C4 oasu.LoggingInfoStream.message [DW][qtp1686223675-19]: startFullFlush
   [junit4]   2> 22070 T19 C4 oasu.LoggingInfoStream.message [DW][qtp168

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

.close closing DirectUpdateHandler2{commits=55,autocommit maxTime=15000ms,autocommits=0,soft autocommits=3,optimizes=2,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=2,cumulative_adds=120,cumulative_deletesById=5,cumulative_deletesByQuery=26,cumulative_errors=2,transaction_logs_total_size=1820,transaction_logs_total_number=10}
   [junit4]   2> 180041 T548 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
   [junit4]   2> 180041 T548 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 180041 T548 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
   [junit4]   2> 180041 T548 C312 oasu.LoggingInfoStream.message [IW][SUITE-SolrExampleJettyTest-seed#[CA47D621EF812E48]-worker]: now flush at close waitForMerges=true
   [junit4]   2> 180042 T548 C312 oasu.LoggingInfoStream.message [IW][SUITE-SolrExampleJettyTest-seed#[CA47D621EF812E48]-worker]:   start flush: applyAllDeletes=true
   [junit4]   2> 180042 T548 C312 oasu.LoggingInfoStream.message [IW][SUITE-SolrExampleJettyTest-seed#[CA47D621EF812E48]-worker]:   index before flush _z(4.8):C2 _11(4.8):C1
   [junit4]   2> 180042 T548 C312 oasu.LoggingInfoStream.message [DW][SUITE-SolrExampleJettyTest-seed#[CA47D621EF812E48]-worker]: startFullFlush
   [junit4]   2> 180042 T548 C312 oasu.LoggingInfoStream.message [DW][SUITE-SolrExampleJettyTest-seed#[CA47D621EF812E48]-worker]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
   [junit4]   2> 180042 T548 C312 oasu.LoggingInfoStream.message [DW][SUITE-SolrExampleJettyTest-seed#[CA47D621EF812E48]-worker]: SUITE-SolrExampleJettyTest-seed#[CA47D621EF812E48]-worker finishFullFlush success=true
   [junit4]   2> 180044 T548 C312 oasu.LoggingInfoStream.message [IW][SUITE-SolrExampleJettyTest-seed#[CA47D621EF812E48]-worker]: apply all deletes during flush
   [junit4]   2> 180044 T548 C312 oasu.LoggingInfoStream.message [BD][SUITE-SolrExampleJettyTest-seed#[CA47D621EF812E48]-worker]: applyDeletes: no deletes; skipping
   [junit4]   2> 180044 T548 C312 oasu.LoggingInfoStream.message [BD][SUITE-SolrExampleJettyTest-seed#[CA47D621EF812E48]-worker]: prune sis=org.apache.lucene.index.SegmentInfos@65897de7 minGen=220 packetCount=0
   [junit4]   2> 180044 T548 C312 oasu.LoggingInfoStream.message [CMS][SUITE-SolrExampleJettyTest-seed#[CA47D621EF812E48]-worker]: now merge
   [junit4]   2> 180044 T548 C312 oasu.LoggingInfoStream.message [CMS][SUITE-SolrExampleJettyTest-seed#[CA47D621EF812E48]-worker]:   index: _z(4.8):C2 _11(4.8):C1
   [junit4]   2> 180044 T548 C312 oasu.LoggingInfoStream.message [CMS][SUITE-SolrExampleJettyTest-seed#[CA47D621EF812E48]-worker]:   no more merges pending; now return
   [junit4]   2> 180044 T548 C312 oasu.LoggingInfoStream.message [IW][SUITE-SolrExampleJettyTest-seed#[CA47D621EF812E48]-worker]: waitForMerges
   [junit4]   2> 180046 T548 C312 oasu.LoggingInfoStream.message [IW][SUITE-SolrExampleJettyTest-seed#[CA47D621EF812E48]-worker]: waitForMerges done
   [junit4]   2> 180046 T548 C312 oasu.LoggingInfoStream.message [IW][SUITE-SolrExampleJettyTest-seed#[CA47D621EF812E48]-worker]: now call final commit()
   [junit4]   2> 180046 T548 C312 oasu.LoggingInfoStream.message [IW][SUITE-SolrExampleJettyTest-seed#[CA47D621EF812E48]-worker]: commit: start
   [junit4]   2> 180046 T548 C312 oasu.LoggingInfoStream.message [IW][SUITE-SolrExampleJettyTest-seed#[CA47D621EF812E48]-worker]: commit: enter lock
   [junit4]   2> 180046 T548 C312 oasu.LoggingInfoStream.message [IW][SUITE-SolrExampleJettyTest-seed#[CA47D621EF812E48]-worker]: commit: now prepare
   [junit4]   2> 180046 T548 C312 oasu.LoggingInfoStream.message [IW][SUITE-SolrExampleJettyTest-seed#[CA47D621EF812E48]-worker]: prepareCommit: flush
   [junit4]   2> 180046 T548 C312 oasu.LoggingInfoStream.message [IW][SUITE-SolrExampleJettyTest-seed#[CA47D621EF812E48]-worker]:   index before flush _z(4.8):C2 _11(4.8):C1
   [junit4]   2> 180048 T548 C312 oasu.LoggingInfoStream.message [DW][SUITE-SolrExampleJettyTest-seed#[CA47D621EF812E48]-worker]: startFullFlush
   [junit4]   2> 180048 T548 C312 oasu.LoggingInfoStream.message [DW][SUITE-SolrExampleJettyTest-seed#[CA47D621EF812E48]-worker]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
   [junit4]   2> 180048 T548 C312 oasu.LoggingInfoStream.message [IW][SUITE-SolrExampleJettyTest-seed#[CA47D621EF812E48]-worker]: apply all deletes during flush
   [junit4]   2> 180048 T548 C312 oasu.LoggingInfoStream.message [BD][SUITE-SolrExampleJettyTest-seed#[CA47D621EF812E48]-worker]: applyDeletes: no deletes; skipping
   [junit4]   2> 180048 T548 C312 oasu.LoggingInfoStream.message [BD][SUITE-SolrExampleJettyTest-seed#[CA47D621EF812E48]-worker]: prune sis=org.apache.lucene.index.SegmentInfos@65897de7 minGen=220 packetCount=0
   [junit4]   2> 180050 T548 C312 oasu.LoggingInfoStream.message [DW][SUITE-SolrExampleJettyTest-seed#[CA47D621EF812E48]-worker]: SUITE-SolrExampleJettyTest-seed#[CA47D621EF812E48]-worker finishFullFlush success=true
   [junit4]   2> 180050 T548 C312 oasu.LoggingInfoStream.message [IW][SUITE-SolrExampleJettyTest-seed#[CA47D621EF812E48]-worker]: startCommit(): start
   [junit4]   2> 180050 T548 C312 oasu.LoggingInfoStream.message [IW][SUITE-SolrExampleJettyTest-seed#[CA47D621EF812E48]-worker]:   skip startCommit(): no changes pending
   [junit4]   2> 180050 T548 C312 oasu.LoggingInfoStream.message [IW][SUITE-SolrExampleJettyTest-seed#[CA47D621EF812E48]-worker]: commit: pendingCommit == null; skip
   [junit4]   2> 180050 T548 C312 oasu.LoggingInfoStream.message [IW][SUITE-SolrExampleJettyTest-seed#[CA47D621EF812E48]-worker]: commit: done
   [junit4]   2> 180052 T548 C312 oasu.LoggingInfoStream.message [IW][SUITE-SolrExampleJettyTest-seed#[CA47D621EF812E48]-worker]: at close: _z(4.8):C2 _11(4.8):C1
   [junit4]   2> 180060 T548 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
   [junit4]   2> 180060 T548 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 180060 T548 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleJettyTest-1396218730286\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleJettyTest-1396218730286\;done=false>>]
   [junit4]   2> 180060 T548 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleJettyTest-1396218730286\
   [junit4]   2> 180060 T548 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleJettyTest-1396218730286\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleJettyTest-1396218730286\index;done=false>>]
   [junit4]   2> 180060 T548 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleJettyTest-1396218730286\index
   [junit4]   2> 180063 T548 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
   [junit4]   2> 180119 T548 oas.SolrTestCaseJ4.deleteCore ###deleteCore
   [junit4]   2> NOTE: test params are: codec=Appending, sim=DefaultSimilarity, locale=es_ES, timezone=Africa/Algiers
   [junit4]   2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.7.0_60-ea (64-bit)/cpus=2,threads=1,free=15669600,total=66191360
   [junit4]   2> NOTE: All tests run in this JVM: [FacetFieldTest, SolrExampleStreamingBinaryTest, AnlysisResponseBaseTest, TestDocumentObjectBinder, TestLBHttpSolrServer, FieldAnalysisResponseTest, LargeVolumeBinaryJettyTest, SolrExampleEmbeddedTest, TestBatchUpdate, SolrExampleXMLTest, NoOpResponseParserTest, SolrPingTest, TestUpdateRequestCodec, MultiCoreEmbeddedTest, TestHash, SolrExampleStreamingTest, SolrExceptionTest, TestNamedListCodec, ContentStreamTest, CloudSolrServerTest, TestFastInputStream, JettyWebappTest, ModifiableSolrParamsTest, MultiCoreExampleJettyTest, NamedListTest, SolrExampleBinaryTest, SolrExampleJettyTest]
   [junit4] Completed in 7.81s, 27 tests, 1 error <<< FAILURES!

[...truncated 80 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:467: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:447: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:45: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\extra-targets.xml:37: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build.xml:202: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\common-build.xml:496: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:1276: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:908: There were test failures: 49 suites, 284 tests, 4 errors, 2 failures

Total time: 93 minutes 8 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.7.0_60-ea-b10 -XX:+UseCompressedOops -XX:+UseSerialGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure