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 2013/07/15 22:01:44 UTC
[JENKINS] Lucene-Solr-4.x-Windows (64bit/jdk1.7.0_25) - Build #
2988 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Windows/2988/
Java: 64bit/jdk1.7.0_25 -XX:-UseCompressedOops -XX:+UseSerialGC
2 tests failed.
FAILED: junit.framework.TestSuite.org.apache.lucene.search.suggest.analyzing.AnalyzingInfixSuggesterTest
Error Message:
Resource in scope SUITE failed to close. Resource was registered from thread Thread[id=55, name=TEST-AnalyzingInfixSuggesterTest.testRandomMinPrefixLength-seed#[B1D21B594838883B], state=RUNNABLE, group=TGRP-AnalyzingInfixSuggesterTest], registration stack trace below.
Stack Trace:
com.carrotsearch.randomizedtesting.ResourceDisposalError: Resource in scope SUITE failed to close. Resource was registered from thread Thread[id=55, name=TEST-AnalyzingInfixSuggesterTest.testRandomMinPrefixLength-seed#[B1D21B594838883B], state=RUNNABLE, group=TGRP-AnalyzingInfixSuggesterTest], registration stack trace below.
at java.lang.Thread.getStackTrace(Thread.java:1568)
at com.carrotsearch.randomizedtesting.RandomizedContext.closeAtEnd(RandomizedContext.java:150)
at org.apache.lucene.util.LuceneTestCase.closeAfterSuite(LuceneTestCase.java:546)
at org.apache.lucene.util._TestUtil.getTempDir(_TestUtil.java:125)
at org.apache.lucene.search.suggest.analyzing.AnalyzingInfixSuggesterTest.testRandomMinPrefixLength(AnalyzingInfixSuggesterTest.java:116)
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:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
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:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
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:358)
at java.lang.Thread.run(Thread.java:724)
Caused by: java.io.IOException: Could not remove: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\build\suggest\test\J0\.\AnalyzingInfixSuggesterTest5922159233tmp
at org.apache.lucene.util.CloseableFile.close(CloseableFile.java:47)
at com.carrotsearch.randomizedtesting.RandomizedRunner$2$1.apply(RandomizedRunner.java:602)
at com.carrotsearch.randomizedtesting.RandomizedRunner$2$1.apply(RandomizedRunner.java:599)
at com.carrotsearch.randomizedtesting.RandomizedContext.closeResources(RandomizedContext.java:167)
at com.carrotsearch.randomizedtesting.RandomizedRunner$2.afterAlways(RandomizedRunner.java:615)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
... 2 more
FAILED: junit.framework.TestSuite.org.apache.lucene.search.suggest.analyzing.AnalyzingInfixSuggesterTest
Error Message:
Resource in scope SUITE failed to close. Resource was registered from thread Thread[id=55, name=TEST-AnalyzingInfixSuggesterTest.testHighlightCaseChange-seed#[B1D21B594838883B], state=RUNNABLE, group=TGRP-AnalyzingInfixSuggesterTest], registration stack trace below.
Stack Trace:
com.carrotsearch.randomizedtesting.ResourceDisposalError: Resource in scope SUITE failed to close. Resource was registered from thread Thread[id=55, name=TEST-AnalyzingInfixSuggesterTest.testHighlightCaseChange-seed#[B1D21B594838883B], state=RUNNABLE, group=TGRP-AnalyzingInfixSuggesterTest], registration stack trace below.
at java.lang.Thread.getStackTrace(Thread.java:1568)
at com.carrotsearch.randomizedtesting.RandomizedContext.closeAtEnd(RandomizedContext.java:150)
at org.apache.lucene.util.LuceneTestCase.closeAfterSuite(LuceneTestCase.java:546)
at org.apache.lucene.util._TestUtil.getTempDir(_TestUtil.java:125)
at org.apache.lucene.search.suggest.analyzing.AnalyzingInfixSuggesterTest.testHighlightCaseChange(AnalyzingInfixSuggesterTest.java:202)
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:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
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:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
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:358)
at java.lang.Thread.run(Thread.java:724)
Caused by: java.io.IOException: Could not remove: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\build\suggest\test\J0\.\AnalyzingInfixSuggesterTest5922159234tmp
at org.apache.lucene.util.CloseableFile.close(CloseableFile.java:47)
at com.carrotsearch.randomizedtesting.RandomizedRunner$2$1.apply(RandomizedRunner.java:602)
at com.carrotsearch.randomizedtesting.RandomizedRunner$2$1.apply(RandomizedRunner.java:599)
at com.carrotsearch.randomizedtesting.RandomizedContext.closeResources(RandomizedContext.java:167)
at com.carrotsearch.randomizedtesting.RandomizedRunner$2.afterAlways(RandomizedRunner.java:615)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
... 2 more
Build Log:
[...truncated 8686 lines...]
[junit4] Suite: org.apache.lucene.search.suggest.analyzing.AnalyzingInfixSuggesterTest
[junit4] 2> NOTE: test params are: codec=Lucene40, sim=DefaultSimilarity, locale=hi_IN, timezone=SystemV/HST10
[junit4] 2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.7.0_25 (64-bit)/cpus=2,threads=1,free=42474480,total=92545024
[junit4] 2> NOTE: All tests run in this JVM: [TestBytesRefArray, TestLevenshteinDistance, TestWordBreakSpellChecker, FSTCompletionTest, TestNGramDistance, AnalyzingSuggesterTest, FuzzySuggesterTest, TestDirectSpellChecker, WFSTCompletionTest, TestSpellChecker, TestSort, TestLuceneDictionary, TestPlainTextDictionary, TestTermFreqIterator, PersistenceTest, BytesRefSortersTest, TestHighFrequencyDictionary, TestJaroWinklerDistance, AnalyzingInfixSuggesterTest]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=AnalyzingInfixSuggesterTest -Dtests.seed=B1D21B594838883B -Dtests.slow=true -Dtests.locale=hi_IN -Dtests.timezone=SystemV/HST10 -Dtests.file.encoding=Cp1252
[junit4] ERROR 0.00s | AnalyzingInfixSuggesterTest (suite) <<<
[junit4] > Throwable #1: com.carrotsearch.randomizedtesting.ResourceDisposalError: Resource in scope SUITE failed to close. Resource was registered from thread Thread[id=55, name=TEST-AnalyzingInfixSuggesterTest.testRandomMinPrefixLength-seed#[B1D21B594838883B], state=RUNNABLE, group=TGRP-AnalyzingInfixSuggesterTest], registration stack trace below.
[junit4] > at java.lang.Thread.getStackTrace(Thread.java:1568)
[junit4] > at com.carrotsearch.randomizedtesting.RandomizedContext.closeAtEnd(RandomizedContext.java:150)
[junit4] > at org.apache.lucene.util.LuceneTestCase.closeAfterSuite(LuceneTestCase.java:546)
[junit4] > at org.apache.lucene.util._TestUtil.getTempDir(_TestUtil.java:125)
[junit4] > at org.apache.lucene.search.suggest.analyzing.AnalyzingInfixSuggesterTest.testRandomMinPrefixLength(AnalyzingInfixSuggesterTest.java:116)
[junit4] > at java.lang.Thread.run(Thread.java:724)
[junit4] > Caused by: java.io.IOException: Could not remove: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\build\suggest\test\J0\.\AnalyzingInfixSuggesterTest5922159233tmp
[junit4] > at org.apache.lucene.util.CloseableFile.close(CloseableFile.java:47)
[junit4] > at com.carrotsearch.randomizedtesting.RandomizedContext.closeResources(RandomizedContext.java:167)
[junit4] > ... 2 moreThrowable #2: com.carrotsearch.randomizedtesting.ResourceDisposalError: Resource in scope SUITE failed to close. Resource was registered from thread Thread[id=55, name=TEST-AnalyzingInfixSuggesterTest.testHighlightCaseChange-seed#[B1D21B594838883B], state=RUNNABLE, group=TGRP-AnalyzingInfixSuggesterTest], registration stack trace below.
[junit4] > at java.lang.Thread.getStackTrace(Thread.java:1568)
[junit4] > at com.carrotsearch.randomizedtesting.RandomizedContext.closeAtEnd(RandomizedContext.java:150)
[junit4] > at org.apache.lucene.util.LuceneTestCase.closeAfterSuite(LuceneTestCase.java:546)
[junit4] > at org.apache.lucene.util._TestUtil.getTempDir(_TestUtil.java:125)
[junit4] > at org.apache.lucene.search.suggest.analyzing.AnalyzingInfixSuggesterTest.testHighlightCaseChange(AnalyzingInfixSuggesterTest.java:202)
[junit4] > at java.lang.Thread.run(Thread.java:724)
[junit4] > Caused by: java.io.IOException: Could not remove: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\build\suggest\test\J0\.\AnalyzingInfixSuggesterTest5922159234tmp
[junit4] > at org.apache.lucene.util.CloseableFile.close(CloseableFile.java:47)
[junit4] > at com.carrotsearch.randomizedtesting.RandomizedContext.closeResources(RandomizedContext.java:167)
[junit4] > ... 2 more
[junit4] Completed in 0.84s, 7 tests, 2 errors <<< FAILURES!
[...truncated 9 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:395: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:375: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:39: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\build.xml:556: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:1904: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\module-build.xml:61: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:1250: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:893: There were test failures: 20 suites, 103 tests, 2 suite-level errors, 1 ignored (1 assumption)
Total time: 20 minutes 58 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.7.0_25 -XX:-UseCompressedOops -XX:+UseSerialGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
[JENKINS] Lucene-Solr-4.x-Windows (32bit/jdk1.7.0_25) - Build #
2989 - Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Windows/2989/
Java: 32bit/jdk1.7.0_25 -server -XX:+UseSerialGC
1 tests failed.
FAILED: org.apache.solr.client.solrj.SolrExampleBinaryTest.testUnicode
Error Message:
IOException occured when talking to server at: https://127.0.0.1:51197/solr/collection1
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: https://127.0.0.1:51197/solr/collection1
at __randomizedtesting.SeedInfo.seed([6B2FF64BB1F8567:1F7E4B6E19A55490]:0)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:435)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:180)
at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:117)
at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:68)
at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:54)
at org.apache.solr.client.solrj.SolrExampleTests.testUnicode(SolrExampleTests.java:375)
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:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
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:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
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:358)
at java.lang.Thread.run(Thread.java:724)
Caused by: org.apache.http.NoHttpResponseException: The target server failed to respond
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:95)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:62)
at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:254)
at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:289)
at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:252)
at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:191)
at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:300)
at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:127)
at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:717)
at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:522)
at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:906)
at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:805)
at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:784)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:365)
... 45 more
Build Log:
[...truncated 10632 lines...]
[junit4] Suite: org.apache.solr.client.solrj.SolrExampleBinaryTest
[junit4] 1> 180005 [SUITE-SolrExampleBinaryTest-seed#[6B2FF64BB1F8567]-worker] INFO org.apache.solr.SolrJettyTestBase ? Randomized ssl (true) and clientAuth (false)
[junit4] 1> 180006 [SUITE-SolrExampleBinaryTest-seed#[6B2FF64BB1F8567]-worker] INFO org.apache.solr.SolrTestCaseJ4 ? ####initCore
[junit4] 1> 180008 [SUITE-SolrExampleBinaryTest-seed#[6B2FF64BB1F8567]-worker] INFO org.apache.solr.SolrTestCaseJ4 ? ####initCore end
[junit4] 1> 180009 [SUITE-SolrExampleBinaryTest-seed#[6B2FF64BB1F8567]-worker] INFO org.eclipse.jetty.server.Server ? jetty-8.1.10.v20130312
[junit4] 1> 180026 [SUITE-SolrExampleBinaryTest-seed#[6B2FF64BB1F8567]-worker] INFO org.eclipse.jetty.util.ssl.SslContextFactory ? Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 1> 180035 [SUITE-SolrExampleBinaryTest-seed#[6B2FF64BB1F8567]-worker] INFO org.eclipse.jetty.server.AbstractConnector ? Started SslSelectChannelConnector@127.0.0.1:51197
[junit4] 1> 180036 [SUITE-SolrExampleBinaryTest-seed#[6B2FF64BB1F8567]-worker] INFO org.apache.solr.servlet.SolrDispatchFilter ? SolrDispatchFilter.init()
[junit4] 1> 180036 [SUITE-SolrExampleBinaryTest-seed#[6B2FF64BB1F8567]-worker] INFO org.apache.solr.core.SolrResourceLoader ? JNDI not configured for solr (NoInitialContextEx)
[junit4] 1> 180038 [SUITE-SolrExampleBinaryTest-seed#[6B2FF64BB1F8567]-worker] INFO org.apache.solr.core.SolrResourceLoader ? using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr
[junit4] 1> 180038 [SUITE-SolrExampleBinaryTest-seed#[6B2FF64BB1F8567]-worker] INFO org.apache.solr.core.SolrResourceLoader ? new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\'
[junit4] 1> 180235 [SUITE-SolrExampleBinaryTest-seed#[6B2FF64BB1F8567]-worker] INFO org.apache.solr.core.ConfigSolr ? Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\solr.xml
[junit4] 1> 180367 [SUITE-SolrExampleBinaryTest-seed#[6B2FF64BB1F8567]-worker] INFO org.apache.solr.core.ConfigSolrXml ? Config-defined core root directory:
[junit4] 1> 180367 [SUITE-SolrExampleBinaryTest-seed#[6B2FF64BB1F8567]-worker] INFO org.apache.solr.core.CoreContainer ? New CoreContainer 26963866
[junit4] 1> 180367 [SUITE-SolrExampleBinaryTest-seed#[6B2FF64BB1F8567]-worker] INFO org.apache.solr.core.CoreContainer ? Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\]
[junit4] 1> 180371 [SUITE-SolrExampleBinaryTest-seed#[6B2FF64BB1F8567]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory ? Setting socketTimeout to: 0
[junit4] 1> 180371 [SUITE-SolrExampleBinaryTest-seed#[6B2FF64BB1F8567]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory ? Setting urlScheme to: http://
[junit4] 1> 180371 [SUITE-SolrExampleBinaryTest-seed#[6B2FF64BB1F8567]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory ? Setting connTimeout to: 0
[junit4] 1> 180371 [SUITE-SolrExampleBinaryTest-seed#[6B2FF64BB1F8567]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory ? Setting maxConnectionsPerHost to: 20
[junit4] 1> 180371 [SUITE-SolrExampleBinaryTest-seed#[6B2FF64BB1F8567]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory ? Setting corePoolSize to: 0
[junit4] 1> 180372 [SUITE-SolrExampleBinaryTest-seed#[6B2FF64BB1F8567]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory ? Setting maximumPoolSize to: 2147483647
[junit4] 1> 180372 [SUITE-SolrExampleBinaryTest-seed#[6B2FF64BB1F8567]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory ? Setting maxThreadIdleTime to: 5
[junit4] 1> 180374 [SUITE-SolrExampleBinaryTest-seed#[6B2FF64BB1F8567]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory ? Setting sizeOfQueue to: -1
[junit4] 1> 180375 [SUITE-SolrExampleBinaryTest-seed#[6B2FF64BB1F8567]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory ? Setting fairnessPolicy to: false
[junit4] 1> 180375 [SUITE-SolrExampleBinaryTest-seed#[6B2FF64BB1F8567]-worker] INFO org.apache.solr.client.solrj.impl.HttpClientUtil ? Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 1> 180382 [SUITE-SolrExampleBinaryTest-seed#[6B2FF64BB1F8567]-worker] INFO org.apache.solr.logging.LogWatcher ? Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 1> 180390 [SUITE-SolrExampleBinaryTest-seed#[6B2FF64BB1F8567]-worker] INFO org.apache.solr.core.CoresLocator ? Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr
[junit4] 1> 180431 [SUITE-SolrExampleBinaryTest-seed#[6B2FF64BB1F8567]-worker] INFO org.apache.solr.core.CoresLocator ? Found core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\collection1\
[junit4] 1> 180433 [SUITE-SolrExampleBinaryTest-seed#[6B2FF64BB1F8567]-worker] INFO org.apache.solr.core.CoresLocator ? Found 1 core definitions
[junit4] 1> 180436 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.core.CoreContainer ? Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\collection1
[junit4] 1> 180438 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.core.SolrResourceLoader ? new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\collection1\'
[junit4] 1> 180478 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.core.SolrConfig ? Adding specified lib dirs to ClassLoader
[junit4] 1> 180480 [coreLoadExecutor-283-thread-1] WARN org.apache.solr.core.SolrResourceLoader ? 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] 1> 180481 [coreLoadExecutor-283-thread-1] WARN org.apache.solr.core.SolrResourceLoader ? 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] 1> 180483 [coreLoadExecutor-283-thread-1] WARN org.apache.solr.core.SolrResourceLoader ? 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] 1> 180484 [coreLoadExecutor-283-thread-1] WARN org.apache.solr.core.SolrResourceLoader ? 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] 1> 180486 [coreLoadExecutor-283-thread-1] WARN org.apache.solr.core.SolrResourceLoader ? 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] 1> 180486 [coreLoadExecutor-283-thread-1] WARN org.apache.solr.core.SolrResourceLoader ? 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] 1> 180489 [coreLoadExecutor-283-thread-1] WARN org.apache.solr.core.SolrResourceLoader ? 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] 1> 180489 [coreLoadExecutor-283-thread-1] WARN org.apache.solr.core.SolrResourceLoader ? 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] 1> 180549 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.update.SolrIndexConfig ? IndexWriter infoStream solr logging is enabled
[junit4] 1> 180556 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.core.SolrConfig ? Using Lucene MatchVersion: LUCENE_43
[junit4] 1> 180698 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.core.Config ? Loaded SolrConfig: solrconfig.xml
[junit4] 1> 180698 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.schema.IndexSchema ? Reading Solr Schema from schema.xml
[junit4] 1> 180724 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.schema.IndexSchema ? [collection1] Schema name=example
[junit4] 1> 181903 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.schema.IndexSchema ? unique key field: id
[junit4] 1> 181929 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.schema.FileExchangeRateProvider ? Reloading exchange rates from file currency.xml
[junit4] 1> 181937 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.schema.FileExchangeRateProvider ? Reloading exchange rates from file currency.xml
[junit4] 1> 181967 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.core.SolrCore ? org.apache.solr.core.MockDirectoryFactory
[junit4] 1> 181968 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.core.SolrCore ? [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-SolrExampleBinaryTest-1373947005075\
[junit4] 1> 181968 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.core.JmxMonitoredMap ? JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@f529e2
[junit4] 1> 181969 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Added SolrEventListener for newSearcher: org.apache.solr.core.QuerySenderListener{queries=[]}
[junit4] 1> 181969 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Added SolrEventListener for firstSearcher: org.apache.solr.core.QuerySenderListener{queries=[{q=static firstSearcher warming in solrconfig.xml}]}
[junit4] 1> 181969 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.core.CachingDirectoryFactory ? return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleBinaryTest-1373947005075\
[junit4] 1> 181969 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.core.SolrCore ? New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleBinaryTest-1373947005075\index/
[junit4] 1> 181969 [coreLoadExecutor-283-thread-1] WARN org.apache.solr.core.SolrCore ? [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleBinaryTest-1373947005075\index' doesn't exist. Creating new index...
[junit4] 1> 181970 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.core.CachingDirectoryFactory ? return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleBinaryTest-1373947005075\index
[junit4] 1> 181974 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_1,generation=1}
[junit4] 1> 181974 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.core.SolrCore ? newest commit generation = 1
[junit4] 1> 181977 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.core.SolrCore ? created json: solr.JSONResponseWriter
[junit4] 1> 181977 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.core.SolrCore ? adding lazy queryResponseWriter: solr.VelocityResponseWriter
[junit4] 1> 181977 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.core.SolrCore ? created velocity: solr.VelocityResponseWriter
[junit4] 1> 181978 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.core.SolrCore ? created xslt: solr.XSLTResponseWriter
[junit4] 1> 181978 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.response.XSLTResponseWriter ? xsltCacheLifetimeSeconds=5
[junit4] 1> 181983 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /select: solr.SearchHandler
[junit4] 1> 181984 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /query: solr.SearchHandler
[junit4] 1> 181984 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /get: solr.RealTimeGetHandler
[junit4] 1> 181987 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /browse: solr.SearchHandler
[junit4] 1> 181987 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /update: solr.UpdateRequestHandler
[junit4] 1> 181988 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /update/json: solr.JsonUpdateRequestHandler
[junit4] 1> 181988 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /update/csv: solr.CSVRequestHandler
[junit4] 1> 181988 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.core.RequestHandlers ? adding lazy requestHandler: solr.extraction.ExtractingRequestHandler
[junit4] 1> 181989 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /update/extract: solr.extraction.ExtractingRequestHandler
[junit4] 1> 181989 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.core.RequestHandlers ? adding lazy requestHandler: solr.FieldAnalysisRequestHandler
[junit4] 1> 181989 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /analysis/field: solr.FieldAnalysisRequestHandler
[junit4] 1> 181989 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.core.RequestHandlers ? adding lazy requestHandler: solr.DocumentAnalysisRequestHandler
[junit4] 1> 181990 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /analysis/document: solr.DocumentAnalysisRequestHandler
[junit4] 1> 181991 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /admin/: solr.admin.AdminHandlers
[junit4] 1> 181992 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /admin/ping: solr.PingRequestHandler
[junit4] 1> 181993 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /debug/dump: solr.DumpRequestHandler
[junit4] 1> 181993 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /replication: solr.ReplicationHandler
[junit4] 1> 181994 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.core.RequestHandlers ? adding lazy requestHandler: solr.SearchHandler
[junit4] 1> 181994 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /spell: solr.SearchHandler
[junit4] 1> 181994 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.core.RequestHandlers ? adding lazy requestHandler: solr.SearchHandler
[junit4] 1> 181994 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /tvrh: solr.SearchHandler
[junit4] 1> 181996 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.core.RequestHandlers ? adding lazy requestHandler: solr.SearchHandler
[junit4] 1> 181996 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /terms: solr.SearchHandler
[junit4] 1> 181997 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.core.RequestHandlers ? adding lazy requestHandler: solr.SearchHandler
[junit4] 1> 181997 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /elevate: solr.SearchHandler
[junit4] 1> 182015 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.handler.loader.XMLLoader ? xsltCacheLifetimeSeconds=60
[junit4] 1> 182016 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.handler.loader.XMLLoader ? xsltCacheLifetimeSeconds=60
[junit4] 1> 182016 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.handler.loader.XMLLoader ? xsltCacheLifetimeSeconds=60
[junit4] 1> 182032 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@970046 main
[junit4] 1> 182036 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.core.SolrCore ? Hard AutoCommit: if uncommited for 15000ms;
[junit4] 1> 182036 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.core.SolrCore ? Soft AutoCommit: disabled
[junit4] 1> 182038 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.handler.component.SpellCheckComponent ? Initializing spell checkers
[junit4] 1> 182038 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.spelling.DirectSolrSpellChecker ? 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] 1> 182039 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.handler.component.SpellCheckComponent ? No queryConverter defined, using default converter
[junit4] 1> 182041 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.handler.component.QueryElevationComponent ? Loading QueryElevation from: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\collection1\conf\elevate.xml
[junit4] 1> 182109 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.handler.ReplicationHandler ? Commits will be reserved for 10000
[junit4] 1> 182110 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@970046 main{StandardDirectoryReader(segments_1:1)}
[junit4] 1> 182112 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=null path=null params={event=firstSearcher&q=static+firstSearcher+warming+in+solrconfig.xml&distrib=false} hits=0 status=0 QTime=1
[junit4] 1> 182112 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 182112 [searcherExecutor-284-thread-1] INFO org.apache.solr.handler.component.SpellCheckComponent ? Loading spell index for spellchecker: default
[junit4] 1> 182112 [searcherExecutor-284-thread-1] INFO org.apache.solr.handler.component.SpellCheckComponent ? Loading spell index for spellchecker: wordbreak
[junit4] 1> 182115 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@970046 main{StandardDirectoryReader(segments_1:1)}
[junit4] 1> 182122 [coreLoadExecutor-283-thread-1] INFO org.apache.solr.core.CoreContainer ? registering core: collection1
[junit4] 1> 182125 [SUITE-SolrExampleBinaryTest-seed#[6B2FF64BB1F8567]-worker] INFO org.apache.solr.servlet.SolrDispatchFilter ? user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0
[junit4] 1> 182125 [SUITE-SolrExampleBinaryTest-seed#[6B2FF64BB1F8567]-worker] INFO org.apache.solr.servlet.SolrDispatchFilter ? SolrDispatchFilter.init() done
[junit4] 1> 182126 [SUITE-SolrExampleBinaryTest-seed#[6B2FF64BB1F8567]-worker] INFO org.apache.solr.SolrJettyTestBase ? Jetty Assigned Port#51197
[junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-SolrExampleBinaryTest-1373947005075
[junit4] 1> 182148 [TEST-SolrExampleBinaryTest.testUnicode-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testUnicode
[junit4] 1> 182148 [TEST-SolrExampleBinaryTest.testUnicode-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.client.solrj.impl.HttpClientUtil ? Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 1> 182241 [qtp25025730-513] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onInit: commits: num=1
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_1,generation=1}
[junit4] 1> 182244 [qtp25025730-513] INFO org.apache.solr.core.SolrCore ? newest commit generation = 1
[junit4] 1> 182246 [qtp25025730-513] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@b80f8f realtime
[junit4] 1> 182248 [qtp25025730-513] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440687857132896256)} 0 11
[junit4] 1> 198204 [commitScheduler-286-thread-1] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 198204 [commitScheduler-286-thread-1] INFO org.apache.solr.update.UpdateHandler ? No uncommitted changes. Skipping IW.commit.
[junit4] 1> 198205 [commitScheduler-286-thread-1] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 382422 [TEST-SolrExampleBinaryTest.testUnicode-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testUnicode
[junit4] 1> 382426 [qtp25025730-517] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 (1440688067042082816), 1 (1440688067043131392), 2 (1440688067044179968), 3 (1440688067044179969), 4 (1440688067044179970), 5 (1440688067046277120), 6 (1440688067046277121), 7 (1440688067046277122), 8 (1440688067046277123)]} 0 4
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SolrExampleBinaryTest -Dtests.method=testUnicode -Dtests.seed=6B2FF64BB1F8567 -Dtests.slow=true -Dtests.locale=en_PH -Dtests.timezone=Australia/Broken_Hill -Dtests.file.encoding=US-ASCII
[junit4] ERROR 200s | SolrExampleBinaryTest.testUnicode <<<
[junit4] > Throwable #1: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: https://127.0.0.1:51197/solr/collection1
[junit4] > at __randomizedtesting.SeedInfo.seed([6B2FF64BB1F8567:1F7E4B6E19A55490]:0)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:435)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:180)
[junit4] > at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:117)
[junit4] > at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:68)
[junit4] > at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:54)
[junit4] > at org.apache.solr.client.solrj.SolrExampleTests.testUnicode(SolrExampleTests.java:375)
[junit4] > at java.lang.Thread.run(Thread.java:724)
[junit4] > Caused by: org.apache.http.NoHttpResponseException: The target server failed to respond
[junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:95)
[junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:62)
[junit4] > at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:254)
[junit4] > at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:289)
[junit4] > at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:252)
[junit4] > at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:191)
[junit4] > at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:300)
[junit4] > at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:127)
[junit4] > at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:717)
[junit4] > at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:522)
[junit4] > at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:906)
[junit4] > at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:805)
[junit4] > at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:784)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:365)
[junit4] > ... 45 more
[junit4] 1> 382541 [TEST-SolrExampleBinaryTest.testQueryWithParams-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testQueryWithParams
[junit4] 1> 382557 [qtp25025730-514] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=query&debug=true&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 1> 382560 [TEST-SolrExampleBinaryTest.testQueryWithParams-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testQueryWithParams
[junit4] 1> 382570 [TEST-SolrExampleBinaryTest.testAddDelete-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testAddDelete
[junit4] 1> 382594 [qtp25025730-516] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@fa3b82 realtime
[junit4] 1> 382597 [qtp25025730-516] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440688067201466368)} 0 23
[junit4] 1> 382604 [qtp25025730-518] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 & 222 (1440688067231875072)]} 0 1
[junit4] 1> 382613 [qtp25025730-513] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 382616 [qtp25025730-513] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_1,generation=1}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_2,generation=2}
[junit4] 1> 382616 [qtp25025730-513] INFO org.apache.solr.core.SolrCore ? newest commit generation = 2
[junit4] 1> 382618 [qtp25025730-513] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@fd16fb main
[junit4] 1> 382619 [qtp25025730-513] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 382619 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@fd16fb main{StandardDirectoryReader(segments_2:7:nrt _1(4.5):C1)}
[junit4] 1> 382619 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 382621 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@fd16fb main{StandardDirectoryReader(segments_2:7:nrt _1(4.5):C1)}
[junit4] 1> 382622 [qtp25025730-513] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 9
[junit4] 1> 382627 [qtp25025730-517] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 1> 382635 [qtp25025730-515] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {delete=[0 & 222 (-1440688067262283776)]} 0 3
[junit4] 1> 382643 [qtp25025730-514] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 382643 [qtp25025730-514] INFO org.apache.solr.update.UpdateHandler ? No uncommitted changes. Skipping IW.commit.
[junit4] 1> 382643 [qtp25025730-514] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@75489a main
[junit4] 1> 382646 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@75489a main{StandardDirectoryReader(segments_2:9:nrt)}
[junit4] 1> 382646 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 382646 [qtp25025730-514] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 382648 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@75489a main{StandardDirectoryReader(segments_2:9:nrt)}
[junit4] 1> 382649 [qtp25025730-514] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 7
[junit4] 1> 382654 [qtp25025730-516] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4] 1> 382661 [qtp25025730-518] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 & 222 (1440688067289546752)]} 0 3
[junit4] 1> 382668 [qtp25025730-513] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 382672 [qtp25025730-513] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_2,generation=2}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_3,generation=3}
[junit4] 1> 382672 [qtp25025730-513] INFO org.apache.solr.core.SolrCore ? newest commit generation = 3
[junit4] 1> 382674 [qtp25025730-513] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1a87ff8 main
[junit4] 1> 382675 [qtp25025730-513] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 382677 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1a87ff8 main{StandardDirectoryReader(segments_3:11:nrt _2(4.5):C1)}
[junit4] 1> 382678 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 382679 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1a87ff8 main{StandardDirectoryReader(segments_3:11:nrt _2(4.5):C1)}
[junit4] 1> 382680 [qtp25025730-513] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 12
[junit4] 1> 382685 [qtp25025730-517] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 1> 382696 [qtp25025730-515] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@19dc74 realtime
[junit4] 1> 382697 [qtp25025730-515] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=id:"0\ \&\ 222" (-1440688067326246912)} 0 4
[junit4] 1> 382711 [qtp25025730-514] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 382711 [qtp25025730-514] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_3,generation=3}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_4,generation=4}
[junit4] 1> 382711 [qtp25025730-514] INFO org.apache.solr.core.SolrCore ? newest commit generation = 4
[junit4] 1> 382713 [qtp25025730-514] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@13d8103 main
[junit4] 1> 382713 [qtp25025730-514] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 382715 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@13d8103 main{StandardDirectoryReader(segments_3:13:nrt)}
[junit4] 1> 382715 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 382717 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@13d8103 main{StandardDirectoryReader(segments_3:13:nrt)}
[junit4] 1> 382720 [qtp25025730-514] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 9
[junit4] 1> 382726 [qtp25025730-516] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=2
[junit4] 1> 382734 [qtp25025730-518] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 & 222 (1440688067366092800)]} 0 3
[junit4] 1> 382739 [qtp25025730-513] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1 & 222 (1440688067373432832)]} 0 1
[junit4] 1> 382744 [qtp25025730-517] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2 & 222 (1440688067378675712)]} 0 1
[junit4] 1> 382752 [qtp25025730-515] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 382754 [qtp25025730-515] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_4,generation=4}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_5,generation=5}
[junit4] 1> 382754 [qtp25025730-515] INFO org.apache.solr.core.SolrCore ? newest commit generation = 5
[junit4] 1> 382755 [qtp25025730-515] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@dbaa2d main
[junit4] 1> 382756 [qtp25025730-515] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 382756 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@dbaa2d main{StandardDirectoryReader(segments_5:15:nrt _3(4.5):C3)}
[junit4] 1> 382758 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 382759 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@dbaa2d main{StandardDirectoryReader(segments_5:15:nrt _3(4.5):C3)}
[junit4] 1> 382760 [qtp25025730-515] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 8
[junit4] 1> 382765 [qtp25025730-514] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=3 status=0 QTime=0
[junit4] 1> 382770 [qtp25025730-516] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {delete=[0 & 222 (-1440688067405938688), 1 & 222 (-1440688067406987264), 2 & 222 (-1440688067406987265)]} 0 1
[junit4] 1> 382779 [qtp25025730-518] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 382779 [qtp25025730-518] INFO org.apache.solr.update.UpdateHandler ? No uncommitted changes. Skipping IW.commit.
[junit4] 1> 382780 [qtp25025730-518] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@ebb9ba main
[junit4] 1> 382782 [qtp25025730-518] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 382782 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@ebb9ba main{StandardDirectoryReader(segments_5:17:nrt)}
[junit4] 1> 382784 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 382787 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@ebb9ba main{StandardDirectoryReader(segments_5:17:nrt)}
[junit4] 1> 382788 [qtp25025730-518] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 9
[junit4] 1> 382795 [qtp25025730-513] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 1> 382797 [TEST-SolrExampleBinaryTest.testAddDelete-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testAddDelete
[junit4] 1> 382818 [TEST-SolrExampleBinaryTest.testPivotFacets-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testPivotFacets
[junit4] 1> 382825 [qtp25025730-517] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440688067463610368)} 0 1
[junit4] 1> 382839 [qtp25025730-514] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 382840 [qtp25025730-514] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_5,generation=5}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_6,generation=6}
[junit4] 1> 382840 [qtp25025730-514] INFO org.apache.solr.core.SolrCore ? newest commit generation = 6
[junit4] 1> 382840 [qtp25025730-514] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@116b42c main
[junit4] 1> 382841 [qtp25025730-514] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 382841 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@116b42c main{StandardDirectoryReader(segments_5:17:nrt)}
[junit4] 1> 382841 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 382844 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@116b42c main{StandardDirectoryReader(segments_5:17:nrt)}
[junit4] 1> 382845 [qtp25025730-514] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 6
[junit4] 1> 382850 [qtp25025730-516] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 1> 382865 [qtp25025730-518] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1 (1440688067496116224), 2 (1440688067500310528), 3 (1440688067500310529), 4 (1440688067501359104), 5 (1440688067501359105), 6 (1440688067501359106), 7 (1440688067502407680), 8 (1440688067502407681), 9 (1440688067503456256), 10 (1440688067503456257), ... (12 adds)]} 0 11
[junit4] 1> 382871 [qtp25025730-513] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 382873 [qtp25025730-513] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_6,generation=6}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_7,generation=7}
[junit4] 1> 382875 [qtp25025730-513] INFO org.apache.solr.core.SolrCore ? newest commit generation = 7
[junit4] 1> 382876 [qtp25025730-513] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@4ee13f main
[junit4] 1> 382878 [qtp25025730-513] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 382878 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@4ee13f main{StandardDirectoryReader(segments_7:19:nrt _4(4.5):C12)}
[junit4] 1> 382879 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 382882 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@4ee13f main{StandardDirectoryReader(segments_7:19:nrt _4(4.5):C12)}
[junit4] 1> 382882 [qtp25025730-513] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 12
[junit4] 1> 382887 [qtp25025730-515] INFO org.apache.solr.core.SolrCore ? UnInverted multi-valued field {field=features,memSize=4521,tindexSize=43,time=0,phase1=0,nTerms=2,bigTerms=2,termInstances=0,uses=0}
[junit4] 1> 382887 [qtp25025730-515] INFO org.apache.solr.core.SolrCore ? UnInverted multi-valued field {field=cat,memSize=4515,tindexSize=41,time=0,phase1=0,nTerms=2,bigTerms=2,termInstances=0,uses=0}
[junit4] 1> 382889 [qtp25025730-515] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={facet.missing=false&facet=true&facet.mincount=0&q=*:*&wt=javabin&facet.pivot=features,cat&facet.pivot=cat,features&facet.pivot=features,cat,inStock&version=2&rows=0} hits=12 status=0 QTime=3
[junit4] 1> 382899 [qtp25025730-517] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=0&q=*:*&wt=javabin&fq={!tag%3Dmytag}-(features:bbb+AND+cat:a+AND+inStock:true)&facet.pivot={!ex%3Dmytag+key%3Dmykey}features,cat&version=2&rows=0} hits=11 status=0 QTime=2
[junit4] 1> 382904 [TEST-SolrExampleBinaryTest.testPivotFacets-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testPivotFacets
[junit4] 1> 382914 [TEST-SolrExampleBinaryTest.testUpdateRequestWithParameters-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testUpdateRequestWithParameters
[junit4] 1> 382915 [TEST-SolrExampleBinaryTest.testUpdateRequestWithParameters-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.client.solrj.impl.HttpClientUtil ? Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 1> 382979 [qtp25025730-518] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@19d9885 realtime
[junit4] 1> 382980 [qtp25025730-518] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440688067624042496)} 0 3
[junit4] 1> 382986 [qtp25025730-513] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 382988 [qtp25025730-513] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_7,generation=7}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_8,generation=8}
[junit4] 1> 382989 [qtp25025730-513] INFO org.apache.solr.core.SolrCore ? newest commit generation = 8
[junit4] 1> 382989 [qtp25025730-513] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@15c9da5 main
[junit4] 1> 382990 [qtp25025730-513] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 382990 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@15c9da5 main{StandardDirectoryReader(segments_7:21:nrt)}
[junit4] 1> 382991 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 382994 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@15c9da5 main{StandardDirectoryReader(segments_7:21:nrt)}
[junit4] 1> 382994 [qtp25025730-513] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 8
[junit4] 1> 383000 [qtp25025730-515] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={overwrite=false&wt=javabin&version=2} {add=[id1 (1440688067645014016)]} 0 3
[junit4] 1> 383004 [qtp25025730-517] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={overwrite=false&wt=javabin&version=2} {add=[id1 (1440688067652354048)]} 0 0
[junit4] 1> 383010 [qtp25025730-514] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 383011 [qtp25025730-514] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_8,generation=8}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_9,generation=9}
[junit4] 1> 383011 [qtp25025730-514] INFO org.apache.solr.core.SolrCore ? newest commit generation = 9
[junit4] 1> 383012 [qtp25025730-514] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1cbf14f main
[junit4] 1> 383015 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1cbf14f main{StandardDirectoryReader(segments_9:23:nrt _5(4.5):C2)}
[junit4] 1> 383015 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 383015 [qtp25025730-514] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 383016 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1cbf14f main{StandardDirectoryReader(segments_9:23:nrt _5(4.5):C2)}
[junit4] 1> 383017 [qtp25025730-514] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 7
[junit4] 1> 383021 [qtp25025730-516] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=0
[junit4] 1> 383023 [TEST-SolrExampleBinaryTest.testUpdateRequestWithParameters-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testUpdateRequestWithParameters
[junit4] 1> 383043 [TEST-SolrExampleBinaryTest.testSetNullUpdates-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testSetNullUpdates
[junit4] 1> 383050 [qtp25025730-518] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[testSetNullUpdates (1440688067699539968)]} 0 1
[junit4] 1> 383066 [qtp25025730-515] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 383069 [qtp25025730-515] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_9,generation=9}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_a,generation=10}
[junit4] 1> 383070 [qtp25025730-515] INFO org.apache.solr.core.SolrCore ? newest commit generation = 10
[junit4] 1> 383070 [qtp25025730-515] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@11ef243 main
[junit4] 1> 383073 [qtp25025730-515] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 383074 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@11ef243 main{StandardDirectoryReader(segments_a:25:nrt _5(4.5):C2 _6(4.5):C1)}
[junit4] 1> 383074 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 383076 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@11ef243 main{StandardDirectoryReader(segments_a:25:nrt _5(4.5):C2 _6(4.5):C1)}
[junit4] 1> 383076 [qtp25025730-515] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 11
[junit4] 1> 383086 [qtp25025730-517] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[testSetNullUpdates (1440688067736240128)]} 0 2
[junit4] 1> 383091 [qtp25025730-514] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 383095 [qtp25025730-514] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_a,generation=10}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_b,generation=11}
[junit4] 1> 383096 [qtp25025730-514] INFO org.apache.solr.core.SolrCore ? newest commit generation = 11
[junit4] 1> 383097 [qtp25025730-514] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@fe5276 main
[junit4] 1> 383098 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@fe5276 main{StandardDirectoryReader(segments_b:29:nrt _5(4.5):C2 _7(4.5):C1)}
[junit4] 1> 383098 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 383100 [qtp25025730-514] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 383101 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@fe5276 main{StandardDirectoryReader(segments_b:29:nrt _5(4.5):C2 _7(4.5):C1)}
[junit4] 1> 383101 [qtp25025730-514] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 10
[junit4] 1> 383106 [qtp25025730-516] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=id:testSetNullUpdates&wt=javabin&version=2} hits=1 status=0 QTime=2
[junit4] 1> 383107 [TEST-SolrExampleBinaryTest.testSetNullUpdates-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testSetNullUpdates
[junit4] 1> 383118 [TEST-SolrExampleBinaryTest.testGetEmptyResults-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testGetEmptyResults
[junit4] 1> 383123 [qtp25025730-513] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1bd98a1 realtime
[junit4] 1> 383125 [qtp25025730-513] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440688067776086016)} 0 3
[junit4] 1> 383132 [qtp25025730-518] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 383132 [qtp25025730-518] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_b,generation=11}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_c,generation=12}
[junit4] 1> 383133 [qtp25025730-518] INFO org.apache.solr.core.SolrCore ? newest commit generation = 12
[junit4] 1> 383133 [qtp25025730-518] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1c359a0 main
[junit4] 1> 383135 [qtp25025730-518] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 383136 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1c359a0 main{StandardDirectoryReader(segments_b:31:nrt)}
[junit4] 1> 383136 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 383138 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1c359a0 main{StandardDirectoryReader(segments_b:31:nrt)}
[junit4] 1> 383140 [qtp25025730-518] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 8
[junit4] 1> 383148 [qtp25025730-515] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id1 (1440688067799154688)]} 0 4
[junit4] 1> 383152 [qtp25025730-517] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id2 (1440688067806494720)]} 0 1
[junit4] 1> 383159 [qtp25025730-514] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 383161 [qtp25025730-514] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_c,generation=12}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_d,generation=13}
[junit4] 1> 383162 [qtp25025730-514] INFO org.apache.solr.core.SolrCore ? newest commit generation = 13
[junit4] 1> 383164 [qtp25025730-514] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1c0bb7d main
[junit4] 1> 383164 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1c0bb7d main{StandardDirectoryReader(segments_d:33:nrt _8(4.5):C2)}
[junit4] 1> 383164 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 383165 [qtp25025730-514] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 383167 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1c0bb7d main{StandardDirectoryReader(segments_d:33:nrt _8(4.5):C2)}
[junit4] 1> 383168 [qtp25025730-514] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 11
[junit4] 1> 383173 [qtp25025730-516] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={fl=foofoofoo&q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=1
[junit4] 1> 383173 [TEST-SolrExampleBinaryTest.testGetEmptyResults-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testGetEmptyResults
[junit4] 1> 383184 [TEST-SolrExampleBinaryTest.testFaceting-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testFaceting
[junit4] 1> 383191 [qtp25025730-513] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1161c9b realtime
[junit4] 1> 383193 [qtp25025730-513] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440688067846340608)} 0 5
[junit4] 1> 383200 [qtp25025730-518] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 383200 [qtp25025730-518] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_d,generation=13}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_e,generation=14}
[junit4] 1> 383200 [qtp25025730-518] INFO org.apache.solr.core.SolrCore ? newest commit generation = 14
[junit4] 1> 383202 [qtp25025730-518] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1a4a8b6 main
[junit4] 1> 383203 [qtp25025730-518] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 383204 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1a4a8b6 main{StandardDirectoryReader(segments_d:35:nrt)}
[junit4] 1> 383204 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 383208 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1a4a8b6 main{StandardDirectoryReader(segments_d:35:nrt)}
[junit4] 1> 383209 [qtp25025730-518] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 10
[junit4] 1> 383215 [qtp25025730-515] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4] 1> 383225 [qtp25025730-517] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1 (1440688067878846464), 2 (1440688067879895040), 3 (1440688067881992192), 4 (1440688067881992193), 5 (1440688067881992194), 6 (1440688067881992195), 7 (1440688067883040768), 8 (1440688067883040769), 9 (1440688067883040770), 10 (1440688067883040771)]} 0 6
[junit4] 1> 383232 [qtp25025730-516] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 383246 [qtp25025730-516] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_e,generation=14}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_f,generation=15}
[junit4] 1> 383247 [qtp25025730-516] INFO org.apache.solr.core.SolrCore ? newest commit generation = 15
[junit4] 1> 383389 [qtp25025730-516] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@7c7388 main
[junit4] 1> 383391 [qtp25025730-516] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 383393 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@7c7388 main{StandardDirectoryReader(segments_f:37:nrt _9(4.5):C10)}
[junit4] 1> 383393 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 383395 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@7c7388 main{StandardDirectoryReader(segments_f:37:nrt _9(4.5):C10)}
[junit4] 1> 383396 [qtp25025730-516] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 164
[junit4] 1> 383402 [qtp25025730-513] INFO org.apache.solr.core.SolrCore ? UnInverted multi-valued field {field=features,memSize=4564,tindexSize=44,time=1,phase1=1,nTerms=4,bigTerms=2,termInstances=4,uses=0}
[junit4] 1> 383403 [qtp25025730-513] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=0&q=*:*&facet.field=features&wt=javabin&version=2&rows=0} hits=10 status=0 QTime=2
[junit4] 1> 383418 [qtp25025730-518] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=0&q=*:*&facet.field=features&wt=javabin&fq=features:two&version=2&rows=0} hits=5 status=0 QTime=1
[junit4] 1> 383423 [qtp25025730-515] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=4&q=*:*&facet.field=features&wt=javabin&fq=features:two&version=2&rows=0} hits=5 status=0 QTime=1
[junit4] 1> 383429 [qtp25025730-517] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=-1&q=*:*&facet.field=features&wt=javabin&fq=features:two&version=2&rows=0} hits=5 status=0 QTime=0
[junit4] 1> 383430 [TEST-SolrExampleBinaryTest.testFaceting-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testFaceting
[junit4] 1> 383454 [TEST-SolrExampleBinaryTest.testSetNullUpdateOrder-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testSetNullUpdateOrder
[junit4] 1> 383460 [qtp25025730-514] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[testSetNullUpdateOrder (1440688068127358976)]} 0 3
[junit4] 1> 383470 [qtp25025730-516] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 383473 [qtp25025730-516] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_f,generation=15}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_g,generation=16}
[junit4] 1> 383473 [qtp25025730-516] INFO org.apache.solr.core.SolrCore ? newest commit generation = 16
[junit4] 1> 383475 [qtp25025730-516] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@33849a main
[junit4] 1> 383475 [qtp25025730-516] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 383477 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@33849a main{StandardDirectoryReader(segments_g:39:nrt _9(4.5):C10 _a(4.5):C1)}
[junit4] 1> 383477 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 383480 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@33849a main{StandardDirectoryReader(segments_g:39:nrt _9(4.5):C10 _a(4.5):C1)}
[junit4] 1> 383481 [qtp25025730-516] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 13
[junit4] 1> 383487 [qtp25025730-513] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[testSetNullUpdateOrder (1440688068155670528)]} 0 3
[junit4] 1> 383496 [qtp25025730-518] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 383498 [qtp25025730-518] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_g,generation=16}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_h,generation=17}
[junit4] 1> 383498 [qtp25025730-518] INFO org.apache.solr.core.SolrCore ? newest commit generation = 17
[junit4] 1> 383500 [qtp25025730-518] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1231c3c main
[junit4] 1> 383502 [qtp25025730-518] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 383502 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1231c3c main{StandardDirectoryReader(segments_h:43:nrt _9(4.5):C10 _b(4.5):C1)}
[junit4] 1> 383502 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 383504 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1231c3c main{StandardDirectoryReader(segments_h:43:nrt _9(4.5):C10 _b(4.5):C1)}
[junit4] 1> 383505 [qtp25025730-518] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 9
[junit4] 1> 383510 [qtp25025730-515] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=id:testSetNullUpdateOrder&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 1> 383512 [TEST-SolrExampleBinaryTest.testSetNullUpdateOrder-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testSetNullUpdateOrder
[junit4] 1> 383532 [TEST-SolrExampleBinaryTest.testRealtimeGet-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testRealtimeGet
[junit4] 1> 383536 [qtp25025730-517] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@14a6abe realtime
[junit4] 1> 383539 [qtp25025730-517] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440688068209147904)} 0 4
[junit4] 1> 383543 [qtp25025730-514] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[DOCID (1440688068215439360)]} 0 2
[junit4] 1> 383562 [qtp25025730-516] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 383564 [qtp25025730-516] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_h,generation=17}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_i,generation=18}
[junit4] 1> 383564 [qtp25025730-516] INFO org.apache.solr.core.SolrCore ? newest commit generation = 18
[junit4] 1> 383566 [qtp25025730-516] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1028f08 main
[junit4] 1> 383568 [qtp25025730-516] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 383569 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1028f08 main{StandardDirectoryReader(segments_i:47:nrt _c(4.5):C1)}
[junit4] 1> 383569 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 383571 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1028f08 main{StandardDirectoryReader(segments_i:47:nrt _c(4.5):C1)}
[junit4] 1> 383573 [qtp25025730-516] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 11
[junit4] 1> 383578 [qtp25025730-513] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/get params={id=DOCID&fl=id,name,aaa:[value+v%3Daaa]&wt=javabin&qt=/get&version=2} status=0 QTime=0
[junit4] 1> 383583 [qtp25025730-518] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/get params={id=DOCID&fl=id,name,aaa:[value+v%3Daaa]&wt=xml&qt=/get&version=2.2} status=0 QTime=1
[junit4] 1> 383585 [TEST-SolrExampleBinaryTest.testRealtimeGet-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testRealtimeGet
[junit4] 1> 383615 [TEST-SolrExampleBinaryTest.testPivotFacetsMissing-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testPivotFacetsMissing
[junit4] 1> 383684 [qtp25025730-515] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@191c1fd realtime
[junit4] 1> 383685 [qtp25025730-515] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440688068363288576)} 0 3
[junit4] 1> 383692 [qtp25025730-514] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 383692 [qtp25025730-514] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_i,generation=18}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_j,generation=19}
[junit4] 1> 383694 [qtp25025730-514] INFO org.apache.solr.core.SolrCore ? newest commit generation = 19
[junit4] 1> 383694 [qtp25025730-514] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1b211c2 main
[junit4] 1> 383696 [qtp25025730-514] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 383696 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1b211c2 main{StandardDirectoryReader(segments_i:49:nrt)}
[junit4] 1> 383696 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 383698 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1b211c2 main{StandardDirectoryReader(segments_i:49:nrt)}
[junit4] 1> 383701 [qtp25025730-514] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 10
[junit4] 1> 383706 [qtp25025730-516] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 1> 383718 [qtp25025730-513] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1 (1440688068393697280), 2 (1440688068395794432), 3 (1440688068395794433), 4 (1440688068395794434), 5 (1440688068396843008), 6 (1440688068398940160), 7 (1440688068398940161), 8 (1440688068398940162), 9 (1440688068399988736), 10 (1440688068399988737), ... (12 adds)]} 0 7
[junit4] 1> 383728 [qtp25025730-518] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 383730 [qtp25025730-518] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_j,generation=19}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_k,generation=20}
[junit4] 1> 383732 [qtp25025730-518] INFO org.apache.solr.core.SolrCore ? newest commit generation = 20
[junit4] 1> 383733 [qtp25025730-518] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1dc019b main
[junit4] 1> 383735 [qtp25025730-518] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 383735 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1dc019b main{StandardDirectoryReader(segments_k:51:nrt _d(4.5):C12)}
[junit4] 1> 383735 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 383738 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1dc019b main{StandardDirectoryReader(segments_k:51:nrt _d(4.5):C12)}
[junit4] 1> 383743 [qtp25025730-518] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 16
[junit4] 1> 383749 [qtp25025730-515] INFO org.apache.solr.core.SolrCore ? UnInverted multi-valued field {field=features,memSize=4521,tindexSize=43,time=1,phase1=1,nTerms=2,bigTerms=2,termInstances=0,uses=0}
[junit4] 1> 383749 [qtp25025730-515] INFO org.apache.solr.core.SolrCore ? UnInverted multi-valued field {field=cat,memSize=4515,tindexSize=41,time=0,phase1=0,nTerms=2,bigTerms=2,termInstances=0,uses=0}
[junit4] 1> 383751 [qtp25025730-515] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={facet.missing=true&facet=true&facet.mincount=0&q=*:*&wt=javabin&facet.pivot=features,cat&facet.pivot=cat,features&facet.pivot=features,cat,inStock&version=2&rows=0} hits=12 status=0 QTime=3
[junit4] 1> 383759 [qtp25025730-517] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=0&q=*:*&wt=javabin&fq={!tag%3Dmytag}-(features:bbb+AND+cat:a+AND+inStock:true)&facet.pivot={!ex%3Dmytag+key%3Dmykey}features,cat&version=2&rows=0} hits=11 status=0 QTime=3
[junit4] 1> 383761 [TEST-SolrExampleBinaryTest.testPivotFacetsMissing-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testPivotFacetsMissing
[junit4] 1> 383785 [TEST-SolrExampleBinaryTest.testErrorHandling-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testErrorHandling
[junit4] 1> 383798 [qtp25025730-514] INFO org.apache.solr.core.SolrCore ? Ignoring exception matching ignore_exception
[junit4] 1> 383798 [qtp25025730-514] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/analysis/field params={analysis.fieldtype=int&wt=javabin&qt=/analysis/field&analysis.fieldvalue=ignore_exception&version=2} status=400 QTime=1
[junit4] 1> 383808 [qtp25025730-516] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {} 0 3
[junit4] 1> 383809 [qtp25025730-516] INFO org.apache.solr.core.SolrCore ? Ignoring exception matching ignore_exception
[junit4] 1> 383814 [qtp25025730-513] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {} 0 0
[junit4] 1> 383815 [qtp25025730-513] ERROR org.apache.solr.core.SolrCore ? org.apache.solr.common.SolrException: Document contains multiple values for uniqueKey field: id=[DOCID, DOCID2]
[junit4] 1> at org.apache.solr.update.AddUpdateCommand.getIndexedId(AddUpdateCommand.java:91)
[junit4] 1> at org.apache.solr.update.processor.DistributedUpdateProcessor.versionAdd(DistributedUpdateProcessor.java:569)
[junit4] 1> at org.apache.solr.update.processor.DistributedUpdateProcessor.processAdd(DistributedUpdateProcessor.java:435)
[junit4] 1> at org.apache.solr.update.processor.LogUpdateProcessor.processAdd(LogUpdateProcessorFactory.java:100)
[junit4] 1> at org.apache.solr.handler.loader.JavabinLoader$1.update(JavabinLoader.java:86)
[junit4] 1> at org.apache.solr.client.solrj.request.JavaBinUpdateRequestCodec$1.readOuterMostDocIterator(JavaBinUpdateRequestCodec.java:143)
[junit4] 1> at org.apache.solr.client.solrj.request.JavaBinUpdateRequestCodec$1.readIterator(JavaBinUpdateRequestCodec.java:123)
[junit4] 1> at org.apache.solr.common.util.JavaBinCodec.readVal(JavaBinCodec.java:221)
[junit4] 1> at org.apache.solr.client.solrj.request.JavaBinUpdateRequestCodec$1.readNamedList(JavaBinUpdateRequestCodec.java:108)
[junit4] 1> at org.apache.solr.common.util.JavaBinCodec.readVal(JavaBinCodec.java:186)
[junit4] 1> at org.apache.solr.common.util.JavaBinCodec.unmarshal(JavaBinCodec.java:112)
[junit4] 1> at org.apache.solr.client.solrj.request.JavaBinUpdateRequestCodec.unmarshal(JavaBinUpdateRequestCodec.java:150)
[junit4] 1> at org.apache.solr.handler.loader.JavabinLoader.parseAndLoadDocs(JavabinLoader.java:96)
[junit4] 1> at org.apache.solr.handler.loader.JavabinLoader.load(JavabinLoader.java:55)
[junit4] 1> at org.apache.solr.handler.UpdateRequestHandler$1.load(UpdateRequestHandler.java:92)
[junit4] 1> at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:74)
[junit4] 1> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:135)
[junit4] 1> at org.apache.solr.core.SolrCore.execute(SolrCore.java:1845)
[junit4] 1> at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:666)
[junit4] 1> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:369)
[junit4] 1> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:158)
[junit4] 1> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4] 1> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:136)
[junit4] 1> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4] 1> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455)
[junit4] 1> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229)
[junit4] 1> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
[junit4] 1> at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301)
[junit4] 1> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077)
[junit4] 1> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384)
[junit4] 1> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
[junit4] 1> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009)
[junit4] 1> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
[junit4] 1> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
[junit4] 1> at org.eclipse.jetty.server.Server.handle(Server.java:368)
[junit4] 1> at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489)
[junit4] 1> at org.eclipse.jetty.server.AbstractHttpConnection.content(AbstractHttpConnection.java:953)
[junit4] 1> at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.content(AbstractHttpConnection.java:1014)
[junit4] 1> at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:953)
[junit4] 1> at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:240)
[junit4] 1> at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
[junit4] 1> at org.eclipse.jetty.io.nio.SslConnection.handle(SslConnection.java:196)
[junit4] 1> at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:628)
[junit4] 1> at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52)
[junit4] 1> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
[junit4] 1> at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
[junit4] 1> at java.lang.Thread.run(Thread.java:724)
[junit4] 1>
[junit4] 1> 383818 [TEST-SolrExampleBinaryTest.testErrorHandling-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testErrorHandling
[junit4] 1> 383829 [TEST-SolrExampleBinaryTest.testStatistics-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testStatistics
[junit4] 1> 383836 [qtp25025730-518] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@c7bb40 realtime
[junit4] 1> 383837 [qtp25025730-518] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440688068522672128)} 0 5
[junit4] 1> 383842 [qtp25025730-515] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 383844 [qtp25025730-515] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_k,generation=20}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_l,generation=21}
[junit4] 1> 383844 [qtp25025730-515] INFO org.apache.solr.core.SolrCore ? newest commit generation = 21
[junit4] 1> 383845 [qtp25025730-515] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@15e7c23 main
[junit4] 1> 383846 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@15e7c23 main{StandardDirectoryReader(segments_k:53:nrt)}
[junit4] 1> 383847 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 383847 [qtp25025730-515] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 383850 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@15e7c23 main{StandardDirectoryReader(segments_k:53:nrt)}
[junit4] 1> 383851 [qtp25025730-515] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 9
[junit4] 1> 383856 [qtp25025730-517] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4] 1> 383866 [qtp25025730-514] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc0 (1440688068552032256)]} 0 5
[junit4] 1> 383872 [qtp25025730-516] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc1 (1440688068561469440)]} 0 1
[junit4] 1> 383882 [qtp25025730-513] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc2 (1440688068570906624)]} 0 3
[junit4] 1> 383890 [qtp25025730-518] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc3 (1440688068579295232)]} 0 2
[junit4] 1> 383898 [qtp25025730-515] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc4 (1440688068587683840)]} 0 3
[junit4] 1> 383905 [qtp25025730-517] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc5 (1440688068596072448)]} 0 1
[junit4] 1> 383912 [qtp25025730-514] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc6 (1440688068604461056)]} 0 1
[junit4] 1> 383921 [qtp25025730-516] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc7 (1440688068612849664)]} 0 1
[junit4] 1> 383927 [qtp25025730-513] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc8 (1440688068620189696)]} 0 0
[junit4] 1> 383936 [qtp25025730-518] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc9 (1440688068628578304)]} 0 1
[junit4] 1> 383948 [qtp25025730-515] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 384011 [qtp25025730-515] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_l,generation=21}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_m,generation=22}
[junit4] 1> 384012 [qtp25025730-515] INFO org.apache.solr.core.SolrCore ? newest commit generation = 22
[junit4] 1> 384013 [qtp25025730-515] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@fccdd6 main
[junit4] 1> 384016 [qtp25025730-515] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 384016 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@fccdd6 main{StandardDirectoryReader(segments_m:55:nrt _e(4.5):C10)}
[junit4] 1> 384016 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 384018 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@fccdd6 main{StandardDirectoryReader(segments_m:55:nrt _e(4.5):C10)}
[junit4] 1> 384019 [qtp25025730-515] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 71
[junit4] 1> 384024 [qtp25025730-517] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=0
[junit4] 1> 384030 [qtp25025730-514] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={stats.field=val_pi&stats=true&q=*:*&wt=javabin&version=2&rows=0} hits=10 status=0 QTime=1
[junit4] 1> 384038 [qtp25025730-516] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@144f245 realtime
[junit4] 1> 384039 [qtp25025730-516] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440688068735533056)} 0 2
[junit4] 1> 384046 [qtp25025730-513] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 384046 [qtp25025730-513] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_m,generation=22}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_n,generation=23}
[junit4] 1> 384048 [qtp25025730-513] INFO org.apache.solr.core.SolrCore ? newest commit generation = 23
[junit4] 1> 384048 [qtp25025730-513] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@8fd4b2 main
[junit4] 1> 384049 [qtp25025730-513] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 384050 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@8fd4b2 main{StandardDirectoryReader(segments_m:57:nrt)}
[junit4] 1> 384050 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 384053 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@8fd4b2 main{StandardDirectoryReader(segments_m:57:nrt)}
[junit4] 1> 384054 [qtp25025730-513] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 8
[junit4] 1> 384060 [qtp25025730-518] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 1> 384070 [qtp25025730-515] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc10 (1440688068763844608)]} 0 6
[junit4] 1> 384077 [qtp25025730-517] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc11 (1440688068776427520)]} 0 1
[junit4] 1> 384085 [qtp25025730-514] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc12 (1440688068785864704)]} 0 2
[junit4] 1> 384091 [qtp25025730-513] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc13 (1440688068792156160)]} 0 0
[junit4] 1> 384096 [qtp25025730-518] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc14 (1440688068797399040)]} 0 2
[junit4] 1> 384102 [qtp25025730-518] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 384104 [qtp25025730-518] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_n,generation=23}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_o,generation=24}
[junit4] 1> 384105 [qtp25025730-518] INFO org.apache.solr.core.SolrCore ? newest commit generation = 24
[junit4] 1> 384107 [qtp25025730-518] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@137c8c2 main
[junit4] 1> 384108 [qtp25025730-518] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 384109 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@137c8c2 main{StandardDirectoryReader(segments_o:59:nrt _f(4.5):C5)}
[junit4] 1> 384109 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 384112 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@137c8c2 main{StandardDirectoryReader(segments_o:59:nrt _f(4.5):C5)}
[junit4] 1> 384113 [qtp25025730-518] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 11
[junit4] 1> 384123 [qtp25025730-517] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=5 status=0 QTime=0
[junit4] 1> 384133 [qtp25025730-514] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={stats.field=val_pi&stats=true&q=*:*&wt=javabin&version=2&rows=0} hits=5 status=0 QTime=2
[junit4] 1> 384139 [qtp25025730-516] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1cbfd59 realtime
[junit4] 1> 384140 [qtp25025730-516] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440688068840390656)} 0 3
[junit4] 1> 384148 [qtp25025730-513] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 384148 [qtp25025730-513] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_o,generation=24}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_p,generation=25}
[junit4] 1> 384148 [qtp25025730-513] INFO org.apache.solr.core.SolrCore ? newest commit generation = 25
[junit4] 1> 384150 [qtp25025730-513] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@b1bf89 main
[junit4] 1> 384152 [qtp25025730-513] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 384152 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@b1bf89 main{StandardDirectoryReader(segments_o:61:nrt)}
[junit4] 1> 384152 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 384155 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@b1bf89 main{StandardDirectoryReader(segments_o:61:nrt)}
[junit4] 1> 384156 [qtp25025730-513] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 8
[junit4] 1> 384161 [qtp25025730-515] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4] 1> 384167 [qtp25025730-518] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc0 (1440688068870799360)]} 0 1
[junit4] 1> 384172 [qtp25025730-517] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc1 (1440688068877090816)]} 0 0
[junit4] 1> 384177 [qtp25025730-514] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc2 (1440688068882333696)]} 0 0
[junit4] 1> 384182 [qtp25025730-516] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc3 (1440688068887576576)]} 0 0
[junit4] 1> 384187 [qtp25025730-513] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc4 (1440688068890722304)]} 0 2
[junit4] 1> 384190 [qtp25025730-515] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc5 (1440688068894916608)]} 0 1
[junit4] 1> 384195 [qtp25025730-518] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc6 (1440688068901208064)]} 0 0
[junit4] 1> 384200 [qtp25025730-517] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc7 (1440688068905402368)]} 0 1
[junit4] 1> 384205 [qtp25025730-514] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc8 (1440688068911693824)]} 0 1
[junit4] 1> 384210 [qtp25025730-516] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc9 (1440688068915888128)]} 0 1
[junit4] 1> 384219 [qtp25025730-513] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 384222 [qtp25025730-513] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_p,generation=25}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_q,generation=26}
[junit4] 1> 384222 [qtp25025730-513] INFO org.apache.solr.core.SolrCore ? newest commit generation = 26
[junit4] 1> 384224 [qtp25025730-513] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@ee62c4 main
[junit4] 1> 384226 [qtp25025730-513] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 384226 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@ee62c4 main{StandardDirectoryReader(segments_q:63:nrt _g(4.5):C10)}
[junit4] 1> 384226 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 384228 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@ee62c4 main{StandardDirectoryReader(segments_q:63:nrt _g(4.5):C10)}
[junit4] 1> 384230 [qtp25025730-513] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 13
[junit4] 1> 384236 [qtp25025730-515] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=inStock:true&wt=javabin&version=2} hits=5 status=0 QTime=1
[junit4] 1> 384241 [qtp25025730-518] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=inStock:false&wt=javabin&version=2} hits=5 status=0 QTime=2
[junit4] 1> 384291 [qtp25025730-517] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={f.val_pi.stats.facet=inStock&stats.field=val_pi&stats=true&q=*:*&wt=javabin&version=2&rows=0} hits=10 status=0 QTime=45
[junit4] 1> 384294 [TEST-SolrExampleBinaryTest.testStatistics-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testStatistics
[junit4] 1> 384312 [TEST-SolrExampleBinaryTest.testAugmentFields-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testAugmentFields
[junit4] 1> 384318 [qtp25025730-514] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1e47b48 realtime
[junit4] 1> 384319 [qtp25025730-514] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440688069029134336)} 0 2
[junit4] 1> 384326 [qtp25025730-516] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[111 (1440688069036474368)]} 0 2
[junit4] 1> 384333 [qtp25025730-513] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 384334 [qtp25025730-513] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_q,generation=26}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_r,generation=27}
[junit4] 1> 384337 [qtp25025730-513] INFO org.apache.solr.core.SolrCore ? newest commit generation = 27
[junit4] 1> 384337 [qtp25025730-513] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@6d2f39 main
[junit4] 1> 384339 [qtp25025730-513] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 384340 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@6d2f39 main{StandardDirectoryReader(segments_r:67:nrt _h(4.5):C1)}
[junit4] 1> 384340 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 384343 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@6d2f39 main{StandardDirectoryReader(segments_r:67:nrt _h(4.5):C1)}
[junit4] 1> 384344 [qtp25025730-513] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 12
[junit4] 1> 384353 [qtp25025730-515] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[222 (1440688069063737344)]} 0 3
[junit4] 1> 384361 [qtp25025730-518] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 384371 [qtp25025730-518] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_r,generation=27}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_s,generation=28}
[junit4] 1> 384371 [qtp25025730-518] INFO org.apache.solr.core.SolrCore ? newest commit generation = 28
[junit4] 1> 384372 [qtp25025730-518] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1801cf2 main
[junit4] 1> 384375 [qtp25025730-518] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 384375 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1801cf2 main{StandardDirectoryReader(segments_s:69:nrt _h(4.5):C1 _i(4.5):C1)}
[junit4] 1> 384375 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 384439 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1801cf2 main{StandardDirectoryReader(segments_s:69:nrt _h(4.5):C1 _i(4.5):C1)}
[junit4] 1> 384439 [qtp25025730-518] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 78
[junit4] 1> 384445 [qtp25025730-517] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={sort=price+asc&fl=id,price,[docid],[explain+style%3Dnl],score,aaa:[value+v%3Daaa],ten:[value+v%3D10+t%3Dint]&q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=1
[junit4] 1> 384446 [TEST-SolrExampleBinaryTest.testAugmentFields-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testAugmentFields
[junit4] 1> 384466 [TEST-SolrExampleBinaryTest.testLukeHandler-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testLukeHandler
[junit4] 1> 384479 [qtp25025730-514] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@170e732 realtime
[junit4] 1> 384481 [qtp25025730-514] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440688069196906496)} 0 4
[junit4] 1> 384485 [qtp25025730-516] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID0 (1440688069204246528)]} 0 1
[junit4] 1> 384492 [qtp25025730-515] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID1 (1440688069210537984)]} 0 2
[junit4] 1> 384496 [qtp25025730-518] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID2 (1440688069216829440)]} 0 0
[junit4] 1> 384500 [qtp25025730-517] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID3 (1440688069221023744)]} 0 0
[junit4] 1> 384505 [qtp25025730-514] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID4 (1440688069226266624)]} 0 0
[junit4] 1> 384512 [qtp25025730-513] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 384515 [qtp25025730-513] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_s,generation=28}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_t,generation=29}
[junit4] 1> 384515 [qtp25025730-513] INFO org.apache.solr.core.SolrCore ? newest commit generation = 29
[junit4] 1> 384517 [qtp25025730-513] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@10ebd93 main
[junit4] 1> 384519 [qtp25025730-513] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 384520 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@10ebd93 main{StandardDirectoryReader(segments_t:73:nrt _j(4.5):C5)}
[junit4] 1> 384520 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 384522 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@10ebd93 main{StandardDirectoryReader(segments_t:73:nrt _j(4.5):C5)}
[junit4] 1> 384524 [qtp25025730-513] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 12
[junit4] 1> 384528 [qtp25025730-516] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=5 status=0 QTime=1
[junit4] 1> 384535 [qtp25025730-515] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/admin/luke params={wt=javabin&version=2} status=0 QTime=0
[junit4] 1> 384541 [qtp25025730-518] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/admin/luke params={show=schema&wt=javabin&version=2} status=0 QTime=1
[junit4] 1> 384556 [TEST-SolrExampleBinaryTest.testLukeHandler-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testLukeHandler
[junit4] 1> 384578 [TEST-SolrExampleBinaryTest.testChineseDefaults-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testChineseDefaults
[junit4] 1> 384585 [qtp25025730-517] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@15510de realtime
[junit4] 1> 384586 [qtp25025730-517] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440688069308055552)} 0 5
[junit4] 1> 384606 [qtp25025730-514] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 384606 [qtp25025730-514] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_t,generation=29}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_u,generation=30}
[junit4] 1> 384606 [qtp25025730-514] INFO org.apache.solr.core.SolrCore ? newest commit generation = 30
[junit4] 1> 384607 [qtp25025730-514] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@10343fe main
[junit4] 1> 384611 [qtp25025730-514] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 384611 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@10343fe main{StandardDirectoryReader(segments_t:75:nrt)}
[junit4] 1> 384611 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 384613 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@10343fe main{StandardDirectoryReader(segments_t:75:nrt)}
[junit4] 1> 384615 [qtp25025730-514] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 9
[junit4] 1> 384618 [qtp25025730-513] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 1> 384626 [qtp25025730-516] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 384629 [qtp25025730-516] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_u,generation=30}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_v,generation=31}
[junit4] 1> 384631 [qtp25025730-516] INFO org.apache.solr.core.SolrCore ? newest commit generation = 31
[junit4] 1> 384631 [qtp25025730-516] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@868ff8 main
[junit4] 1> 384632 [qtp25025730-516] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 384633 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@868ff8 main{StandardDirectoryReader(segments_v:77:nrt _k(4.5):C1)}
[junit4] 1> 384633 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 384635 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@868ff8 main{StandardDirectoryReader(segments_v:77:nrt _k(4.5):C1)}
[junit4] 1> 384637 [qtp25025730-516] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {add=[42 (1440688069351047168)],commit=} 0 13
[junit4] 1> 384643 [qtp25025730-515] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=????&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4] 1> 384644 [TEST-SolrExampleBinaryTest.testChineseDefaults-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testChineseDefaults
[junit4] 1> 384665 [TEST-SolrExampleBinaryTest.testCommitWithinOnAdd-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testCommitWithinOnAdd
[junit4] 1> 384671 [qtp25025730-518] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@18c719b realtime
[junit4] 1> 384671 [qtp25025730-518] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440688069399281664)} 0 2
[junit4] 1> 384693 [qtp25025730-517] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 384694 [qtp25025730-517] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_v,generation=31}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_w,generation=32}
[junit4] 1> 384694 [qtp25025730-517] INFO org.apache.solr.core.SolrCore ? newest commit generation = 32
[junit4] 1> 384696 [qtp25025730-517] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@34f73 main
[junit4] 1> 384698 [qtp25025730-517] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 384698 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@34f73 main{StandardDirectoryReader(segments_v:79:nrt)}
[junit4] 1> 384699 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 384701 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@34f73 main{StandardDirectoryReader(segments_v:79:nrt)}
[junit4] 1> 384702 [qtp25025730-517] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 9
[junit4] 1> 384707 [qtp25025730-514] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 1> 384714 [qtp25025730-513] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id3 (1440688069441224704)]} 0 4
[junit4] 1> 384719 [qtp25025730-516] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 1> 385343 [commitScheduler-287-thread-1] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 1> 385346 [commitScheduler-287-thread-1] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@df153a main
[junit4] 1> 385346 [commitScheduler-287-thread-1] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 385346 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@df153a main{StandardDirectoryReader(segments_w:81:nrt _l(4.5):C1)}
[junit4] 1> 385347 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 385350 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@df153a main{StandardDirectoryReader(segments_w:81:nrt _l(4.5):C1)}
[junit4] 1> 385983 [qtp25025730-517] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4] 1> 385988 [qtp25025730-514] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id4 (1440688070780256256)]} 0 1
[junit4] 1> 386616 [commitScheduler-287-thread-1] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 1> 386624 [commitScheduler-287-thread-1] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@8504ba main
[junit4] 1> 386625 [commitScheduler-287-thread-1] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 386625 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@8504ba main{StandardDirectoryReader(segments_w:83:nrt _l(4.5):C1 _m(4.5):C1)}
[junit4] 1> 386626 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 386629 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@8504ba main{StandardDirectoryReader(segments_w:83:nrt _l(4.5):C1 _m(4.5):C1)}
[junit4] 1> 387252 [qtp25025730-515] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=id:id4&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4] 1> 387253 [TEST-SolrExampleBinaryTest.testCommitWithinOnAdd-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testCommitWithinOnAdd
[junit4] 1> 387265 [TEST-SolrExampleBinaryTest.testContentStreamRequest-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testContentStreamRequest
[junit4] 1> 387269 [qtp25025730-518] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@2d638b realtime
[junit4] 1> 387269 [qtp25025730-518] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440688072122433536)} 0 2
[junit4] 1> 387276 [qtp25025730-517] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 387276 [qtp25025730-517] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_w,generation=32}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_x,generation=33}
[junit4] 1> 387277 [qtp25025730-517] INFO org.apache.solr.core.SolrCore ? newest commit generation = 33
[junit4] 1> 387277 [qtp25025730-517] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1aacada main
[junit4] 1> 387278 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1aacada main{StandardDirectoryReader(segments_w:85:nrt)}
[junit4] 1> 387278 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 387282 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1aacada main{StandardDirectoryReader(segments_w:85:nrt)}
[junit4] 1> 387278 [qtp25025730-517] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 387287 [qtp25025730-517] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 11
[junit4] 1> 387290 [qtp25025730-514] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 1> 387331 [qtp25025730-513] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 387336 [qtp25025730-513] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_x,generation=33}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_y,generation=34}
[junit4] 1> 387336 [qtp25025730-513] INFO org.apache.solr.core.SolrCore ? newest commit generation = 34
[junit4] 1> 387338 [qtp25025730-513] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1a351ca main
[junit4] 1> 387338 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1a351ca main{StandardDirectoryReader(segments_y:87:nrt _n(4.5):C10)}
[junit4] 1> 387338 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 387340 [qtp25025730-513] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 387341 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1a351ca main{StandardDirectoryReader(segments_y:87:nrt _n(4.5):C10)}
[junit4] 1> 387342 [qtp25025730-513] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {add=[0553573403 (1440688072160182272), 0553579908 (1440688072178008064), 055357342X (1440688072180105216), 0553293354 (1440688072182202368), 0812521390 (1440688072183250944), 0812550706 (1440688072184299520), 0441385532 (1440688072184299521), 0380014300 (1440688072185348096), 0805080481 (1440688072186396672), 080508049X (1440688072188493824)],commit=} 0 41
[junit4] 1> 387347 [qtp25025730-516] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=1
[junit4] 1> 387348 [TEST-SolrExampleBinaryTest.testContentStreamRequest-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testContentStreamRequest
[junit4] 1> 387362 [TEST-SolrExampleBinaryTest.testExampleConfig-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testExampleConfig
[junit4] 1> 387365 [qtp25025730-515] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@15e1d40 realtime
[junit4] 1> 387368 [qtp25025730-515] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440688072224145408)} 0 4
[junit4] 1> 387373 [qtp25025730-518] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1112211111 (1440688072232534016)]} 0 1
[junit4] 1> 387379 [qtp25025730-517] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 387381 [qtp25025730-517] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_y,generation=34}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_z,generation=35}
[junit4] 1> 387381 [qtp25025730-517] INFO org.apache.solr.core.SolrCore ? newest commit generation = 35
[junit4] 1> 387383 [qtp25025730-517] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@84bd02 main
[junit4] 1> 387384 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@84bd02 main{StandardDirectoryReader(segments_z:91:nrt _o(4.5):C1)}
[junit4] 1> 387384 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 387385 [qtp25025730-517] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 387386 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@84bd02 main{StandardDirectoryReader(segments_z:91:nrt _o(4.5):C1)}
[junit4] 1> 387388 [qtp25025730-517] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 10
[junit4] 1> 387392 [qtp25025730-514] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 387397 [qtp25025730-514] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_z,generation=35}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_10,generation=36}
[junit4] 1> 387397 [qtp25025730-514] INFO org.apache.solr.core.SolrCore ? newest commit generation = 36
[junit4] 1> 387400 [qtp25025730-514] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1e466a0 main
[junit4] 1> 387400 [qtp25025730-514] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 387401 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1e466a0 main{StandardDirectoryReader(segments_10:93:nrt _p(4.5):C1)}
[junit4] 1> 387401 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 387404 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1e466a0 main{StandardDirectoryReader(segments_10:93:nrt _p(4.5):C1)}
[junit4] 1> 387404 [qtp25025730-514] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={optimize=true&waitSearcher=true&maxSegments=1&wt=javabin&version=2} {optimize=} 0 12
[junit4] 1> 387407 [qtp25025730-516] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=id:1112211111&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 1> 387417 [qtp25025730-515] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2 (1440688072274477056), 3 (1440688072277622784), 4 (1440688072278671360), 5 (1440688072278671361)]} 0 5
[junit4] 1> 387423 [qtp25025730-518] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 387426 [qtp25025730-518] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_10,generation=36}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_11,generation=37}
[junit4] 1> 387426 [qtp25025730-518] INFO org.apache.solr.core.SolrCore ? newest commit generation = 37
[junit4] 1> 387427 [qtp25025730-518] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@bdcbe5 main
[junit4] 1> 387429 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@bdcbe5 main{StandardDirectoryReader(segments_11:95:nrt _p(4.5):C1 _q(4.5):C4)}
[junit4] 1> 387429 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 387429 [qtp25025730-518] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 387431 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@bdcbe5 main{StandardDirectoryReader(segments_11:95:nrt _p(4.5):C1 _q(4.5):C4)}
[junit4] 1> 387432 [qtp25025730-518] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 10
[junit4] 1> 387438 [qtp25025730-517] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 387444 [qtp25025730-517] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_11,generation=37}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_12,generation=38}
[junit4] 1> 387444 [qtp25025730-517] INFO org.apache.solr.core.SolrCore ? newest commit generation = 38
[junit4] 1> 387445 [qtp25025730-517] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1ee34a0 main
[junit4] 1> 387447 [qtp25025730-517] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 387447 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1ee34a0 main{StandardDirectoryReader(segments_12:97:nrt _r(4.5):C5)}
[junit4] 1> 387448 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 387449 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1ee34a0 main{StandardDirectoryReader(segments_12:97:nrt _r(4.5):C5)}
[junit4] 1> 387450 [qtp25025730-517] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={optimize=true&waitSearcher=true&maxSegments=1&wt=javabin&version=2} {optimize=} 0 13
[junit4] 1> 387458 [qtp25025730-514] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={facet=true&facet.query=price:[*+TO+2]&facet.query=price:[2+TO+4]&facet.query=price:[5+TO+*]&q=*:*&facet.field=inStock&facet.field=price&facet.field=timestamp_dt&wt=javabin&version=2} hits=5 status=0 QTime=4
[junit4] 1> 387463 [qtp25025730-513] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={facet=true&facet.query=price:[*+TO+2]&facet.query=price:[2+TO+4]&facet.query=price:[5+TO+*]&q=*:*&facet.field=inStock&facet.field=price&facet.field=timestamp_dt&wt=javabin&fq=inStock:true&version=2} hits=2 status=0 QTime=2
[junit4] 1> 387469 [qtp25025730-516] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={facet=true&facet.query=price:[*+TO+2]&facet.query=price:[2+TO+4]&q=foo&wt=javabin&fq={!field+f%3DinStock}true&fq={!term+f%3Dname}hoss&version=2} hits=0 status=0 QTime=2
[junit4] 1> 387470 [TEST-SolrExampleBinaryTest.testExampleConfig-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.client.solrj.impl.HttpClientUtil ? Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 1> 387534 [qtp25025730-517] INFO org.apache.solr.servlet.SolrDispatchFilter ? [admin] webapp=null path=/admin/info/system params={wt=javabin&qt=/admin/info/system&version=2} status=0 QTime=1
[junit4] 1> 387538 [TEST-SolrExampleBinaryTest.testExampleConfig-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testExampleConfig
[junit4] 1> 387551 [TEST-SolrExampleBinaryTest.testPingHandler-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testPingHandler
[junit4] 1> 387558 [qtp25025730-514] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@85643b realtime
[junit4] 1> 387558 [qtp25025730-514] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440688072424423424)} 0 3
[junit4] 1> 387572 [qtp25025730-513] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 387573 [qtp25025730-513] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_12,generation=38}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_13,generation=39}
[junit4] 1> 387574 [qtp25025730-513] INFO org.apache.solr.core.SolrCore ? newest commit generation = 39
[junit4] 1> 387574 [qtp25025730-513] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1e3e9da main
[junit4] 1> 387577 [qtp25025730-513] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 387578 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1e3e9da main{StandardDirectoryReader(segments_12:99:nrt)}
[junit4] 1> 387578 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 387582 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1e3e9da main{StandardDirectoryReader(segments_12:99:nrt)}
[junit4] 1> 387583 [qtp25025730-513] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 11
[junit4] 1> 387588 [qtp25025730-516] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4] 1> 387593 [qtp25025730-515] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/admin/ping params={wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4] 1> 387593 [qtp25025730-515] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/admin/ping params={wt=javabin&version=2} status=0 QTime=1
[junit4] 1> 387595 [TEST-SolrExampleBinaryTest.testPingHandler-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testPingHandler
[junit4] 1> 387617 [TEST-SolrExampleBinaryTest.testAddRetrieve-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testAddRetrieve
[junit4] 1> 387624 [qtp25025730-518] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440688072493629440)} 0 4
[junit4] 1> 387631 [qtp25025730-517] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id1 (1440688072503066624), id2 (1440688072504115200)]} 0 2
[junit4] 1> 387642 [qtp25025730-514] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 387662 [qtp25025730-514] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_13,generation=39}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_14,generation=40}
[junit4] 1> 387663 [qtp25025730-514] INFO org.apache.solr.core.SolrCore ? newest commit generation = 40
[junit4] 1> 387665 [qtp25025730-514] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@11ed3ad main
[junit4] 1> 387668 [qtp25025730-514] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 387669 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@11ed3ad main{StandardDirectoryReader(segments_14:101:nrt _s(4.5):C2)}
[junit4] 1> 387669 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 387673 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@11ed3ad main{StandardDirectoryReader(segments_14:101:nrt _s(4.5):C2)}
[junit4] 1> 387674 [qtp25025730-514] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 32
[junit4] 1> 387680 [qtp25025730-513] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={sort=price+asc&q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=1
[junit4] 1> 387689 [qtp25025730-516] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id1 (1440688072560738304), id2 (1440688072564932608)]} 0 4
[junit4] 1> 387697 [qtp25025730-515] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 387705 [qtp25025730-515] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_14,generation=40}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_15,generation=41}
[junit4] 1> 387706 [qtp25025730-515] INFO org.apache.solr.core.SolrCore ? newest commit generation = 41
[junit4] 1> 387708 [qtp25025730-515] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@19ab287 main
[junit4] 1> 387711 [qtp25025730-515] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 387712 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@19ab287 main{StandardDirectoryReader(segments_15:105:nrt _t(4.5):C2)}
[junit4] 1> 387712 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 387716 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@19ab287 main{StandardDirectoryReader(segments_15:105:nrt _t(4.5):C2)}
[junit4] 1> 387717 [qtp25025730-515] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 21
[junit4] 1> 387724 [qtp25025730-518] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={sort=price+asc&q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=1
[junit4] 1> 387732 [qtp25025730-517] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={sort=price+asc&q=name:h?llo&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4] 1> 387734 [TEST-SolrExampleBinaryTest.testAddRetrieve-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testAddRetrieve
[junit4] 1> 387753 [TEST-SolrExampleBinaryTest.testMultiContentStreamRequest-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testMultiContentStreamRequest
[junit4] 1> 387766 [qtp25025730-513] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1f11a11 realtime
[junit4] 1> 387767 [qtp25025730-513] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440688072634138624)} 0 12
[junit4] 1> 387774 [qtp25025730-516] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 387775 [qtp25025730-516] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_15,generation=41}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_16,generation=42}
[junit4] 1> 387776 [qtp25025730-516] INFO org.apache.solr.core.SolrCore ? newest commit generation = 42
[junit4] 1> 387776 [qtp25025730-516] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@15f470a main
[junit4] 1> 387777 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@15f470a main{StandardDirectoryReader(segments_15:107:nrt)}
[junit4] 1> 387777 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 387777 [qtp25025730-516] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 387781 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@15f470a main{StandardDirectoryReader(segments_15:107:nrt)}
[junit4] 1> 387782 [qtp25025730-516] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 8
[junit4] 1> 387787 [qtp25025730-515] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4] 1> 387812 [qtp25025730-518] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 387818 [qtp25025730-518] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_16,generation=42}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_17,generation=43}
[junit4] 1> 387818 [qtp25025730-518] INFO org.apache.solr.core.SolrCore ? newest commit generation = 43
[junit4] 1> 387820 [qtp25025730-518] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1d7b102 main
[junit4] 1> 387822 [qtp25025730-518] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 387823 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1d7b102 main{StandardDirectoryReader(segments_17:109:nrt _u(4.5):C5)}
[junit4] 1> 387823 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 387825 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1d7b102 main{StandardDirectoryReader(segments_17:109:nrt _u(4.5):C5)}
[junit4] 1> 387827 [qtp25025730-518] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&echoParams=ALL&commit=true&a=?&wt=javabin&version=2&softCommit=false} {add=[SP2514N (1440688072682373120), 6H500F0 (1440688072685518848), TWINX2048-3200PRO (1440688072687616000), VS1GB400C3 (1440688072689713152), VDBDB1A16 (1440688072692858880)],commit=} 0 28
[junit4] 1> 387831 [qtp25025730-514] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=5 status=0 QTime=1
[junit4] 1> 387834 [TEST-SolrExampleBinaryTest.testMultiContentStreamRequest-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testMultiContentStreamRequest
[junit4] 1> 387861 [TEST-SolrExampleBinaryTest.testCommitWithinOnDelete-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testCommitWithinOnDelete
[junit4] 1> 387867 [qtp25025730-513] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@93f39f realtime
[junit4] 1> 387868 [qtp25025730-513] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440688072750530560)} 0 2
[junit4] 1> 387876 [qtp25025730-516] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 387876 [qtp25025730-516] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_17,generation=43}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_18,generation=44}
[junit4] 1> 387876 [qtp25025730-516] INFO org.apache.solr.core.SolrCore ? newest commit generation = 44
[junit4] 1> 387877 [qtp25025730-516] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@bacdd3 main
[junit4] 1> 387879 [qtp25025730-516] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 387879 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@bacdd3 main{StandardDirectoryReader(segments_17:111:nrt)}
[junit4] 1> 387881 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 387882 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@bacdd3 main{StandardDirectoryReader(segments_17:111:nrt)}
[junit4] 1> 387883 [qtp25025730-516] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 7
[junit4] 1> 387888 [qtp25025730-515] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 1> 387894 [qtp25025730-518] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id3 (1440688072778842112)]} 0 1
[junit4] 1> 387903 [qtp25025730-517] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 387905 [qtp25025730-517] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_18,generation=44}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_19,generation=45}
[junit4] 1> 387906 [qtp25025730-517] INFO org.apache.solr.core.SolrCore ? newest commit generation = 45
[junit4] 1> 387908 [qtp25025730-517] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@10a11e main
[junit4] 1> 387909 [qtp25025730-517] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 387910 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@10a11e main{StandardDirectoryReader(segments_19:113:nrt _v(4.5):C1)}
[junit4] 1> 387910 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 387913 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@10a11e main{StandardDirectoryReader(segments_19:113:nrt _v(4.5):C1)}
[junit4] 1> 387913 [qtp25025730-517] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 10
[junit4] 1> 387919 [qtp25025730-514] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4] 1> 387925 [qtp25025730-513] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {delete=[id3 (-1440688072811347968)]} 0 1
[junit4] 1> 387930 [qtp25025730-516] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 1> 388228 [qtp25025730-515] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 1> 388517 [qtp25025730-517] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4] 1> 388806 [qtp25025730-514] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4] 1> 389068 [commitScheduler-287-thread-1] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 1> 389069 [commitScheduler-287-thread-1] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1ff78f0 main
[junit4] 1> 389069 [commitScheduler-287-thread-1] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 389069 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1ff78f0 main{StandardDirectoryReader(segments_19:115:nrt)}
[junit4] 1> 389069 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 389071 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1ff78f0 main{StandardDirectoryReader(segments_19:115:nrt)}
[junit4] 1> 389095 [qtp25025730-516] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 1> 389096 [TEST-SolrExampleBinaryTest.testCommitWithinOnDelete-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testCommitWithinOnDelete
[junit4] 1> 389117 [TEST-SolrExampleBinaryTest.testStreamingRequest-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testStreamingRequest
[junit4] 1> 389122 [qtp25025730-515] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440688074066493440)} 0 1
[junit4] 1> 389132 [qtp25025730-518] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 389132 [qtp25025730-518] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_19,generation=45}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_1a,generation=46}
[junit4] 1> 389133 [qtp25025730-518] INFO org.apache.solr.core.SolrCore ? newest commit generation = 46
[junit4] 1> 389136 [qtp25025730-518] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@d873e5 main
[junit4] 1> 389137 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@d873e5 main{StandardDirectoryReader(segments_19:115:nrt)}
[junit4] 1> 389137 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 389138 [qtp25025730-518] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 389142 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@d873e5 main{StandardDirectoryReader(segments_19:115:nrt)}
[junit4] 1> 389143 [qtp25025730-518] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 12
[junit4] 1> 389149 [qtp25025730-517] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 1> 389164 [qtp25025730-513] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 389170 [qtp25025730-513] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_1a,generation=46}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_1b,generation=47}
[junit4] 1> 389171 [qtp25025730-513] INFO org.apache.solr.core.SolrCore ? newest commit generation = 47
[junit4] 1> 389172 [qtp25025730-513] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@10ddac9 main
[junit4] 1> 389174 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@10ddac9 main{StandardDirectoryReader(segments_1b:117:nrt _w(4.5):C10)}
[junit4] 1> 389175 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 389177 [qtp25025730-513] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 389180 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@10ddac9 main{StandardDirectoryReader(segments_1b:117:nrt _w(4.5):C10)}
[junit4] 1> 389182 [qtp25025730-513] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {add=[0 (1440688074101096448), 1 (1440688074106339328), 2 (1440688074106339329), 3 (1440688074107387904), 4 (1440688074108436480), 5 (1440688074108436481), 6 (1440688074109485056), 7 (1440688074110533632), 8 (1440688074110533633), 9 (1440688074111582208)],commit=} 0 28
[junit4] 1> 389189 [qtp25025730-514] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={fl=id,score,_docid_&q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=1
[junit4] 1> 389195 [qtp25025730-516] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={fl=id,score,_docid_&q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=1
[junit4] 1> 389197 [TEST-SolrExampleBinaryTest.testStreamingRequest-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testStreamingRequest
[junit4] 1> 389211 [TEST-SolrExampleBinaryTest.testUpdateField-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testUpdateField
[junit4] 1> 389216 [qtp25025730-515] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@c6a279 realtime
[junit4] 1> 389218 [qtp25025730-515] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440688074164011008)} 0 4
[junit4] 1> 389223 [qtp25025730-517] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 389224 [qtp25025730-517] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_1b,generation=47}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_1c,generation=48}
[junit4] 1> 389224 [qtp25025730-517] INFO org.apache.solr.core.SolrCore ? newest commit generation = 48
[junit4] 1> 389225 [qtp25025730-517] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@f40916 main
[junit4] 1> 389226 [qtp25025730-517] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 389226 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@f40916 main{StandardDirectoryReader(segments_1b:119:nrt)}
[junit4] 1> 389226 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 389229 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@f40916 main{StandardDirectoryReader(segments_1b:119:nrt)}
[junit4] 1> 389230 [qtp25025730-517] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 8
[junit4] 1> 389235 [qtp25025730-513] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[unique (1440688074183933952)]} 0 2
[junit4] 1> 389240 [qtp25025730-514] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 389243 [qtp25025730-514] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_1c,generation=48}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_1d,generation=49}
[junit4] 1> 389244 [qtp25025730-514] INFO org.apache.solr.core.SolrCore ? newest commit generation = 49
[junit4] 1> 389245 [qtp25025730-514] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@442294 main
[junit4] 1> 389247 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@442294 main{StandardDirectoryReader(segments_1d:121:nrt _x(4.5):C1)}
[junit4] 1> 389247 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 389247 [qtp25025730-514] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 389250 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@442294 main{StandardDirectoryReader(segments_1d:121:nrt _x(4.5):C1)}
[junit4] 1> 389251 [qtp25025730-514] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 11
[junit4] 1> 389263 [qtp25025730-516] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={fl=id,price_f,name,_version_&q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4] 1> 389268 [qtp25025730-518] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {} 0 0
[junit4] 1> 389275 [qtp25025730-515] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[unique (1440688074225876992)]} 0 2
[junit4] 1> 389282 [qtp25025730-517] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 389284 [qtp25025730-517] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_1d,generation=49}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_1e,generation=50}
[junit4] 1> 389284 [qtp25025730-517] INFO org.apache.solr.core.SolrCore ? newest commit generation = 50
[junit4] 1> 389285 [qtp25025730-517] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@bedfe1 main
[junit4] 1> 389286 [qtp25025730-517] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 389287 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@bedfe1 main{StandardDirectoryReader(segments_1e:125:nrt _y(4.5):C1)}
[junit4] 1> 389287 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 389289 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@bedfe1 main{StandardDirectoryReader(segments_1e:125:nrt _y(4.5):C1)}
[junit4] 1> 389291 [qtp25025730-517] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 9
[junit4] 1> 389295 [qtp25025730-513] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={fl=id,price_f,name,_version_&q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 1> 389301 [qtp25025730-514] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[unique (1440688074252091392)]} 0 3
[junit4] 1> 389306 [qtp25025730-516] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 389308 [qtp25025730-516] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_1e,generation=50}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_1f,generation=51}
[junit4] 1> 389308 [qtp25025730-516] INFO org.apache.solr.core.SolrCore ? newest commit generation = 51
[junit4] 1> 389309 [qtp25025730-516] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@91fd4c main
[junit4] 1> 389310 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@91fd4c main{StandardDirectoryReader(segments_1f:129:nrt _z(4.5):C1)}
[junit4] 1> 389310 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 389312 [qtp25025730-516] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 389313 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@91fd4c main{StandardDirectoryReader(segments_1f:129:nrt _z(4.5):C1)}
[junit4] 1> 389313 [qtp25025730-516] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 8
[junit4] 1> 389317 [qtp25025730-518] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={fl=id,price_f,name,_version_&q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 1> 389318 [TEST-SolrExampleBinaryTest.testUpdateField-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testUpdateField
[junit4] 1> 389330 [TEST-SolrExampleBinaryTest.testUpdateMultiValuedField-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testUpdateMultiValuedField
[junit4] 1> 389336 [qtp25025730-515] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123 (1440688074289840128)]} 0 2
[junit4] 1> 389344 [qtp25025730-517] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 389347 [qtp25025730-517] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_1f,generation=51}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_1g,generation=52}
[junit4] 1> 389347 [qtp25025730-517] INFO org.apache.solr.core.SolrCore ? newest commit generation = 52
[junit4] 1> 389349 [qtp25025730-517] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@8f2091 main
[junit4] 1> 389351 [qtp25025730-517] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 389353 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@8f2091 main{StandardDirectoryReader(segments_1g:131:nrt _z(4.5):C1 _10(4.5):C1)}
[junit4] 1> 389353 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 389355 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@8f2091 main{StandardDirectoryReader(segments_1g:131:nrt _z(4.5):C1 _10(4.5):C1)}
[junit4] 1> 389356 [qtp25025730-517] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 13
[junit4] 1> 389361 [qtp25025730-513] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=id:123&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4] 1> 389367 [qtp25025730-514] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123 (1440688074323394560)]} 0 1
[junit4] 1> 389372 [qtp25025730-516] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 389375 [qtp25025730-516] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_1g,generation=52}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_1h,generation=53}
[junit4] 1> 389375 [qtp25025730-516] INFO org.apache.solr.core.SolrCore ? newest commit generation = 53
[junit4] 1> 389377 [qtp25025730-516] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@179732c main
[junit4] 1> 389377 [qtp25025730-516] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 389378 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@179732c main{StandardDirectoryReader(segments_1h:135:nrt _z(4.5):C1 _11(4.5):C1)}
[junit4] 1> 389378 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 389380 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@179732c main{StandardDirectoryReader(segments_1h:135:nrt _z(4.5):C1 _11(4.5):C1)}
[junit4] 1> 389381 [qtp25025730-516] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 9
[junit4] 1> 389388 [qtp25025730-518] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=id:123&wt=javabin&version=2} hits=1 status=0 QTime=2
[junit4] 1> 389391 [qtp25025730-515] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123 (1440688074348560384)]} 0 1
[junit4] 1> 389397 [qtp25025730-517] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 389399 [qtp25025730-517] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_1h,generation=53}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@e1e592 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8fde02),segFN=segments_1i,generation=54}
[junit4] 1> 389400 [qtp25025730-517] INFO org.apache.solr.core.SolrCore ? newest commit generation = 54
[junit4] 1> 389401 [qtp25025730-517] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@157cae4 main
[junit4] 1> 389402 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@157cae4 main{StandardDirectoryReader(segments_1i:139:nrt _z(4.5):C1 _12(4.5):C1)}
[junit4] 1> 389402 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 389402 [qtp25025730-517] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 389405 [searcherExecutor-284-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@157cae4 main{StandardDirectoryReader(segments_1i:139:nrt _z(4.5):C1 _12(4.5):C1)}
[junit4] 1> 389405 [qtp25025730-517] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 8
[junit4] 1> 389409 [qtp25025730-513] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=id:123&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 1> 389411 [TEST-SolrExampleBinaryTest.testUpdateMultiValuedField-seed#[6B2FF64BB1F8567]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testUpdateMultiValuedField
[junit4] 1> 389426 [SUITE-SolrExampleBinaryTest-seed#[6B2FF64BB1F8567]-worker] INFO org.apache.solr.core.CoreContainer ? Shutting down CoreContainer instance=26963866
[junit4] 1> 389427 [SUITE-SolrExampleBinaryTest-seed#[6B2FF64BB1F8567]-worker] INFO org.apache.solr.core.SolrCore ? [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@d1d5de
[junit4] 1> 389449 [SUITE-SolrExampleBinaryTest-seed#[6B2FF64BB1F8567]-worker] INFO org.apache.solr.update.UpdateHandler ? closing DirectUpdateHandler2{commits=57,autocommit maxTime=15000ms,autocommits=1,soft autocommits=3,optimizes=2,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=1,cumulative_adds=133,cumulative_deletesById=5,cumulative_deletesByQuery=25,cumulative_errors=1}
[junit4] 1> 389450 [SUITE-SolrExampleBinaryTest-seed#[6B2FF64BB1F8567]-worker] INFO org.apache.solr.update.SolrCoreState ? Closing SolrCoreState
[junit4] 1> 389450 [SUITE-SolrExampleBinaryTest-seed#[6B2FF64BB1F8567]-worker] INFO org.apache.solr.update.DefaultSolrCoreState ? SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 1> 389450 [SUITE-SolrExampleBinaryTest-seed#[6B2FF64BB1F8567]-worker] INFO org.apache.solr.update.DefaultSolrCoreState ? closing IndexWriter with IndexWriterCloser
[junit4] 1> 389455 [SUITE-SolrExampleBinaryTest-seed#[6B2FF64BB1F8567]-worker] INFO org.apache.solr.core.SolrCore ? [collection1] Closing main searcher on request.
[junit4] 1> 389455 [SUITE-SolrExampleBinaryTest-seed#[6B2FF64BB1F8567]-worker] INFO org.apache.solr.core.CachingDirectoryFactory ? Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 1> 389457 [SUITE-SolrExampleBinaryTest-seed#[6B2FF64BB1F8567]-worker] INFO org.apache.solr.core.CachingDirectoryFactory ? looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleBinaryTest-1373947005075\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleBinaryTest-1373947005075\index;done=false>>]
[junit4] 1> 389457 [SUITE-SolrExampleBinaryTest-seed#[6B2FF64BB1F8567]-worker] INFO org.apache.solr.core.CachingDirectoryFactory ? Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleBinaryTest-1373947005075\index
[junit4] 1> 389457 [SUITE-SolrExampleBinaryTest-seed#[6B2FF64BB1F8567]-worker] INFO org.apache.solr.core.CachingDirectoryFactory ? looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleBinaryTest-1373947005075\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleBinaryTest-1373947005075\;done=false>>]
[junit4] 1> 389457 [SUITE-SolrExampleBinaryTest-seed#[6B2FF64BB1F8567]-worker] INFO org.apache.solr.core.CachingDirectoryFactory ? Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleBinaryTest-1373947005075\
[junit4] 1> 389458 [SUITE-SolrExampleBinaryTest-seed#[6B2FF64BB1F8567]-worker] INFO org.eclipse.jetty.server.handler.ContextHandler ? stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 1> 389511 [SUITE-SolrExampleBinaryTest-seed#[6B2FF64BB1F8567]-worker] INFO org.apache.solr.SolrTestCaseJ4 ? ###deleteCore
[junit4] 2> NOTE: test params are: codec=Lucene40, sim=RandomSimilarityProvider(queryNorm=false,coord=crazy): {}, locale=en_PH, timezone=Australia/Broken_Hill
[junit4] 2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.7.0_25 (32-bit)/cpus=2,threads=1,free=21763392,total=71700480
[junit4] 2> NOTE: All tests run in this JVM: [TestFastInputStream, SolrExampleEmbeddedTest, QueryResponseTest, MergeIndexesEmbeddedTest, ModifiableSolrParamsTest, SolrExampleStreamingTest, FieldAnalysisResponseTest, LargeVolumeBinaryJettyTest, AnlysisResponseBaseTest, MultiCoreEmbeddedTest, SolrDocumentTest, LargeVolumeJettyTest, FacetFieldTest, NamedListTest, TestEmbeddedSolrServer, SolrParamTest, IteratorChainTest, TestBatchUpdate, TestNamedListCodec, TestHash, CloudSolrServerTest, TestCoreAdmin, TestLBHttpSolrServer, SolrExampleStreamingBinaryTest, SolrExampleBinaryTest]
[junit4] Completed in 209.60s, 26 tests, 1 error <<< FAILURES!
[...truncated 71 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:395: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:375: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:39: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build.xml:194: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\common-build.xml:449: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:1250: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:893: There were test failures: 46 suites, 272 tests, 1 error, 1 ignored (1 assumption)
Total time: 82 minutes 56 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/jdk1.7.0_25 -server -XX:+UseSerialGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure