lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@thetaphi.de>
Subject [JENKINS] Lucene-Solr-4.x-MacOSX (64bit/jdk1.6.0) - Build # 405 - Still Failing!
Date Thu, 25 Apr 2013 06:39:33 GMT
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-MacOSX/405/
Java: 64bit/jdk1.6.0 -XX:+UseCompressedOops -XX:+UseParallelGC

1 tests failed.
FAILED:  org.apache.solr.client.solrj.SolrExampleBinaryTest.testFaceting

Error Message:
IOException occured when talking to server at: https://127.0.0.1:53731/solr

Stack Trace:
org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: https://127.0.0.1:53731/solr
	at __randomizedtesting.SeedInfo.seed([DEEE934B89405AC7:F1E29A1B7EBC5F20]: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.commit(SolrServer.java:168)
	at org.apache.solr.client.solrj.SolrServer.commit(SolrServer.java:146)
	at org.apache.solr.client.solrj.SolrExampleTests.testFaceting(SolrExampleTests.java:914)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
	at java.lang.reflect.Method.invoke(Method.java:597)
	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:680)
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 9587 lines...]
[junit4:junit4] Suite: org.apache.solr.client.solrj.SolrExampleBinaryTest
[junit4:junit4]   1> 51785 [SUITE-SolrExampleBinaryTest-seed#[DEEE934B89405AC7]-worker] INFO  org.apache.solr.SolrJettyTestBase  ? Randomized ssl (true) and clientAuth (false)
[junit4:junit4]   1> 51800 [SUITE-SolrExampleBinaryTest-seed#[DEEE934B89405AC7]-worker] INFO  org.apache.solr.SolrTestCaseJ4  ? ####initCore
[junit4:junit4]   1> 51809 [SUITE-SolrExampleBinaryTest-seed#[DEEE934B89405AC7]-worker] INFO  org.apache.solr.SolrTestCaseJ4  ? ####initCore end
[junit4:junit4]   1> 51889 [SUITE-SolrExampleBinaryTest-seed#[DEEE934B89405AC7]-worker] INFO  org.eclipse.jetty.server.Server  ? jetty-8.1.10.v20130312
[junit4:junit4]   1> 52488 [SUITE-SolrExampleBinaryTest-seed#[DEEE934B89405AC7]-worker] INFO  org.eclipse.jetty.util.ssl.SslContextFactory  ? Enabled Protocols [SSLv2Hello, SSLv3, TLSv1] of [SSLv2Hello, SSLv3, TLSv1]
[junit4:junit4]   1> 52494 [SUITE-SolrExampleBinaryTest-seed#[DEEE934B89405AC7]-worker] INFO  org.eclipse.jetty.server.AbstractConnector  ? Started SslSelectChannelConnector@127.0.0.1:53731
[junit4:junit4]   1> 52497 [SUITE-SolrExampleBinaryTest-seed#[DEEE934B89405AC7]-worker] INFO  org.apache.solr.servlet.SolrDispatchFilter  ? SolrDispatchFilter.init()
[junit4:junit4]   1> 52528 [SUITE-SolrExampleBinaryTest-seed#[DEEE934B89405AC7]-worker] INFO  org.apache.solr.core.SolrResourceLoader  ? JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   1> 52529 [SUITE-SolrExampleBinaryTest-seed#[DEEE934B89405AC7]-worker] INFO  org.apache.solr.core.SolrResourceLoader  ? using system property solr.solr.home: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr
[junit4:junit4]   1> 52530 [SUITE-SolrExampleBinaryTest-seed#[DEEE934B89405AC7]-worker] INFO  org.apache.solr.core.CoreContainer  ? looking for solr config file: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/solr.xml
[junit4:junit4]   1> 52531 [SUITE-SolrExampleBinaryTest-seed#[DEEE934B89405AC7]-worker] INFO  org.apache.solr.core.CoreContainer  ? New CoreContainer 905413847
[junit4:junit4]   1> 52532 [SUITE-SolrExampleBinaryTest-seed#[DEEE934B89405AC7]-worker] INFO  org.apache.solr.core.CoreContainer  ? Loading CoreContainer using Solr Home: '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/'
[junit4:junit4]   1> 52534 [SUITE-SolrExampleBinaryTest-seed#[DEEE934B89405AC7]-worker] INFO  org.apache.solr.core.SolrResourceLoader  ? new SolrResourceLoader for directory: '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/'
[junit4:junit4]   1> 52871 [SUITE-SolrExampleBinaryTest-seed#[DEEE934B89405AC7]-worker] INFO  org.apache.solr.handler.component.HttpShardHandlerFactory  ? Setting socketTimeout to: 0
[junit4:junit4]   1> 52873 [SUITE-SolrExampleBinaryTest-seed#[DEEE934B89405AC7]-worker] INFO  org.apache.solr.handler.component.HttpShardHandlerFactory  ? Setting urlScheme to: http://
[junit4:junit4]   1> 52874 [SUITE-SolrExampleBinaryTest-seed#[DEEE934B89405AC7]-worker] INFO  org.apache.solr.handler.component.HttpShardHandlerFactory  ? Setting connTimeout to: 0
[junit4:junit4]   1> 52879 [SUITE-SolrExampleBinaryTest-seed#[DEEE934B89405AC7]-worker] INFO  org.apache.solr.handler.component.HttpShardHandlerFactory  ? Setting maxConnectionsPerHost to: 20
[junit4:junit4]   1> 52882 [SUITE-SolrExampleBinaryTest-seed#[DEEE934B89405AC7]-worker] INFO  org.apache.solr.handler.component.HttpShardHandlerFactory  ? Setting corePoolSize to: 0
[junit4:junit4]   1> 52886 [SUITE-SolrExampleBinaryTest-seed#[DEEE934B89405AC7]-worker] INFO  org.apache.solr.handler.component.HttpShardHandlerFactory  ? Setting maximumPoolSize to: 2147483647
[junit4:junit4]   1> 52887 [SUITE-SolrExampleBinaryTest-seed#[DEEE934B89405AC7]-worker] INFO  org.apache.solr.handler.component.HttpShardHandlerFactory  ? Setting maxThreadIdleTime to: 5
[junit4:junit4]   1> 52888 [SUITE-SolrExampleBinaryTest-seed#[DEEE934B89405AC7]-worker] INFO  org.apache.solr.handler.component.HttpShardHandlerFactory  ? Setting sizeOfQueue to: -1
[junit4:junit4]   1> 52889 [SUITE-SolrExampleBinaryTest-seed#[DEEE934B89405AC7]-worker] INFO  org.apache.solr.handler.component.HttpShardHandlerFactory  ? Setting fairnessPolicy to: false
[junit4:junit4]   1> 52890 [SUITE-SolrExampleBinaryTest-seed#[DEEE934B89405AC7]-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:junit4]   1> 52908 [SUITE-SolrExampleBinaryTest-seed#[DEEE934B89405AC7]-worker] INFO  org.apache.solr.core.CoreContainer  ? Registering Log Listener
[junit4:junit4]   1> 52982 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.core.CoreContainer  ? Creating SolrCore 'collection1' using instanceDir: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/collection1
[junit4:junit4]   1> 52984 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.core.SolrResourceLoader  ? new SolrResourceLoader for directory: '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/collection1/'
[junit4:junit4]   1> 53069 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.core.SolrConfig  ? Adding specified lib dirs to ClassLoader
[junit4:junit4]   1> 53071 [coreLoadExecutor-17-thread-1] WARN  org.apache.solr.core.SolrResourceLoader  ? No files added to classloader from lib: ../../../contrib/extraction/lib (resolved as: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/collection1/../../../contrib/extraction/lib).
[junit4:junit4]   1> 53072 [coreLoadExecutor-17-thread-1] WARN  org.apache.solr.core.SolrResourceLoader  ? Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/collection1/../../../dist).
[junit4:junit4]   1> 53073 [coreLoadExecutor-17-thread-1] WARN  org.apache.solr.core.SolrResourceLoader  ? No files added to classloader from lib: ../../../contrib/clustering/lib/ (resolved as: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/collection1/../../../contrib/clustering/lib).
[junit4:junit4]   1> 53073 [coreLoadExecutor-17-thread-1] WARN  org.apache.solr.core.SolrResourceLoader  ? Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/collection1/../../../dist).
[junit4:junit4]   1> 53074 [coreLoadExecutor-17-thread-1] WARN  org.apache.solr.core.SolrResourceLoader  ? No files added to classloader from lib: ../../../contrib/langid/lib/ (resolved as: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/collection1/../../../contrib/langid/lib).
[junit4:junit4]   1> 53075 [coreLoadExecutor-17-thread-1] WARN  org.apache.solr.core.SolrResourceLoader  ? Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/collection1/../../../dist).
[junit4:junit4]   1> 53076 [coreLoadExecutor-17-thread-1] WARN  org.apache.solr.core.SolrResourceLoader  ? No files added to classloader from lib: ../../../contrib/velocity/lib (resolved as: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/collection1/../../../contrib/velocity/lib).
[junit4:junit4]   1> 53077 [coreLoadExecutor-17-thread-1] WARN  org.apache.solr.core.SolrResourceLoader  ? Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/collection1/../../../dist).
[junit4:junit4]   1> 53078 [coreLoadExecutor-17-thread-1] WARN  org.apache.solr.core.SolrResourceLoader  ? Can't find (or read) directory to add to classloader: /non/existent/dir/yields/warning (resolved as: /non/existent/dir/yields/warning).
[junit4:junit4]   1> 53332 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.core.SolrConfig  ? Using Lucene MatchVersion: LUCENE_43
[junit4:junit4]   1> 53609 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.core.Config  ? Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   1> 53611 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.schema.IndexSchema  ? Reading Solr Schema from schema.xml
[junit4:junit4]   1> 53696 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.schema.IndexSchema  ? Schema name=example
[junit4:junit4]   1> 55374 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.schema.IndexSchema  ? unique key field: id
[junit4:junit4]   1> 55472 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.schema.FileExchangeRateProvider  ? Reloading exchange rates from file currency.xml
[junit4:junit4]   1> 55528 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.schema.FileExchangeRateProvider  ? Reloading exchange rates from file currency.xml
[junit4:junit4]   1> 55820 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.core.SolrCore  ? org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> 55840 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Opening new SolrCore at /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/collection1/, dataDir=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleBinaryTest-1366871452994/
[junit4:junit4]   1> 55855 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.core.JmxMonitoredMap  ? JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@396ab2e8
[junit4:junit4]   1> 55858 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Added SolrEventListener for newSearcher: org.apache.solr.core.QuerySenderListener{queries=[]}
[junit4:junit4]   1> 55859 [coreLoadExecutor-17-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:junit4]   1> 55860 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.core.SolrCore  ? org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> 55861 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.core.CachingDirectoryFactory  ? return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleBinaryTest-1366871452994
[junit4:junit4]   1> 55862 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.core.SolrCore  ? New index directory detected: old=null new=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleBinaryTest-1366871452994/index/
[junit4:junit4]   1> 55863 [coreLoadExecutor-17-thread-1] WARN  org.apache.solr.core.SolrCore  ? [collection1] Solr index directory '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleBinaryTest-1366871452994/index' doesn't exist. Creating new index...
[junit4:junit4]   1> 55864 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.core.CachingDirectoryFactory  ? return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleBinaryTest-1366871452994/index
[junit4:junit4]   1> 55869 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_1,generation=1}
[junit4:junit4]   1> 55875 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 1
[junit4:junit4]   1> 55878 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.core.SolrCore  ? created json: solr.JSONResponseWriter
[junit4:junit4]   1> 55891 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.core.SolrCore  ? adding lazy queryResponseWriter: solr.VelocityResponseWriter
[junit4:junit4]   1> 55892 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.core.SolrCore  ? created velocity: solr.VelocityResponseWriter
[junit4:junit4]   1> 55893 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.core.SolrCore  ? created xslt: solr.XSLTResponseWriter
[junit4:junit4]   1> 55894 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.response.XSLTResponseWriter  ? xsltCacheLifetimeSeconds=5
[junit4:junit4]   1> 55900 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /select: solr.SearchHandler
[junit4:junit4]   1> 55910 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /query: solr.SearchHandler
[junit4:junit4]   1> 55912 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /get: solr.RealTimeGetHandler
[junit4:junit4]   1> 55913 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /browse: solr.SearchHandler
[junit4:junit4]   1> 55915 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /update: solr.UpdateRequestHandler
[junit4:junit4]   1> 55930 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /update/json: solr.JsonUpdateRequestHandler
[junit4:junit4]   1> 55932 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /update/csv: solr.CSVRequestHandler
[junit4:junit4]   1> 55933 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? adding lazy requestHandler: solr.extraction.ExtractingRequestHandler
[junit4:junit4]   1> 55934 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /update/extract: solr.extraction.ExtractingRequestHandler
[junit4:junit4]   1> 55934 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? adding lazy requestHandler: solr.FieldAnalysisRequestHandler
[junit4:junit4]   1> 55935 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /analysis/field: solr.FieldAnalysisRequestHandler
[junit4:junit4]   1> 55936 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? adding lazy requestHandler: solr.DocumentAnalysisRequestHandler
[junit4:junit4]   1> 55939 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /analysis/document: solr.DocumentAnalysisRequestHandler
[junit4:junit4]   1> 55940 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /admin/: solr.admin.AdminHandlers
[junit4:junit4]   1> 55942 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /admin/ping: solr.PingRequestHandler
[junit4:junit4]   1> 55943 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   1> 55946 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /replication: solr.ReplicationHandler
[junit4:junit4]   1> 55946 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? adding lazy requestHandler: solr.SearchHandler
[junit4:junit4]   1> 55947 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /spell: solr.SearchHandler
[junit4:junit4]   1> 55948 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? adding lazy requestHandler: solr.SearchHandler
[junit4:junit4]   1> 55959 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /tvrh: solr.SearchHandler
[junit4:junit4]   1> 55960 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? adding lazy requestHandler: solr.SearchHandler
[junit4:junit4]   1> 55961 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /terms: solr.SearchHandler
[junit4:junit4]   1> 55962 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? adding lazy requestHandler: solr.SearchHandler
[junit4:junit4]   1> 55964 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /elevate: solr.SearchHandler
[junit4:junit4]   1> 55976 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.handler.loader.XMLLoader  ? xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> 55980 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.handler.loader.XMLLoader  ? xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> 55986 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.handler.loader.XMLLoader  ? xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> 56010 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@798c668c main
[junit4:junit4]   1> 56011 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.update.UpdateHandler  ? Clearing tlog files, tlogDir=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleBinaryTest-1366871452994/tlog
[junit4:junit4]   1> 56013 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.core.SolrCore  ? Hard AutoCommit: if uncommited for 15000ms; 
[junit4:junit4]   1> 56014 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.core.SolrCore  ? Soft AutoCommit: disabled
[junit4:junit4]   1> 56015 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.handler.component.SpellCheckComponent  ? Initializing spell checkers
[junit4:junit4]   1> 56016 [coreLoadExecutor-17-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:junit4]   1> 56047 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.handler.component.SpellCheckComponent  ? No queryConverter defined, using default converter
[junit4:junit4]   1> 56049 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.handler.component.QueryElevationComponent  ? Loading QueryElevation from: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/collection1/conf/elevate.xml
[junit4:junit4]   1> 56077 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.handler.ReplicationHandler  ? Commits will be reserved for  10000
[junit4:junit4]   1> 56078 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@798c668c main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> 56083 [searcherExecutor-18-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:junit4]   1> 56084 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 56084 [searcherExecutor-18-thread-1] INFO  org.apache.solr.handler.component.SpellCheckComponent  ? Loading spell index for spellchecker: default
[junit4:junit4]   1> 56085 [searcherExecutor-18-thread-1] INFO  org.apache.solr.handler.component.SpellCheckComponent  ? Loading spell index for spellchecker: wordbreak
[junit4:junit4]   1> 56087 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@798c668c main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> 56115 [coreLoadExecutor-17-thread-1] INFO  org.apache.solr.core.CoreContainer  ? registering core: collection1
[junit4:junit4]   1> 56118 [SUITE-SolrExampleBinaryTest-seed#[DEEE934B89405AC7]-worker] INFO  org.apache.solr.servlet.SolrDispatchFilter  ? user.dir=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0
[junit4:junit4]   1> 56119 [SUITE-SolrExampleBinaryTest-seed#[DEEE934B89405AC7]-worker] INFO  org.apache.solr.servlet.SolrDispatchFilter  ? SolrDispatchFilter.init() done
[junit4:junit4]   1> 56120 [SUITE-SolrExampleBinaryTest-seed#[DEEE934B89405AC7]-worker] INFO  org.apache.solr.SolrJettyTestBase  ? Jetty Assigned Port#53731
[junit4:junit4]   2> Creating dataDir: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0/./solrtest-SolrExampleBinaryTest-1366871452994
[junit4:junit4]   1> 56162 [TEST-SolrExampleBinaryTest.testCommitWithinOnDelete-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testCommitWithinOnDelete
[junit4:junit4]   1> 56164 [TEST-SolrExampleBinaryTest.testCommitWithinOnDelete-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.client.solrj.impl.HttpClientUtil  ? Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   1> 56723 [qtp1905823030-56] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onInit: commits: num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_1,generation=1}
[junit4:junit4]   1> 56725 [qtp1905823030-56] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 1
[junit4:junit4]   1> 56729 [qtp1905823030-56] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@fe0f790 realtime
[junit4:junit4]   1> 56731 [qtp1905823030-56] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433268605849436160)} 0 31
[junit4:junit4]   1> 56750 [qtp1905823030-57] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 56753 [qtp1905823030-57] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_1,generation=1}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_2,generation=2}
[junit4:junit4]   1> 56754 [qtp1905823030-57] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 2
[junit4:junit4]   1> 56756 [qtp1905823030-57] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@29af45f4 main
[junit4:junit4]   1> 56763 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@29af45f4 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4:junit4]   1> 56764 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 56771 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@29af45f4 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4:junit4]   1> 56772 [qtp1905823030-57] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 56775 [qtp1905823030-57] 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 26
[junit4:junit4]   1> 56783 [qtp1905823030-58] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1 
[junit4:junit4]   1> 56820 [qtp1905823030-59] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id3 (1433268605944856576)]} 0 12
[junit4:junit4]   1> 56827 [qtp1905823030-60] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 56836 [qtp1905823030-60] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_2,generation=2}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_3,generation=3}
[junit4:junit4]   1> 56837 [qtp1905823030-60] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 3
[junit4:junit4]   1> 56839 [qtp1905823030-60] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@3e10c986 main
[junit4:junit4]   1> 56841 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@3e10c986 main{StandardDirectoryReader(segments_3:3:nrt _0(4.4):C1)}
[junit4:junit4]   1> 56841 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 56846 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@3e10c986 main{StandardDirectoryReader(segments_3:3:nrt _0(4.4):C1)}
[junit4:junit4]   1> 56849 [qtp1905823030-60] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 56849 [qtp1905823030-60] 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 22
[junit4:junit4]   1> 56877 [qtp1905823030-61] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=2 
[junit4:junit4]   1> 56889 [qtp1905823030-56] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {delete=[id3 (-1433268606025596928)]} 0 4
[junit4:junit4]   1> 56898 [qtp1905823030-57] 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:junit4]   1> 57167 [qtp1905823030-58] 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:junit4]   1> 57452 [qtp1905823030-60] 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:junit4]   1> 57731 [qtp1905823030-61] 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:junit4]   1> 57890 [commitScheduler-21-thread-1] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4:junit4]   1> 57915 [commitScheduler-21-thread-1] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@6bc839a main
[junit4:junit4]   1> 57916 [commitScheduler-21-thread-1] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 57917 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@6bc839a main{StandardDirectoryReader(segments_3:5:nrt)}
[junit4:junit4]   1> 57919 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 57925 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@6bc839a main{StandardDirectoryReader(segments_3:5:nrt)}
[junit4:junit4]   1> 57997 [qtp1905823030-57] 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:junit4]   1> 58000 [TEST-SolrExampleBinaryTest.testCommitWithinOnDelete-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testCommitWithinOnDelete
[junit4:junit4]   1> 58095 [TEST-SolrExampleBinaryTest.testSetNullUpdateOrder-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testSetNullUpdateOrder
[junit4:junit4]   1> 58107 [qtp1905823030-58] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[testSetNullUpdateOrder (1433268607298568192)]} 0 6
[junit4:junit4]   1> 58128 [qtp1905823030-59] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 58139 [qtp1905823030-59] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_3,generation=3}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_4,generation=4}
[junit4:junit4]   1> 58140 [qtp1905823030-59] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 4
[junit4:junit4]   1> 58144 [qtp1905823030-59] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@3b280492 main
[junit4:junit4]   1> 58146 [qtp1905823030-59] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 58147 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@3b280492 main{StandardDirectoryReader(segments_4:7:nrt _1(4.4):C1)}
[junit4:junit4]   1> 58148 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 58160 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@3b280492 main{StandardDirectoryReader(segments_4:7:nrt _1(4.4):C1)}
[junit4:junit4]   1> 58175 [qtp1905823030-59] 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 47
[junit4:junit4]   1> 58508 [qtp1905823030-61] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[testSetNullUpdateOrder (1433268607723241472)]} 0 6
[junit4:junit4]   1> 58524 [qtp1905823030-56] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 58532 [qtp1905823030-56] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_4,generation=4}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_5,generation=5}
[junit4:junit4]   1> 58534 [qtp1905823030-56] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 5
[junit4:junit4]   1> 58538 [qtp1905823030-56] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@4611dfe3 main
[junit4:junit4]   1> 58541 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@4611dfe3 main{StandardDirectoryReader(segments_5:11:nrt _2(4.4):C1)}
[junit4:junit4]   1> 58542 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 58548 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@4611dfe3 main{StandardDirectoryReader(segments_5:11:nrt _2(4.4):C1)}
[junit4:junit4]   1> 58566 [qtp1905823030-56] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 58588 [qtp1905823030-56] 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 64
[junit4:junit4]   1> 58599 [qtp1905823030-58] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=id:testSetNullUpdateOrder&wt=javabin&version=2} hits=1 status=0 QTime=3 
[junit4:junit4]   1> 58603 [TEST-SolrExampleBinaryTest.testSetNullUpdateOrder-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testSetNullUpdateOrder
[junit4:junit4]   1> 58669 [TEST-SolrExampleBinaryTest.testPivotFacetsMissing-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testPivotFacetsMissing
[junit4:junit4]   1> 59156 [qtp1905823030-59] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@6a5a7ff7 realtime
[junit4:junit4]   1> 59159 [qtp1905823030-59] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433268607920373760)} 0 466
[junit4:junit4]   1> 59170 [qtp1905823030-61] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 59173 [qtp1905823030-61] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_5,generation=5}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_6,generation=6}
[junit4:junit4]   1> 59175 [qtp1905823030-61] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 6
[junit4:junit4]   1> 59179 [qtp1905823030-61] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@65eef88 main
[junit4:junit4]   1> 59199 [qtp1905823030-61] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 59202 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@65eef88 main{StandardDirectoryReader(segments_5:13:nrt)}
[junit4:junit4]   1> 59203 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 59211 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@65eef88 main{StandardDirectoryReader(segments_5:13:nrt)}
[junit4:junit4]   1> 59216 [qtp1905823030-61] 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 46
[junit4:junit4]   1> 59234 [qtp1905823030-56] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1 
[junit4:junit4]   1> 59313 [qtp1905823030-57] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1 (1433268608518062080), 2 (1433268608529596416), 3 (1433268608530644992), 4 (1433268608532742144), 5 (1433268608555810816), 6 (1433268608557907968), 7 (1433268608560005120), 8 (1433268608562102272), 9 (1433268608564199424), 10 (1433268608565248000), ... (12 adds)]} 0 51
[junit4:junit4]   1> 59326 [qtp1905823030-58] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 59343 [qtp1905823030-58] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_6,generation=6}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_7,generation=7}
[junit4:junit4]   1> 59345 [qtp1905823030-58] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 7
[junit4:junit4]   1> 59350 [qtp1905823030-58] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@77ec3adc main
[junit4:junit4]   1> 59353 [qtp1905823030-58] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 59355 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@77ec3adc main{StandardDirectoryReader(segments_7:15:nrt _3(4.4):C12)}
[junit4:junit4]   1> 59356 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 59371 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@77ec3adc main{StandardDirectoryReader(segments_7:15:nrt _3(4.4):C12)}
[junit4:junit4]   1> 59377 [qtp1905823030-58] 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 51
[junit4:junit4]   1> 59807 [qtp1905823030-60] INFO  org.apache.solr.core.SolrCore  ? UnInverted multi-valued field {field=features,memSize=4521,tindexSize=43,time=39,phase1=39,nTerms=2,bigTerms=2,termInstances=0,uses=0}
[junit4:junit4]   1> 59871 [qtp1905823030-60] 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:junit4]   1> 59900 [qtp1905823030-60] 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=513 
[junit4:junit4]   1> 59996 [qtp1905823030-61] 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=78 
[junit4:junit4]   1> 59999 [TEST-SolrExampleBinaryTest.testPivotFacetsMissing-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testPivotFacetsMissing
[junit4:junit4]   1> 60031 [TEST-SolrExampleBinaryTest.testStatistics-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testStatistics
[junit4:junit4]   1> 60043 [qtp1905823030-56] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@40ac8360 realtime
[junit4:junit4]   1> 60044 [qtp1905823030-56] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433268609331757056)} 0 5
[junit4:junit4]   1> 60051 [qtp1905823030-57] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 60052 [qtp1905823030-57] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_7,generation=7}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_8,generation=8}
[junit4:junit4]   1> 60053 [qtp1905823030-57] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 8
[junit4:junit4]   1> 60056 [qtp1905823030-57] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@71591b4d main
[junit4:junit4]   1> 60063 [qtp1905823030-57] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 60064 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@71591b4d main{StandardDirectoryReader(segments_7:17:nrt)}
[junit4:junit4]   1> 60065 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 60092 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@71591b4d main{StandardDirectoryReader(segments_7:17:nrt)}
[junit4:junit4]   1> 60097 [qtp1905823030-57] 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 47
[junit4:junit4]   1> 60107 [qtp1905823030-58] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=2 
[junit4:junit4]   1> 60122 [qtp1905823030-59] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc0 (1433268609415643136)]} 0 3
[junit4:junit4]   1> 60140 [qtp1905823030-60] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc1 (1433268609432420352)]} 0 5
[junit4:junit4]   1> 60154 [qtp1905823030-61] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc2 (1433268609450246144)]} 0 2
[junit4:junit4]   1> 60185 [qtp1905823030-56] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc3 (1433268609483800576)]} 0 2
[junit4:junit4]   1> 60195 [qtp1905823030-58] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc4 (1433268609493237760)]} 0 2
[junit4:junit4]   1> 60248 [qtp1905823030-59] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc5 (1433268609549860864)]} 0 1
[junit4:junit4]   1> 60255 [qtp1905823030-60] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc6 (1433268609557200896)]} 0 1
[junit4:junit4]   1> 60264 [qtp1905823030-61] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc7 (1433268609565589504)]} 0 3
[junit4:junit4]   1> 60278 [qtp1905823030-56] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc8 (1433268609581318144)]} 0 1
[junit4:junit4]   1> 60310 [qtp1905823030-57] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc9 (1433268609613824000)]} 0 2
[junit4:junit4]   1> 60327 [qtp1905823030-58] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 60338 [qtp1905823030-58] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_8,generation=8}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_9,generation=9}
[junit4:junit4]   1> 60339 [qtp1905823030-58] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 9
[junit4:junit4]   1> 60343 [qtp1905823030-58] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@37f2ae62 main
[junit4:junit4]   1> 60344 [qtp1905823030-58] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 60345 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@37f2ae62 main{StandardDirectoryReader(segments_9:19:nrt _4(4.4):C10)}
[junit4:junit4]   1> 60345 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 60359 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@37f2ae62 main{StandardDirectoryReader(segments_9:19:nrt _4(4.4):C10)}
[junit4:junit4]   1> 60361 [qtp1905823030-58] 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 34
[junit4:junit4]   1> 60380 [qtp1905823030-59] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=1 
[junit4:junit4]   1> 61845 [qtp1905823030-60] 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=1450 
[junit4:junit4]   1> 61863 [qtp1905823030-58] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@77a477b7 realtime
[junit4:junit4]   1> 61865 [qtp1905823030-58] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433268611241213952)} 0 6
[junit4:junit4]   1> 61875 [qtp1905823030-59] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 61878 [qtp1905823030-59] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_9,generation=9}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_a,generation=10}
[junit4:junit4]   1> 61879 [qtp1905823030-59] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 10
[junit4:junit4]   1> 61882 [qtp1905823030-59] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@27daf835 main
[junit4:junit4]   1> 61886 [qtp1905823030-59] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 61887 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@27daf835 main{StandardDirectoryReader(segments_9:21:nrt)}
[junit4:junit4]   1> 61888 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 61895 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@27daf835 main{StandardDirectoryReader(segments_9:21:nrt)}
[junit4:junit4]   1> 61898 [qtp1905823030-59] 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 23
[junit4:junit4]   1> 61906 [qtp1905823030-61] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 
[junit4:junit4]   1> 61917 [qtp1905823030-56] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc10 (1433268611297837056)]} 0 3
[junit4:junit4]   1> 61932 [qtp1905823030-57] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc11 (1433268611314614272)]} 0 2
[junit4:junit4]   1> 61941 [qtp1905823030-60] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc12 (1433268611324051456)]} 0 2
[junit4:junit4]   1> 61950 [qtp1905823030-58] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc13 (1433268611334537216)]} 0 2
[junit4:junit4]   1> 61957 [qtp1905823030-59] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc14 (1433268611341877248)]} 0 1
[junit4:junit4]   1> 61964 [qtp1905823030-61] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 61973 [qtp1905823030-61] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_a,generation=10}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_b,generation=11}
[junit4:junit4]   1> 61974 [qtp1905823030-61] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 11
[junit4:junit4]   1> 61979 [qtp1905823030-61] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@63822112 main
[junit4:junit4]   1> 61981 [qtp1905823030-61] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 61982 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@63822112 main{StandardDirectoryReader(segments_b:23:nrt _5(4.4):C5)}
[junit4:junit4]   1> 61983 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 61989 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@63822112 main{StandardDirectoryReader(segments_b:23:nrt _5(4.4):C5)}
[junit4:junit4]   1> 61991 [qtp1905823030-61] 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 27
[junit4:junit4]   1> 61998 [qtp1905823030-56] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=5 status=0 QTime=0 
[junit4:junit4]   1> 62006 [qtp1905823030-57] 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=1 
[junit4:junit4]   1> 62015 [qtp1905823030-58] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@6208f57a realtime
[junit4:junit4]   1> 62015 [qtp1905823030-58] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433268611400597504)} 0 3
[junit4:junit4]   1> 62028 [qtp1905823030-59] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 62031 [qtp1905823030-59] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_b,generation=11}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_c,generation=12}
[junit4:junit4]   1> 62033 [qtp1905823030-59] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 12
[junit4:junit4]   1> 62035 [qtp1905823030-59] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@6132b73b main
[junit4:junit4]   1> 62038 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@6132b73b main{StandardDirectoryReader(segments_b:25:nrt)}
[junit4:junit4]   1> 62039 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 62045 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@6132b73b main{StandardDirectoryReader(segments_b:25:nrt)}
[junit4:junit4]   1> 62047 [qtp1905823030-59] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 62049 [qtp1905823030-59] 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:junit4]   1> 62058 [qtp1905823030-61] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1 
[junit4:junit4]   1> 62079 [qtp1905823030-56] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc0 (1433268611459317760)]} 0 11
[junit4:junit4]   1> 62127 [qtp1905823030-60] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc1 (1433268611478192128)]} 0 41
[junit4:junit4]   1> 62157 [qtp1905823030-57] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc2 (1433268611551592448)]} 0 2
[junit4:junit4]   1> 62182 [qtp1905823030-58] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc3 (1433268611577806848)]} 0 1
[junit4:junit4]   1> 62191 [qtp1905823030-59] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc4 (1433268611587244032)]} 0 1
[junit4:junit4]   1> 62254 [qtp1905823030-61] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc5 (1433268611652255744)]} 0 2
[junit4:junit4]   1> 62284 [qtp1905823030-56] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc6 (1433268611682664448)]} 0 3
[junit4:junit4]   1> 62355 [qtp1905823030-60] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc7 (1433268611759210496)]} 0 2
[junit4:junit4]   1> 62380 [qtp1905823030-57] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc8 (1433268611784376320)]} 0 8
[junit4:junit4]   1> 62393 [qtp1905823030-58] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc9 (1433268611799056384)]} 0 1
[junit4:junit4]   1> 62406 [qtp1905823030-59] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 62437 [qtp1905823030-59] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_c,generation=12}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_d,generation=13}
[junit4:junit4]   1> 62439 [qtp1905823030-59] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 13
[junit4:junit4]   1> 62442 [qtp1905823030-59] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@203b4f0e main
[junit4:junit4]   1> 62444 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@203b4f0e main{StandardDirectoryReader(segments_d:27:nrt _6(4.4):C10)}
[junit4:junit4]   1> 62445 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 62450 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@203b4f0e main{StandardDirectoryReader(segments_d:27:nrt _6(4.4):C10)}
[junit4:junit4]   1> 62452 [qtp1905823030-59] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 62455 [qtp1905823030-59] 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 49
[junit4:junit4]   1> 62471 [qtp1905823030-56] 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:junit4]   1> 62478 [qtp1905823030-60] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=inStock:false&wt=javabin&version=2} hits=5 status=0 QTime=0 
[junit4:junit4]   1> 62647 [qtp1905823030-57] 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=162 
[junit4:junit4]   1> 62656 [TEST-SolrExampleBinaryTest.testStatistics-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testStatistics
[junit4:junit4]   1> 62751 [TEST-SolrExampleBinaryTest.testFaceting-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testFaceting
[junit4:junit4]   1> 62760 [qtp1905823030-58] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@12e6f711 realtime
[junit4:junit4]   1> 62761 [qtp1905823030-58] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433268612181786624)} 0 4
[junit4:junit4]   1> 77763 [commitScheduler-20-thread-1] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 77764 [commitScheduler-20-thread-1] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_d,generation=13}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_e,generation=14}
[junit4:junit4]   1> 77765 [commitScheduler-20-thread-1] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 14
[junit4:junit4]   1> 77775 [commitScheduler-20-thread-1] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 262969 [TEST-SolrExampleBinaryTest.testFaceting-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testFaceting
[junit4:junit4]   1> 262994 [qtp1905823030-56] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 262997 [qtp1905823030-56] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_e,generation=14}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_f,generation=15}
[junit4:junit4]   1> 262998 [qtp1905823030-56] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 15
[junit4:junit4]   1> 262999 [qtp1905823030-56] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@39126d91 main
[junit4:junit4]   1> 263000 [qtp1905823030-56] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 263001 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@39126d91 main{StandardDirectoryReader(segments_d:29:nrt)}
[junit4:junit4]   1> 263002 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 263118 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@39126d91 main{StandardDirectoryReader(segments_d:29:nrt)}
[junit4:junit4]   1> 263132 [qtp1905823030-56] 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 139
[junit4:junit4]   1> 263189 [qtp1905823030-56] ERROR org.apache.solr.servlet.SolrDispatchFilter  ? null:org.eclipse.jetty.io.EofException
[junit4:junit4]   1> 	at org.eclipse.jetty.server.HttpOutput.write(HttpOutput.java:142)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.HttpOutput.write(HttpOutput.java:107)
[junit4:junit4]   1> 	at org.apache.solr.common.util.FastOutputStream.flush(FastOutputStream.java:214)
[junit4:junit4]   1> 	at org.apache.solr.common.util.FastOutputStream.flushBuffer(FastOutputStream.java:207)
[junit4:junit4]   1> 	at org.apache.solr.common.util.JavaBinCodec.marshal(JavaBinCodec.java:94)
[junit4:junit4]   1> 	at org.apache.solr.response.BinaryResponseWriter.write(BinaryResponseWriter.java:50)
[junit4:junit4]   1> 	at org.apache.solr.servlet.SolrDispatchFilter.writeResponse(SolrDispatchFilter.java:623)
[junit4:junit4]   1> 	at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:358)
[junit4:junit4]   1> 	at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:141)
[junit4:junit4]   1> 	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4:junit4]   1> 	at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:135)
[junit4:junit4]   1> 	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4:junit4]   1> 	at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077)
[junit4:junit4]   1> 	at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.Server.handle(Server.java:368)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.AbstractHttpConnection.content(AbstractHttpConnection.java:953)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.content(AbstractHttpConnection.java:1014)
[junit4:junit4]   1> 	at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:861)
[junit4:junit4]   1> 	at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:240)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
[junit4:junit4]   1> 	at org.eclipse.jetty.io.nio.SslConnection.handle(SslConnection.java:196)
[junit4:junit4]   1> 	at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:628)
[junit4:junit4]   1> 	at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52)
[junit4:junit4]   1> 	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
[junit4:junit4]   1> 	at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
[junit4:junit4]   1> 	at java.lang.Thread.run(Thread.java:680)
[junit4:junit4]   1> 
[junit4:junit4]   1> 263197 [qtp1905823030-56] ERROR org.apache.solr.servlet.SolrDispatchFilter  ? null:org.eclipse.jetty.io.EofException
[junit4:junit4]   1> 	at org.eclipse.jetty.server.HttpOutput.write(HttpOutput.java:142)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.HttpOutput.write(HttpOutput.java:107)
[junit4:junit4]   1> 	at org.apache.solr.common.util.FastOutputStream.flush(FastOutputStream.java:214)
[junit4:junit4]   1> 	at org.apache.solr.common.util.FastOutputStream.flushBuffer(FastOutputStream.java:207)
[junit4:junit4]   1> 	at org.apache.solr.common.util.JavaBinCodec.marshal(JavaBinCodec.java:94)
[junit4:junit4]   1> 	at org.apache.solr.response.BinaryResponseWriter.write(BinaryResponseWriter.java:50)
[junit4:junit4]   1> 	at org.apache.solr.servlet.SolrDispatchFilter.writeResponse(SolrDispatchFilter.java:623)
[junit4:junit4]   1> 	at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:358)
[junit4:junit4]   1> 	at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:141)
[junit4:junit4]   1> 	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4:junit4]   1> 	at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:135)
[junit4:junit4]   1> 	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4:junit4]   1> 	at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077)
[junit4:junit4]   1> 	at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.Server.handle(Server.java:368)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.AbstractHttpConnection.content(AbstractHttpConnection.java:953)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.content(AbstractHttpConnection.java:1014)
[junit4:junit4]   1> 	at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:861)
[junit4:junit4]   1> 	at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:240)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
[junit4:junit4]   1> 	at org.eclipse.jetty.io.nio.SslConnection.handle(SslConnection.java:196)
[junit4:junit4]   1> 	at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:628)
[junit4:junit4]   1> 	at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52)
[junit4:junit4]   1> 	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
[junit4:junit4]   1> 	at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
[junit4:junit4]   1> 	at java.lang.Thread.run(Thread.java:680)
[junit4:junit4]   1> 
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=SolrExampleBinaryTest -Dtests.method=testFaceting -Dtests.seed=DEEE934B89405AC7 -Dtests.slow=true -Dtests.locale=es_MX -Dtests.timezone=Indian/Mayotte -Dtests.file.encoding=US-ASCII
[junit4:junit4] ERROR    201s | SolrExampleBinaryTest.testFaceting <<<
[junit4:junit4]    > Throwable #1: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: https://127.0.0.1:53731/solr
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([DEEE934B89405AC7:F1E29A1B7EBC5F20]:0)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:435)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:180)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:117)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.SolrServer.commit(SolrServer.java:168)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.SolrServer.commit(SolrServer.java:146)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.SolrExampleTests.testFaceting(SolrExampleTests.java:914)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:680)
[junit4:junit4]    > Caused by: org.apache.http.NoHttpResponseException: The target server failed to respond
[junit4:junit4]    > 	at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:95)
[junit4:junit4]    > 	at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:62)
[junit4:junit4]    > 	at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:254)
[junit4:junit4]    > 	at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:289)
[junit4:junit4]    > 	at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:252)
[junit4:junit4]    > 	at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:191)
[junit4:junit4]    > 	at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:300)
[junit4:junit4]    > 	at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:127)
[junit4:junit4]    > 	at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:717)
[junit4:junit4]    > 	at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:522)
[junit4:junit4]    > 	at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:906)
[junit4:junit4]    > 	at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:805)
[junit4:junit4]    > 	at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:784)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:365)
[junit4:junit4]    > 	... 45 more
[junit4:junit4]   1> 263464 [TEST-SolrExampleBinaryTest.testSetNullUpdates-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testSetNullUpdates
[junit4:junit4]   1> 263506 [qtp1905823030-60] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[testSetNullUpdates (1433268822679224320)]} 0 4
[junit4:junit4]   1> 263515 [qtp1905823030-61] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 263523 [qtp1905823030-61] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_f,generation=15}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_g,generation=16}
[junit4:junit4]   1> 263524 [qtp1905823030-61] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 16
[junit4:junit4]   1> 263527 [qtp1905823030-61] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@2b6b0c24 main
[junit4:junit4]   1> 263530 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@2b6b0c24 main{StandardDirectoryReader(segments_g:31:nrt _7(4.4):C1)}
[junit4:junit4]   1> 263531 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 263535 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@2b6b0c24 main{StandardDirectoryReader(segments_g:31:nrt _7(4.4):C1)}
[junit4:junit4]   1> 263538 [qtp1905823030-61] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 263540 [qtp1905823030-61] 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 25
[junit4:junit4]   1> 263562 [qtp1905823030-56] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[testSetNullUpdates (1433268822738993152)]} 0 3
[junit4:junit4]   1> 263571 [qtp1905823030-58] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 263578 [qtp1905823030-58] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_g,generation=16}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_h,generation=17}
[junit4:junit4]   1> 263579 [qtp1905823030-58] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 17
[junit4:junit4]   1> 263583 [qtp1905823030-58] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@566f0962 main
[junit4:junit4]   1> 263587 [qtp1905823030-58] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 263588 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@566f0962 main{StandardDirectoryReader(segments_h:35:nrt _8(4.4):C1)}
[junit4:junit4]   1> 263589 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 263594 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@566f0962 main{StandardDirectoryReader(segments_h:35:nrt _8(4.4):C1)}
[junit4:junit4]   1> 263597 [qtp1905823030-58] 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 26
[junit4:junit4]   1> 263606 [qtp1905823030-57] 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:junit4]   1> 263621 [TEST-SolrExampleBinaryTest.testSetNullUpdates-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testSetNullUpdates
[junit4:junit4]   1> 263671 [TEST-SolrExampleBinaryTest.testMultiContentStreamRequest-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testMultiContentStreamRequest
[junit4:junit4]   1> 263687 [qtp1905823030-59] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@6e1f5438 realtime
[junit4:junit4]   1> 263689 [qtp1905823030-59] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433268822869016576)} 0 5
[junit4:junit4]   1> 263699 [qtp1905823030-60] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 263701 [qtp1905823030-60] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_h,generation=17}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_i,generation=18}
[junit4:junit4]   1> 263703 [qtp1905823030-60] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 18
[junit4:junit4]   1> 263705 [qtp1905823030-60] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@7730661d main
[junit4:junit4]   1> 263706 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@7730661d main{StandardDirectoryReader(segments_h:37:nrt)}
[junit4:junit4]   1> 263709 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 263715 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@7730661d main{StandardDirectoryReader(segments_h:37:nrt)}
[junit4:junit4]   1> 263744 [qtp1905823030-60] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 263756 [qtp1905823030-60] 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 57
[junit4:junit4]   1> 263772 [qtp1905823030-61] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=3 
[junit4:junit4]   1> 264245 [qtp1905823030-56] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 264271 [qtp1905823030-56] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_i,generation=18}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_j,generation=19}
[junit4:junit4]   1> 264272 [qtp1905823030-56] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 19
[junit4:junit4]   1> 264288 [qtp1905823030-56] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@500fbfa2 main
[junit4:junit4]   1> 264292 [qtp1905823030-56] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 264294 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@500fbfa2 main{StandardDirectoryReader(segments_j:39:nrt _9(4.4):C5)}
[junit4:junit4]   1> 264303 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 264310 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@500fbfa2 main{StandardDirectoryReader(segments_j:39:nrt _9(4.4):C5)}
[junit4:junit4]   1> 264312 [qtp1905823030-56] 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 (1433268823376527360), 6H500F0 (1433268823396450304), TWINX2048-3200PRO (1433268823401693184), VS1GB400C3 (1433268823448879104), VDBDB1A16 (1433268823453073408)],commit=} 0 154
[junit4:junit4]   1> 264322 [qtp1905823030-59] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=5 status=0 QTime=1 
[junit4:junit4]   1> 264358 [TEST-SolrExampleBinaryTest.testMultiContentStreamRequest-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testMultiContentStreamRequest
[junit4:junit4]   1> 264392 [TEST-SolrExampleBinaryTest.testContentStreamRequest-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testContentStreamRequest
[junit4:junit4]   1> 264403 [qtp1905823030-60] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@ddb1fe0 realtime
[junit4:junit4]   1> 264404 [qtp1905823030-60] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433268823618748416)} 0 5
[junit4:junit4]   1> 264415 [qtp1905823030-61] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 264418 [qtp1905823030-61] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_j,generation=19}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_k,generation=20}
[junit4:junit4]   1> 264420 [qtp1905823030-61] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 20
[junit4:junit4]   1> 264424 [qtp1905823030-61] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@5b2c9e5d main
[junit4:junit4]   1> 264428 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@5b2c9e5d main{StandardDirectoryReader(segments_j:41:nrt)}
[junit4:junit4]   1> 264429 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 264436 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@5b2c9e5d main{StandardDirectoryReader(segments_j:41:nrt)}
[junit4:junit4]   1> 264441 [qtp1905823030-61] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 264443 [qtp1905823030-61] 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 28
[junit4:junit4]   1> 264452 [qtp1905823030-58] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=2 
[junit4:junit4]   1> 264761 [qtp1905823030-57] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 264787 [qtp1905823030-57] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_k,generation=20}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_l,generation=21}
[junit4:junit4]   1> 264789 [qtp1905823030-57] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 21
[junit4:junit4]   1> 264796 [qtp1905823030-57] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@7c75c2be main
[junit4:junit4]   1> 264798 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@7c75c2be main{StandardDirectoryReader(segments_l:43:nrt _a(4.4):C10)}
[junit4:junit4]   1> 264799 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 264798 [qtp1905823030-57] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 264804 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@7c75c2be main{StandardDirectoryReader(segments_l:43:nrt _a(4.4):C10)}
[junit4:junit4]   1> 264805 [qtp1905823030-57] 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 (1433268823956389888), 0553579908 (1433268823961632768), 055357342X (1433268823971069952), 0553293354 (1433268823975264256), 0812521390 (1433268823978409984), 0812550706 (1433268823980507136), 0441385532 (1433268823986798592), 0380014300 (1433268823989944320), 0805080481 (1433268823993090048), 080508049X (1433268823996235776)],commit=} 0 317
[junit4:junit4]   1> 264814 [qtp1905823030-59] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=2 
[junit4:junit4]   1> 264837 [TEST-SolrExampleBinaryTest.testContentStreamRequest-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testContentStreamRequest
[junit4:junit4]   1> 264889 [TEST-SolrExampleBinaryTest.testUpdateField-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testUpdateField
[junit4:junit4]   1> 264908 [qtp1905823030-60] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@2396649c realtime
[junit4:junit4]   1> 264909 [qtp1905823030-60] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433268824148279296)} 0 5
[junit4:junit4]   1> 264921 [qtp1905823030-61] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 264932 [qtp1905823030-61] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_l,generation=21}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_m,generation=22}
[junit4:junit4]   1> 264933 [qtp1905823030-61] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 22
[junit4:junit4]   1> 264935 [qtp1905823030-61] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@6e024d3a main
[junit4:junit4]   1> 264942 [qtp1905823030-61] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 264945 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@6e024d3a main{StandardDirectoryReader(segments_l:45:nrt)}
[junit4:junit4]   1> 264947 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 264953 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@6e024d3a main{StandardDirectoryReader(segments_l:45:nrt)}
[junit4:junit4]   1> 264956 [qtp1905823030-61] 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 36
[junit4:junit4]   1> 264968 [qtp1905823030-58] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[unique (1433268824211193856)]} 0 4
[junit4:junit4]   1> 264976 [qtp1905823030-57] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 264990 [qtp1905823030-57] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_m,generation=22}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_n,generation=23}
[junit4:junit4]   1> 264991 [qtp1905823030-57] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 23
[junit4:junit4]   1> 265015 [qtp1905823030-57] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@751d3ec8 main
[junit4:junit4]   1> 265020 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@751d3ec8 main{StandardDirectoryReader(segments_n:47:nrt _b(4.4):C1)}
[junit4:junit4]   1> 265021 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 265024 [qtp1905823030-57] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 265027 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@751d3ec8 main{StandardDirectoryReader(segments_n:47:nrt _b(4.4):C1)}
[junit4:junit4]   1> 265030 [qtp1905823030-57] 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 54
[junit4:junit4]   1> 265088 [qtp1905823030-56] 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=37 
[junit4:junit4]   1> 265108 [qtp1905823030-59] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {} 0 2
[junit4:junit4]   1> 265122 [qtp1905823030-60] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[unique (1433268824373723136)]} 0 4
[junit4:junit4]   1> 265134 [qtp1905823030-61] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 265151 [qtp1905823030-61] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_n,generation=23}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_o,generation=24}
[junit4:junit4]   1> 265156 [qtp1905823030-61] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 24
[junit4:junit4]   1> 265163 [qtp1905823030-61] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@4c4947d3 main
[junit4:junit4]   1> 265174 [qtp1905823030-61] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 265176 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@4c4947d3 main{StandardDirectoryReader(segments_o:51:nrt _c(4.4):C1)}
[junit4:junit4]   1> 265177 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 265211 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@4c4947d3 main{StandardDirectoryReader(segments_o:51:nrt _c(4.4):C1)}
[junit4:junit4]   1> 265233 [qtp1905823030-61] 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 99
[junit4:junit4]   1> 265241 [qtp1905823030-57] 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:junit4]   1> 265252 [qtp1905823030-56] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[unique (1433268824510038016)]} 0 4
[junit4:junit4]   1> 265270 [qtp1905823030-59] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 265286 [qtp1905823030-59] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_o,generation=24}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_p,generation=25}
[junit4:junit4]   1> 265287 [qtp1905823030-59] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 25
[junit4:junit4]   1> 265295 [qtp1905823030-59] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@77feb2ea main
[junit4:junit4]   1> 265304 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@77feb2ea main{StandardDirectoryReader(segments_p:55:nrt _d(4.4):C1)}
[junit4:junit4]   1> 265305 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 265312 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@77feb2ea main{StandardDirectoryReader(segments_p:55:nrt _d(4.4):C1)}
[junit4:junit4]   1> 265317 [qtp1905823030-59] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 265319 [qtp1905823030-59] 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 50
[junit4:junit4]   1> 265332 [qtp1905823030-60] 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=2 
[junit4:junit4]   1> 265337 [TEST-SolrExampleBinaryTest.testUpdateField-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testUpdateField
[junit4:junit4]   1> 265383 [TEST-SolrExampleBinaryTest.testUnicode-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testUnicode
[junit4:junit4]   1> 265407 [qtp1905823030-61] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@48b89bc5 realtime
[junit4:junit4]   1> 265408 [qtp1905823030-61] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1433268824672567296)} 0 5
[junit4:junit4]   1> 265602 [qtp1905823030-58] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1433268824870748160), 1 (1433268824872845312), 2 (1433268824874942464), 3 (1433268824874942465), 4 (1433268824875991040), 5 (1433268824877039616), 6 (1433268824878088192), 7 (1433268824878088193)]} 0 8
[junit4:junit4]   1> 265608 [qtp1905823030-57] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 265613 [qtp1905823030-57] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_p,generation=25}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_q,generation=26}
[junit4:junit4]   1> 265614 [qtp1905823030-57] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 26
[junit4:junit4]   1> 265616 [qtp1905823030-57] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@6750cf54 main
[junit4:junit4]   1> 265620 [qtp1905823030-57] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 265622 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@6750cf54 main{StandardDirectoryReader(segments_q:59:nrt _e(4.4):C8)}
[junit4:junit4]   1> 265622 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 265638 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@6750cf54 main{StandardDirectoryReader(segments_q:59:nrt _e(4.4):C8)}
[junit4:junit4]   1> 265640 [qtp1905823030-57] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 32
[junit4:junit4]   1> 265653 [qtp1905823030-59] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2&rows=8} hits=8 status=0 QTime=2 
[junit4:junit4]   1> 265679 [qtp1905823030-60] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@8afcd0c realtime
[junit4:junit4]   1> 265681 [qtp1905823030-60] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433268824957779968)} 0 5
[junit4:junit4]   1> 265699 [qtp1905823030-61] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 (1433268824973508608), 1 (1433268824974557184), 2 (1433268824977702912), 3 (1433268824978751488), 4 (1433268824978751489), 5 (1433268824979800064), 6 (1433268824979800065), 7 (1433268824980848640)]} 0 8
[junit4:junit4]   1> 265709 [qtp1905823030-58] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 265717 [qtp1905823030-58] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_q,generation=26}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_r,generation=27}
[junit4:junit4]   1> 265719 [qtp1905823030-58] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 27
[junit4:junit4]   1> 265723 [qtp1905823030-58] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@6d642fd main
[junit4:junit4]   1> 265740 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@6d642fd main{StandardDirectoryReader(segments_r:63:nrt _f(4.4):C8)}
[junit4:junit4]   1> 265741 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 265746 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@6d642fd main{StandardDirectoryReader(segments_r:63:nrt _f(4.4):C8)}
[junit4:junit4]   1> 265740 [qtp1905823030-58] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 265754 [qtp1905823030-58] 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 45
[junit4:junit4]   1> 265762 [qtp1905823030-57] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2&rows=8} hits=8 status=0 QTime=1 
[junit4:junit4]   1> 265800 [qtp1905823030-56] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@bdd040a realtime
[junit4:junit4]   1> 265801 [qtp1905823030-56] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1433268825083609088)} 0 5
[junit4:junit4]   1> 265812 [qtp1905823030-59] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1433268825097240576), 1 (1433268825098289152), 2 (1433268825099337728)]} 0 3
[junit4:junit4]   1> 265820 [qtp1905823030-60] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 265833 [qtp1905823030-60] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_r,generation=27}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_s,generation=28}
[junit4:junit4]   1> 265835 [qtp1905823030-60] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 28
[junit4:junit4]   1> 265838 [qtp1905823030-60] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@5ad52411 main
[junit4:junit4]   1> 265840 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@5ad52411 main{StandardDirectoryReader(segments_s:67:nrt _g(4.4):C3)}
[junit4:junit4]   1> 265841 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 265846 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@5ad52411 main{StandardDirectoryReader(segments_s:67:nrt _g(4.4):C3)}
[junit4:junit4]   1> 265850 [qtp1905823030-60] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 265851 [qtp1905823030-60] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 31
[junit4:junit4]   1> 265860 [qtp1905823030-61] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2&rows=3} hits=3 status=0 QTime=2 
[junit4:junit4]   1> 265868 [TEST-SolrExampleBinaryTest.testUnicode-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testUnicode
[junit4:junit4]   1> 265942 [TEST-SolrExampleBinaryTest.testAddRetrieve-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testAddRetrieve
[junit4:junit4]   1> 265992 [qtp1905823030-58] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@7dabdb8a realtime
[junit4:junit4]   1> 265997 [qtp1905823030-58] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433268825262915584)} 0 31
[junit4:junit4]   1> 266027 [qtp1905823030-57] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id1 (1433268825315344384), id2 (1433268825320587264)]} 0 11
[junit4:junit4]   1> 266085 [qtp1905823030-56] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 266094 [qtp1905823030-56] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_s,generation=28}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_t,generation=29}
[junit4:junit4]   1> 266095 [qtp1905823030-56] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 29
[junit4:junit4]   1> 266102 [qtp1905823030-56] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@2393846a main
[junit4:junit4]   1> 266140 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@2393846a main{StandardDirectoryReader(segments_t:71:nrt _h(4.4):C2)}
[junit4:junit4]   1> 266141 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 266149 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@2393846a main{StandardDirectoryReader(segments_t:71:nrt _h(4.4):C2)}
[junit4:junit4]   1> 266156 [qtp1905823030-56] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 266163 [qtp1905823030-56] 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 79
[junit4:junit4]   1> 266591 [qtp1905823030-60] 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=376 
[junit4:junit4]   1> 266612 [qtp1905823030-58] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id1 (1433268825929809920), id2 (1433268825936101376)]} 0 9
[junit4:junit4]   1> 266633 [qtp1905823030-57] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 266654 [qtp1905823030-57] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_t,generation=29}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_u,generation=30}
[junit4:junit4]   1> 266655 [qtp1905823030-57] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 30
[junit4:junit4]   1> 266658 [qtp1905823030-57] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@6473fc2 main
[junit4:junit4]   1> 266659 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@6473fc2 main{StandardDirectoryReader(segments_u:75:nrt _i(4.4):C2)}
[junit4:junit4]   1> 266660 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 266670 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@6473fc2 main{StandardDirectoryReader(segments_u:75:nrt _i(4.4):C2)}
[junit4:junit4]   1> 266672 [qtp1905823030-57] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 266672 [qtp1905823030-57] 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 39
[junit4:junit4]   1> 266679 [qtp1905823030-59] 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:junit4]   1> 266691 [qtp1905823030-56] 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=3 
[junit4:junit4]   1> 266693 [TEST-SolrExampleBinaryTest.testAddRetrieve-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testAddRetrieve
[junit4:junit4]   1> 266724 [TEST-SolrExampleBinaryTest.testGetEmptyResults-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testGetEmptyResults
[junit4:junit4]   1> 266736 [qtp1905823030-60] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@51d7aa64 realtime
[junit4:junit4]   1> 266737 [qtp1905823030-60] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433268826064027648)} 0 6
[junit4:junit4]   1> 266746 [qtp1905823030-61] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 266748 [qtp1905823030-61] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_u,generation=30}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_v,generation=31}
[junit4:junit4]   1> 266750 [qtp1905823030-61] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 31
[junit4:junit4]   1> 266758 [qtp1905823030-61] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@4f9c205b main
[junit4:junit4]   1> 266761 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@4f9c205b main{StandardDirectoryReader(segments_u:77:nrt)}
[junit4:junit4]   1> 266769 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 266770 [qtp1905823030-61] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 266775 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@4f9c205b main{StandardDirectoryReader(segments_u:77:nrt)}
[junit4:junit4]   1> 266777 [qtp1905823030-61] 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 31
[junit4:junit4]   1> 266788 [qtp1905823030-58] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id1 (1433268826120650752)]} 0 3
[junit4:junit4]   1> 266812 [qtp1905823030-57] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id2 (1433268826135330816)]} 0 13
[junit4:junit4]   1> 266826 [qtp1905823030-59] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 266832 [qtp1905823030-59] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_v,generation=31}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_w,generation=32}
[junit4:junit4]   1> 266833 [qtp1905823030-59] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 32
[junit4:junit4]   1> 266839 [qtp1905823030-59] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@76eb1327 main
[junit4:junit4]   1> 266841 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@76eb1327 main{StandardDirectoryReader(segments_w:79:nrt _j(4.4):C2)}
[junit4:junit4]   1> 266842 [qtp1905823030-59] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 266844 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 266849 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@76eb1327 main{StandardDirectoryReader(segments_w:79:nrt _j(4.4):C2)}
[junit4:junit4]   1> 266850 [qtp1905823030-59] 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 25
[junit4:junit4]   1> 266878 [qtp1905823030-56] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={fl=foofoofoo&q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=18 
[junit4:junit4]   1> 266894 [TEST-SolrExampleBinaryTest.testGetEmptyResults-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testGetEmptyResults
[junit4:junit4]   1> 266934 [TEST-SolrExampleBinaryTest.testLukeHandler-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testLukeHandler
[junit4:junit4]   1> 266949 [qtp1905823030-60] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@c351f6d realtime
[junit4:junit4]   1> 266952 [qtp1905823030-60] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433268826283180032)} 0 12
[junit4:junit4]   1> 266966 [qtp1905823030-61] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID0 (1433268826307297280)]} 0 3
[junit4:junit4]   1> 267003 [qtp1905823030-58] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID1 (1433268826347143168)]} 0 2
[junit4:junit4]   1> 267025 [qtp1905823030-59] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID2 (1433268826370211840)]} 0 2
[junit4:junit4]   1> 267036 [qtp1905823030-56] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID3 (1433268826382794752)]} 0 1
[junit4:junit4]   1> 267047 [qtp1905823030-60] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID4 (1433268826394329088)]} 0 1
[junit4:junit4]   1> 267056 [qtp1905823030-61] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 267062 [qtp1905823030-61] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_w,generation=32}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_x,generation=33}
[junit4:junit4]   1> 267063 [qtp1905823030-61] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 33
[junit4:junit4]   1> 267067 [qtp1905823030-61] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@4b5d7792 main
[junit4:junit4]   1> 267071 [qtp1905823030-61] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 267073 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@4b5d7792 main{StandardDirectoryReader(segments_x:83:nrt _k(4.4):C5)}
[junit4:junit4]   1> 267074 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 267083 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@4b5d7792 main{StandardDirectoryReader(segments_x:83:nrt _k(4.4):C5)}
[junit4:junit4]   1> 267087 [qtp1905823030-61] 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:junit4]   1> 267096 [qtp1905823030-58] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=5 status=0 QTime=1 
[junit4:junit4]   1> 267203 [qtp1905823030-57] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/admin/luke params={wt=javabin&version=2} status=0 QTime=72 
[junit4:junit4]   1> 267248 [qtp1905823030-59] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/admin/luke params={show=schema&wt=javabin&version=2} status=0 QTime=10 
[junit4:junit4]   1> 267355 [TEST-SolrExampleBinaryTest.testLukeHandler-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testLukeHandler
[junit4:junit4]   1> 267421 [TEST-SolrExampleBinaryTest.testQueryWithParams-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testQueryWithParams
[junit4:junit4]   1> 267847 [qtp1905823030-56] 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=420 
[junit4:junit4]   1> 267849 [TEST-SolrExampleBinaryTest.testQueryWithParams-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testQueryWithParams
[junit4:junit4]   1> 267866 [TEST-SolrExampleBinaryTest.testPingHandler-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testPingHandler
[junit4:junit4]   1> 267874 [qtp1905823030-58] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@63bed674 realtime
[junit4:junit4]   1> 267876 [qtp1905823030-58] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433268827260452864)} 0 5
[junit4:junit4]   1> 267883 [qtp1905823030-57] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 267887 [qtp1905823030-57] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_x,generation=33}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_y,generation=34}
[junit4:junit4]   1> 267892 [qtp1905823030-57] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 34
[junit4:junit4]   1> 267894 [qtp1905823030-57] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@65202d8a main
[junit4:junit4]   1> 267898 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@65202d8a main{StandardDirectoryReader(segments_x:85:nrt)}
[junit4:junit4]   1> 267899 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 267899 [qtp1905823030-57] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 267902 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@65202d8a main{StandardDirectoryReader(segments_x:85:nrt)}
[junit4:junit4]   1> 267904 [qtp1905823030-57] 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 22
[junit4:junit4]   1> 267915 [qtp1905823030-59] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=2 
[junit4:junit4]   1> 268490 [qtp1905823030-60] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/admin/ping params={wt=javabin&version=2} hits=0 status=0 QTime=387 
[junit4:junit4]   1> 268493 [qtp1905823030-60] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/admin/ping params={wt=javabin&version=2} status=0 QTime=389 
[junit4:junit4]   1> 268498 [TEST-SolrExampleBinaryTest.testPingHandler-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testPingHandler
[junit4:junit4]   1> 268531 [TEST-SolrExampleBinaryTest.testUpdateMultiValuedField-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testUpdateMultiValuedField
[junit4:junit4]   1> 268545 [qtp1905823030-56] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123 (1433268827964047360)]} 0 2
[junit4:junit4]   1> 268554 [qtp1905823030-58] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 268560 [qtp1905823030-58] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_y,generation=34}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_z,generation=35}
[junit4:junit4]   1> 268561 [qtp1905823030-58] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 35
[junit4:junit4]   1> 268562 [qtp1905823030-58] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@49dc11af main
[junit4:junit4]   1> 268565 [qtp1905823030-58] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 268567 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@49dc11af main{StandardDirectoryReader(segments_z:87:nrt _l(4.4):C1)}
[junit4:junit4]   1> 268567 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 268570 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@49dc11af main{StandardDirectoryReader(segments_z:87:nrt _l(4.4):C1)}
[junit4:junit4]   1> 268575 [qtp1905823030-58] 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:junit4]   1> 268587 [qtp1905823030-57] 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:junit4]   1> 268597 [qtp1905823030-59] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123 (1433268828018573312)]} 0 2
[junit4:junit4]   1> 268605 [qtp1905823030-60] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 268615 [qtp1905823030-60] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_z,generation=35}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_10,generation=36}
[junit4:junit4]   1> 268617 [qtp1905823030-60] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 36
[junit4:junit4]   1> 268676 [qtp1905823030-60] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@3cec7c94 main
[junit4:junit4]   1> 268680 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@3cec7c94 main{StandardDirectoryReader(segments_10:91:nrt _m(4.4):C1)}
[junit4:junit4]   1> 268681 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 268692 [qtp1905823030-60] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 268696 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@3cec7c94 main{StandardDirectoryReader(segments_10:91:nrt _m(4.4):C1)}
[junit4:junit4]   1> 268710 [qtp1905823030-60] 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 105
[junit4:junit4]   1> 268720 [qtp1905823030-61] 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:junit4]   1> 268742 [qtp1905823030-56] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123 (1433268828168519680)]} 0 7
[junit4:junit4]   1> 268752 [qtp1905823030-58] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 268760 [qtp1905823030-58] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_10,generation=36}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_11,generation=37}
[junit4:junit4]   1> 268762 [qtp1905823030-58] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 37
[junit4:junit4]   1> 268767 [qtp1905823030-58] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@314f1b7f main
[junit4:junit4]   1> 268772 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@314f1b7f main{StandardDirectoryReader(segments_11:95:nrt _n(4.4):C1)}
[junit4:junit4]   1> 268774 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 268824 [qtp1905823030-58] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 268826 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@314f1b7f main{StandardDirectoryReader(segments_11:95:nrt _n(4.4):C1)}
[junit4:junit4]   1> 268827 [qtp1905823030-58] 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 75
[junit4:junit4]   1> 268836 [qtp1905823030-57] 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:junit4]   1> 268843 [TEST-SolrExampleBinaryTest.testUpdateMultiValuedField-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testUpdateMultiValuedField
[junit4:junit4]   1> 268875 [TEST-SolrExampleBinaryTest.testUpdateRequestWithParameters-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testUpdateRequestWithParameters
[junit4:junit4]   1> 268876 [TEST-SolrExampleBinaryTest.testUpdateRequestWithParameters-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.client.solrj.impl.HttpClientUtil  ? Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   1> 268948 [qtp1905823030-58] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@32e6e42e realtime
[junit4:junit4]   1> 268949 [qtp1905823030-58] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433268828386623488)} 0 4
[junit4:junit4]   1> 268963 [qtp1905823030-57] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 268965 [qtp1905823030-57] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_11,generation=37}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_12,generation=38}
[junit4:junit4]   1> 268966 [qtp1905823030-57] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 38
[junit4:junit4]   1> 268968 [qtp1905823030-57] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@64900079 main
[junit4:junit4]   1> 268971 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@64900079 main{StandardDirectoryReader(segments_11:97:nrt)}
[junit4:junit4]   1> 268972 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 268971 [qtp1905823030-57] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 268976 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@64900079 main{StandardDirectoryReader(segments_11:97:nrt)}
[junit4:junit4]   1> 268983 [qtp1905823030-57] 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 19
[junit4:junit4]   1> 269001 [qtp1905823030-59] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={overwrite=false&wt=javabin&version=2} {add=[id1 (1433268828440100864)]} 0 5
[junit4:junit4]   1> 269022 [qtp1905823030-61] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={overwrite=false&wt=javabin&version=2} {add=[id1 (1433268828451635200)]} 0 14
[junit4:junit4]   1> 269038 [qtp1905823030-60] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 269047 [qtp1905823030-60] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_12,generation=38}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_13,generation=39}
[junit4:junit4]   1> 269048 [qtp1905823030-60] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 39
[junit4:junit4]   1> 269050 [qtp1905823030-60] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@3154fc43 main
[junit4:junit4]   1> 269052 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@3154fc43 main{StandardDirectoryReader(segments_13:99:nrt _o(4.4):C2)}
[junit4:junit4]   1> 269052 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 269063 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@3154fc43 main{StandardDirectoryReader(segments_13:99:nrt _o(4.4):C2)}
[junit4:junit4]   1> 269052 [qtp1905823030-60] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 269065 [qtp1905823030-60] 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 26
[junit4:junit4]   1> 269072 [qtp1905823030-56] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=1 
[junit4:junit4]   1> 269076 [TEST-SolrExampleBinaryTest.testUpdateRequestWithParameters-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testUpdateRequestWithParameters
[junit4:junit4]   1> 269099 [TEST-SolrExampleBinaryTest.testChineseDefaults-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testChineseDefaults
[junit4:junit4]   1> 269107 [qtp1905823030-58] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@7448074a realtime
[junit4:junit4]   1> 269109 [qtp1905823030-58] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433268828552298496)} 0 5
[junit4:junit4]   1> 269128 [qtp1905823030-57] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 269130 [qtp1905823030-57] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_13,generation=39}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_14,generation=40}
[junit4:junit4]   1> 269131 [qtp1905823030-57] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 40
[junit4:junit4]   1> 269133 [qtp1905823030-57] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@14cfc659 main
[junit4:junit4]   1> 269136 [qtp1905823030-57] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 269138 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@14cfc659 main{StandardDirectoryReader(segments_13:101:nrt)}
[junit4:junit4]   1> 269140 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 269144 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@14cfc659 main{StandardDirectoryReader(segments_13:101:nrt)}
[junit4:junit4]   1> 269146 [qtp1905823030-57] 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 18
[junit4:junit4]   1> 269154 [qtp1905823030-59] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1 
[junit4:junit4]   1> 269164 [qtp1905823030-61] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 269171 [qtp1905823030-61] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_14,generation=40}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_15,generation=41}
[junit4:junit4]   1> 269172 [qtp1905823030-61] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 41
[junit4:junit4]   1> 269178 [qtp1905823030-61] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@cd74b31 main
[junit4:junit4]   1> 269184 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@cd74b31 main{StandardDirectoryReader(segments_15:103:nrt _p(4.4):C1)}
[junit4:junit4]   1> 269190 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 269190 [qtp1905823030-61] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 269196 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@cd74b31 main{StandardDirectoryReader(segments_15:103:nrt _p(4.4):C1)}
[junit4:junit4]   1> 269198 [qtp1905823030-61] 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 (1433268828612067328)],commit=} 0 37
[junit4:junit4]   1> 269227 [qtp1905823030-60] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=????&wt=javabin&version=2} hits=1 status=0 QTime=2 
[junit4:junit4]   1> 269234 [TEST-SolrExampleBinaryTest.testChineseDefaults-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testChineseDefaults
[junit4:junit4]   1> 269278 [TEST-SolrExampleBinaryTest.testStreamingRequest-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testStreamingRequest
[junit4:junit4]   1> 269288 [qtp1905823030-56] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@18aa5e75 realtime
[junit4:junit4]   1> 269290 [qtp1905823030-56] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433268828742090752)} 0 6
[junit4:junit4]   1> 269296 [qtp1905823030-58] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 269300 [qtp1905823030-58] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_15,generation=41}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_16,generation=42}
[junit4:junit4]   1> 269301 [qtp1905823030-58] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 42
[junit4:junit4]   1> 269303 [qtp1905823030-58] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@5e6a1140 main
[junit4:junit4]   1> 269305 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@5e6a1140 main{StandardDirectoryReader(segments_15:105:nrt)}
[junit4:junit4]   1> 269306 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 269314 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@5e6a1140 main{StandardDirectoryReader(segments_15:105:nrt)}
[junit4:junit4]   1> 269307 [qtp1905823030-58] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 269319 [qtp1905823030-58] 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 23
[junit4:junit4]   1> 269328 [qtp1905823030-59] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1 
[junit4:junit4]   1> 269355 [qtp1905823030-61] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 269366 [qtp1905823030-61] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_16,generation=42}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_17,generation=43}
[junit4:junit4]   1> 269368 [qtp1905823030-61] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 43
[junit4:junit4]   1> 269373 [qtp1905823030-61] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@2853d34c main
[junit4:junit4]   1> 269383 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@2853d34c main{StandardDirectoryReader(segments_17:107:nrt _q(4.4):C10)}
[junit4:junit4]   1> 269385 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 269384 [qtp1905823030-61] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 269389 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@2853d34c main{StandardDirectoryReader(segments_17:107:nrt _q(4.4):C10)}
[junit4:junit4]   1> 269391 [qtp1905823030-61] 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 (1433268828797665280), 1 (1433268828800811008), 2 (1433268828807102464), 3 (1433268828808151040), 4 (1433268828809199616), 5 (1433268828810248192), 6 (1433268828811296768), 7 (1433268828813393920), 8 (1433268828814442496), 9 (1433268828814442497)],commit=} 0 53
[junit4:junit4]   1> 269403 [qtp1905823030-60] 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=2 
[junit4:junit4]   1> 269455 [qtp1905823030-56] 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:junit4]   1> 269472 [TEST-SolrExampleBinaryTest.testStreamingRequest-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testStreamingRequest
[junit4:junit4]   1> 269497 [TEST-SolrExampleBinaryTest.testExampleConfig-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testExampleConfig
[junit4:junit4]   1> 269519 [qtp1905823030-58] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@399c123d realtime
[junit4:junit4]   1> 269520 [qtp1905823030-58] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433268828970680320)} 0 18
[junit4:junit4]   1> 269529 [qtp1905823030-57] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1112211111 (1433268828995846144)]} 0 3
[junit4:junit4]   1> 269536 [qtp1905823030-59] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 269543 [qtp1905823030-59] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_17,generation=43}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_18,generation=44}
[junit4:junit4]   1> 269544 [qtp1905823030-59] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 44
[junit4:junit4]   1> 269600 [qtp1905823030-59] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@10d13e3e main
[junit4:junit4]   1> 269604 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@10d13e3e main{StandardDirectoryReader(segments_18:111:nrt _r(4.4):C1)}
[junit4:junit4]   1> 269604 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 269610 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@10d13e3e main{StandardDirectoryReader(segments_18:111:nrt _r(4.4):C1)}
[junit4:junit4]   1> 269611 [qtp1905823030-59] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 269617 [qtp1905823030-59] 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 81
[junit4:junit4]   1> 269626 [qtp1905823030-61] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 269629 [qtp1905823030-61] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_18,generation=44}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_19,generation=45}
[junit4:junit4]   1> 269634 [qtp1905823030-61] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 45
[junit4:junit4]   1> 269635 [qtp1905823030-61] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@8939ec3 main
[junit4:junit4]   1> 269635 [qtp1905823030-61] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 269635 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@8939ec3 main{StandardDirectoryReader(segments_18:111:nrt _r(4.4):C1)}
[junit4:junit4]   1> 269636 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 269639 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@8939ec3 main{StandardDirectoryReader(segments_18:111:nrt _r(4.4):C1)}
[junit4:junit4]   1> 269644 [qtp1905823030-61] 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 18
[junit4:junit4]   1> 269654 [qtp1905823030-60] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=id:1112211111&wt=javabin&version=2} hits=1 status=0 QTime=2 
[junit4:junit4]   1> 269697 [qtp1905823030-56] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2 (1433268829137403904), 3 (1433268829168861184), 4 (1433268829170958336), 5 (1433268829172006912)]} 0 35
[junit4:junit4]   1> 269705 [qtp1905823030-58] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 269725 [qtp1905823030-58] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_19,generation=45}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_1a,generation=46}
[junit4:junit4]   1> 269727 [qtp1905823030-58] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 46
[junit4:junit4]   1> 269731 [qtp1905823030-58] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@4774e78a main
[junit4:junit4]   1> 269733 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@4774e78a main{StandardDirectoryReader(segments_1a:113:nrt _r(4.4):C1 _s(4.4):C4)}
[junit4:junit4]   1> 269734 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 269738 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@4774e78a main{StandardDirectoryReader(segments_1a:113:nrt _r(4.4):C1 _s(4.4):C4)}
[junit4:junit4]   1> 269734 [qtp1905823030-58] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 269741 [qtp1905823030-58] 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 37
[junit4:junit4]   1> 269748 [qtp1905823030-59] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 269779 [qtp1905823030-59] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_1a,generation=46}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_1b,generation=47}
[junit4:junit4]   1> 269781 [qtp1905823030-59] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 47
[junit4:junit4]   1> 269785 [qtp1905823030-59] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@730eb2f0 main
[junit4:junit4]   1> 269786 [qtp1905823030-59] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 269787 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@730eb2f0 main{StandardDirectoryReader(segments_1b:115:nrt _t(4.4):C5)}
[junit4:junit4]   1> 269787 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 269790 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@730eb2f0 main{StandardDirectoryReader(segments_1b:115:nrt _t(4.4):C5)}
[junit4:junit4]   1> 269792 [qtp1905823030-59] 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 44
[junit4:junit4]   1> 270028 [qtp1905823030-61] 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=224 
[junit4:junit4]   1> 270072 [qtp1905823030-60] 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:junit4]   1> 270141 [qtp1905823030-56] 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=59 
[junit4:junit4]   1> 270144 [TEST-SolrExampleBinaryTest.testExampleConfig-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testExampleConfig
[junit4:junit4]   1> 270191 [TEST-SolrExampleBinaryTest.testAddDelete-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testAddDelete
[junit4:junit4]   1> 270198 [qtp1905823030-57] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@4b26f29f realtime
[junit4:junit4]   1> 270214 [qtp1905823030-57] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433268829696294912)} 0 19
[junit4:junit4]   1> 270230 [qtp1905823030-59] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 & 222 (1433268829730897920)]} 0 2
[junit4:junit4]   1> 270243 [qtp1905823030-61] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 270249 [qtp1905823030-61] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_1b,generation=47}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_1c,generation=48}
[junit4:junit4]   1> 270250 [qtp1905823030-61] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 48
[junit4:junit4]   1> 270255 [qtp1905823030-61] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@b53b098 main
[junit4:junit4]   1> 270258 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@b53b098 main{StandardDirectoryReader(segments_1c:119:nrt _u(4.4):C1)}
[junit4:junit4]   1> 270259 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 270269 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@b53b098 main{StandardDirectoryReader(segments_1c:119:nrt _u(4.4):C1)}
[junit4:junit4]   1> 270272 [qtp1905823030-61] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 270273 [qtp1905823030-61] 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 30
[junit4:junit4]   1> 270284 [qtp1905823030-60] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=1 
[junit4:junit4]   1> 270300 [qtp1905823030-56] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {delete=[0 & 222 (-1433268829799055360)]} 0 7
[junit4:junit4]   1> 270312 [qtp1905823030-58] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 270315 [qtp1905823030-58] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_1c,generation=48}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_1d,generation=49}
[junit4:junit4]   1> 270316 [qtp1905823030-58] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 49
[junit4:junit4]   1> 270318 [qtp1905823030-58] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@63ce50a9 main
[junit4:junit4]   1> 270319 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@63ce50a9 main{StandardDirectoryReader(segments_1d:121:nrt)}
[junit4:junit4]   1> 270321 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 270320 [qtp1905823030-58] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 270324 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@63ce50a9 main{StandardDirectoryReader(segments_1d:121:nrt)}
[junit4:junit4]   1> 270326 [qtp1905823030-58] 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 14
[junit4:junit4]   1> 270333 [qtp1905823030-57] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1 
[junit4:junit4]   1> 270341 [qtp1905823030-59] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 & 222 (1433268829847289856)]} 0 2
[junit4:junit4]   1> 270348 [qtp1905823030-61] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 270353 [qtp1905823030-61] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_1d,generation=49}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_1e,generation=50}
[junit4:junit4]   1> 270354 [qtp1905823030-61] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 50
[junit4:junit4]   1> 270357 [qtp1905823030-61] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@5daabda3 main
[junit4:junit4]   1> 270361 [qtp1905823030-61] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 270363 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@5daabda3 main{StandardDirectoryReader(segments_1e:123:nrt _v(4.4):C1)}
[junit4:junit4]   1> 270365 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 270371 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@5daabda3 main{StandardDirectoryReader(segments_1e:123:nrt _v(4.4):C1)}
[junit4:junit4]   1> 270374 [qtp1905823030-61] 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 27
[junit4:junit4]   1> 270380 [qtp1905823030-60] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=0 
[junit4:junit4]   1> 270390 [qtp1905823030-56] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@c1b9b03 realtime
[junit4:junit4]   1> 270391 [qtp1905823030-56] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=id:"0\ \&\ 222" (-1433268829897621504)} 0 4
[junit4:junit4]   1> 270398 [qtp1905823030-58] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 270402 [qtp1905823030-58] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_1e,generation=50}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_1f,generation=51}
[junit4:junit4]   1> 270403 [qtp1905823030-58] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 51
[junit4:junit4]   1> 270404 [qtp1905823030-58] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@147834eb main
[junit4:junit4]   1> 270406 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@147834eb main{StandardDirectoryReader(segments_1e:125:nrt)}
[junit4:junit4]   1> 270407 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 270411 [qtp1905823030-58] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 270418 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@147834eb main{StandardDirectoryReader(segments_1e:125:nrt)}
[junit4:junit4]   1> 270421 [qtp1905823030-58] 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 23
[junit4:junit4]   1> 270427 [qtp1905823030-57] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1 
[junit4:junit4]   1> 270437 [qtp1905823030-59] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 & 222 (1433268829947953152)]} 0 2
[junit4:junit4]   1> 270517 [qtp1905823030-61] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1 & 222 (1433268830003527680)]} 0 29
[junit4:junit4]   1> 270525 [qtp1905823030-60] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2 & 222 (1433268830041276416)]} 0 1
[junit4:junit4]   1> 270534 [qtp1905823030-56] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 270538 [qtp1905823030-56] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_1f,generation=51}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_1g,generation=52}
[junit4:junit4]   1> 270539 [qtp1905823030-56] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 52
[junit4:junit4]   1> 270542 [qtp1905823030-56] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@188f7e20 main
[junit4:junit4]   1> 270557 [qtp1905823030-56] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 270588 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@188f7e20 main{StandardDirectoryReader(segments_1g:127:nrt _w(4.4):C3)}
[junit4:junit4]   1> 270590 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 270601 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@188f7e20 main{StandardDirectoryReader(segments_1g:127:nrt _w(4.4):C3)}
[junit4:junit4]   1> 270627 [qtp1905823030-56] 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 93
[junit4:junit4]   1> 270659 [qtp1905823030-58] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=3 status=0 QTime=1 
[junit4:junit4]   1> 270673 [qtp1905823030-59] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {delete=[0 & 222 (-1433268830195417088), 1 & 222 (-1433268830197514240), 2 & 222 (-1433268830197514241)]} 0 2
[junit4:junit4]   1> 270742 [qtp1905823030-61] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 270746 [qtp1905823030-61] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_1g,generation=52}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_1h,generation=53}
[junit4:junit4]   1> 270747 [qtp1905823030-61] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 53
[junit4:junit4]   1> 270754 [qtp1905823030-61] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@3d3cb165 main
[junit4:junit4]   1> 270759 [qtp1905823030-61] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 270761 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@3d3cb165 main{StandardDirectoryReader(segments_1h:129:nrt)}
[junit4:junit4]   1> 270762 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 270766 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@3d3cb165 main{StandardDirectoryReader(segments_1h:129:nrt)}
[junit4:junit4]   1> 270769 [qtp1905823030-61] 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 27
[junit4:junit4]   1> 270777 [qtp1905823030-60] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1 
[junit4:junit4]   1> 270780 [TEST-SolrExampleBinaryTest.testAddDelete-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testAddDelete
[junit4:junit4]   1> 270801 [TEST-SolrExampleBinaryTest.testRealtimeGet-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testRealtimeGet
[junit4:junit4]   1> 270810 [qtp1905823030-56] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433268830338023424)} 0 3
[junit4:junit4]   1> 270818 [qtp1905823030-57] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[DOCID (1433268830348509184)]} 0 1
[junit4:junit4]   1> 270825 [qtp1905823030-58] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 270837 [qtp1905823030-58] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_1h,generation=53}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_1i,generation=54}
[junit4:junit4]   1> 270838 [qtp1905823030-58] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 54
[junit4:junit4]   1> 270842 [qtp1905823030-58] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@794f8b9a main
[junit4:junit4]   1> 270846 [qtp1905823030-58] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 270848 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@794f8b9a main{StandardDirectoryReader(segments_1i:131:nrt _x(4.4):C1)}
[junit4:junit4]   1> 270849 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 270853 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@794f8b9a main{StandardDirectoryReader(segments_1i:131:nrt _x(4.4):C1)}
[junit4:junit4]   1> 270854 [qtp1905823030-58] 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 29
[junit4:junit4]   1> 270864 [qtp1905823030-59] 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=1 
[junit4:junit4]   1> 270872 [qtp1905823030-61] 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:junit4]   1> 270876 [TEST-SolrExampleBinaryTest.testRealtimeGet-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testRealtimeGet
[junit4:junit4]   1> 270903 [TEST-SolrExampleBinaryTest.testErrorHandling-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testErrorHandling
[junit4:junit4]   1> 271039 [qtp1905823030-60] INFO  org.apache.solr.core.SolrCore  ? Ignoring exception matching ignore_exception
[junit4:junit4]   1> 271041 [qtp1905823030-60] 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=131 
[junit4:junit4]   1> 271380 [qtp1905823030-56] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {} 0 333
[junit4:junit4]   1> 271382 [qtp1905823030-56] INFO  org.apache.solr.core.SolrCore  ? Ignoring exception matching ignore_exception
[junit4:junit4]   1> 271391 [qtp1905823030-58] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {} 0 1
[junit4:junit4]   1> 271392 [qtp1905823030-58] INFO  org.apache.solr.core.SolrCore  ? Ignoring exception matching uniqueKey
[junit4:junit4]   1> 271395 [TEST-SolrExampleBinaryTest.testErrorHandling-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testErrorHandling
[junit4:junit4]   1> 271435 [TEST-SolrExampleBinaryTest.testAugmentFields-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testAugmentFields
[junit4:junit4]   1> 271442 [qtp1905823030-59] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@1afcfd10 realtime
[junit4:junit4]   1> 271446 [qtp1905823030-59] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433268831000723456)} 0 7
[junit4:junit4]   1> 271458 [qtp1905823030-61] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[111 (1433268831018549248)]} 0 2
[junit4:junit4]   1> 271465 [qtp1905823030-60] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 271475 [qtp1905823030-60] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_1i,generation=54}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_1j,generation=55}
[junit4:junit4]   1> 271476 [qtp1905823030-60] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 55
[junit4:junit4]   1> 271482 [qtp1905823030-60] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@5a6b54ef main
[junit4:junit4]   1> 271491 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@5a6b54ef main{StandardDirectoryReader(segments_1j:135:nrt _y(4.4):C1)}
[junit4:junit4]   1> 271492 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 271491 [qtp1905823030-60] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 271499 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@5a6b54ef main{StandardDirectoryReader(segments_1j:135:nrt _y(4.4):C1)}
[junit4:junit4]   1> 271502 [qtp1905823030-60] 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 36
[junit4:junit4]   1> 271510 [qtp1905823030-56] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[222 (1433268831073075200)]} 0 2
[junit4:junit4]   1> 271515 [qtp1905823030-57] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 271528 [qtp1905823030-57] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_1j,generation=55}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_1k,generation=56}
[junit4:junit4]   1> 271530 [qtp1905823030-57] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 56
[junit4:junit4]   1> 271534 [qtp1905823030-57] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@54d57bcb main
[junit4:junit4]   1> 271536 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@54d57bcb main{StandardDirectoryReader(segments_1k:137:nrt _y(4.4):C1 _z(4.4):C1)}
[junit4:junit4]   1> 271538 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 271542 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@54d57bcb main{StandardDirectoryReader(segments_1k:137:nrt _y(4.4):C1 _z(4.4):C1)}
[junit4:junit4]   1> 271538 [qtp1905823030-57] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 271545 [qtp1905823030-57] 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 30
[junit4:junit4]   1> 271578 [qtp1905823030-58] 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=16 
[junit4:junit4]   1> 271585 [TEST-SolrExampleBinaryTest.testAugmentFields-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testAugmentFields
[junit4:junit4]   1> 271628 [TEST-SolrExampleBinaryTest.testCommitWithinOnAdd-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testCommitWithinOnAdd
[junit4:junit4]   1> 271648 [qtp1905823030-59] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@2dbbec72 realtime
[junit4:junit4]   1> 271650 [qtp1905823030-59] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433268831215681536)} 0 7
[junit4:junit4]   1> 271662 [qtp1905823030-61] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 271664 [qtp1905823030-61] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lu

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

