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-trunk-MacOSX (64bit/jdk1.7.0) - Build # 423 - Still Failing!
Date Sat, 27 Apr 2013 00:39:45 GMT
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/423/
Java: 64bit/jdk1.7.0 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC

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

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

Stack Trace:
org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: https://127.0.0.1:51453/solr/collection1
	at __randomizedtesting.SeedInfo.seed([F4AF78882B941BC1:3C4F0582DA3CC817]: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.testAddDelete(SolrExampleTests.java:754)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:601)
	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:722)
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 10135 lines...]
[junit4:junit4] Suite: org.apache.solr.client.solrj.SolrExampleBinaryTest
[junit4:junit4]   1> 124084 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] WARN  org.apache.solr.SolrTestCaseJ4  ? startTrackingSearchers: numOpens=21 numCloses=21
[junit4:junit4]   1> 124085 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.apache.solr.SolrJettyTestBase  ? Randomized ssl (true) and clientAuth (false)
[junit4:junit4]   1> 124086 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.apache.solr.SolrTestCaseJ4  ? ####initCore
[junit4:junit4]   1> 124087 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.apache.solr.SolrTestCaseJ4  ? ####initCore end
[junit4:junit4]   1> 124141 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.eclipse.jetty.server.Server  ? jetty-8.1.10.v20130312
[junit4:junit4]   1> 125637 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.eclipse.jetty.util.ssl.SslContextFactory  ? Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4:junit4]   1> 125733 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.eclipse.jetty.server.AbstractConnector  ? Started SslSelectChannelConnector@127.0.0.1:51453
[junit4:junit4]   1> 125745 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.apache.solr.servlet.SolrDispatchFilter  ? SolrDispatchFilter.init()
[junit4:junit4]   1> 125748 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.apache.solr.core.SolrResourceLoader  ? JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   1> 125753 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.apache.solr.core.SolrResourceLoader  ? using system property solr.solr.home: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr
[junit4:junit4]   1> 125756 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.apache.solr.core.CoreContainer  ? looking for solr config file: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/solr.xml
[junit4:junit4]   1> 125760 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.apache.solr.core.CoreContainer  ? New CoreContainer 1437291442
[junit4:junit4]   1> 125773 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.apache.solr.core.CoreContainer  ? Loading CoreContainer using Solr Home: '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/'
[junit4:junit4]   1> 125777 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.apache.solr.core.SolrResourceLoader  ? new SolrResourceLoader for directory: '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/'
[junit4:junit4]   1> 126354 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  ? Looking for cores in /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr
[junit4:junit4]   1> 126357 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  ? Looking for cores in /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/.svn
[junit4:junit4]   1> 126415 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  ? Looking for cores in /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/.svn/prop-base
[junit4:junit4]   1> 126427 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  ? Looking for cores in /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/.svn/props
[junit4:junit4]   1> 126430 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  ? Looking for cores in /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/.svn/text-base
[junit4:junit4]   1> 126452 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  ? Looking for cores in /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/.svn/tmp
[junit4:junit4]   1> 126455 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  ? Looking for cores in /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/.svn/tmp/prop-base
[junit4:junit4]   1> 126457 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  ? Looking for cores in /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/.svn/tmp/props
[junit4:junit4]   1> 126461 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  ? Looking for cores in /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/.svn/tmp/text-base
[junit4:junit4]   1> 126465 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  ? Discovered properties file /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/collection1/core.properties, adding to cores
[junit4:junit4]   1> SET INST DIR:/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/collection1
[junit4:junit4]   1> 126476 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.apache.solr.handler.component.HttpShardHandlerFactory  ? Setting socketTimeout to: 0
[junit4:junit4]   1> 126477 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.apache.solr.handler.component.HttpShardHandlerFactory  ? Setting urlScheme to: http://
[junit4:junit4]   1> 126478 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.apache.solr.handler.component.HttpShardHandlerFactory  ? Setting connTimeout to: 0
[junit4:junit4]   1> 126479 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.apache.solr.handler.component.HttpShardHandlerFactory  ? Setting maxConnectionsPerHost to: 20
[junit4:junit4]   1> 126480 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.apache.solr.handler.component.HttpShardHandlerFactory  ? Setting corePoolSize to: 0
[junit4:junit4]   1> 126481 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.apache.solr.handler.component.HttpShardHandlerFactory  ? Setting maximumPoolSize to: 2147483647
[junit4:junit4]   1> 126482 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.apache.solr.handler.component.HttpShardHandlerFactory  ? Setting maxThreadIdleTime to: 5
[junit4:junit4]   1> 126483 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.apache.solr.handler.component.HttpShardHandlerFactory  ? Setting sizeOfQueue to: -1
[junit4:junit4]   1> 126485 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.apache.solr.handler.component.HttpShardHandlerFactory  ? Setting fairnessPolicy to: false
[junit4:junit4]   1> 126487 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-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> 126560 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.apache.solr.core.CoreContainer  ? Registering Log Listener
[junit4:junit4]   1> 126583 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.core.CoreContainer  ? Creating SolrCore 'collection1' using instanceDir: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/collection1
[junit4:junit4]   1> 126586 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.core.SolrResourceLoader  ? new SolrResourceLoader for directory: '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/collection1/'
[junit4:junit4]   1> 126717 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.core.SolrConfig  ? Adding specified lib dirs to ClassLoader
[junit4:junit4]   1> 126719 [coreLoadExecutor-151-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-trunk-MacOSX/solr/example/solr/collection1/../../../contrib/extraction/lib).
[junit4:junit4]   1> 126740 [coreLoadExecutor-151-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-trunk-MacOSX/solr/example/solr/collection1/../../../dist).
[junit4:junit4]   1> 126742 [coreLoadExecutor-151-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-trunk-MacOSX/solr/example/solr/collection1/../../../contrib/clustering/lib).
[junit4:junit4]   1> 126743 [coreLoadExecutor-151-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-trunk-MacOSX/solr/example/solr/collection1/../../../dist).
[junit4:junit4]   1> 126744 [coreLoadExecutor-151-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-trunk-MacOSX/solr/example/solr/collection1/../../../contrib/langid/lib).
[junit4:junit4]   1> 126750 [coreLoadExecutor-151-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-trunk-MacOSX/solr/example/solr/collection1/../../../dist).
[junit4:junit4]   1> 126752 [coreLoadExecutor-151-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-trunk-MacOSX/solr/example/solr/collection1/../../../contrib/velocity/lib).
[junit4:junit4]   1> 126753 [coreLoadExecutor-151-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-trunk-MacOSX/solr/example/solr/collection1/../../../dist).
[junit4:junit4]   1> 126773 [coreLoadExecutor-151-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> 126923 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.core.SolrConfig  ? Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   1> 127190 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.core.Config  ? Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   1> 127192 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.schema.IndexSchema  ? Reading Solr Schema from schema.xml
[junit4:junit4]   1> 127319 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.schema.IndexSchema  ? Schema name=example
[junit4:junit4]   1> 130120 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.schema.IndexSchema  ? unique key field: id
[junit4:junit4]   1> 130196 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.schema.FileExchangeRateProvider  ? Reloading exchange rates from file currency.xml
[junit4:junit4]   1> 130214 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.schema.FileExchangeRateProvider  ? Reloading exchange rates from file currency.xml
[junit4:junit4]   1> 130432 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> 130435 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Opening new SolrCore at /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/collection1/, dataDir=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleBinaryTest-1367022724409/
[junit4:junit4]   1> 130436 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.core.JmxMonitoredMap  ? JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@251d737b
[junit4:junit4]   1> 130438 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Added SolrEventListener for newSearcher: org.apache.solr.core.QuerySenderListener{queries=[]}
[junit4:junit4]   1> 130439 [coreLoadExecutor-151-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> 130441 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> 130443 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.core.CachingDirectoryFactory  ? return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleBinaryTest-1367022724409
[junit4:junit4]   1> 130444 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? New index directory detected: old=null new=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleBinaryTest-1367022724409/index/
[junit4:junit4]   1> 130446 [coreLoadExecutor-151-thread-1] WARN  org.apache.solr.core.SolrCore  ? [collection1] Solr index directory '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleBinaryTest-1367022724409/index' doesn't exist. Creating new index...
[junit4:junit4]   1> 130448 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.core.CachingDirectoryFactory  ? return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleBinaryTest-1367022724409/index
[junit4:junit4]   1> 130458 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_1,generation=1}
[junit4:junit4]   1> 130467 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 1
[junit4:junit4]   1> 130471 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? created json: solr.JSONResponseWriter
[junit4:junit4]   1> 130472 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? adding lazy queryResponseWriter: solr.VelocityResponseWriter
[junit4:junit4]   1> 130473 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? created velocity: solr.VelocityResponseWriter
[junit4:junit4]   1> 130474 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? created xslt: solr.XSLTResponseWriter
[junit4:junit4]   1> 130475 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.response.XSLTResponseWriter  ? xsltCacheLifetimeSeconds=5
[junit4:junit4]   1> 130480 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /select: solr.SearchHandler
[junit4:junit4]   1> 130483 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /query: solr.SearchHandler
[junit4:junit4]   1> 130484 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /get: solr.RealTimeGetHandler
[junit4:junit4]   1> 130486 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /browse: solr.SearchHandler
[junit4:junit4]   1> 130488 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /update: solr.UpdateRequestHandler
[junit4:junit4]   1> 130489 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /update/json: solr.JsonUpdateRequestHandler
[junit4:junit4]   1> 130491 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /update/csv: solr.CSVRequestHandler
[junit4:junit4]   1> 130491 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? adding lazy requestHandler: solr.extraction.ExtractingRequestHandler
[junit4:junit4]   1> 130492 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /update/extract: solr.extraction.ExtractingRequestHandler
[junit4:junit4]   1> 130493 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? adding lazy requestHandler: solr.FieldAnalysisRequestHandler
[junit4:junit4]   1> 130494 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /analysis/field: solr.FieldAnalysisRequestHandler
[junit4:junit4]   1> 130494 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? adding lazy requestHandler: solr.DocumentAnalysisRequestHandler
[junit4:junit4]   1> 130495 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /analysis/document: solr.DocumentAnalysisRequestHandler
[junit4:junit4]   1> 130496 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /admin/: solr.admin.AdminHandlers
[junit4:junit4]   1> 130497 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /admin/ping: solr.PingRequestHandler
[junit4:junit4]   1> 130498 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   1> 130500 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /replication: solr.ReplicationHandler
[junit4:junit4]   1> 130500 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? adding lazy requestHandler: solr.SearchHandler
[junit4:junit4]   1> 130501 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /spell: solr.SearchHandler
[junit4:junit4]   1> 130501 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? adding lazy requestHandler: solr.SearchHandler
[junit4:junit4]   1> 130502 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /tvrh: solr.SearchHandler
[junit4:junit4]   1> 130502 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? adding lazy requestHandler: solr.SearchHandler
[junit4:junit4]   1> 130503 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /terms: solr.SearchHandler
[junit4:junit4]   1> 130504 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? adding lazy requestHandler: solr.SearchHandler
[junit4:junit4]   1> 130505 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /elevate: solr.SearchHandler
[junit4:junit4]   1> 130529 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.handler.loader.XMLLoader  ? xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> 130532 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.handler.loader.XMLLoader  ? xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> 130536 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.handler.loader.XMLLoader  ? xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> 130553 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@66fff327 main
[junit4:junit4]   1> 130554 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.update.UpdateHandler  ? Clearing tlog files, tlogDir=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleBinaryTest-1367022724409/tlog
[junit4:junit4]   1> 130557 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? Hard AutoCommit: if uncommited for 15000ms; 
[junit4:junit4]   1> 130557 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? Soft AutoCommit: disabled
[junit4:junit4]   1> 130558 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.handler.component.SpellCheckComponent  ? Initializing spell checkers
[junit4:junit4]   1> 130559 [coreLoadExecutor-151-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> 130560 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.handler.component.SpellCheckComponent  ? No queryConverter defined, using default converter
[junit4:junit4]   1> 130562 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.handler.component.QueryElevationComponent  ? Loading QueryElevation from: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/example/solr/collection1/conf/elevate.xml
[junit4:junit4]   1> 130633 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.handler.ReplicationHandler  ? Commits will be reserved for  10000
[junit4:junit4]   1> 130634 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@66fff327 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> 130653 [searcherExecutor-152-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=3 
[junit4:junit4]   1> 130654 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 130655 [searcherExecutor-152-thread-1] INFO  org.apache.solr.handler.component.SpellCheckComponent  ? Loading spell index for spellchecker: default
[junit4:junit4]   1> 130656 [searcherExecutor-152-thread-1] INFO  org.apache.solr.handler.component.SpellCheckComponent  ? Loading spell index for spellchecker: wordbreak
[junit4:junit4]   1> 130660 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@66fff327 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> 130675 [coreLoadExecutor-151-thread-1] INFO  org.apache.solr.core.CoreContainer  ? registering core: collection1
[junit4:junit4]   1> 130677 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.apache.solr.servlet.SolrDispatchFilter  ? user.dir=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0
[junit4:junit4]   1> 130679 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.apache.solr.servlet.SolrDispatchFilter  ? SolrDispatchFilter.init() done
[junit4:junit4]   1> 130679 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.apache.solr.SolrJettyTestBase  ? Jetty Assigned Port#51453
[junit4:junit4]   2> Creating dataDir: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./solrtest-SolrExampleBinaryTest-1367022724409
[junit4:junit4]   1> 130707 [TEST-SolrExampleBinaryTest.testStreamingRequest-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testStreamingRequest
[junit4:junit4]   1> 130709 [TEST-SolrExampleBinaryTest.testStreamingRequest-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.client.solrj.impl.HttpClientUtil  ? Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   1> 131569 [qtp1276803187-243] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onInit: commits: num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_1,generation=1}
[junit4:junit4]   1> 131571 [qtp1276803187-243] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 1
[junit4:junit4]   1> 131576 [qtp1276803187-243] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@57ac804a realtime
[junit4:junit4]   1> 131578 [qtp1276803187-243] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433427228111142912)} 0 29
[junit4:junit4]   1> 131595 [qtp1276803187-238] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 131600 [qtp1276803187-238] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_1,generation=1}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_2,generation=2}
[junit4:junit4]   1> 131602 [qtp1276803187-238] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 2
[junit4:junit4]   1> 131604 [qtp1276803187-238] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@1b17f6bf main
[junit4:junit4]   1> 131607 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@1b17f6bf main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4:junit4]   1> 131609 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 131619 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@1b17f6bf main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4:junit4]   1> 131622 [qtp1276803187-238] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 131624 [qtp1276803187-238] 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> 131645 [qtp1276803187-239] 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> 131688 [qtp1276803187-240] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 131702 [qtp1276803187-240] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_2,generation=2}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_3,generation=3}
[junit4:junit4]   1> 131704 [qtp1276803187-240] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 3
[junit4:junit4]   1> 131709 [qtp1276803187-240] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@ca9e09c main
[junit4:junit4]   1> 131712 [qtp1276803187-240] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 131714 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@ca9e09c main{StandardDirectoryReader(segments_3:3:nrt _0(5.0):C10)}
[junit4:junit4]   1> 131716 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 131725 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@ca9e09c main{StandardDirectoryReader(segments_3:3:nrt _0(5.0):C10)}
[junit4:junit4]   1> 131728 [qtp1276803187-240] 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 (1433427228220194816), 1 (1433427228225437696), 2 (1433427228226486272), 3 (1433427228228583424), 4 (1433427228230680576), 5 (1433427228231729152), 6 (1433427228233826304), 7 (1433427228234874880), 8 (1433427228236972032), 9 (1433427228239069184)],commit=} 0 61
[junit4:junit4]   1> 131768 [qtp1276803187-241] 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=6 
[junit4:junit4]   1> 131787 [qtp1276803187-242] 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> 131793 [TEST-SolrExampleBinaryTest.testStreamingRequest-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testStreamingRequest
[junit4:junit4]   1> 131956 [TEST-SolrExampleBinaryTest.testFaceting-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testFaceting
[junit4:junit4]   1> 131981 [qtp1276803187-239] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@77632d2c realtime
[junit4:junit4]   1> 131988 [qtp1276803187-239] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433427228543156224)} 0 13
[junit4:junit4]   1> 132019 [qtp1276803187-240] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 132022 [qtp1276803187-240] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_3,generation=3}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_4,generation=4}
[junit4:junit4]   1> 132024 [qtp1276803187-240] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 4
[junit4:junit4]   1> 132027 [qtp1276803187-240] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@5b414746 main
[junit4:junit4]   1> 132030 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@5b414746 main{StandardDirectoryReader(segments_3:5:nrt)}
[junit4:junit4]   1> 132031 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 132041 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@5b414746 main{StandardDirectoryReader(segments_3:5:nrt)}
[junit4:junit4]   1> 132046 [qtp1276803187-240] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 132048 [qtp1276803187-240] 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> 132065 [qtp1276803187-242] 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> 132124 [qtp1276803187-238] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1 (1433427228655353856), 2 (1433427228683665408), 3 (1433427228688908288), 4 (1433427228691005440), 5 (1433427228692054016), 6 (1433427228693102592), 7 (1433427228694151168), 8 (1433427228695199744), 9 (1433427228696248320), 10 (1433427228696248321)]} 0 42
[junit4:junit4]   1> 132134 [qtp1276803187-239] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 132164 [qtp1276803187-239] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_4,generation=4}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_5,generation=5}
[junit4:junit4]   1> 132166 [qtp1276803187-239] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 5
[junit4:junit4]   1> 132170 [qtp1276803187-239] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@588d8962 main
[junit4:junit4]   1> 132174 [qtp1276803187-239] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 132176 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@588d8962 main{StandardDirectoryReader(segments_5:7:nrt _1(5.0):C10)}
[junit4:junit4]   1> 132177 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 132185 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@588d8962 main{StandardDirectoryReader(segments_5:7:nrt _1(5.0):C10)}
[junit4:junit4]   1> 132187 [qtp1276803187-239] 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 53
[junit4:junit4]   1> 132201 [qtp1276803187-241] INFO  org.apache.solr.core.SolrCore  ? UnInverted multi-valued field {field=features,memSize=4564,tindexSize=44,time=1,phase1=1,nTerms=4,bigTerms=2,termInstances=4,uses=0}
[junit4:junit4]   1> 132203 [qtp1276803187-241] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=0&q=*:*&facet.field=features&wt=javabin&version=2&rows=0} hits=10 status=0 QTime=3 
[junit4:junit4]   1> 132219 [qtp1276803187-242] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=0&q=*:*&facet.field=features&wt=javabin&fq=features:two&version=2&rows=0} hits=5 status=0 QTime=2 
[junit4:junit4]   1> 132263 [qtp1276803187-238] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=4&q=*:*&facet.field=features&wt=javabin&fq=features:two&version=2&rows=0} hits=5 status=0 QTime=2 
[junit4:junit4]   1> 132284 [qtp1276803187-239] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=-1&q=*:*&facet.field=features&wt=javabin&fq=features:two&version=2&rows=0} hits=5 status=0 QTime=2 
[junit4:junit4]   1> 132291 [TEST-SolrExampleBinaryTest.testFaceting-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testFaceting
[junit4:junit4]   1> 132333 [TEST-SolrExampleBinaryTest.testPivotFacetsMissing-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testPivotFacetsMissing
[junit4:junit4]   1> 132350 [qtp1276803187-240] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@79e9131a realtime
[junit4:junit4]   1> 132356 [qtp1276803187-240] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433427228925886464)} 0 15
[junit4:junit4]   1> 132376 [qtp1276803187-242] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 132381 [qtp1276803187-242] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_5,generation=5}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_6,generation=6}
[junit4:junit4]   1> 132383 [qtp1276803187-242] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 6
[junit4:junit4]   1> 132385 [qtp1276803187-242] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@a6e6617 main
[junit4:junit4]   1> 132387 [qtp1276803187-242] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 132388 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@a6e6617 main{StandardDirectoryReader(segments_5:9:nrt)}
[junit4:junit4]   1> 132389 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 132397 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@a6e6617 main{StandardDirectoryReader(segments_5:9:nrt)}
[junit4:junit4]   1> 132400 [qtp1276803187-242] 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 24
[junit4:junit4]   1> 132416 [qtp1276803187-243] 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> 132460 [qtp1276803187-238] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1 (1433427229016064000), 2 (1433427229025501184), 3 (1433427229027598336), 4 (1433427229029695488), 5 (1433427229032841216), 6 (1433427229034938368), 7 (1433427229037035520), 8 (1433427229040181248), 9 (1433427229042278400), 10 (1433427229044375552), ... (12 adds)]} 0 33
[junit4:junit4]   1> 132472 [qtp1276803187-239] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 132495 [qtp1276803187-239] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_6,generation=6}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_7,generation=7}
[junit4:junit4]   1> 132497 [qtp1276803187-239] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 7
[junit4:junit4]   1> 132501 [qtp1276803187-239] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@9cb18a4 main
[junit4:junit4]   1> 132505 [qtp1276803187-239] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 132507 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@9cb18a4 main{StandardDirectoryReader(segments_7:11:nrt _2(5.0):C12)}
[junit4:junit4]   1> 132508 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 132516 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@9cb18a4 main{StandardDirectoryReader(segments_7:11:nrt _2(5.0):C12)}
[junit4:junit4]   1> 132519 [qtp1276803187-239] 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> 132543 [qtp1276803187-240] INFO  org.apache.solr.core.SolrCore  ? UnInverted multi-valued field {field=features,memSize=4521,tindexSize=43,time=0,phase1=0,nTerms=2,bigTerms=2,termInstances=0,uses=0}
[junit4:junit4]   1> 132546 [qtp1276803187-240] 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> 132564 [qtp1276803187-240] 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=35 
[junit4:junit4]   1> 132584 [qtp1276803187-241] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=0&q=*:*&wt=javabin&fq={!tag%3Dmytag}-(features:bbb+AND+cat:a+AND+inStock:true)&facet.pivot={!ex%3Dmytag+key%3Dmykey}features,cat&version=2&rows=0} hits=11 status=0 QTime=2 
[junit4:junit4]   1> 132640 [TEST-SolrExampleBinaryTest.testPivotFacetsMissing-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testPivotFacetsMissing
[junit4:junit4]   1> 132694 [TEST-SolrExampleBinaryTest.testUnicode-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testUnicode
[junit4:junit4]   1> 132706 [qtp1276803187-242] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@a8dab8e realtime
[junit4:junit4]   1> 132708 [qtp1276803187-242] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433427229303373824)} 0 8
[junit4:junit4]   1> 132723 [qtp1276803187-238] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 (1433427229321199616), 1 (1433427229324345344)]} 0 6
[junit4:junit4]   1> 132735 [qtp1276803187-239] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 132743 [qtp1276803187-239] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_7,generation=7}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_8,generation=8}
[junit4:junit4]   1> 132745 [qtp1276803187-239] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 8
[junit4:junit4]   1> 132748 [qtp1276803187-239] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@20bb595c main
[junit4:junit4]   1> 132751 [qtp1276803187-239] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 132752 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@20bb595c main{StandardDirectoryReader(segments_8:15:nrt _3(5.0):C2)}
[junit4:junit4]   1> 132755 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 132771 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@20bb595c main{StandardDirectoryReader(segments_8:15:nrt _3(5.0):C2)}
[junit4:junit4]   1> 132774 [qtp1276803187-239] 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> 132784 [qtp1276803187-240] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2&rows=2} hits=2 status=0 QTime=1 
[junit4:junit4]   1> 132801 [qtp1276803187-241] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@3bfc299e realtime
[junit4:junit4]   1> 132804 [qtp1276803187-241] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1433427229404037120)} 0 6
[junit4:junit4]   1> 133143 [qtp1276803187-243] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1433427229759504384), 1 (1433427229761601536), 2 (1433427229762650112), 3 (1433427229763698688), 4 (1433427229764747264)]} 0 8
[junit4:junit4]   1> 133170 [qtp1276803187-238] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 133180 [qtp1276803187-238] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_8,generation=8}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_9,generation=9}
[junit4:junit4]   1> 133183 [qtp1276803187-238] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 9
[junit4:junit4]   1> 133190 [qtp1276803187-238] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@a0f59dd main
[junit4:junit4]   1> 133193 [qtp1276803187-238] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 133194 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@a0f59dd main{StandardDirectoryReader(segments_9:19:nrt _4(5.0):C5)}
[junit4:junit4]   1> 133196 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 133205 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@a0f59dd main{StandardDirectoryReader(segments_9:19:nrt _4(5.0):C5)}
[junit4:junit4]   1> 133221 [qtp1276803187-238] 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 52
[junit4:junit4]   1> 133314 [qtp1276803187-239] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2&rows=5} hits=5 status=0 QTime=1 
[junit4:junit4]   1> 133354 [qtp1276803187-240] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@7e9949a3 realtime
[junit4:junit4]   1> 133356 [qtp1276803187-240] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1433427229977608192)} 0 12
[junit4:junit4]   1> 133453 [qtp1276803187-241] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1433427230085611520), 1 (1433427230087708672), 2 (1433427230088757248), 3 (1433427230089805824), 4 (1433427230089805825), 5 (1433427230090854400)]} 0 7
[junit4:junit4]   1> 133472 [qtp1276803187-242] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 133480 [qtp1276803187-242] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_9,generation=9}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_a,generation=10}
[junit4:junit4]   1> 133481 [qtp1276803187-242] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 10
[junit4:junit4]   1> 133496 [qtp1276803187-242] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@7255cde4 main
[junit4:junit4]   1> 133503 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@7255cde4 main{StandardDirectoryReader(segments_a:23:nrt _5(5.0):C6)}
[junit4:junit4]   1> 133504 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 133512 [qtp1276803187-242] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 133524 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@7255cde4 main{StandardDirectoryReader(segments_a:23:nrt _5(5.0):C6)}
[junit4:junit4]   1> 133536 [qtp1276803187-242] 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 64
[junit4:junit4]   1> 133550 [qtp1276803187-238] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2&rows=6} hits=6 status=0 QTime=0 
[junit4:junit4]   1> 133620 [qtp1276803187-239] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@365f10e6 realtime
[junit4:junit4]   1> 133635 [qtp1276803187-239] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1433427230213537792)} 0 66
[junit4:junit4]   1> 133660 [qtp1276803187-240] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1433427230300569600), 1 (1433427230306861056)]} 0 9
[junit4:junit4]   1> 133675 [qtp1276803187-241] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 133683 [qtp1276803187-241] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_a,generation=10}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_b,generation=11}
[junit4:junit4]   1> 133685 [qtp1276803187-241] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 11
[junit4:junit4]   1> 133689 [qtp1276803187-241] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@3ec92b5d main
[junit4:junit4]   1> 133692 [qtp1276803187-241] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 133694 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@3ec92b5d main{StandardDirectoryReader(segments_b:27:nrt _6(5.0):C2)}
[junit4:junit4]   1> 133695 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 133713 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@3ec92b5d main{StandardDirectoryReader(segments_b:27:nrt _6(5.0):C2)}
[junit4:junit4]   1> 133715 [qtp1276803187-241] 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 40
[junit4:junit4]   1> 133734 [qtp1276803187-243] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2&rows=2} hits=2 status=0 QTime=1 
[junit4:junit4]   1> 133742 [TEST-SolrExampleBinaryTest.testUnicode-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testUnicode
[junit4:junit4]   1> 133839 [TEST-SolrExampleBinaryTest.testAddDelete-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testAddDelete
[junit4:junit4]   1> 133864 [qtp1276803187-242] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@462619c6 realtime
[junit4:junit4]   1> 133866 [qtp1276803187-242] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433427230516576256)} 0 8
[junit4:junit4]   1> 133904 [qtp1276803187-238] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 & 222 (1433427230561665024)]} 0 3
[junit4:junit4]   1> 133920 [qtp1276803187-239] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 133936 [qtp1276803187-239] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_b,generation=11}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_c,generation=12}
[junit4:junit4]   1> 133937 [qtp1276803187-239] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 12
[junit4:junit4]   1> 133948 [qtp1276803187-239] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@459714ea main
[junit4:junit4]   1> 133951 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@459714ea main{StandardDirectoryReader(segments_c:31:nrt _7(5.0):C1)}
[junit4:junit4]   1> 133952 [qtp1276803187-239] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 133952 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 133958 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@459714ea main{StandardDirectoryReader(segments_c:31:nrt _7(5.0):C1)}
[junit4:junit4]   1> 133960 [qtp1276803187-239] 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 40
[junit4:junit4]   1> 133971 [qtp1276803187-241] 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> 133983 [qtp1276803187-243] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {delete=[0 & 222 (-1433427230645551104)]} 0 2
[junit4:junit4]   1> 133996 [qtp1276803187-242] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 134001 [qtp1276803187-242] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_c,generation=12}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_d,generation=13}
[junit4:junit4]   1> 134002 [qtp1276803187-242] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 13
[junit4:junit4]   1> 134005 [qtp1276803187-242] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@24892f8b main
[junit4:junit4]   1> 134007 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@24892f8b main{StandardDirectoryReader(segments_d:33:nrt)}
[junit4:junit4]   1> 134008 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 134012 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@24892f8b main{StandardDirectoryReader(segments_d:33:nrt)}
[junit4:junit4]   1> 134019 [qtp1276803187-242] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 134027 [qtp1276803187-242] 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> 134037 [qtp1276803187-238] 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> 134058 [qtp1276803187-239] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 & 222 (1433427230722097152)]} 0 4
[junit4:junit4]   1> 134069 [qtp1276803187-240] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 134077 [qtp1276803187-240] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_d,generation=13}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_e,generation=14}
[junit4:junit4]   1> 134078 [qtp1276803187-240] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 14
[junit4:junit4]   1> 134082 [qtp1276803187-240] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@4062587b main
[junit4:junit4]   1> 134087 [qtp1276803187-240] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 134089 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@4062587b main{StandardDirectoryReader(segments_e:35:nrt _8(5.0):C1)}
[junit4:junit4]   1> 134090 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 134097 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@4062587b main{StandardDirectoryReader(segments_e:35:nrt _8(5.0):C1)}
[junit4:junit4]   1> 134100 [qtp1276803187-240] 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> 134112 [qtp1276803187-241] 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> 134141 [qtp1276803187-243] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@7f7d499e realtime
[junit4:junit4]   1> 134144 [qtp1276803187-243] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=id:"0\ \&\ 222" (-1433427230796546048)} 0 19
[junit4:junit4]   1> 134173 [qtp1276803187-242] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 134176 [qtp1276803187-242] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_e,generation=14}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_f,generation=15}
[junit4:junit4]   1> 134178 [qtp1276803187-242] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 15
[junit4:junit4]   1> 134180 [qtp1276803187-242] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@5d30948f main
[junit4:junit4]   1> 134199 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@5d30948f main{StandardDirectoryReader(segments_e:37:nrt)}
[junit4:junit4]   1> 134200 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 134203 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@5d30948f main{StandardDirectoryReader(segments_e:37:nrt)}
[junit4:junit4]   1> 134221 [qtp1276803187-242] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 134223 [qtp1276803187-242] 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> 134235 [qtp1276803187-238] 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> 134259 [qtp1276803187-239] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 & 222 (1433427230932860928)]} 0 5
[junit4:junit4]   1> 134276 [qtp1276803187-240] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1 & 222 (1433427230951735296)]} 0 4
[junit4:junit4]   1> 134289 [qtp1276803187-241] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2 & 222 (1433427230966415360)]} 0 2
[junit4:junit4]   1> 134303 [qtp1276803187-243] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 134312 [qtp1276803187-243] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_f,generation=15}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_g,generation=16}
[junit4:junit4]   1> 134314 [qtp1276803187-243] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 16
[junit4:junit4]   1> 134318 [qtp1276803187-243] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@6dc71e86 main
[junit4:junit4]   1> 134322 [qtp1276803187-243] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 134324 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@6dc71e86 main{StandardDirectoryReader(segments_g:39:nrt _9(5.0):C3)}
[junit4:junit4]   1> 134325 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 134332 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@6dc71e86 main{StandardDirectoryReader(segments_g:39:nrt _9(5.0):C3)}
[junit4:junit4]   1> 134335 [qtp1276803187-243] 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> 134444 [qtp1276803187-238] 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> 134467 [qtp1276803187-239] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {delete=[0 & 222 (-1433427231153061888), 1 & 222 (-1433427231154110464), 2 & 222 (-1433427231154110465)]} 0 3
[junit4:junit4]   1> 149468 [commitScheduler-154-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> 149481 [commitScheduler-154-thread-1] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_g,generation=16}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_h,generation=17}
[junit4:junit4]   1> 149484 [commitScheduler-154-thread-1] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 17
[junit4:junit4]   1> 149516 [commitScheduler-154-thread-1] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@65a3a83d realtime
[junit4:junit4]   1> 149520 [commitScheduler-154-thread-1] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 334704 [qtp1276803187-241] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 334706 [qtp1276803187-241] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_h,generation=17}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_i,generation=18}
[junit4:junit4]   1> 334706 [qtp1276803187-241] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 18
[junit4:junit4]   1> 334707 [qtp1276803187-241] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@71c3174 main
[junit4:junit4]   1> 334708 [qtp1276803187-241] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 334709 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@71c3174 main{StandardDirectoryReader(segments_h:41:nrt)}
[junit4:junit4]   1> 334710 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 334712 [TEST-SolrExampleBinaryTest.testAddDelete-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testAddDelete
[junit4:junit4]   1> 334714 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@71c3174 main{StandardDirectoryReader(segments_h:41:nrt)}
[junit4:junit4]   1> 334716 [qtp1276803187-241] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 12
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=SolrExampleBinaryTest -Dtests.method=testAddDelete -Dtests.seed=F4AF78882B941BC1 -Dtests.slow=true -Dtests.locale=ar_YE -Dtests.timezone=Etc/GMT+9 -Dtests.file.encoding=US-ASCII
[junit4:junit4] ERROR    201s | SolrExampleBinaryTest.testAddDelete <<<
[junit4:junit4]    > Throwable #1: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: https://127.0.0.1:51453/solr/collection1
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([F4AF78882B941BC1:3C4F0582DA3CC817]: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.testAddDelete(SolrExampleTests.java:754)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:722)
[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> 335017 [TEST-SolrExampleBinaryTest.testPingHandler-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testPingHandler
[junit4:junit4]   1> 335053 [qtp1276803187-239] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433427441481678848)} 0 4
[junit4:junit4]   1> 335060 [qtp1276803187-240] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 335063 [qtp1276803187-240] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_i,generation=18}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_j,generation=19}
[junit4:junit4]   1> 335064 [qtp1276803187-240] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 19
[junit4:junit4]   1> 335065 [qtp1276803187-240] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@166360c9 main
[junit4:junit4]   1> 335068 [qtp1276803187-240] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 335069 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@166360c9 main{StandardDirectoryReader(segments_h:41:nrt)}
[junit4:junit4]   1> 335069 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 335073 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@166360c9 main{StandardDirectoryReader(segments_h:41:nrt)}
[junit4:junit4]   1> 335074 [qtp1276803187-240] 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> 335084 [qtp1276803187-243] 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> 335093 [qtp1276803187-238] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/admin/ping params={wt=javabin&version=2} hits=0 status=0 QTime=1 
[junit4:junit4]   1> 335095 [qtp1276803187-238] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/admin/ping params={wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   1> 335098 [TEST-SolrExampleBinaryTest.testPingHandler-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testPingHandler
[junit4:junit4]   1> 335135 [TEST-SolrExampleBinaryTest.testUpdateField-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testUpdateField
[junit4:junit4]   1> 335142 [qtp1276803187-242] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433427441576050688)} 0 2
[junit4:junit4]   1> 335148 [qtp1276803187-239] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 335149 [qtp1276803187-239] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_j,generation=19}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_k,generation=20}
[junit4:junit4]   1> 335149 [qtp1276803187-239] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 20
[junit4:junit4]   1> 335150 [qtp1276803187-239] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@75eba802 main
[junit4:junit4]   1> 335152 [qtp1276803187-239] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 335153 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@75eba802 main{StandardDirectoryReader(segments_h:41:nrt)}
[junit4:junit4]   1> 335153 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 335157 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@75eba802 main{StandardDirectoryReader(segments_h:41:nrt)}
[junit4:junit4]   1> 335164 [qtp1276803187-239] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 16
[junit4:junit4]   1> 335174 [qtp1276803187-240] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[unique (1433427441608556544)]} 0 3
[junit4:junit4]   1> 335181 [qtp1276803187-243] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 335187 [qtp1276803187-243] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_k,generation=20}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_l,generation=21}
[junit4:junit4]   1> 335189 [qtp1276803187-243] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 21
[junit4:junit4]   1> 335222 [qtp1276803187-243] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@17d9f20f main
[junit4:junit4]   1> 335235 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@17d9f20f main{StandardDirectoryReader(segments_l:43:nrt _a(5.0):C1)}
[junit4:junit4]   1> 335235 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 335256 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@17d9f20f main{StandardDirectoryReader(segments_l:43:nrt _a(5.0):C1)}
[junit4:junit4]   1> 335273 [qtp1276803187-243] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 335274 [qtp1276803187-243] 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> 335281 [qtp1276803187-241] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={fl=id,price_f,name,_version_&q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=0 
[junit4:junit4]   1> 335299 [qtp1276803187-238] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {} 0 1
[junit4:junit4]   1> 335337 [qtp1276803187-242] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[unique (1433427441780523008)]} 0 3
[junit4:junit4]   1> 335343 [qtp1276803187-239] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 335349 [qtp1276803187-239] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_l,generation=21}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_m,generation=22}
[junit4:junit4]   1> 335350 [qtp1276803187-239] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 22
[junit4:junit4]   1> 335354 [qtp1276803187-239] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@78da0072 main
[junit4:junit4]   1> 335356 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@78da0072 main{StandardDirectoryReader(segments_m:47:nrt _b(5.0):C1)}
[junit4:junit4]   1> 335357 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 335362 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@78da0072 main{StandardDirectoryReader(segments_m:47:nrt _b(5.0):C1)}
[junit4:junit4]   1> 335368 [qtp1276803187-239] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 335370 [qtp1276803187-239] 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> 335384 [qtp1276803187-240] 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=5 
[junit4:junit4]   1> 335394 [qtp1276803187-243] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[unique (1433427441838194688)]} 0 4
[junit4:junit4]   1> 335403 [qtp1276803187-241] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 335410 [qtp1276803187-241] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_m,generation=22}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_n,generation=23}
[junit4:junit4]   1> 335411 [qtp1276803187-241] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 23
[junit4:junit4]   1> 335413 [qtp1276803187-241] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@3d510eb6 main
[junit4:junit4]   1> 335415 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@3d510eb6 main{StandardDirectoryReader(segments_n:51:nrt _c(5.0):C1)}
[junit4:junit4]   1> 335415 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 335421 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@3d510eb6 main{StandardDirectoryReader(segments_n:51:nrt _c(5.0):C1)}
[junit4:junit4]   1> 335428 [qtp1276803187-241] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 335429 [qtp1276803187-241] 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> 335436 [qtp1276803187-238] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={fl=id,price_f,name,_version_&q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=0 
[junit4:junit4]   1> 335439 [TEST-SolrExampleBinaryTest.testUpdateField-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testUpdateField
[junit4:junit4]   1> 335460 [TEST-SolrExampleBinaryTest.testRealtimeGet-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testRealtimeGet
[junit4:junit4]   1> 335468 [qtp1276803187-242] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@2b59dd6c realtime
[junit4:junit4]   1> 335469 [qtp1276803187-242] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433427441916837888)} 0 4
[junit4:junit4]   1> 335480 [qtp1276803187-239] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[DOCID (1433427441929420800)]} 0 3
[junit4:junit4]   1> 335488 [qtp1276803187-240] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 335501 [qtp1276803187-240] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_n,generation=23}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_o,generation=24}
[junit4:junit4]   1> 335502 [qtp1276803187-240] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 24
[junit4:junit4]   1> 335504 [qtp1276803187-240] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@64c04559 main
[junit4:junit4]   1> 335509 [qtp1276803187-240] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 335510 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@64c04559 main{StandardDirectoryReader(segments_o:55:nrt _d(5.0):C1)}
[junit4:junit4]   1> 335511 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 335513 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@64c04559 main{StandardDirectoryReader(segments_o:55:nrt _d(5.0):C1)}
[junit4:junit4]   1> 335515 [qtp1276803187-240] 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> 335521 [qtp1276803187-241] 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> 335541 [qtp1276803187-238] 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=0 
[junit4:junit4]   1> 335546 [TEST-SolrExampleBinaryTest.testRealtimeGet-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testRealtimeGet
[junit4:junit4]   1> 335612 [TEST-SolrExampleBinaryTest.testQueryWithParams-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testQueryWithParams
[junit4:junit4]   1> 335618 [qtp1276803187-242] 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=1 
[junit4:junit4]   1> 335622 [TEST-SolrExampleBinaryTest.testQueryWithParams-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testQueryWithParams
[junit4:junit4]   1> 335661 [TEST-SolrExampleBinaryTest.testStatistics-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testStatistics
[junit4:junit4]   1> 335670 [qtp1276803187-239] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@662ec6a8 realtime
[junit4:junit4]   1> 335671 [qtp1276803187-239] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433427442128650240)} 0 5
[junit4:junit4]   1> 335680 [qtp1276803187-240] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 335681 [qtp1276803187-240] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_o,generation=24}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_p,generation=25}
[junit4:junit4]   1> 335681 [qtp1276803187-240] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 25
[junit4:junit4]   1> 335683 [qtp1276803187-240] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@28a2316f main
[junit4:junit4]   1> 335684 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@28a2316f main{StandardDirectoryReader(segments_o:57:nrt)}
[junit4:junit4]   1> 335685 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 335689 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@28a2316f main{StandardDirectoryReader(segments_o:57:nrt)}
[junit4:junit4]   1> 335696 [qtp1276803187-240] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 335700 [qtp1276803187-240] 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> 335707 [qtp1276803187-243] 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> 335720 [qtp1276803187-241] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc0 (1433427442178981888)]} 0 5
[junit4:junit4]   1> 335731 [qtp1276803187-238] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc1 (1433427442191564800)]} 0 4
[junit4:junit4]   1> 335739 [qtp1276803187-242] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc2 (1433427442203099136)]} 0 1
[junit4:junit4]   1> 335748 [qtp1276803187-239] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc3 (1433427442212536320)]} 0 1
[junit4:junit4]   1> 335764 [qtp1276803187-240] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc4 (1433427442227216384)]} 0 4
[junit4:junit4]   1> 335773 [qtp1276803187-243] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc5 (1433427442238750720)]} 0 1
[junit4:junit4]   1> 335781 [qtp1276803187-241] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc6 (1433427442247139328)]} 0 2
[junit4:junit4]   1> 335788 [qtp1276803187-238] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc7 (1433427442254479360)]} 0 1
[junit4:junit4]   1> 335803 [qtp1276803187-242] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc8 (1433427442270208000)]} 0 1
[junit4:junit4]   1> 335811 [qtp1276803187-239] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc9 (1433427442278596608)]} 0 1
[junit4:junit4]   1> 335828 [qtp1276803187-240] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 335834 [qtp1276803187-240] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_p,generation=25}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_q,generation=26}
[junit4:junit4]   1> 335835 [qtp1276803187-240] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 26
[junit4:junit4]   1> 335837 [qtp1276803187-240] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@6d661444 main
[junit4:junit4]   1> 335841 [qtp1276803187-240] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 335842 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@6d661444 main{StandardDirectoryReader(segments_q:59:nrt _e(5.0):C10)}
[junit4:junit4]   1> 335842 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 335846 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@6d661444 main{StandardDirectoryReader(segments_q:59:nrt _e(5.0):C10)}
[junit4:junit4]   1> 335852 [qtp1276803187-240] 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 24
[junit4:junit4]   1> 335859 [qtp1276803187-243] 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> 335871 [qtp1276803187-241] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={stats.field=val_pi&stats=true&q=*:*&wt=javabin&version=2&rows=0} hits=10 status=0 QTime=1 
[junit4:junit4]   1> 335883 [qtp1276803187-238] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@29f38115 realtime
[junit4:junit4]   1> 335887 [qtp1276803187-238] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433427442349899776)} 0 9
[junit4:junit4]   1> 335904 [qtp1276803187-242] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 335907 [qtp1276803187-242] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_q,generation=26}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_r,generation=27}
[junit4:junit4]   1> 335908 [qtp1276803187-242] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 27
[junit4:junit4]   1> 335911 [qtp1276803187-242] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@6516d640 main
[junit4:junit4]   1> 335913 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@6516d640 main{StandardDirectoryReader(segments_q:61:nrt)}
[junit4:junit4]   1> 335915 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 335916 [qtp1276803187-242] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 335918 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@6516d640 main{StandardDirectoryReader(segments_q:61:nrt)}
[junit4:junit4]   1> 335920 [qtp1276803187-242] 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> 335928 [qtp1276803187-240] 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> 335942 [qtp1276803187-243] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc10 (1433427442411765760)]} 0 5
[junit4:junit4]   1> 335956 [qtp1276803187-241] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc11 (1433427442428542976)]} 0 3
[junit4:junit4]   1> 335975 [qtp1276803187-238] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc12 (1433427442449514496)]} 0 3
[junit4:junit4]   1> 335990 [qtp1276803187-242] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc13 (1433427442461048832)]} 0 6
[junit4:junit4]   1> 336002 [qtp1276803187-239] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc14 (1433427442477826048)]} 0 2
[junit4:junit4]   1> 336012 [qtp1276803187-240] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 336022 [qtp1276803187-240] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_r,generation=27}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_s,generation=28}
[junit4:junit4]   1> 336039 [qtp1276803187-240] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 28
[junit4:junit4]   1> 336043 [qtp1276803187-240] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@36bc5208 main
[junit4:junit4]   1> 336044 [qtp1276803187-240] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 336046 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@36bc5208 main{StandardDirectoryReader(segments_s:63:nrt _f(5.0):C5)}
[junit4:junit4]   1> 336046 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 336049 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@36bc5208 main{StandardDirectoryReader(segments_s:63:nrt _f(5.0):C5)}
[junit4:junit4]   1> 336053 [qtp1276803187-240] 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 41
[junit4:junit4]   1> 336065 [qtp1276803187-243] 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> 336075 [qtp1276803187-241] 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> 336087 [qtp1276803187-238] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@378095fb realtime
[junit4:junit4]   1> 336088 [qtp1276803187-238] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433427442565906432)} 0 5
[junit4:junit4]   1> 336095 [qtp1276803187-242] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 336097 [qtp1276803187-242] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_s,generation=28}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_t,generation=29}
[junit4:junit4]   1> 336097 [qtp1276803187-242] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 29
[junit4:junit4]   1> 336099 [qtp1276803187-242] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@240c243e main
[junit4:junit4]   1> 336100 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@240c243e main{StandardDirectoryReader(segments_s:65:nrt)}
[junit4:junit4]   1> 336101 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 336104 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@240c243e main{StandardDirectoryReader(segments_s:65:nrt)}
[junit4:junit4]   1> 336107 [qtp1276803187-242] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 336108 [qtp1276803187-242] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 13
[junit4:junit4]   1> 336115 [qtp1276803187-239] 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> 336125 [qtp1276803187-240] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc0 (1433427442605752320)]} 0 3
[junit4:junit4]   1> 336141 [qtp1276803187-243] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc1 (1433427442622529536)]} 0 3
[junit4:junit4]   1> 336148 [qtp1276803187-241] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc2 (1433427442631966720)]} 0 2
[junit4:junit4]   1> 336157 [qtp1276803187-238] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc3 (1433427442640355328)]} 0 2
[junit4:junit4]   1> 336172 [qtp1276803187-242] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc4 (1433427442657132544)]} 0 1
[junit4:junit4]   1> 336198 [qtp1276803187-239] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc5 (1433427442679152640)]} 0 6
[junit4:junit4]   1> 336211 [qtp1276803187-240] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc6 (1433427442698027008)]} 0 2
[junit4:junit4]   1> 336219 [qtp1276803187-243] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc7 (1433427442706415616)]} 0 2
[junit4:junit4]   1> 336227 [qtp1276803187-241] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc8 (1433427442714804224)]} 0 1
[junit4:junit4]   1> 336235 [qtp1276803187-238] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc9 (1433427442723192832)]} 0 1
[junit4:junit4]   1> 336242 [qtp1276803187-242] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 336249 [qtp1276803187-242] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_t,generation=29}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_u,generation=30}
[junit4:junit4]   1> 336250 [qtp1276803187-242] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 30
[junit4:junit4]   1> 336253 [qtp1276803187-242] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@2da2e2e3 main
[junit4:junit4]   1> 336255 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@2da2e2e3 main{StandardDirectoryReader(segments_u:67:nrt _g(5.0):C10)}
[junit4:junit4]   1> 336255 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 336255 [qtp1276803187-242] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 336265 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@2da2e2e3 main{StandardDirectoryReader(segments_u:67:nrt _g(5.0):C10)}
[junit4:junit4]   1> 336268 [qtp1276803187-242] 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> 336274 [qtp1276803187-239] 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> 336283 [qtp1276803187-240] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=inStock:false&wt=javabin&version=2} hits=5 status=0 QTime=1 
[junit4:junit4]   1> 336291 [qtp1276803187-243] 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=1 
[junit4:junit4]   1> 336294 [TEST-SolrExampleBinaryTest.testStatistics-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testStatistics
[junit4:junit4]   1> 336332 [TEST-SolrExampleBinaryTest.testChineseDefaults-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testChineseDefaults
[junit4:junit4]   1> 336341 [qtp1276803187-241] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@7102adc0 realtime
[junit4:junit4]   1> 336342 [qtp1276803187-241] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433427442831196160)} 0 5
[junit4:junit4]   1> 336348 [qtp1276803187-242] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 336349 [qtp1276803187-242] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_u,generation=30}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_v,generation=31}
[junit4:junit4]   1> 336350 [qtp1276803187-242] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 31
[junit4:junit4]   1> 336351 [qtp1276803187-242] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@372a90c2 main
[junit4:junit4]   1> 336353 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@372a90c2 main{StandardDirectoryReader(segments_u:69:nrt)}
[junit4:junit4]   1> 336354 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 336355 [qtp1276803187-242] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 336357 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@372a90c2 main{StandardDirectoryReader(segments_u:69:nrt)}
[junit4:junit4]   1> 336359 [qtp1276803187-242] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 10
[junit4:junit4]   1> 336367 [qtp1276803187-239] 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> 336377 [qtp1276803187-240] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 336381 [qtp1276803187-240] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_v,generation=31}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_w,generation=32}
[junit4:junit4]   1> 336382 [qtp1276803187-240] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 32
[junit4:junit4]   1> 336385 [qtp1276803187-240] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@67fe5cb7 main
[junit4:junit4]   1> 336387 [qtp1276803187-240] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 336389 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@67fe5cb7 main{StandardDirectoryReader(segments_w:71:nrt _h(5.0):C1)}
[junit4:junit4]   1> 336389 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 336392 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@67fe5cb7 main{StandardDirectoryReader(segments_w:71:nrt _h(5.0):C1)}
[junit4:junit4]   1> 336397 [qtp1276803187-240] 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 (1433427442869993472)],commit=} 0 23
[junit4:junit4]   1> 336409 [qtp1276803187-243] 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> 336412 [TEST-SolrExampleBinaryTest.testChineseDefaults-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testChineseDefaults
[junit4:junit4]   1> 336437 [TEST-SolrExampleBinaryTest.testSetNullUpdateOrder-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testSetNullUpdateOrder
[junit4:junit4]   1> 336454 [qtp1276803187-238] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[testSetNullUpdateOrder (1433427442950733824)]} 0 3
[junit4:junit4]   1> 336476 [qtp1276803187-241] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 336482 [qtp1276803187-241] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_w,generation=32}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_x,generation=33}
[junit4:junit4]   1> 336483 [qtp1276803187-241] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 33
[junit4:junit4]   1> 336486 [qtp1276803187-241] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@21a59e9b main
[junit4:junit4]   1> 336489 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@21a59e9b main{StandardDirectoryReader(segments_x:73:nrt _h(5.0):C1 _i(5.0):C1)}
[junit4:junit4]   1> 336490 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 336489 [qtp1276803187-241] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 336493 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@21a59e9b main{StandardDirectoryReader(segments_x:73:nrt _h(5.0):C1 _i(5.0):C1)}
[junit4:junit4]   1> 336499 [qtp1276803187-241] 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> 336512 [qtp1276803187-242] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[testSetNullUpdateOrder (1433427443011551232)]} 0 3
[junit4:junit4]   1> 336525 [qtp1276803187-239] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 336532 [qtp1276803187-239] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_x,generation=33}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_y,generation=34}
[junit4:junit4]   1> 336538 [qtp1276803187-239] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 34
[junit4:junit4]   1> 336541 [qtp1276803187-239] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@3e848c55 main
[junit4:junit4]   1> 336545 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@3e848c55 main{StandardDirectoryReader(segments_y:77:nrt _h(5.0):C1 _j(5.0):C1)}
[junit4:junit4]   1> 336546 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 336546 [qtp1276803187-239] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 336549 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@3e848c55 main{StandardDirectoryReader(segments_y:77:nrt _h(5.0):C1 _j(5.0):C1)}
[junit4:junit4]   1> 336550 [qtp1276803187-239] 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> 336558 [qtp1276803187-240] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=id:testSetNullUpdateOrder&wt=javabin&version=2} hits=1 status=0 QTime=1 
[junit4:junit4]   1> 336562 [TEST-SolrExampleBinaryTest.testSetNullUpdateOrder-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testSetNullUpdateOrder
[junit4:junit4]   1> 336593 [TEST-SolrExampleBinaryTest.testErrorHandling-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testErrorHandling
[junit4:junit4]   1> 336608 [qtp1276803187-238] INFO  org.apache.solr.core.SolrCore  ? Ignoring exception matching ignore_exception
[junit4:junit4]   1> 336609 [qtp1276803187-238] 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=3 
[junit4:junit4]   1> 336625 [qtp1276803187-241] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {} 0 4
[junit4:junit4]   1> 336629 [qtp1276803187-241] INFO  org.apache.solr.core.SolrCore  ? Ignoring exception matching ignore_exception
[junit4:junit4]   1> 336640 [qtp1276803187-242] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {} 0 1
[junit4:junit4]   1> 336643 [qtp1276803187-242] ERROR org.apache.solr.core.SolrCore  ? org.apache.solr.common.SolrException: Document contains multiple values for uniqueKey field: id=[DOCID, DOCID2]
[junit4:junit4]   1> 	at org.apache.solr.update.AddUpdateCommand.getIndexedId(AddUpdateCommand.java:91)
[junit4:junit4]   1> 	at org.apache.solr.update.processor.DistributedUpdateProcessor.versionAdd(DistributedUpdateProcessor.java:518)
[junit4:junit4]   1> 	at org.apache.solr.update.processor.DistributedUpdateProcessor.processAdd(DistributedUpdateProcessor.java:397)
[junit4:junit4]   1> 	at org.apache.solr.update.processor.LogUpdateProcessor.processAdd(LogUpdateProcessorFactory.java:100)
[junit4:junit4]   1> 	at org.apache.solr.handler.loader.JavabinLoader$1.update(JavabinLoader.java:86)
[junit4:junit4]   1> 	at org.apache.solr.client.solrj.request.JavaBinUpdateRequestCodec$1.readOuterMostDocIterator(JavaBinUpdateRequestCodec.java:140)
[junit4:junit4]   1> 	at org.apache.solr.client.solrj.request.JavaBinUpdateRequestCodec$1.readIterator(JavaBinUpdateRequestCodec.java:120)
[junit4:junit4]   1> 	at org.apache.solr.common.util.JavaBinCodec.readVal(JavaBinCodec.java:221)
[junit4:junit4]   1> 	at org.apache.solr.client.solrj.request.JavaBinUpdateRequestCodec$1.readNamedList(JavaBinUpdateRequestCodec.java:105)
[junit4:junit4]   1> 	at org.apache.solr.common.util.JavaBinCodec.readVal(JavaBinCodec.java:186)
[junit4:junit4]   1> 	at org.apache.solr.common.util.JavaBinCodec.unmarshal(JavaBinCodec.java:112)
[junit4:junit4]   1> 	at org.apache.solr.client.solrj.request.JavaBinUpdateRequestCodec.unmarshal(JavaBinUpdateRequestCodec.java:147)
[junit4:junit4]   1> 	at org.apache.solr.handler.loader.JavabinLoader.parseAndLoadDocs(JavabinLoader.java:96)
[junit4:junit4]   1> 	at org.apache.solr.handler.loader.JavabinLoader.load(JavabinLoader.java:55)
[junit4:junit4]   1> 	at org.apache.solr.handler.UpdateRequestHandler$1.load(UpdateRequestHandler.java:92)
[junit4:junit4]   1> 	at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:74)
[junit4:junit4]   1> 	at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:135)
[junit4:junit4]   1> 	at org.apache.solr.core.SolrCore.execute(SolrCore.java:1832)
[junit4:junit4]   1> 	at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:656)
[junit4:junit4]   1> 	at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:359)
[junit4:junit4]   1> 	at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:155)
[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:953)
[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:722)
[junit4:junit4]   1> 
[junit4:junit4]   1> 336650 [TEST-SolrExampleBinaryTest.testErrorHandling-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testErrorHandling
[junit4:junit4]   1> 336692 [TEST-SolrExampleBinaryTest.testCommitWithinOnDelete-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testCommitWithinOnDelete
[junit4:junit4]   1> 336703 [qtp1276803187-239] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@3f974f36 realtime
[junit4:junit4]   1> 336705 [qtp1276803187-239] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433427443210780672)} 0 7
[junit4:junit4]   1> 336715 [qtp1276803187-240] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 336717 [qtp1276803187-240] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_y,generation=34}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_z,generation=35}
[junit4:junit4]   1> 336719 [qtp1276803187-240] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 35
[junit4:junit4]   1> 336722 [qtp1276803187-240] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@60e208c1 main
[junit4:junit4]   1> 336727 [qtp1276803187-240] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 336728 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@60e208c1 main{StandardDirectoryReader(segments_y:79:nrt)}
[junit4:junit4]   1> 336729 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 336732 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@60e208c1 main{StandardDirectoryReader(segments_y:79:nrt)}
[junit4:junit4]   1> 336734 [qtp1276803187-240] 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> 336744 [qtp1276803187-238] 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> 336770 [qtp1276803187-241] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id3 (1433427443274743808)]} 0 11
[junit4:junit4]   1> 336779 [qtp1276803187-242] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 336789 [qtp1276803187-242] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_z,generation=35}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_10,generation=36}
[junit4:junit4]   1> 336791 [qtp1276803187-242] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 36
[junit4:junit4]   1> 336795 [qtp1276803187-242] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@2a83ad63 main
[junit4:junit4]   1> 336800 [qtp1276803187-242] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 336802 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@2a83ad63 main{StandardDirectoryReader(segments_10:81:nrt _k(5.0):C1)}
[junit4:junit4]   1> 336803 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 336808 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@2a83ad63 main{StandardDirectoryReader(segments_10:81:nrt _k(5.0):C1)}
[junit4:junit4]   1> 336810 [qtp1276803187-242] 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> 336845 [qtp1276803187-239] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=3 
[junit4:junit4]   1> 336875 [qtp1276803187-240] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {delete=[id3 (-1433427443391135744)]} 0 4
[junit4:junit4]   1> 336887 [qtp1276803187-243] 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> 337149 [qtp1276803187-238] 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> 337416 [qtp1276803187-242] 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> 337680 [qtp1276803187-239] 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> 337876 [commitScheduler-155-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> 337882 [commitScheduler-155-thread-1] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@482a0e9e main
[junit4:junit4]   1> 337883 [commitScheduler-155-thread-1] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 337883 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@482a0e9e main{StandardDirectoryReader(segments_10:83:nrt)}
[junit4:junit4]   1> 337884 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 337889 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@482a0e9e main{StandardDirectoryReader(segments_10:83:nrt)}
[junit4:junit4]   1> 337941 [qtp1276803187-243] 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> 337944 [TEST-SolrExampleBinaryTest.testCommitWithinOnDelete-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testCommitWithinOnDelete
[junit4:junit4]   1> 337969 [TEST-SolrExampleBinaryTest.testExampleConfig-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testExampleConfig
[junit4:junit4]   1> 337975 [qtp1276803187-241] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433427444547715072)} 0 1
[junit4:junit4]   1> 337984 [qtp1276803187-238] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1112211111 (1433427444556103680)]} 0 2
[junit4:junit4]   1> 338002 [qtp1276803187-242] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 338009 [qtp1276803187-242] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_10,generation=36}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_11,generation=37}
[junit4:junit4]   1> 338010 [qtp1276803187-242] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 37
[junit4:junit4]   1> 338012 [qtp1276803187-242] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@696bba80 main
[junit4:junit4]   1> 338014 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@696bba80 main{StandardDirectoryReader(segments_11:85:nrt _l(5.0):C1)}
[junit4:junit4]   1> 338014 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 338015 [qtp1276803187-242] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 338018 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@696bba80 main{StandardDirectoryReader(segments_11:85:nrt _l(5.0):C1)}
[junit4:junit4]   1> 338019 [qtp1276803187-242] 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 17
[junit4:junit4]   1> 338025 [qtp1276803187-240] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 338027 [qtp1276803187-240] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_11,generation=37}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_12,generation=38}
[junit4:junit4]   1> 338028 [qtp1276803187-240] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 38
[junit4:junit4]   1> 338029 [qtp1276803187-240] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@75b06274 main
[junit4:junit4]   1> 338030 [qtp1276803187-240] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 338031 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@75b06274 main{StandardDirectoryReader(segments_11:85:nrt _l(5.0):C1)}
[junit4:junit4]   1> 338031 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 338035 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@75b06274 main{StandardDirectoryReader(segments_11:85:nrt _l(5.0):C1)}
[junit4:junit4]   1> 338036 [qtp1276803187-240] 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 11
[junit4:junit4]   1> 338045 [qtp1276803187-239] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=id:1112211111&wt=javabin&version=2} hits=1 status=0 QTime=1 
[junit4:junit4]   1> 338078 [qtp1276803187-243] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2 (1433427444633698304), 3 (1433427444652572672), 4 (1433427444654669824), 5 (1433427444655718400)]} 0 23
[junit4:junit4]   1> 338087 [qtp1276803187-238] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 338098 [qtp1276803187-238] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_12,generation=38}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_13,generation=39}
[junit4:junit4]   1> 338100 [qtp1276803187-238] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 39
[junit4:junit4]   1> 338104 [qtp1276803187-238] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@716a6589 main
[junit4:junit4]   1> 338107 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@716a6589 main{StandardDirectoryReader(segments_13:87:nrt _l(5.0):C1 _m(5.0):C4)}
[junit4:junit4]   1> 338107 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 338110 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@716a6589 main{StandardDirectoryReader(segments_13:87:nrt _l(5.0):C1 _m(5.0):C4)}
[junit4:junit4]   1> 338107 [qtp1276803187-238] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 338112 [qtp1276803187-238] 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> 338118 [qtp1276803187-242] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 338148 [qtp1276803187-242] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_13,generation=39}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_14,generation=40}
[junit4:junit4]   1> 338149 [qtp1276803187-242] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 40
[junit4:junit4]   1> 338154 [qtp1276803187-242] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@6dfadcb main
[junit4:junit4]   1> 338156 [qtp1276803187-242] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 338157 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@6dfadcb main{StandardDirectoryReader(segments_14:89:nrt _n(5.0):C5)}
[junit4:junit4]   1> 338157 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 338160 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@6dfadcb main{StandardDirectoryReader(segments_14:89:nrt _n(5.0):C5)}
[junit4:junit4]   1> 338163 [qtp1276803187-242] 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> 338173 [qtp1276803187-240] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={facet=true&facet.query=price:[*+TO+2]&facet.query=price:[2+TO+4]&facet.query=price:[5+TO+*]&q=*:*&facet.field=inStock&facet.field=price&facet.field=timestamp_dt&wt=javabin&version=2} hits=5 status=0 QTime=4 
[junit4:junit4]   1> 338184 [qtp1276803187-239] 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=3 
[junit4:junit4]   1> 338194 [qtp1276803187-243] 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=3 
[junit4:junit4]   1> 338201 [TEST-SolrExampleBinaryTest.testExampleConfig-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testExampleConfig
[junit4:junit4]   1> 338228 [TEST-SolrExampleBinaryTest.testLukeHandler-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testLukeHandler
[junit4:junit4]   1> 338236 [qtp1276803187-241] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@68e712c5 realtime
[junit4:junit4]   1> 338237 [qtp1276803187-241] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433427444819296256)} 0 4
[junit4:junit4]   1> 338246 [qtp1276803187-238] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID0 (1433427444829782016)]} 0 3
[junit4:junit4]   1> 338256 [qtp1276803187-240] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID1 (1433427444841316352)]} 0 3
[junit4:junit4]   1> 338274 [qtp1276803187-243] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID2 (1433427444858093568)]} 0 5
[junit4:junit4]   1> 338295 [qtp1276803187-241] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID3 (1433427444883259392)]} 0 0
[junit4:junit4]   1> 338302 [qtp1276803187-238] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID4 (1433427444890599424)]} 0 1
[junit4:junit4]   1> 338310 [qtp1276803187-242] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 338314 [qtp1276803187-242] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_14,generation=40}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_15,generation=41}
[junit4:junit4]   1> 338314 [qtp1276803187-242] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 41
[junit4:junit4]   1> 338317 [qtp1276803187-242] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@18a02a8a main
[junit4:junit4]   1> 338320 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@18a02a8a main{StandardDirectoryReader(segments_15:93:nrt _o(5.0):C5)}
[junit4:junit4]   1> 338321 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 338323 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@18a02a8a main{StandardDirectoryReader(segments_15:93:nrt _o(5.0):C5)}
[junit4:junit4]   1> 338325 [qtp1276803187-242] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 338326 [qtp1276803187-242] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 16
[junit4:junit4]   1> 338338 [qtp1276803187-239] 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> 338347 [qtp1276803187-243] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/admin/luke params={wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   1> 338354 [qtp1276803187-241] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/admin/luke params={show=schema&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   1> 338487 [TEST-SolrExampleBinaryTest.testLukeHandler-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testLukeHandler
[junit4:junit4]   1> 338596 [TEST-SolrExampleBinaryTest.testGetEmptyResults-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testGetEmptyResults
[junit4:junit4]   1> 338644 [qtp1276803187-238] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@2d786502 realtime
[junit4:junit4]   1> 338646 [qtp1276803187-238] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433427445236629504)} 0 15
[junit4:junit4]   1> 338653 [qtp1276803187-240] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 338655 [qtp1276803187-240] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_15,generation=41}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_16,generation=42}
[junit4:junit4]   1> 338656 [qtp1276803187-240] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 42
[junit4:junit4]   1> 338657 [qtp1276803187-240] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@dc188aa main
[junit4:junit4]   1> 338659 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@dc188aa main{StandardDirectoryReader(segments_15:95:nrt)}
[junit4:junit4]   1> 338659 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 338662 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@dc188aa main{StandardDirectoryReader(segments_15:95:nrt)}
[junit4:junit4]   1> 338665 [qtp1276803187-240] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 338666 [qtp1276803187-240] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 13
[junit4:junit4]   1> 338719 [qtp1276803187-243] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id1 (1433427445313175552)]} 0 16
[junit4:junit4]   1> 338736 [qtp1276803187-241] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id2 (1433427445343584256)]} 0 3
[junit4:junit4]   1> 338743 [qtp1276803187-242] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 338750 [qtp1276803187-242] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_16,generation=42}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_17,generation=43}
[junit4:junit4]   1> 338752 [qtp1276803187-242] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 43
[junit4:junit4]   1> 338757 [qtp1276803187-242] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@6fb1a043 main
[junit4:junit4]   1> 338760 [qtp1276803187-242] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 338761 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@6fb1a043 main{StandardDirectoryReader(segments_17:97:nrt _p(5.0):C2)}
[junit4:junit4]   1> 338762 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 338767 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@6fb1a043 main{StandardDirectoryReader(segments_17:97:nrt _p(5.0):C2)}
[junit4:junit4]   1> 338769 [qtp1276803187-242] 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> 338787 [qtp1276803187-238] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={fl=foofoofoo&q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=1 
[junit4:junit4]   1> 338791 [TEST-SolrExampleBinaryTest.testGetEmptyResults-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testGetEmptyResults
[junit4:junit4]   1> 338830 [TEST-SolrExampleBinaryTest.testSetNullUpdates-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testSetNullUpdates
[junit4:junit4]   1> 338844 [qtp1276803187-240] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[testSetNullUpdates (1433427445456830464)]} 0 3
[junit4:junit4]   1> 338850 [qtp1276803187-239] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 338867 [qtp1276803187-239] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_17,generation=43}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_18,generation=44}
[junit4:junit4]   1> 338871 [qtp1276803187-239] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 44
[junit4:junit4]   1> 338898 [qtp1276803187-239] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@3c177568 main
[junit4:junit4]   1> 338900 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@3c177568 main{StandardDirectoryReader(segments_18:99:nrt _p(5.0):C2 _q(5.0):C1)}
[junit4:junit4]   1> 338901 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 338905 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@3c177568 main{StandardDirectoryReader(segments_18:99:nrt _p(5.0):C2 _q(5.0):C1)}
[junit4:junit4]   1> 338907 [qtp1276803187-239] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 338907 [qtp1276803187-239] 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> 338916 [qtp1276803187-243] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[testSetNullUpdates (1433427445533376512)]} 0 3
[junit4:junit4]   1> 338921 [qtp1276803187-241] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 338928 [qtp1276803187-241] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_18,generation=44}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_19,generation=45}
[junit4:junit4]   1> 338928 [qtp1276803187-241] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 45
[junit4:junit4]   1> 338932 [qtp1276803187-241] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@4686376b main
[junit4:junit4]   1> 338935 [qtp1276803187-241] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 338936 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@4686376b main{StandardDirectoryReader(segments_19:103:nrt _p(5.0):C2 _r(5.0):C1)}
[junit4:junit4]   1> 338936 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 338939 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@4686376b main{StandardDirectoryReader(segments_19:103:nrt _p(5.0):C2 _r(5.0):C1)}
[junit4:junit4]   1> 338941 [qtp1276803187-241] 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 20
[junit4:junit4]   1> 338948 [qtp1276803187-242] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=id:testSetNullUpdates&wt=javabin&version=2} hits=1 status=0 QTime=1 
[junit4:junit4]   1> 338952 [TEST-SolrExampleBinaryTest.testSetNullUpdates-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testSetNullUpdates
[junit4:junit4]   1> 338982 [TEST-SolrExampleBinaryTest.testMultiContentStreamRequest-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testMultiContentStreamRequest
[junit4:junit4]   1> 338991 [qtp1276803187-238] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@3e87adf realtime
[junit4:junit4]   1> 338993 [qtp1276803187-238] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433427445609922560)} 0 6
[junit4:junit4]   1> 339003 [qtp1276803187-239] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 339005 [qtp1276803187-239] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_19,generation=45}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_1a,generation=46}
[junit4:junit4]   1> 339007 [qtp1276803187-239] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 46
[junit4:junit4]   1> 339010 [qtp1276803187-239] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@715c7b5f main
[junit4:junit4]   1> 339012 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@715c7b5f main{StandardDirectoryReader(segments_19:105:nrt)}
[junit4:junit4]   1> 339013 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 339017 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@715c7b5f main{StandardDirectoryReader(segments_19:105:nrt)}
[junit4:junit4]   1> 339035 [qtp1276803187-239] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 339044 [qtp1276803187-239] 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 41
[junit4:junit4]   1> 339057 [qtp1276803187-241] 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> 339490 [qtp1276803187-238] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 339520 [qtp1276803187-238] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_1a,generation=46}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_1b,generation=47}
[junit4:junit4]   1> 339521 [qtp1276803187-238] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 47
[junit4:junit4]   1> 339526 [qtp1276803187-238] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@1b081d5e main
[junit4:junit4]   1> 339533 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@1b081d5e main{StandardDirectoryReader(segments_1b:107:nrt _s(5.0):C5)}
[junit4:junit4]   1> 339533 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 339534 [qtp1276803187-238] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 339536 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@1b081d5e main{StandardDirectoryReader(segments_1b:107:nrt _s(5.0):C5)}
[junit4:junit4]   1> 339536 [qtp1276803187-238] 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 (1433427446088073216), 6H500F0 (1433427446095413248), TWINX2048-3200PRO (1433427446100656128), VS1GB400C3 (1433427446107996160), VDBDB1A16 (1433427446112190464)],commit=} 0 94
[junit4:junit4]   1> 339550 [qtp1276803187-243] 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> 339556 [TEST-SolrExampleBinaryTest.testMultiContentStreamRequest-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testMultiContentStreamRequest
[junit4:junit4]   1> 339581 [TEST-SolrExampleBinaryTest.testPivotFacets-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testPivotFacets
[junit4:junit4]   1> 339590 [qtp1276803187-239] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@43bc16f5 realtime
[junit4:junit4]   1> 339591 [qtp1276803187-239] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433427446238019584)} 0 5
[junit4:junit4]   1> 339598 [qtp1276803187-241] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 339600 [qtp1276803187-241] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_1b,generation=47}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_1c,generation=48}
[junit4:junit4]   1> 339602 [qtp1276803187-241] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 48
[junit4:junit4]   1> 339604 [qtp1276803187-241] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@4a394901 main
[junit4:junit4]   1> 339607 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@4a394901 main{StandardDirectoryReader(segments_1b:109:nrt)}
[junit4:junit4]   1> 339607 [qtp1276803187-241] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 339607 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 339611 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@4a394901 main{StandardDirectoryReader(segments_1b:109:nrt)}
[junit4:junit4]   1> 339613 [qtp1276803187-241] 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 15
[junit4:junit4]   1> 339624 [qtp1276803187-242] 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> 339644 [qtp1276803187-240] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1 (1433427446285205504), 2 (1433427446288351232), 3 (1433427446289399808), 4 (1433427446290448384), 5 (1433427446291496960), 6 (1433427446292545536), 7 (1433427446292545537), 8 (1433427446293594112), 9 (1433427446295691264), 10 (1433427446295691265), ... (12 adds)]} 0 13
[junit4:junit4]   1> 339651 [qtp1276803187-238] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 339658 [qtp1276803187-238] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_1c,generation=48}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_1d,generation=49}
[junit4:junit4]   1> 339660 [qtp1276803187-238] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 49
[junit4:junit4]   1> 339663 [qtp1276803187-238] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@5e1bbf35 main
[junit4:junit4]   1> 339664 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@5e1bbf35 main{StandardDirectoryReader(segments_1d:111:nrt _t(5.0):C12)}
[junit4:junit4]   1> 339665 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 339695 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@5e1bbf35 main{StandardDirectoryReader(segments_1d:111:nrt _t(5.0):C12)}
[junit4:junit4]   1> 339704 [qtp1276803187-238] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 339704 [qtp1276803187-238] 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 53
[junit4:junit4]   1> 339715 [qtp1276803187-243] INFO  org.apache.solr.core.SolrCore  ? UnInverted multi-valued field {field=features,memSize=4521,tindexSize=43,time=0,phase1=0,nTerms=2,bigTerms=2,termInstances=0,uses=0}
[junit4:junit4]   1> 339717 [qtp1276803187-243] 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> 339719 [qtp1276803187-243] 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=5 
[junit4:junit4]   1> 339732 [qtp1276803187-239] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=0&q=*:*&wt=javabin&fq={!tag%3Dmytag}-(features:bbb+AND+cat:a+AND+inStock:true)&facet.pivot={!ex%3Dmytag+key%3Dmykey}features,cat&version=2&rows=0} hits=11 status=0 QTime=2 
[junit4:junit4]   1> 339736 [TEST-SolrExampleBinaryTest.testPivotFacets-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testPivotFacets
[junit4:junit4]   1> 339762 [TEST-SolrExampleBinaryTest.testContentStreamRequest-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testContentStreamRequest
[junit4:junit4]   1> 339775 [qtp1276803187-241] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@7cd62439 realtime
[junit4:junit4]   1> 339777 [qtp1276803187-241] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433427446433054720)} 0 5
[junit4:junit4]   1> 339782 [qtp1276803187-242] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 339784 [qtp1276803187-242] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_1d,generation=49}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_1e,generation=50}
[junit4:junit4]   1> 339784 [qtp1276803187-242] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 50
[junit4:junit4]   1> 339785 [qtp1276803187-242] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@44633870 main
[junit4:junit4]   1> 339787 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@44633870 main{StandardDirectoryReader(segments_1d:113:nrt)}
[junit4:junit4]   1> 339788 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 339788 [qtp1276803187-242] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 339791 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@44633870 main{StandardDirectoryReader(segments_1d:113:nrt)}
[junit4:junit4]   1> 339793 [qtp1276803187-242] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 11
[junit4:junit4]   1> 339800 [qtp1276803187-240] 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> 339861 [qtp1276803187-238] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 339919 [qtp1276803187-238] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_1e,generation=50}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_1f,generation=51}
[junit4:junit4]   1> 339920 [qtp1276803187-238] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 51
[junit4:junit4]   1> 339923 [qtp1276803187-238] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@75836867 main
[junit4:junit4]   1> 339926 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@75836867 main{StandardDirectoryReader(segments_1f:115:nrt _u(5.0):C10)}
[junit4:junit4]   1> 339926 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 339929 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@75836867 main{StandardDirectoryReader(segments_1f:115:nrt _u(5.0):C10)}
[junit4:junit4]   1> 339963 [qtp1276803187-238] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 339981 [qtp1276803187-238] 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 (1433427446502260736), 0553579908 (1433427446505406464), 055357342X (1433427446507503616), 0553293354 (1433427446509600768), 0812521390 (1433427446510649344), 0812550706 (1433427446512746496), 0441385532 (1433427446513795072), 0380014300 (1433427446516940800), 0805080481 (1433427446520086528), 080508049X (1433427446524280832)],commit=} 0 143
[junit4:junit4]   1> 340006 [qtp1276803187-239] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=0 
[junit4:junit4]   1> 340029 [TEST-SolrExampleBinaryTest.testContentStreamRequest-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testContentStreamRequest
[junit4:junit4]   1> 340080 [TEST-SolrExampleBinaryTest.testAddRetrieve-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testAddRetrieve
[junit4:junit4]   1> 340097 [qtp1276803187-242] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@7187ac48 realtime
[junit4:junit4]   1> 340098 [qtp1276803187-242] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433427446770696192)} 0 4
[junit4:junit4]   1> 340115 [qtp1276803187-240] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id1 (1433427446789570560), id2 (1433427446791667712)]} 0 3
[junit4:junit4]   1> 340122 [qtp1276803187-243] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 340145 [qtp1276803187-243] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_1f,generation=51}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_1g,generation=52}
[junit4:junit4]   1> 340146 [qtp1276803187-243] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 52
[junit4:junit4]   1> 340168 [qtp1276803187-243] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@5f2421d0 main
[junit4:junit4]   1> 340171 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@5f2421d0 main{StandardDirectoryReader(segments_1g:119:nrt _v(5.0):C2)}
[junit4:junit4]   1> 340172 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 340172 [qtp1276803187-243] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 340180 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@5f2421d0 main{StandardDirectoryReader(segments_1g:119:nrt _v(5.0):C2)}
[junit4:junit4]   1> 340182 [qtp1276803187-243] 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 60
[junit4:junit4]   1> 340188 [qtp1276803187-238] 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=0 
[junit4:junit4]   1> 340245 [qtp1276803187-239] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id1 (1433427446916448256), id2 (1433427446927982592)]} 0 13
[junit4:junit4]   1> 340267 [qtp1276803187-242] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 340287 [qtp1276803187-242] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_1g,generation=52}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_1h,generation=53}
[junit4:junit4]   1> 340288 [qtp1276803187-242] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 53
[junit4:junit4]   1> 340291 [qtp1276803187-242] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@306359f3 main
[junit4:junit4]   1> 340299 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@306359f3 main{StandardDirectoryReader(segments_1h:123:nrt _w(5.0):C2)}
[junit4:junit4]   1> 340300 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 340303 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@306359f3 main{StandardDirectoryReader(segments_1h:123:nrt _w(5.0):C2)}
[junit4:junit4]   1> 340306 [qtp1276803187-242] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 340308 [qtp1276803187-242] 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 41
[junit4:junit4]   1> 340316 [qtp1276803187-240] 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> 340337 [qtp1276803187-243] 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=2 
[junit4:junit4]   1> 340340 [TEST-SolrExampleBinaryTest.testAddRetrieve-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testAddRetrieve
[junit4:junit4]   1> 340378 [TEST-SolrExampleBinaryTest.testUpdateRequestWithParameters-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testUpdateRequestWithParameters
[junit4:junit4]   1> 340379 [TEST-SolrExampleBinaryTest.testUpdateRequestWithParameters-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.client.solrj.impl.HttpClientUtil  ? Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   1> 340472 [qtp1276803187-243] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@30eff17d realtime
[junit4:junit4]   1> 340473 [qtp1276803187-243] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433427447161815040)} 0 6
[junit4:junit4]   1> 340502 [qtp1276803187-238] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 340530 [qtp1276803187-238] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_1h,generation=53}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_1i,generation=54}
[junit4:junit4]   1> 340531 [qtp1276803187-238] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 54
[junit4:junit4]   1> 340532 [qtp1276803187-238] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@6ebe375f main
[junit4:junit4]   1> 340534 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@6ebe375f main{StandardDirectoryReader(segments_1h:125:nrt)}
[junit4:junit4]   1> 340536 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 340546 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@6ebe375f main{StandardDirectoryReader(segments_1h:125:nrt)}
[junit4:junit4]   1> 340548 [qtp1276803187-238] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 340549 [qtp1276803187-238] 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> 340578 [qtp1276803187-239] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={overwrite=false&wt=javabin&version=2} {add=[id1 (1433427447275061248)]} 0 3
[junit4:junit4]   1> 340608 [qtp1276803187-242] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={overwrite=false&wt=javabin&version=2} {add=[id1 (1433427447303372800)]} 0 6
[junit4:junit4]   1> 340639 [qtp1276803187-243] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 340648 [qtp1276803187-243] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_1i,generation=54}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_1j,generation=55}
[junit4:junit4]   1> 340649 [qtp1276803187-243] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 55
[junit4:junit4]   1> 340651 [qtp1276803187-243] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@7cf4ae2 main
[junit4:junit4]   1> 340653 [qtp1276803187-243] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 340655 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@7cf4ae2 main{StandardDirectoryReader(segments_1j:127:nrt _x(5.0):C2)}
[junit4:junit4]   1> 340655 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 340658 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@7cf4ae2 main{StandardDirectoryReader(segments_1j:127:nrt _x(5.0):C2)}
[junit4:junit4]   1> 340659 [qtp1

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

timize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 343203 [qtp1276803187-240] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_1n,generation=59}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_1o,generation=60}
[junit4:junit4]   1> 343204 [qtp1276803187-240] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 60
[junit4:junit4]   1> 343207 [qtp1276803187-240] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@2edfbe28 main
[junit4:junit4]   1> 343209 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@2edfbe28 main{StandardDirectoryReader(segments_1o:147:nrt _13(5.0):C1)}
[junit4:junit4]   1> 343210 [qtp1276803187-240] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 343210 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 343214 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@2edfbe28 main{StandardDirectoryReader(segments_1o:147:nrt _13(5.0):C1)}
[junit4:junit4]   1> 343215 [qtp1276803187-240] 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> 343225 [qtp1276803187-238] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[222 (1433427450049593344)]} 0 4
[junit4:junit4]   1> 343231 [qtp1276803187-243] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 343237 [qtp1276803187-243] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_1o,generation=60}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@430d9b69 lockFactory=org.apache.lucene.store.NativeFSLockFactory@25acb490),segFN=segments_1p,generation=61}
[junit4:junit4]   1> 343238 [qtp1276803187-243] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 61
[junit4:junit4]   1> 343241 [qtp1276803187-243] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@30ef19d4 main
[junit4:junit4]   1> 343244 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@30ef19d4 main{StandardDirectoryReader(segments_1p:149:nrt _13(5.0):C1 _14(5.0):C1)}
[junit4:junit4]   1> 343244 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 343247 [searcherExecutor-152-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@30ef19d4 main{StandardDirectoryReader(segments_1p:149:nrt _13(5.0):C1 _14(5.0):C1)}
[junit4:junit4]   1> 343244 [qtp1276803187-243] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 343249 [qtp1276803187-243] 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> 343256 [qtp1276803187-239] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={sort=price+asc&fl=id,price,[docid],[explain+style%3Dnl],score,aaa:[value+v%3Daaa],ten:[value+v%3D10+t%3Dint]&q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=1 
[junit4:junit4]   1> 343267 [TEST-SolrExampleBinaryTest.testAugmentFields-seed#[F4AF78882B941BC1]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testAugmentFields
[junit4:junit4]   1> 343286 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.apache.solr.core.CoreContainer  ? Shutting down CoreContainer instance=1437291442
[junit4:junit4]   1> 343287 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.apache.solr.core.SolrCore  ? [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@4a3d5c5e
[junit4:junit4]   1> 343330 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.apache.solr.update.UpdateHandler  ? closing DirectUpdateHandler2{commits=61,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=139,cumulative_deletesById=5,cumulative_deletesByQuery=28,cumulative_errors=1}
[junit4:junit4]   1> 343340 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.apache.solr.update.SolrCoreState  ? Closing SolrCoreState
[junit4:junit4]   1> 343344 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.apache.solr.update.DefaultSolrCoreState  ? SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   1> 343345 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.apache.solr.update.DefaultSolrCoreState  ? closing IndexWriter with IndexWriterCloser
[junit4:junit4]   1> 343365 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.apache.solr.core.SolrCore  ? [collection1] Closing main searcher on request.
[junit4:junit4]   1> 343367 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.apache.solr.core.CachingDirectoryFactory  ? Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4]   1> 343367 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.apache.solr.core.CachingDirectoryFactory  ? looking to close /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleBinaryTest-1367022724409/index [CachedDir<<refCount=0;path=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleBinaryTest-1367022724409/index;done=false>>]
[junit4:junit4]   1> 343368 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.apache.solr.core.CachingDirectoryFactory  ? Closing directory: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleBinaryTest-1367022724409/index
[junit4:junit4]   1> 343369 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.apache.solr.core.CachingDirectoryFactory  ? looking to close /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleBinaryTest-1367022724409 [CachedDir<<refCount=0;path=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleBinaryTest-1367022724409;done=false>>]
[junit4:junit4]   1> 343369 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.apache.solr.core.CachingDirectoryFactory  ? Closing directory: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleBinaryTest-1367022724409
[junit4:junit4]   1> 343370 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.eclipse.jetty.server.handler.ContextHandler  ? stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   1> 343438 [SUITE-SolrExampleBinaryTest-seed#[F4AF78882B941BC1]-worker] INFO  org.apache.solr.SolrTestCaseJ4  ? ###deleteCore
[junit4:junit4]   2> NOTE: test params are: codec=Lucene42: {}, docValues:{}, sim=DefaultSimilarity, locale=ar_YE, timezone=Etc/GMT+9
[junit4:junit4]   2> NOTE: Mac OS X 10.8.3 x86_64/Oracle Corporation 1.7.0_21 (64-bit)/cpus=2,threads=1,free=53495408,total=93388800
[junit4:junit4]   2> NOTE: All tests run in this JVM: [TestXMLEscaping, TestHash, TestEmbeddedSolrServer, SolrParamTest, FacetFieldTest, SolrExampleEmbeddedTest, SolrDocumentTest, LargeVolumeJettyTest, MergeIndexesEmbeddedTest, TestLBHttpSolrServer, FieldAnalysisResponseTest, SolrExampleBinaryTest]
[junit4:junit4] Completed in 219.52s, 26 tests, 1 error <<< FAILURES!

[...truncated 108 lines...]
BUILD FAILED
/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/build.xml:378: The following error occurred while executing this line:
/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/build.xml:358: The following error occurred while executing this line:
/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/build.xml:39: The following error occurred while executing this line:
/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build.xml:194: The following error occurred while executing this line:
/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/common-build.xml:437: The following error occurred while executing this line:
/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:1240: The following error occurred while executing this line:
/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:884: There were test failures: 45 suites, 268 tests, 1 error

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



Mime
View raw message