re  ? QuerySenderListener sending requests to Searcher@6d330af4 main{StandardDirectoryReader(segments_1l:145:nrt)}
[junit4:junit4]   1> 273900 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 273906 [qtp1905823030-61] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 273908 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@6d330af4 main{StandardDirectoryReader(segments_1l:145:nrt)}
[junit4:junit4]   1> 273913 [qtp1905823030-61] 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 43
[junit4:junit4]   1> 273922 [qtp1905823030-60] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1 
[junit4:junit4]   1> 273957 [qtp1905823030-56] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1 (1433268833620066304), 2 (1433268833624260608), 3 (1433268833626357760), 4 (1433268833627406336), 5 (1433268833628454912), 6 (1433268833630552064), 7 (1433268833631600640), 8 (1433268833632649216), 9 (1433268833634746368), 10 (1433268833636843520), ... (12 adds)]} 0 20
[junit4:junit4]   1> 273967 [qtp1905823030-58] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 273979 [qtp1905823030-58] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_1m,generation=58}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b0014f0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6102d81c),segFN=segments_1n,generation=59}
[junit4:junit4]   1> 273984 [qtp1905823030-58] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 59
[junit4:junit4]   1> 273996 [qtp1905823030-58] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@45884319 main
[junit4:junit4]   1> 273998 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@45884319 main{StandardDirectoryReader(segments_1n:147:nrt _12(4.4):C12)}
[junit4:junit4]   1> 273999 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 273999 [qtp1905823030-58] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 274009 [searcherExecutor-18-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@45884319 main{StandardDirectoryReader(segments_1n:147:nrt _12(4.4):C12)}
[junit4:junit4]   1> 274011 [qtp1905823030-58] 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 44
[junit4:junit4]   1> 274022 [qtp1905823030-59] 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:junit4]   1> 274024 [qtp1905823030-59] INFO  org.apache.solr.core.SolrCore  ? UnInverted multi-valued field {field=cat,memSize=4515,tindexSize=41,time=1,phase1=1,nTerms=2,bigTerms=2,termInstances=0,uses=0}
[junit4:junit4]   1> 274029 [qtp1905823030-59] 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=9 
[junit4:junit4]   1> 274045 [qtp1905823030-61] 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=4 
[junit4:junit4]   1> 274112 [TEST-SolrExampleBinaryTest.testPivotFacets-seed#[DEEE934B89405AC7]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testPivotFacets
[junit4:junit4]   1> 274166 [SUITE-SolrExampleBinaryTest-seed#[DEEE934B89405AC7]-worker] INFO  org.apache.solr.core.CoreContainer  ? Shutting down CoreContainer instance=905413847
[junit4:junit4]   1> 274168 [SUITE-SolrExampleBinaryTest-seed#[DEEE934B89405AC7]-worker] INFO  org.apache.solr.core.SolrCore  ? [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@2d74e4b3
[junit4:junit4]   1> 274212 [SUITE-SolrExampleBinaryTest-seed#[DEEE934B89405AC7]-worker] INFO  org.apache.solr.update.UpdateHandler  ? closing DirectUpdateHandler2{commits=59,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=27,cumulative_errors=1}
[junit4:junit4]   1> 274217 [SUITE-SolrExampleBinaryTest-seed#[DEEE934B89405AC7]-worker] INFO  org.apache.solr.update.SolrCoreState  ? Closing SolrCoreState
[junit4:junit4]   1> 274250 [SUITE-SolrExampleBinaryTest-seed#[DEEE934B89405AC7]-worker] INFO  org.apache.solr.update.DefaultSolrCoreState  ? SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   1> 274260 [SUITE-SolrExampleBinaryTest-seed#[DEEE934B89405AC7]-worker] INFO  org.apache.solr.update.DefaultSolrCoreState  ? closing IndexWriter with IndexWriterCloser
[junit4:junit4]   1> 274274 [SUITE-SolrExampleBinaryTest-seed#[DEEE934B89405AC7]-worker] INFO  org.apache.solr.core.SolrCore  ? [collection1] Closing main searcher on request.
[junit4:junit4]   1> 274275 [SUITE-SolrExampleBinaryTest-seed#[DEEE934B89405AC7]-worker] INFO  org.apache.solr.core.CachingDirectoryFactory  ? Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4]   1> 274276 [SUITE-SolrExampleBinaryTest-seed#[DEEE934B89405AC7]-worker] INFO  org.apache.solr.core.CachingDirectoryFactory  ? looking to close /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleBinaryTest-1366871452994/index [CachedDir<<refCount=0;path=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleBinaryTest-1366871452994/index;done=false>>]
[junit4:junit4]   1> 274276 [SUITE-SolrExampleBinaryTest-seed#[DEEE934B89405AC7]-worker] INFO  org.apache.solr.core.CachingDirectoryFactory  ? Closing directory: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleBinaryTest-1366871452994/index
[junit4:junit4]   1> 274277 [SUITE-SolrExampleBinaryTest-seed#[DEEE934B89405AC7]-worker] INFO  org.apache.solr.core.CachingDirectoryFactory  ? looking to close /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleBinaryTest-1366871452994 [CachedDir<<refCount=0;path=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleBinaryTest-1366871452994;done=false>>]
[junit4:junit4]   1> 274278 [SUITE-SolrExampleBinaryTest-seed#[DEEE934B89405AC7]-worker] INFO  org.apache.solr.core.CachingDirectoryFactory  ? Closing directory: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleBinaryTest-1366871452994
[junit4:junit4]   1> 274279 [SUITE-SolrExampleBinaryTest-seed#[DEEE934B89405AC7]-worker] INFO  org.eclipse.jetty.server.handler.ContextHandler  ? stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   1> 274339 [SUITE-SolrExampleBinaryTest-seed#[DEEE934B89405AC7]-worker] INFO  org.apache.solr.SolrTestCaseJ4  ? ###deleteCore
[junit4:junit4]   2> NOTE: test params are: codec=Lucene42: {}, docValues:{}, sim=DefaultSimilarity, locale=es_MX, timezone=Indian/Mayotte
[junit4:junit4]   2> NOTE: Mac OS X 10.8.3 x86_64/Apple Inc. 1.6.0_45 (64-bit)/cpus=2,threads=1,free=43624816,total=113246208
[junit4:junit4]   2> NOTE: All tests run in this JVM: [JettyWebappTest, LargeVolumeJettyTest, AnlysisResponseBaseTest, SolrExampleBinaryTest]
[junit4:junit4] Completed in 222.86s, 26 tests, 1 error <<< FAILURES!

[...truncated 132 lines...]
BUILD FAILED
/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/build.xml:384: The following error occurred while executing this line:
/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/build.xml:364: The following error occurred while executing this line:
/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/build.xml:39: The following error occurred while executing this line:
/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/build.xml:194: The following error occurred while executing this line:
/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/common-build.xml:437: The following error occurred while executing this line:
/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/lucene/common-build.xml:1243: The following error occurred while executing this line:
/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/lucene/common-build.xml:887: There were test failures: 45 suites, 268 tests, 1 error

Total time: 124 minutes 52 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.6.0 -XX:+UseCompressedOops -XX:+UseParallelGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message