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-Windows (32bit/jdk1.6.0_38) - Build # 2577 - Failure!
Date Thu, 21 Feb 2013 04:22:33 GMT
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/2577/
Java: 32bit/jdk1.6.0_38 -server -XX:+UseSerialGC

1 tests failed.
REGRESSION:  org.apache.solr.client.solrj.embedded.SolrExampleStreamingBinaryTest.testUpdateRequestWithParameters

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

Stack Trace:
org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: https://127.0.0.1:58043/solr
	at __randomizedtesting.SeedInfo.seed([FF51741125548A75:BD27DAC40A91AAB5]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:416)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:181)
	at org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer.request(ConcurrentUpdateSolrServer.java:256)
	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.testUpdateRequestWithParameters(SolrExampleTests.java:641)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
	at java.lang.reflect.Method.invoke(Method.java:597)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:662)
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:353)
	... 46 more




Build Log:
[...truncated 9243 lines...]
[junit4:junit4] Suite: org.apache.solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
[junit4:junit4]   2> 200 T11 oas.SolrJettyTestBase.beforeSolrJettyTestBase Randomized ssl (true) and clientAuth (false)
[junit4:junit4]   2> 208 T11 oas.SolrTestCaseJ4.initCore ####initCore
[junit4:junit4]   2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 212 T11 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4:junit4]   2> 608 T11 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 1136 T11 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1] of [SSLv2Hello, SSLv3, TLSv1]
[junit4:junit4]   2> 1186 T11 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:58043
[junit4:junit4]   2> 1244 T11 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 1326 T11 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 1330 T11 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr
[junit4:junit4]   2> 1363 T11 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr\solr.xml
[junit4:junit4]   2> 1368 T11 oasc.CoreContainer.<init> New CoreContainer 20056641
[junit4:junit4]   2> 1369 T11 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr\'
[junit4:junit4]   2> 1370 T11 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr\'
[junit4:junit4]   2> 1923 T11 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 1924 T11 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 1925 T11 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 1926 T11 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 1926 T11 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 1926 T11 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 1927 T11 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 1927 T11 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 1927 T11 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 1965 T11 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 2238 T11 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 2379 T22 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr\collection1
[junit4:junit4]   2> 2380 T22 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr\collection1\'
[junit4:junit4]   2> 2503 T22 oasc.SolrConfig.initLibs Adding specified lib dirs to ClassLoader
[junit4:junit4]   2> 2770 T22 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 3066 T22 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 3096 T22 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 3183 T22 oass.IndexSchema.readSchema Schema name=example
[junit4:junit4]   2> 5169 T22 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 5582 T22 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 5604 T22 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 5954 T22 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\
[junit4:junit4]   2> 5959 T22 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@fd4662
[junit4:junit4]   2> 6206 T22 oasc.SolrCore.initListeners [collection1] Added SolrEventListener for newSearcher: org.apache.solr.core.QuerySenderListener{queries=[]}
[junit4:junit4]   2> 6206 T22 oasc.SolrCore.initListeners [collection1] Added SolrEventListener for firstSearcher: org.apache.solr.core.QuerySenderListener{queries=[{q=static firstSearcher warming in solrconfig.xml}]}
[junit4:junit4]   2> 6214 T22 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 6332 T22 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513 forceNew:false
[junit4:junit4]   2> 6332 T22 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 6332 T22 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index/
[junit4:junit4]   2> 6334 T22 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 6349 T22 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index forceNew:false
[junit4:junit4]   2> 6630 T22 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 6631 T22 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 6633 T22 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 6661 T22 oasc.SolrCore.initWriters created json: solr.JSONResponseWriter
[junit4:junit4]   2> 6661 T22 oasc.SolrCore.initWriters adding lazy queryResponseWriter: solr.VelocityResponseWriter
[junit4:junit4]   2> 6666 T22 oasc.SolrCore.initWriters created velocity: solr.VelocityResponseWriter
[junit4:junit4]   2> 6701 T22 oasc.SolrCore.initWriters created xslt: solr.XSLTResponseWriter
[junit4:junit4]   2> 6701 T22 oasr.XSLTResponseWriter.init xsltCacheLifetimeSeconds=5
[junit4:junit4]   2> 7692 T22 oasc.RequestHandlers.initHandlersFromConfig created /select: solr.SearchHandler
[junit4:junit4]   2> 7692 T22 oasc.RequestHandlers.initHandlersFromConfig created /query: solr.SearchHandler
[junit4:junit4]   2> 7713 T22 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 7715 T22 oasc.RequestHandlers.initHandlersFromConfig created /browse: solr.SearchHandler
[junit4:junit4]   2> 7742 T22 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 7761 T22 oasc.RequestHandlers.initHandlersFromConfig created /update/json: solr.JsonUpdateRequestHandler
[junit4:junit4]   2> 7777 T22 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: solr.CSVRequestHandler
[junit4:junit4]   2> 7778 T22 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.extraction.ExtractingRequestHandler
[junit4:junit4]   2> 7783 T22 oasc.RequestHandlers.initHandlersFromConfig created /update/extract: solr.extraction.ExtractingRequestHandler
[junit4:junit4]   2> 7783 T22 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.FieldAnalysisRequestHandler
[junit4:junit4]   2> 7784 T22 oasc.RequestHandlers.initHandlersFromConfig created /analysis/field: solr.FieldAnalysisRequestHandler
[junit4:junit4]   2> 7784 T22 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.DocumentAnalysisRequestHandler
[junit4:junit4]   2> 7784 T22 oasc.RequestHandlers.initHandlersFromConfig created /analysis/document: solr.DocumentAnalysisRequestHandler
[junit4:junit4]   2> 7803 T22 oasc.RequestHandlers.initHandlersFromConfig created /admin/: solr.admin.AdminHandlers
[junit4:junit4]   2> 7821 T22 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: solr.PingRequestHandler
[junit4:junit4]   2> 7839 T22 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 7881 T22 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 7881 T22 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
[junit4:junit4]   2> 7883 T22 oasc.RequestHandlers.initHandlersFromConfig created /spell: solr.SearchHandler
[junit4:junit4]   2> 7883 T22 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
[junit4:junit4]   2> 7883 T22 oasc.RequestHandlers.initHandlersFromConfig created /tvrh: solr.SearchHandler
[junit4:junit4]   2> 7883 T22 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
[junit4:junit4]   2> 7884 T22 oasc.RequestHandlers.initHandlersFromConfig created /terms: solr.SearchHandler
[junit4:junit4]   2> 7885 T22 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
[junit4:junit4]   2> 7885 T22 oasc.RequestHandlers.initHandlersFromConfig created /elevate: solr.SearchHandler
[junit4:junit4]   2> 7963 T22 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 7980 T22 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 7982 T22 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 7995 T22 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 8044 T22 oass.SolrIndexSearcher.<init> Opening Searcher@132e910 main
[junit4:junit4]   2> 8117 T22 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\tlog
[junit4:junit4]   2> 8146 T22 oasu.CommitTracker.<init> Hard AutoCommit: if uncommited for 15000ms; 
[junit4:junit4]   2> 8146 T22 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 8147 T22 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 8210 T22 oass.DirectSolrSpellChecker.init init: {name=default,field=name,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4,maxQueryFrequency=0.01}
[junit4:junit4]   2> 8267 T22 oashc.SpellCheckComponent.inform No queryConverter defined, using default converter
[junit4:junit4]   2> 8280 T22 oashc.QueryElevationComponent.inform Loading QueryElevation from: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr\collection1\conf\elevate.xml
[junit4:junit4]   2> 8503 T22 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 8504 T22 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 8506 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@132e910 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 8521 T22 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 8521 T22 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> ASYNC  NEW_CORE C0 name=collection1 org.apache.solr.core.SolrCore@56182f
[junit4:junit4]   2> 8780 T23 C0 REQ [collection1] webapp=null path=null params={event=firstSearcher&q=static+firstSearcher+warming+in+solrconfig.xml&distrib=false} hits=0 status=0 QTime=261 
[junit4:junit4]   2> 8786 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 8786 T23 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default
[junit4:junit4]   2> 8787 T23 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak
[junit4:junit4]   2> 8792 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@132e910 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 8803 T22 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 8807 T11 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0
[junit4:junit4]   2> 8807 T11 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 8808 T11 oas.SolrJettyTestBase.createJetty Jetty Assigned Port#58043
[junit4:junit4]   2> 8842 T11 oas.SolrTestCaseJ4.setUp ###Starting testUpdateMultiValuedField
[junit4:junit4]   2> 8875 T11 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 8914 T24 oascsi.ConcurrentUpdateSolrServer$Runner.run starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@e3404f
[junit4:junit4]   2> 10041 T21 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 10047 T21 C0 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 10048 T21 C0 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 10214 T21 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123 (1427552507102494720)]} 0 207
[junit4:junit4]   2> 10310 T24 oascsi.ConcurrentUpdateSolrServer$Runner.run Status for: 123 is 200
[junit4:junit4]   2> 10311 T24 oascsi.ConcurrentUpdateSolrServer$Runner.run finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@e3404f
[junit4:junit4]   2> 10326 T16 C0 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 10620 T16 C0 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_2,generation=2,filenames=[_0.fnm, _0_Memory_0.ram, segments_2, _0.fdx, _0.si, _0_Memory_1.ram, _0.fdt]
[junit4:junit4]   2> 10625 T16 C0 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[_0.fnm, _0_Memory_0.ram, segments_2, _0.fdx, _0.si, _0_Memory_1.ram, _0.fdt]
[junit4:junit4]   2> 10626 T16 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 10675 T16 C0 oass.SolrIndexSearcher.<init> Opening Searcher@476914 main
[junit4:junit4]   2> 10684 T16 C0 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 10688 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@476914 main{StandardDirectoryReader(segments_2:3 _0(5.0):C1)}
[junit4:junit4]   2> 10688 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 10694 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@476914 main{StandardDirectoryReader(segments_2:3 _0(5.0):C1)}
[junit4:junit4]   2> 10696 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 10697 T16 C0 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 371
[junit4:junit4]   2> 10792 T19 C0 REQ [collection1] webapp=/solr path=/select params={q=id:123&wt=javabin&version=2} hits=1 status=0 QTime=69 
[junit4:junit4]   2> 10813 T24 oascsi.ConcurrentUpdateSolrServer$Runner.run starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1152c0e
[junit4:junit4]   2> 11067 T18 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123 (1427552507915141120)]} 0 252
[junit4:junit4]   2> 11069 T24 oascsi.ConcurrentUpdateSolrServer$Runner.run Status for: 123 is 200
[junit4:junit4]   2> 11071 T24 oascsi.ConcurrentUpdateSolrServer$Runner.run finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1152c0e
[junit4:junit4]   2> 11078 T20 C0 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 11209 T20 C0 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_2,generation=2,filenames=[_0.fnm, _0_Memory_0.ram, segments_2, _0.fdx, _0.si, _0_Memory_1.ram, _0.fdt]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_3,generation=3,filenames=[_1.fdx, _1_Memory_0.ram, _1.fdt, _1_Memory_1.ram, _1.si, _1_MockRandom_0.tip, _1_MockRandom_0.sd, _1_MockRandom_0.doc, _1_MockRandom_0.tim, _1.fnm, segments_3]
[junit4:junit4]   2> 11210 T20 C0 oasc.SolrDeletionPolicy.updateCommits newest commit = 3[_1.fdx, _1_Memory_0.ram, _1.fdt, _1_Memory_1.ram, _1.si, _1_MockRandom_0.tip, _1_MockRandom_0.sd, _1_MockRandom_0.doc, _1_MockRandom_0.tim, _1.fnm, segments_3]
[junit4:junit4]   2> 11212 T20 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 11246 T20 C0 oass.SolrIndexSearcher.<init> Opening Searcher@197d20c main
[junit4:junit4]   2> 11249 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@197d20c main{StandardDirectoryReader(segments_3:7 _1(5.0):C1)}
[junit4:junit4]   2> 11249 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 11249 T20 C0 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 11257 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@197d20c main{StandardDirectoryReader(segments_3:7 _1(5.0):C1)}
[junit4:junit4]   2> 11259 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 11260 T20 C0 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 182
[junit4:junit4]   2> 11266 T16 C0 REQ [collection1] webapp=/solr path=/select params={q=id:123&wt=javabin&version=2} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 11270 T24 oascsi.ConcurrentUpdateSolrServer$Runner.run starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@ae8af4
[junit4:junit4]   2> 11523 T17 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123 (1427552508393291776)]} 0 251
[junit4:junit4]   2> 11526 T24 oascsi.ConcurrentUpdateSolrServer$Runner.run Status for: 123 is 200
[junit4:junit4]   2> 11527 T24 oascsi.ConcurrentUpdateSolrServer$Runner.run finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@ae8af4
[junit4:junit4]   2> 11533 T19 C0 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 11560 T19 C0 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_3,generation=3,filenames=[_1.fdx, _1_Memory_0.ram, _1.fdt, _1_Memory_1.ram, _1.si, _1_MockRandom_0.tip, _1_MockRandom_0.sd, _1_MockRandom_0.doc, _1_MockRandom_0.tim, _1.fnm, segments_3]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_4,generation=4,filenames=[_2.si, _2_MockRandom_0.sd, _2_MockRandom_0.tip, _2_Memory_1.ram, _2_MockRandom_0.tim, _2.fdt, _2_Memory_0.ram, _2.fdx, _2_MockRandom_0.doc, _2.fnm, segments_4]
[junit4:junit4]   2> 11562 T19 C0 oasc.SolrDeletionPolicy.updateCommits newest commit = 4[_2.si, _2_MockRandom_0.sd, _2_MockRandom_0.tip, _2_Memory_1.ram, _2_MockRandom_0.tim, _2.fdt, _2_Memory_0.ram, _2.fdx, _2_MockRandom_0.doc, _2.fnm, segments_4]
[junit4:junit4]   2> 11563 T19 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 11570 T19 C0 oass.SolrIndexSearcher.<init> Opening Searcher@1e193f2 main
[junit4:junit4]   2> 11572 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1e193f2 main{StandardDirectoryReader(segments_4:11 _2(5.0):C1)}
[junit4:junit4]   2> 11572 T19 C0 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 11573 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 11586 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1e193f2 main{StandardDirectoryReader(segments_4:11 _2(5.0):C1)}
[junit4:junit4]   2> 11590 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 11590 T19 C0 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 57
[junit4:junit4]   2> 11594 T18 C0 REQ [collection1] webapp=/solr path=/select params={q=id:123&wt=javabin&version=2} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 11597 T11 oas.SolrTestCaseJ4.tearDown ###Ending testUpdateMultiValuedField
[junit4:junit4]   2> 11625 T11 oas.SolrTestCaseJ4.setUp ###Starting testCommitWithinOnDelete
[junit4:junit4]   2> 11684 T20 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 11842 T20 C0 oass.SolrIndexSearcher.<init> Opening Searcher@9ba134 realtime
[junit4:junit4]   2> 11843 T20 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1427552508783362048)} 0 199
[junit4:junit4]   2> 11849 T16 C0 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 11851 T16 C0 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_4,generation=4,filenames=[_2.si, _2_MockRandom_0.sd, _2_MockRandom_0.tip, _2_Memory_1.ram, _2_MockRandom_0.tim, _2.fdt, _2_Memory_0.ram, _2.fdx, _2_MockRandom_0.doc, _2.fnm, segments_4]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_5,generation=5,filenames=[segments_5]
[junit4:junit4]   2> 11851 T16 C0 oasc.SolrDeletionPolicy.updateCommits newest commit = 5[segments_5]
[junit4:junit4]   2> 11852 T16 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 11853 T16 C0 oass.SolrIndexSearcher.<init> Opening Searcher@a3ce3f main
[junit4:junit4]   2> 11854 T16 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 11854 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@a3ce3f main{StandardDirectoryReader(segments_4:13:nrt)}
[junit4:junit4]   2> 11854 T16 C0 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 11855 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 11860 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@a3ce3f main{StandardDirectoryReader(segments_4:13:nrt)}
[junit4:junit4]   2> 11862 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 11862 T16 C0 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 13
[junit4:junit4]   2> 11868 T17 C0 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=2 
[junit4:junit4]   2> 11886 T24 oascsi.ConcurrentUpdateSolrServer$Runner.run starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@7103af
[junit4:junit4]   2> 12143 T19 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id3 (1427552509042360320)]} 0 252
[junit4:junit4]   2> 12146 T24 oascsi.ConcurrentUpdateSolrServer$Runner.run Status for: id3 is 200
[junit4:junit4]   2> 12146 T24 oascsi.ConcurrentUpdateSolrServer$Runner.run finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@7103af
[junit4:junit4]   2> 12152 T18 C0 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 12219 T18 C0 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_5,generation=5,filenames=[segments_5]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_6,generation=6,filenames=[_3_MockRandom_0.doc, _3.fdt, _3.fnm, _3_MockRandom_0.tib, _3_MockRandom_0.sd, _3.fdx, _3.nvd, _3_SimpleText_0.pst, _3_MockRandom_0.tiv, _3.si, _3.nvm, _3_Memory_0.ram, _3_MockRandom_0.pos, segments_6, _3_Memory_1.ram]
[junit4:junit4]   2> 12219 T18 C0 oasc.SolrDeletionPolicy.updateCommits newest commit = 6[_3_MockRandom_0.doc, _3.fdt, _3.fnm, _3_MockRandom_0.tib, _3_MockRandom_0.sd, _3.fdx, _3.nvd, _3_SimpleText_0.pst, _3_MockRandom_0.tiv, _3.si, _3.nvm, _3_Memory_0.ram, _3_MockRandom_0.pos, segments_6, _3_Memory_1.ram]
[junit4:junit4]   2> 12220 T18 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 12275 T18 C0 oass.SolrIndexSearcher.<init> Opening Searcher@1262bf4 main
[junit4:junit4]   2> 12277 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1262bf4 main{StandardDirectoryReader(segments_6:15:nrt _3(5.0):C1)}
[junit4:junit4]   2> 12277 T18 C0 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 12277 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 12282 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1262bf4 main{StandardDirectoryReader(segments_6:15:nrt _3(5.0):C1)}
[junit4:junit4]   2> 12283 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 12283 T18 C0 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 132
[junit4:junit4]   2> 12289 T21 C0 REQ [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=2 
[junit4:junit4]   2> 12295 T16 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {delete=[id3 (-1427552509464936448)]} 0 2
[junit4:junit4]   2> 12299 T17 C0 REQ [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 12555 T19 C0 REQ [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 12811 T18 C0 REQ [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 13068 T21 C0 REQ [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 13125 T26 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true}
[junit4:junit4]   2> 13127 T26 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 13128 T26 oass.SolrIndexSearcher.<init> Opening Searcher@13ada main
[junit4:junit4]   2> 13130 T26 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 13131 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@13ada main{StandardDirectoryReader(segments_6:17:nrt)}
[junit4:junit4]   2> 13132 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 13141 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@13ada main{StandardDirectoryReader(segments_6:17:nrt)}
[junit4:junit4]   2> 13142 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 13335 T16 C0 REQ [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 13338 T11 oas.SolrTestCaseJ4.tearDown ###Ending testCommitWithinOnDelete
[junit4:junit4]   2> 13374 T11 oas.SolrTestCaseJ4.setUp ###Starting testPingHandler
[junit4:junit4]   2> 13382 T19 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 13384 T19 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1427552510603689984)} 0 4
[junit4:junit4]   2> 13393 T20 C0 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 13395 T20 C0 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_6,generation=6,filenames=[_3_MockRandom_0.doc, _3.fdt, _3.fnm, _3_MockRandom_0.tib, _3_MockRandom_0.sd, _3.fdx, _3.nvd, _3_SimpleText_0.pst, _3_MockRandom_0.tiv, _3.si, _3.nvm, _3_Memory_0.ram, _3_MockRandom_0.pos, segments_6, _3_Memory_1.ram]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_7,generation=7,filenames=[segments_7]
[junit4:junit4]   2> 13396 T20 C0 oasc.SolrDeletionPolicy.updateCommits newest commit = 7[segments_7]
[junit4:junit4]   2> 13397 T20 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 13399 T20 C0 oass.SolrIndexSearcher.<init> Opening Searcher@695527 main
[junit4:junit4]   2> 13402 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@695527 main{StandardDirectoryReader(segments_6:17:nrt)}
[junit4:junit4]   2> 13402 T20 C0 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 13403 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 13413 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@695527 main{StandardDirectoryReader(segments_6:17:nrt)}
[junit4:junit4]   2> 13415 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 13417 T20 C0 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 24
[junit4:junit4]   2> 13423 T18 C0 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 13444 T21 C0 REQ [collection1] webapp=/solr path=/admin/ping params={wt=javabin&version=2} hits=0 status=0 QTime=9 
[junit4:junit4]   2> 13445 T21 C0 REQ [collection1] webapp=/solr path=/admin/ping params={wt=javabin&version=2} status=0 QTime=10 
[junit4:junit4]   2> 13447 T11 oas.SolrTestCaseJ4.tearDown ###Ending testPingHandler
[junit4:junit4]   2> 13463 T11 oas.SolrTestCaseJ4.setUp ###Starting testPivotFacetsMissing
[junit4:junit4]   2> 13466 T17 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 13468 T17 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1427552510692818944)} 0 3
[junit4:junit4]   2> 13471 T16 C0 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 13473 T16 C0 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_7,generation=7,filenames=[segments_7]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_8,generation=8,filenames=[segments_8]
[junit4:junit4]   2> 13473 T16 C0 oasc.SolrDeletionPolicy.updateCommits newest commit = 8[segments_8]
[junit4:junit4]   2> 13474 T16 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 13474 T16 C0 oass.SolrIndexSearcher.<init> Opening Searcher@598a5d main
[junit4:junit4]   2> 13477 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@598a5d main{StandardDirectoryReader(segments_6:17:nrt)}
[junit4:junit4]   2> 13477 T16 C0 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 13477 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 13482 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@598a5d main{StandardDirectoryReader(segments_6:17:nrt)}
[junit4:junit4]   2> 13487 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 13488 T16 C0 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 17
[junit4:junit4]   2> 13492 T19 C0 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 13493 T24 oascsi.ConcurrentUpdateSolrServer$Runner.run starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@a449e0
[junit4:junit4]   2> 13749 T20 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1 (1427552510726373376), 2 (1427552510729519104), 3 (1427552510730567680), 4 (1427552510731616256), 5 (1427552510732664832), 6 (1427552510734761984), 7 (1427552510735810560), 8 (1427552510736859136), 9 (1427552510736859137), 10 (1427552510738956288), ... (12 adds)]} 0 252
[junit4:junit4]   2> 13752 T24 oascsi.ConcurrentUpdateSolrServer$Runner.run Status for: 1 is 200
[junit4:junit4]   2> 13753 T24 oascsi.ConcurrentUpdateSolrServer$Runner.run finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@a449e0
[junit4:junit4]   2> 13758 T21 C0 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 13787 T21 C0 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_8,generation=8,filenames=[segments_8]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_9,generation=9,filenames=[_4_MockRandom_0.pos, _4_MockRandom_0.tii, _4.fnm, _4_MockRandom_0.tib, _4.fdt, _4.si, _4_Memory_1.ram, segments_9, _4.fdx, _4_SimpleText_0.pst, _4_MockRandom_0.doc, _4.nvd, _4_MockRandom_0.sd, _4_Memory_0.ram, _4.nvm]
[junit4:junit4]   2> 13788 T21 C0 oasc.SolrDeletionPolicy.updateCommits newest commit = 9[_4_MockRandom_0.pos, _4_MockRandom_0.tii, _4.fnm, _4_MockRandom_0.tib, _4.fdt, _4.si, _4_Memory_1.ram, segments_9, _4.fdx, _4_SimpleText_0.pst, _4_MockRandom_0.doc, _4.nvd, _4_MockRandom_0.sd, _4_Memory_0.ram, _4.nvm]
[junit4:junit4]   2> 13789 T21 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 13824 T21 C0 oass.SolrIndexSearcher.<init> Opening Searcher@1d7d58f main
[junit4:junit4]   2> 13826 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1d7d58f main{StandardDirectoryReader(segments_9:19:nrt _4(5.0):C12)}
[junit4:junit4]   2> 13826 T21 C0 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 13827 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 13832 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1d7d58f main{StandardDirectoryReader(segments_9:19:nrt _4(5.0):C12)}
[junit4:junit4]   2> 13834 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 13835 T21 C0 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 77
[junit4:junit4]   2> 13959 T17 C0 oasr.UnInvertedField.<init> UnInverted multi-valued field {field=features,memSize=4521,tindexSize=43,time=30,phase1=30,nTerms=2,bigTerms=2,termInstances=0,uses=0}
[junit4:junit4]   2> 14001 T17 C0 oasr.UnInvertedField.<init> UnInverted multi-valued field {field=cat,memSize=4474,tindexSize=0,time=11,phase1=11,nTerms=2,bigTerms=2,termInstances=0,uses=0}
[junit4:junit4]   2> 14012 T17 C0 REQ [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=170 
[junit4:junit4]   2> 14073 T16 C0 REQ [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=53 
[junit4:junit4]   2> 14075 T11 oas.SolrTestCaseJ4.tearDown ###Ending testPivotFacetsMissing
[junit4:junit4]   2> 14089 T11 oas.SolrTestCaseJ4.setUp ###Starting testAddDelete
[junit4:junit4]   2> 14095 T19 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 14097 T19 C0 oass.SolrIndexSearcher.<init> Opening Searcher@3d5149 realtime
[junit4:junit4]   2> 14097 T19 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1427552511351324672)} 0 4
[junit4:junit4]   2> 14100 T24 oascsi.ConcurrentUpdateSolrServer$Runner.run starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1a3bff5
[junit4:junit4]   2> 14353 T18 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 & 222 (1427552511361810432)]} 0 250
[junit4:junit4]   2> 14354 T24 oascsi.ConcurrentUpdateSolrServer$Runner.run Status for: 0 & 222 is 200
[junit4:junit4]   2> 14354 T24 oascsi.ConcurrentUpdateSolrServer$Runner.run finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1a3bff5
[junit4:junit4]   2> 14358 T21 C0 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 14361 T21 C0 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_9,generation=9,filenames=[_4_MockRandom_0.pos, _4_MockRandom_0.tii, _4.fnm, _4_MockRandom_0.tib, _4.fdt, _4.si, _4_Memory_1.ram, segments_9, _4.fdx, _4_SimpleText_0.pst, _4_MockRandom_0.doc, _4.nvd, _4_MockRandom_0.sd, _4_Memory_0.ram, _4.nvm]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_a,generation=10,filenames=[_5.si, _5_Memory_0.ram, _5.fdt, _5_Memory_1.ram, _5.fnm, segments_a, _5.fdx]
[junit4:junit4]   2> 14361 T21 C0 oasc.SolrDeletionPolicy.updateCommits newest commit = 10[_5.si, _5_Memory_0.ram, _5.fdt, _5_Memory_1.ram, _5.fnm, segments_a, _5.fdx]
[junit4:junit4]   2> 14362 T21 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 14364 T21 C0 oass.SolrIndexSearcher.<init> Opening Searcher@3c33d3 main
[junit4:junit4]   2> 14365 T21 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 14366 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@3c33d3 main{StandardDirectoryReader(segments_a:23:nrt _5(5.0):C1)}
[junit4:junit4]   2> 14366 T21 C0 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 14366 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 14370 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3c33d3 main{StandardDirectoryReader(segments_a:23:nrt _5(5.0):C1)}
[junit4:junit4]   2> 14372 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 14372 T21 C0 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 14
[junit4:junit4]   2> 14377 T17 C0 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 14384 T16 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {delete=[0 & 222 (-1427552511654363136)]} 0 2
[junit4:junit4]   2> 14389 T19 C0 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 14390 T19 C0 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_a,generation=10,filenames=[_5.si, _5_Memory_0.ram, _5.fdt, _5_Memory_1.ram, _5.fnm, segments_a, _5.fdx]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_b,generation=11,filenames=[segments_b]
[junit4:junit4]   2> 14391 T19 C0 oasc.SolrDeletionPolicy.updateCommits newest commit = 11[segments_b]
[junit4:junit4]   2> 14391 T19 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 14393 T19 C0 oass.SolrIndexSearcher.<init> Opening Searcher@18ad9a0 main
[junit4:junit4]   2> 14394 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@18ad9a0 main{StandardDirectoryReader(segments_b:25:nrt)}
[junit4:junit4]   2> 14394 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 14398 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@18ad9a0 main{StandardDirectoryReader(segments_b:25:nrt)}
[junit4:junit4]   2> 14398 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 14394 T19 C0 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 14400 T19 C0 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 11
[junit4:junit4]   2> 14404 T20 C0 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 14406 T24 oascsi.ConcurrentUpdateSolrServer$Runner.run starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@11799e7
[junit4:junit4]   2> 14660 T18 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 & 222 (1427552511682674688)]} 0 251
[junit4:junit4]   2> 14664 T24 oascsi.ConcurrentUpdateSolrServer$Runner.run Status for: 0 & 222 is 200
[junit4:junit4]   2> 14665 T24 oascsi.ConcurrentUpdateSolrServer$Runner.run finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@11799e7
[junit4:junit4]   2> 14672 T21 C0 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 14681 T21 C0 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_b,generation=11,filenames=[segments_b]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_c,generation=12,filenames=[_6_Memory_0.ram, _6_Memory_1.ram, segments_c, _6.fdx, _6.si, _6.fnm, _6.fdt]
[junit4:junit4]   2> 14683 T21 C0 oasc.SolrDeletionPolicy.updateCommits newest commit = 12[_6_Memory_0.ram, _6_Memory_1.ram, segments_c, _6.fdx, _6.si, _6.fnm, _6.fdt]
[junit4:junit4]   2> 14683 T21 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 14686 T21 C0 oass.SolrIndexSearcher.<init> Opening Searcher@4589eb main
[junit4:junit4]   2> 14689 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@4589eb main{StandardDirectoryReader(segments_c:27:nrt _6(5.0):C1)}
[junit4:junit4]   2> 14690 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 14692 T21 C0 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 14699 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4589eb main{StandardDirectoryReader(segments_c:27:nrt _6(5.0):C1)}
[junit4:junit4]   2> 14702 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 14703 T21 C0 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 31
[junit4:junit4]   2> 14714 T21 C0 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 14729 T16 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 14731 T16 C0 oass.SolrIndexSearcher.<init> Opening Searcher@191dd1d realtime
[junit4:junit4]   2> 14732 T16 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=id:"0\ \&\ 222" (-1427552512015073280)} 0 7
[junit4:junit4]   2> 14739 T19 C0 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 14739 T19 C0 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_c,generation=12,filenames=[_6_Memory_0.ram, _6_Memory_1.ram, segments_c, _6.fdx, _6.si, _6.fnm, _6.fdt]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_d,generation=13,filenames=[segments_d]
[junit4:junit4]   2> 14740 T19 C0 oasc.SolrDeletionPolicy.updateCommits newest commit = 13[segments_d]
[junit4:junit4]   2> 14740 T19 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 14742 T19 C0 oass.SolrIndexSearcher.<init> Opening Searcher@188d92e main
[junit4:junit4]   2> 14742 T19 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 14743 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@188d92e main{StandardDirectoryReader(segments_c:29:nrt)}
[junit4:junit4]   2> 14743 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 14744 T19 C0 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 14746 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@188d92e main{StandardDirectoryReader(segments_c:29:nrt)}
[junit4:junit4]   2> 14749 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 14749 T19 C0 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 10
[junit4:junit4]   2> 14754 T20 C0 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 14755 T24 oascsi.ConcurrentUpdateSolrServer$Runner.run starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@652552
[junit4:junit4]   2> 14759 T27 oascsi.ConcurrentUpdateSolrServer$Runner.run starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@40f3c1
[junit4:junit4]   2> 14760 T28 oascsi.ConcurrentUpdateSolrServer$Runner.run starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@14ef239
[junit4:junit4]   2> 14759 T29 oascsi.ConcurrentUpdateSolrServer$Runner.run starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1ea25aa
[junit4:junit4]   2> 14761 T29 oascsi.ConcurrentUpdateSolrServer$Runner.run finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1ea25aa
[junit4:junit4]   2> 14761 T30 oascsi.ConcurrentUpdateSolrServer$Runner.run starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1c1a68b
[junit4:junit4]   2> 14768 T30 oascsi.ConcurrentUpdateSolrServer$Runner.run finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1c1a68b
[junit4:junit4]   2> 15015 T17 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 & 222 (1427552512053870592)]} 0 252
[junit4:junit4]   2> 15017 T24 oascsi.ConcurrentUpdateSolrServer$Runner.run Status for: 0 & 222 is 200
[junit4:junit4]   2> 15019 T24 oascsi.ConcurrentUpdateSolrServer$Runner.run finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@652552
[junit4:junit4]   2> 15026 T19 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1 & 222 (1427552512065404928)]} 0 252
[junit4:junit4]   2> 15026 T16 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2 & 222 (1427552512068550656)]} 0 249
[junit4:junit4]   2> 15028 T27 oascsi.ConcurrentUpdateSolrServer$Runner.run Status for: 1 & 222 is 200
[junit4:junit4]   2> 15029 T27 oascsi.ConcurrentUpdateSolrServer$Runner.run finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@40f3c1
[junit4:junit4]   2> 15030 T28 oascsi.ConcurrentUpdateSolrServer$Runner.run Status for: 2 & 222 is 200
[junit4:junit4]   2> 15031 T28 oascsi.ConcurrentUpdateSolrServer$Runner.run finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@14ef239
[junit4:junit4]   2> 15039 T20 C0 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 15057 T20 C0 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_d,generation=13,filenames=[segments_d]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_e,generation=14,filenames=[segments_e, _7.fnm, _7.si, _7.fdx, _7_Memory_1.ram, _7.fdt, _7_Memory_0.ram]
[junit4:junit4]   2> 15058 T20 C0 oasc.SolrDeletionPolicy.updateCommits newest commit = 14[segments_e, _7.fnm, _7.si, _7.fdx, _7_Memory_1.ram, _7.fdt, _7_Memory_0.ram]
[junit4:junit4]   2> 15058 T20 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 15059 T20 C0 oass.SolrIndexSearcher.<init> Opening Searcher@66da2c main
[junit4:junit4]   2> 15060 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@66da2c main{StandardDirectoryReader(segments_e:31:nrt _7(5.0):C3)}
[junit4:junit4]   2> 15061 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 15061 T20 C0 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 15063 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@66da2c main{StandardDirectoryReader(segments_e:31:nrt _7(5.0):C3)}
[junit4:junit4]   2> 15064 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 15064 T20 C0 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 25
[junit4:junit4]   2> 15069 T21 C0 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=3 status=0 QTime=1 
[junit4:junit4]   2> 15075 T18 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {delete=[0 & 222 (-1427552512378929152), 1 & 222 (-1427552512381026304), 2 & 222 (-1427552512381026305)]} 0 2
[junit4:junit4]   2> 15079 T17 C0 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 15080 T17 C0 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_e,generation=14,filenames=[segments_e, _7.fnm, _7.si, _7.fdx, _7_Memory_1.ram, _7.fdt, _7_Memory_0.ram]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_f,generation=15,filenames=[segments_f]
[junit4:junit4]   2> 15080 T17 C0 oasc.SolrDeletionPolicy.updateCommits newest commit = 15[segments_f]
[junit4:junit4]   2> 15081 T17 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 15082 T17 C0 oass.SolrIndexSearcher.<init> Opening Searcher@12c08c7 main
[junit4:junit4]   2> 15083 T17 C0 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 15084 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@12c08c7 main{StandardDirectoryReader(segments_f:33:nrt)}
[junit4:junit4]   2> 15084 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 15093 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@12c08c7 main{StandardDirectoryReader(segments_f:33:nrt)}
[junit4:junit4]   2> 15094 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 15094 T17 C0 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 15
[junit4:junit4]   2> 15101 T19 C0 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=2 
[junit4:junit4]   2> 15102 T11 oas.SolrTestCaseJ4.tearDown ###Ending testAddDelete
[junit4:junit4]   2> 15119 T11 oas.SolrTestCaseJ4.setUp ###Starting testSetNullUpdates
[junit4:junit4]   2> 15290 T28 oascsi.ConcurrentUpdateSolrServer$Runner.run starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@c71191
[junit4:junit4]   2> 15546 T20 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[testSetNullUpdates (1427552512610664448)]} 0 252
[junit4:junit4]   2> 15549 T28 oascsi.ConcurrentUpdateSolrServer$Runner.run Status for: testSetNullUpdates is 200
[junit4:junit4]   2> 15550 T28 oascsi.ConcurrentUpdateSolrServer$Runner.run finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@c71191
[junit4:junit4]   2> 15558 T21 C0 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 15607 T21 C0 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_f,generation=15,filenames=[segments_f]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_g,generation=16,filenames=[_8.si, _8_MockRandom_0.doc, _8_MockRandom_0.tip, _8.fnm, _8_MockRandom_0.sd, _8_Memory_0.ram, _8_Memory_1.ram, _8_MockRandom_0.skp, _8.fdx, _8_MockRandom_0.tim, segments_g, _8.fdt]
[junit4:junit4]   2> 15607 T21 C0 oasc.SolrDeletionPolicy.updateCommits newest commit = 16[_8.si, _8_MockRandom_0.doc, _8_MockRandom_0.tip, _8.fnm, _8_MockRandom_0.sd, _8_Memory_0.ram, _8_Memory_1.ram, _8_MockRandom_0.skp, _8.fdx, _8_MockRandom_0.tim, segments_g, _8.fdt]
[junit4:junit4]   2> 15610 T21 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 15629 T21 C0 oass.SolrIndexSearcher.<init> Opening Searcher@167f48c main
[junit4:junit4]   2> 15631 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@167f48c main{StandardDirectoryReader(segments_g:35:nrt _8(5.0):C1)}
[junit4:junit4]   2> 15631 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 15632 T21 C0 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 15634 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@167f48c main{StandardDirectoryReader(segments_g:35:nrt _8(5.0):C1)}
[junit4:junit4]   2> 15635 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 15636 T21 C0 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 78
[junit4:junit4]   2> 15686 T28 oascsi.ConcurrentUpdateSolrServer$Runner.run starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1b0620c
[junit4:junit4]   2> 15940 T18 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[testSetNullUpdates (1427552513024851968)]} 0 252
[junit4:junit4]   2> 15943 T28 oascsi.ConcurrentUpdateSolrServer$Runner.run Status for: testSetNullUpdates is 200
[junit4:junit4]   2> 15944 T28 oascsi.ConcurrentUpdateSolrServer$Runner.run finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1b0620c
[junit4:junit4]   2> 15949 T19 C0 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 15960 T19 C0 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_g,generation=16,filenames=[_8.si, _8_MockRandom_0.doc, _8_MockRandom_0.tip, _8.fnm, _8_MockRandom_0.sd, _8_Memory_0.ram, _8_Memory_1.ram, _8_MockRandom_0.skp, _8.fdx, _8_MockRandom_0.tim, segments_g, _8.fdt]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_h,generation=17,filenames=[_9_Memory_1.ram, _9_Memory_0.ram, _9.fnm, _9.fdt, _9.si, _9.fdx, segments_h]
[junit4:junit4]   2> 15961 T19 C0 oasc.SolrDeletionPolicy.updateCommits newest commit = 17[_9_Memory_1.ram, _9_Memory_0.ram, _9.fnm, _9.fdt, _9.si, _9.fdx, segments_h]
[junit4:junit4]   2> 15962 T19 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 15965 T19 C0 oass.SolrIndexSearcher.<init> Opening Searcher@19c18ae main
[junit4:junit4]   2> 15967 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@19c18ae main{StandardDirectoryReader(segments_h:39:nrt _9(5.0):C1)}
[junit4:junit4]   2> 15969 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 15970 T19 C0 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 15989 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@19c18ae main{StandardDirectoryReader(segments_h:39:nrt _9(5.0):C1)}
[junit4:junit4]   2> 15992 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 15992 T19 C0 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 43
[junit4:junit4]   2> 16000 T16 C0 REQ [collection1] webapp=/solr path=/select params={q=id:testSetNullUpdates&wt=javabin&version=2} hits=1 status=0 QTime=2 
[junit4:junit4]   2> 16002 T11 oas.SolrTestCaseJ4.tearDown ###Ending testSetNullUpdates
[junit4:junit4]   2> 16023 T11 oas.SolrTestCaseJ4.setUp ###Starting testMultiContentStreamRequest
[junit4:junit4]   2> 16028 T20 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 16029 T20 C0 oass.SolrIndexSearcher.<init> Opening Searcher@1917a08 realtime
[junit4:junit4]   2> 16031 T20 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1427552513379270656)} 0 4
[junit4:junit4]   2> 16035 T21 C0 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 16036 T21 C0 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_h,generation=17,filenames=[_9_Memory_1.ram, _9_Memory_0.ram, _9.fnm, _9.fdt, _9.si, _9.fdx, segments_h]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_i,generation=18,filenames=[segments_i]
[junit4:junit4]   2> 16037 T21 C0 oasc.SolrDeletionPolicy.updateCommits newest commit = 18[segments_i]
[junit4:junit4]   2> 16037 T21 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 16038 T21 C0 oass.SolrIndexSearcher.<init> Opening Searcher@1a79071 main
[junit4:junit4]   2> 16038 T21 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 16039 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1a79071 main{StandardDirectoryReader(segments_h:41:nrt)}
[junit4:junit4]   2> 16039 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 16040 T21 C0 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 16044 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1a79071 main{StandardDirectoryReader(segments_h:41:nrt)}
[junit4:junit4]   2> 16046 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 16046 T21 C0 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 11
[junit4:junit4]   2> 16051 T17 C0 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 16369 T18 C0 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 16420 T18 C0 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_i,generation=18,filenames=[segments_i]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_j,generation=19,filenames=[_a_MockRandom_0.pyl, _a_MockRandom_0.doc, _a_MockRandom_0.pos, _a_SimpleText_0.pst, _a_Memory_0.ram, _a_MockRandom_0.tiv, _a.fdt, _a_MockRandom_0.frq, _a.si, segments_j, _a.fdx, _a_MockRandom_0.skp, _a_Memory_1.ram, _a.nvm, _a_MockRandom_0.sd, _a.nvd, _a.fnm, _a_MockRandom_0.tib]
[junit4:junit4]   2> 16421 T18 C0 oasc.SolrDeletionPolicy.updateCommits newest commit = 19[_a_MockRandom_0.pyl, _a_MockRandom_0.doc, _a_MockRandom_0.pos, _a_SimpleText_0.pst, _a_Memory_0.ram, _a_MockRandom_0.tiv, _a.fdt, _a_MockRandom_0.frq, _a.si, segments_j, _a.fdx, _a_MockRandom_0.skp, _a_Memory_1.ram, _a.nvm, _a_MockRandom_0.sd, _a.nvd, _a.fnm, _a_MockRandom_0.tib]
[junit4:junit4]   2> 16421 T18 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 16431 T18 C0 oass.SolrIndexSearcher.<init> Opening Searcher@c4fedd main
[junit4:junit4]   2> 16433 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@c4fedd main{StandardDirectoryReader(segments_j:43:nrt _a(5.0):C5)}
[junit4:junit4]   2> 16433 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 16435 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c4fedd main{StandardDirectoryReader(segments_j:43:nrt _a(5.0):C5)}
[junit4:junit4]   2> 16437 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 16438 T18 C0 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 16438 T18 C0 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&echoParams=ALL&commit=true&a=?&wt=javabin&version=2&softCommit=false} {add=[SP2514N (1427552513703280640), 6H500F0 (1427552513713766400), TWINX2048-3200PRO (1427552513720057856), VS1GB400C3 (1427552513732640768), VDBDB1A16 (1427552513735786496)],commit=} 0 245
[junit4:junit4]   2> 16442 T16 C0 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=5 status=0 QTime=0 
[junit4:junit4]   2> 16458 T11 oas.SolrTestCaseJ4.tearDown ###Ending testMultiContentStreamRequest
[junit4:junit4]   2> 16477 T11 oas.SolrTestCaseJ4.setUp ###Starting testQueryWithParams
[junit4:junit4]   2> 16513 T20 C0 REQ [collection1] webapp=/solr path=/select params={q=query&debug=true&wt=javabin&version=2} hits=0 status=0 QTime=34 
[junit4:junit4]   2> 16514 T11 oas.SolrTestCaseJ4.tearDown ###Ending testQueryWithParams
[junit4:junit4]   2> 16530 T11 oas.SolrTestCaseJ4.setUp ###Starting testUnicode
[junit4:junit4]   2> 16535 T21 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 16537 T21 C0 oass.SolrIndexSearcher.<init> Opening Searcher@1860045 realtime
[junit4:junit4]   2> 16537 T21 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1427552513909850112)} 0 4
[junit4:junit4]   2> 16549 T28 oascsi.ConcurrentUpdateSolrServer$Runner.run starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@7bb2cb
[junit4:junit4]   2> 16805 T17 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 (1427552513930821632), 1 (1427552513932918784), 2 (1427552513933967360)]} 0 252
[junit4:junit4]   2> 16807 T28 oascsi.ConcurrentUpdateSolrServer$Runner.run Status for: 0 is 200
[junit4:junit4]   2> 16809 T28 oascsi.ConcurrentUpdateSolrServer$Runner.run finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@7bb2cb
[junit4:junit4]   2> 16813 T19 C0 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 16821 T19 C0 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_j,generation=19,filenames=[_a_MockRandom_0.pyl, _a_MockRandom_0.doc, _a_MockRandom_0.pos, _a_SimpleText_0.pst, _a_Memory_0.ram, _a_MockRandom_0.tiv, _a.fdt, _a_MockRandom_0.frq, _a.si, segments_j, _a.fdx, _a_MockRandom_0.skp, _a_Memory_1.ram, _a.nvm, _a_MockRandom_0.sd, _a.nvd, _a.fnm, _a_MockRandom_0.tib]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_k,generation=20,filenames=[_b.si, _b_Memory_0.ram, _b_Memory_1.ram, _b_SimpleText_0.pst, _b.fnm, _b.fdt, segments_k, _b.fdx]
[junit4:junit4]   2> 16823 T19 C0 oasc.SolrDeletionPolicy.updateCommits newest commit = 20[_b.si, _b_Memory_0.ram, _b_Memory_1.ram, _b_SimpleText_0.pst, _b.fnm, _b.fdt, segments_k, _b.fdx]
[junit4:junit4]   2> 16825 T19 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 16828 T19 C0 oass.SolrIndexSearcher.<init> Opening Searcher@1279c8 main
[junit4:junit4]   2> 16831 T19 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 16832 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1279c8 main{StandardDirectoryReader(segments_k:47:nrt _b(5.0):C3)}
[junit4:junit4]   2> 16832 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 16833 T19 C0 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 16838 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1279c8 main{StandardDirectoryReader(segments_k:47:nrt _b(5.0):C3)}
[junit4:junit4]   2> 16839 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 16840 T19 C0 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 27
[junit4:junit4]   2> 16845 T16 C0 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2&rows=3} hits=3 status=0 QTime=2 
[junit4:junit4]   2> 16850 T20 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 16851 T20 C0 oass.SolrIndexSearcher.<init> Opening Searcher@818805 realtime
[junit4:junit4]   2> 16852 T20 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1427552514240151552)} 0 4
[junit4:junit4]   2> 16856 T28 oascsi.ConcurrentUpdateSolrServer$Runner.run starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@938f0
[junit4:junit4]   2> 17112 T21 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 (1427552514250637312)]} 0 254
[junit4:junit4]   2> 17113 T28 oascsi.ConcurrentUpdateSolrServer$Runner.run Status for: 0 is 200
[junit4:junit4]   2> 17115 T28 oascsi.ConcurrentUpdateSolrServer$Runner.run finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@938f0
[junit4:junit4]   2> 17121 T17 C0 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 17126 T17 C0 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_k,generation=20,filenames=[_b.si, _b_Memory_0.ram, _b_Memory_1.ram, _b_SimpleText_0.pst, _b.fnm, _b.fdt, segments_k, _b.fdx]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_l,generation=21,filenames=[_c.fdt, _c_Memory_1.ram, _c_SimpleText_0.pst, _c.si, _c.fnm, segments_l, _c.fdx, _c_Memory_0.ram]
[junit4:junit4]   2> 17133 T17 C0 oasc.SolrDeletionPolicy.updateCommits newest commit = 21[_c.fdt, _c_Memory_1.ram, _c_SimpleText_0.pst, _c.si, _c.fnm, segments_l, _c.fdx, _c_Memory_0.ram]
[junit4:junit4]   2> 17134 T17 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 17138 T17 C0 oass.SolrIndexSearcher.<init> Opening Searcher@1db04ed main
[junit4:junit4]   2> 17139 T17 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 17150 T17 C0 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 17151 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1db04ed main{StandardDirectoryReader(segments_l:51:nrt _c(5.0):C1)}
[junit4:junit4]   2> 17152 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 17159 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1db04ed main{StandardDirectoryReader(segments_l:51:nrt _c(5.0):C1)}
[junit4:junit4]   2> 17160 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 17161 T17 C0 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 41
[junit4:junit4]   2> 17166 T18 C0 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2&rows=1} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 17172 T19 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 17173 T19 C0 oass.SolrIndexSearcher.<init> Opening Searcher@da6d09 realtime
[junit4:junit4]   2> 17173 T19 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1427552514577793024)} 0 3
[junit4:junit4]   2> 17175 T28 oascsi.ConcurrentUpdateSolrServer$Runner.run starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@2c6f70
[junit4:junit4]   2> 17431 T16 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 (1427552514585133056), 1 (1427552514587230208), 2 (1427552514588278784), 3 (1427552514588278785)]} 0 254
[junit4:junit4]   2> 17432 T28 oascsi.ConcurrentUpdateSolrServer$Runner.run Status for: 0 is 200
[junit4:junit4]   2> 17434 T28 oascsi.ConcurrentUpdateSolrServer$Runner.run finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@2c6f70
[junit4:junit4]   2> 17439 T21 C0 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 17447 T21 C0 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_l,generation=21,filenames=[_c.fdt, _c_Memory_1.ram, _c_SimpleText_0.pst, _c.si, _c.fnm, segments_l, _c.fdx, _c_Memory_0.ram]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_m,generation=22,filenames=[_d.fdt, _d_SimpleText_0.pst, _d_Memory_0.ram, segments_m, _d.si, _d.fnm, _d_Memory_1.ram, _d.fdx]
[junit4:junit4]   2> 17448 T21 C0 oasc.SolrDeletionPolicy.updateCommits newest commit = 22[_d.fdt, _d_SimpleText_0.pst, _d_Memory_0.ram, segments_m, _d.si, _d.fnm, _d_Memory_1.ram, _d.fdx]
[junit4:junit4]   2> 17449 T21 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 17452 T21 C0 oass.SolrIndexSearcher.<init> Opening Searcher@173ee92 main
[junit4:junit4]   2> 17453 T21 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 17455 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@173ee92 main{StandardDirectoryReader(segments_m:55:nrt _d(5.0):C4)}
[junit4:junit4]   2> 17456 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 17457 T21 C0 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 17462 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@173ee92 main{StandardDirectoryReader(segments_m:55:nrt _d(5.0):C4)}
[junit4:junit4]   2> 17464 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 17465 T21 C0 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 26
[junit4:junit4]   2> 17473 T17 C0 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2&rows=4} hits=4 status=0 QTime=1 
[junit4:junit4]   2> 17478 T11 oas.SolrTestCaseJ4.tearDown ###Ending testUnicode
[junit4:junit4]   2> 17507 T11 oas.SolrTestCaseJ4.setUp ###Starting testUpdateRequestWithParameters
[junit4:junit4]   2> 17508 T11 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 17553 T20 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 17555 T20 C0 oass.SolrIndexSearcher.<init> Opening Searcher@1f2e41d realtime
[junit4:junit4]   2> 17555 T20 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1427552514978349056)} 0 3
[junit4:junit4]   2> 32665 T25 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 32666 T25 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_m,generation=22,filenames=[_d.fdt, _d_SimpleText_0.pst, _d_Memory_0.ram, segments_m, _d.si, _d.fnm, _d_Memory_1.ram, _d.fdx]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_n,generation=23,filenames=[segments_n]
[junit4:junit4]   2> 32667 T25 oasc.SolrDeletionPolicy.updateCommits newest commit = 23[segments_n]
[junit4:junit4]   2> 32668 T25 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 32673 T25 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 217765 T16 C0 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 217765 T11 oas.SolrTestCaseJ4.tearDown ###Ending testUpdateRequestWithParameters
[junit4:junit4]   2> 217766 T16 C0 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_n,generation=23,filenames=[segments_n]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_o,generation=24,filenames=[segments_o]
[junit4:junit4]   2> 217768 T16 C0 oasc.SolrDeletionPolicy.updateCommits newest commit = 24[segments_o]
[junit4:junit4]   2> 217769 T16 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 217770 T16 C0 oass.SolrIndexSearcher.<init> Opening Searcher@19f1a8a main
[junit4:junit4]   2> 217774 T16 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 217775 T16 C0 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 217776 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@19f1a8a main{StandardDirectoryReader(segments_m:57:nrt)}
[junit4:junit4]   2> 217777 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 217784 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@19f1a8a main{StandardDirectoryReader(segments_m:57:nrt)}
[junit4:junit4]   2> 217785 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 217785 T16 C0 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 20
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=SolrExampleStreamingBinaryTest -Dtests.method=testUpdateRequestWithParameters -Dtests.seed=FF51741125548A75 -Dtests.slow=true -Dtests.locale=mk_MK -Dtests.timezone=Europe/Budapest -Dtests.file.encoding=Cp1252
[junit4:junit4] ERROR    200s | SolrExampleStreamingBinaryTest.testUpdateRequestWithParameters <<<
[junit4:junit4]    > Throwable #1: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: https://127.0.0.1:58043/solr
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([FF51741125548A75:BD27DAC40A91AAB5]:0)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:416)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:181)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer.request(ConcurrentUpdateSolrServer.java:256)
[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.testUpdateRequestWithParameters(SolrExampleTests.java:641)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
[junit4:junit4]    > 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit4:junit4]    > 	at java.lang.reflect.Method.invoke(Method.java:597)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:662)
[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:353)
[junit4:junit4]    > 	... 46 more
[junit4:junit4]   2> 217846 T11 oas.SolrTestCaseJ4.setUp ###Starting testAddRetrieve
[junit4:junit4]   2> 217857 T20 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 217857 T20 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1427552725012316160)} 0 1
[junit4:junit4]   2> 217864 T31 oascsi.ConcurrentUpdateSolrServer$Runner.run starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@b64078
[junit4:junit4]   2> 218118 T21 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id1 (1427552725024899072), id2 (1427552725026996224)]} 0 250
[junit4:junit4]   2> 218121 T31 oascsi.ConcurrentUpdateSolrServer$Runner.run Status for: id1 is 200
[junit4:junit4]   2> 218122 T31 oascsi.ConcurrentUpdateSolrServer$Runner.run finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@b64078
[junit4:junit4]   2> 218127 T17 C0 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 218144 T17 C0 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_o,generation=24,filenames=[segments_o]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_p,generation=25,filenames=[_e.nvd, _e.nvm, _e.fdx, _e_Memory_1.ram, _e_SimpleText_0.pst, _e.si, _e_MockRandom_0.doc, _e_MockRandom_0.tib, segments_p, _e.fdt, _e_Memory_0.ram, _e_MockRandom_0.sd, _e.fnm, _e_MockRandom_0.pos, _e_MockRandom_0.tii]
[junit4:junit4]   2> 218146 T17 C0 oasc.SolrDeletionPolicy.updateCommits newest commit = 25[_e.nvd, _e.nvm, _e.fdx, _e_Memory_1.ram, _e_SimpleText_0.pst, _e.si, _e_MockRandom_0.doc, _e_MockRandom_0.tib, segments_p, _e.fdt, _e_Memory_0.ram, _e_MockRandom_0.sd, _e.fnm, _e_MockRandom_0.pos, _e_MockRandom_0.tii]
[junit4:junit4]   2> 218147 T17 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 218203 T17 C0 oass.SolrIndexSearcher.<init> Opening Searcher@656758 main
[junit4:junit4]   2> 218205 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@656758 main{StandardDirectoryReader(segments_p:59:nrt _e(5.0):C2)}
[junit4:junit4]   2> 218205 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 218207 T17 C0 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 218212 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@656758 main{StandardDirectoryReader(segments_p:59:nrt _e(5.0):C2)}
[junit4:junit4]   2> 218215 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 218216 T17 C0 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 89
[junit4:junit4]   2> 218362 T18 C0 REQ [collection1] webapp=/solr path=/select params={sort=price+asc&q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=135 
[junit4:junit4]   2> 218364 T31 oascsi.ConcurrentUpdateSolrServer$Runner.run starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@17eb767
[junit4:junit4]   2> 218618 T19 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id1 (1427552725549187072), id2 (1427552725554429952)]} 0 250
[junit4:junit4]   2> 218620 T31 oascsi.ConcurrentUpdateSolrServer$Runner.run Status for: id1 is 200
[junit4:junit4]   2> 218621 T31 oascsi.ConcurrentUpdateSolrServer$Runner.run finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@17eb767
[junit4:junit4]   2> 218624 T21 C0 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 218632 T21 C0 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_p,generation=25,filenames=[_e.nvd, _e.nvm, _e.fdx, _e_Memory_1.ram, _e_SimpleText_0.pst, _e.si, _e_MockRandom_0.doc, _e_MockRandom_0.tib, segments_p, _e.fdt, _e_Memory_0.ram, _e_MockRandom_0.sd, _e.fnm, _e_MockRandom_0.pos, _e_MockRandom_0.tii]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_q,generation=26,filenames=[_f_MockRandom_0.pos, _f.nvm, _f_SimpleText_0.pst, _f_MockRandom_0.tip, _f_MockRandom_0.tim, _f.fdt, _f_Memory_0.ram, _f.nvd, _f.si, _f.fdx, _f.fnm, segments_q, _f_Memory_1.ram, _f_MockRandom_0.sd, _f_MockRandom_0.doc]
[junit4:junit4]   2> 218633 T21 C0 oasc.SolrDeletionPolicy.updateCommits newest commit = 26[_f_MockRandom_0.pos, _f.nvm, _f_SimpleText_0.pst, _f_MockRandom_0.tip, _f_MockRandom_0.tim, _f.fdt, _f_Memory_0.ram, _f.nvd, _f.si, _f.fdx, _f.fnm, segments_q, _f_Memory_1.ram, _f_MockRandom_0.sd, _f_MockRandom_0.doc]
[junit4:junit4]   2> 218635 T21 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 218637 T21 C0 oass.SolrIndexSearcher.<init> Opening Searcher@1ccbdf7 main
[junit4:junit4]   2> 218639 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1ccbdf7 main{StandardDirectoryReader(segments_q:63:nrt _f(5.0):C2)}
[junit4:junit4]   2> 218639 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 218640 T21 C0 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 218642 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ccbdf7 main{StandardDirectoryReader(segments_q:63:nrt _f(5.0):C2)}
[junit4:junit4]   2> 218642 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 218644 T21 C0 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 20
[junit4:junit4]   2> 218648 T17 C0 REQ [collection1] webapp=/solr path=/select params={sort=price+asc&q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=2 
[junit4:junit4]   2> 218654 T16 C0 REQ [collection1] webapp=/solr path=/select params={sort=price+asc&q=name:h?llo&wt=javabin&version=2} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 218656 T11 oas.SolrTestCaseJ4.tearDown ###Ending testAddRetrieve
[junit4:junit4]   2> 218685 T11 oas.SolrTestCaseJ4.setUp ###Starting testFaceting
[junit4:junit4]   2> 218689 T18 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 218690 T18 C0 oass.SolrIndexSearcher.<init> Opening Searcher@126f816 realtime
[junit4:junit4]   2> 218690 T18 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1427552725884731392)} 0 2
[junit4:junit4]   2> 218695 T19 C0 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 218695 T19 C0 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_q,generation=26,filenames=[_f_MockRandom_0.pos, _f.nvm, _f_SimpleText_0.pst, _f_MockRandom_0.tip, _f_MockRandom_0.tim, _f.fdt, _f_Memory_0.ram, _f.nvd, _f.si, _f.fdx, _f.fnm, segments_q, _f_Memory_1.ram, _f_MockRandom_0.sd, _f_MockRandom_0.doc]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_r,generation=27,filenames=[segments_r]
[junit4:junit4]   2> 218696 T19 C0 oasc.SolrDeletionPolicy.updateCommits newest commit = 27[segments_r]
[junit4:junit4]   2> 218697 T19 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 218698 T19 C0 oass.SolrIndexSearcher.<init> Opening Searcher@8ab721 main
[junit4:junit4]   2> 218699 T19 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 218700 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@8ab721 main{StandardDirectoryReader(segments_q:65:nrt)}
[junit4:junit4]   2> 218700 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 218701 T19 C0 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 218703 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@8ab721 main{StandardDirectoryReader(segments_q:65:nrt)}
[junit4:junit4]   2> 218704 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 218705 T19 C0 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 11
[junit4:junit4]   2> 218709 T20 C0 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 218710 T31 oascsi.ConcurrentUpdateSolrServer$Runner.run starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1f01b29
[junit4:junit4]   2> 218964 T21 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1 (1427552725910945792), 2 (1427552725913042944), 3 (1427552725915140096), 4 (1427552725917237248), 5 (1427552725918285824), 6 (1427552725919334400), 7 (1427552725920382976), 8 (1427552725920382977), 9 (1427552725921431552), 10 (1427552725921431553)]} 0 251
[junit4:junit4]   2> 218966 T31 oascsi.ConcurrentUpdateSolrServer$Runner.run Status for: 1 is 200
[junit4:junit4]   2> 218968 T31 oascsi.ConcurrentUpdateSolrServer$Runner.run finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1f01b29
[junit4:junit4]   2> 218975 T17 C0 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 218987 T17 C0 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_r,generation=27,filenames=[segments_r]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_s,generation=28,filenames=[_g.fdt, _g.si, _g.nvm, _g.fnm, segments_s, _g.fdx, _g_Memory_0.ram, _g_Memory_1.ram, _g_SimpleText_0.pst, _g.nvd]
[junit4:junit4]   2> 218988 T17 C0 oasc.SolrDeletionPolicy.updateCommits newest commit = 28[_g.fdt, _g.si, _g.nvm, _g.fnm, segments_s, _g.fdx, _g_Memory_0.ram, _g_Memory_1.ram, _g_SimpleText_0.pst, _g.nvd]
[junit4:junit4]   2> 218988 T17 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 218989 T17 C0 oass.SolrIndexSearcher.<init> Opening Searcher@18fee4f main
[junit4:junit4]   2> 218991 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@18fee4f main{StandardDirectoryReader(segments_s:67:nrt _g(5.0):C10)}
[junit4:junit4]   2> 218991 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 218991 T17 C0 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 218995 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@18fee4f main{StandardDirectoryReader(segments_s:67:nrt _g(5.0):C10)}
[junit4:junit4]   2> 218997 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 218998 T17 C0 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 23
[junit4:junit4]   2> 219003 T16 C0 oasr.UnInvertedField.<init> UnInverted multi-valued field {field=features,memSize=4564,tindexSize=44,time=1,phase1=1,nTerms=4,bigTerms=2,termInstances=4,uses=0}
[junit4:junit4]   2> 219008 T16 C0 REQ [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=6 
[junit4:junit4]   2> 219022 T18 C0 REQ [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]   2> 219028 T20 C0 REQ [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=4&q=*:*&facet.field=features&wt=javabin&fq=features:two&version=2&rows=0} hits=5 status=0 QTime=1 
[junit4:junit4]   2> 219035 T21 C0 REQ [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=1 
[junit4:junit4]   2> 219035 T11 oas.SolrTestCaseJ4.tearDown ###Ending testFaceting
[junit4:junit4]   2> 219062 T11 oas.SolrTestCaseJ4.setUp ###Starting testCommitWithinOnAdd
[junit4:junit4]   2> 219071 T17 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 219073 T17 C0 oass.SolrIndexSearcher.<init> Opening Searcher@ccbab7 realtime
[junit4:junit4]   2> 219074 T17 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1427552726283190272)} 0 6
[junit4:junit4]   2> 219082 T16 C0 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 219083 T16 C0 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_s,generation=28,filenames=[_g.fdt, _g.si, _g.nvm, _g.fnm, segments_s, _g.fdx, _g_Memory_0.ram, _g_Memory_1.ram, _g_SimpleText_0.pst, _g.nvd]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_t,generation=29,filenames=[segments_t]
[junit4:junit4]   2> 219083 T16 C0 oasc.SolrDeletionPolicy.updateCommits newest commit = 29[segments_t]
[junit4:junit4]   2> 219083 T16 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 219084 T16 C0 oass.SolrIndexSearcher.<init> Opening Searcher@465863 main
[junit4:junit4]   2> 219085 T16 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 219086 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@465863 main{StandardDirectoryReader(segments_s:69:nrt)}
[junit4:junit4]   2> 219086 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 219086 T16 C0 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 219088 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@465863 main{StandardDirectoryReader(segments_s:69:nrt)}
[junit4:junit4]   2> 219090 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 219091 T16 C0 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 9
[junit4:junit4]   2> 219096 T18 C0 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 219098 T31 oascsi.ConcurrentUpdateSolrServer$Runner.run starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1ce67ca
[junit4:junit4]   2> 219100 T21 C0 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 219357 T20 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id3 (1427552726321987584)]} 0 252
[junit4:junit4]   2> 219359 T31 oascsi.ConcurrentUpdateSolrServer$Runner.run Status for: id3 is 200
[junit4:junit4]   2> 219361 T31 oascsi.ConcurrentUpdateSolrServer$Runner.run finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1ce67ca
[junit4:junit4]   2> 219608 T26 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true}
[junit4:junit4]   2> 219608 T26 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 219616 T26 oass.SolrIndexSearcher.<init> Opening Searcher@ada795 main
[junit4:junit4]   2> 219617 T26 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 219617 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@ada795 main{StandardDirectoryReader(segments_t:71:nrt _h(5.0):C1)}
[junit4:junit4]   2> 219618 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 219621 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@ada795 main{StandardDirectoryReader(segments_t:71:nrt _h(5.0):C1)}
[junit4:junit4]   2> 219622 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 220113 T16 C0 REQ [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 220115 T31 oascsi.ConcurrentUpdateSolrServer$Runner.run starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1cc7f4b
[junit4:junit4]   2> 220370 T18 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id4 (1427552727384195072)]} 0 252
[junit4:junit4]   2> 220372 T31 oascsi.ConcurrentUpdateSolrServer$Runner.run Status for: id4 is 200
[junit4:junit4]   2> 220374 T31 oascsi.ConcurrentUpdateSolrServer$Runner.run finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1cc7f4b
[junit4:junit4]   2> 220450 T26 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true}
[junit4:junit4]   2> 220450 T26 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 220475 T26 oass.SolrIndexSearcher.<init> Opening Searcher@11eb50b main
[junit4:junit4]   2> 220476 T26 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 220477 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@11eb50b main{StandardDirectoryReader(segments_t:73:nrt _h(5.0):C1 _i(5.0):C1)}
[junit4:junit4]   2> 220478 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 220486 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@11eb50b main{StandardDirectoryReader(segments_t:73:nrt _h(5.0):C1 _i(5.0):C1)}
[junit4:junit4]   2> 220487 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 221120 T20 C0 REQ [collection1] webapp=/solr path=/select params={q=id:id4&wt=javabin&version=2} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 221124 T11 oas.SolrTestCaseJ4.tearDown ###Ending testCommitWithinOnAdd
[junit4:junit4]   2> 221145 T11 oas.SolrTestCaseJ4.setUp ###Starting testPivotFacets
[junit4:junit4]   2> 221148 T17 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 221150 T17 C0 oass.SolrIndexSearcher.<init> Opening Searcher@1db8a73 realtime
[junit4:junit4]   2> 221151 T17 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1427552728464228352)} 0 3
[junit4:junit4]   2> 221156 T16 C0 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 221156 T16 C0 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_t,generation=29,filenames=[segments_t]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_u,generation=30,filenames=[segments_u]
[junit4:junit4]   2> 221157 T16 C0 oasc.SolrDeletionPolicy.updateCommits newest commit = 30[segments_u]
[junit4:junit4]   2> 221158 T16 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 221158 T16 C0 oass.SolrIndexSearcher.<init> Opening Searcher@11ae81d main
[junit4:junit4]   2> 221159 T16 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 221161 T16 C0 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 221161 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@11ae81d main{StandardDirectoryReader(segments_t:75:nrt)}
[junit4:junit4]   2> 221162 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 221165 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@11ae81d main{StandardDirectoryReader(segments_t:75:nrt)}
[junit4:junit4]   2> 221166 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 221167 T16 C0 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 11
[junit4:junit4]   2> 221171 T19 C0 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 221172 T31 oascsi.ConcurrentUpdateSolrServer$Runner.run starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@178991
[junit4:junit4]   2> 221424 T18 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1 (1427552728492539904), 2 (1427552728494637056), 3 (1427552728496734208), 4 (1427552728496734209), 5 (1427552728497782784), 6 (1427552728498831360), 7 (1427552728498831361), 8 (1427552728498831362), 9 (1427552728499879936), 10 (1427552728500928512), ... (12 adds)]} 0 249
[junit4:junit4]   2> 221426 T31 oascsi.ConcurrentUpdateSolrServer$Runner.run Status for: 1 is 200
[junit4:junit4]   2> 221426 T31 oascsi.ConcurrentUpdateSolrServer$Runner.run finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@178991
[junit4:junit4]   2> 221430 T20 C0 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 221437 T20 C0 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_u,generation=30,filenames=[segments_u]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_v,generation=31,filenames=[_j.fdx, _j_Memory_0.ram, _j_MockRandom_0.tii, _j.si, _j.fdt, _j.nvm, _j_MockRandom_0.sd, _j_Memory_1.ram, _j_MockRandom_0.tib, _j_MockRandom_0.doc, _j_MockRandom_0.frq, segments_v, _j.nvd, _j_SimpleText_0.pst, _j.fnm, _j_MockRandom_0.pyl, _j_MockRandom_0.skp, _j_MockRandom_0.pos]
[junit4:junit4]   2> 221438 T20 C0 oasc.SolrDeletionPolicy.updateCommits newest commit = 31[_j.fdx, _j_Memory_0.ram, _j_MockRandom_0.tii, _j.si, _j.fdt, _j.nvm, _j_MockRandom_0.sd, _j_Memory_1.ram, _j_MockRandom_0.tib, _j_MockRandom_0.doc, _j_MockRandom_0.frq, segments_v, _j.nvd, _j_SimpleText_0.pst, _j.fnm, _j_MockRandom_0.pyl, _j_MockRandom_0.skp, _j_MockRandom_0.pos]
[junit4:junit4]   2> 221438 T20 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 221447 T20 C0 oass.SolrIndexSearcher.<init> Opening Searcher@1685e30 main
[junit4:junit4]   2> 221449 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1685e30 main{StandardDirectoryReader(segments_v:77:nrt _j(5.0):C12)}
[junit4:junit4]   2> 221449 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 221450 T20 C0 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 221452 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1685e30 main{StandardDirectoryReader(segments_v:77:nrt _j(5.0):C12)}
[junit4:junit4]   2> 221453 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 221453 T20 C0 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 23
[junit4:junit4]   2> 221458 T17 C0 oasr.UnInvertedField.<init> UnInverted multi-valued field {field=features,memSize=4521,tindexSize=43,time=1,phase1=1,nTerms=2,bigTerms=2,termInstances=0,uses=0}
[junit4:junit4]   2> 221462 T17 C0 oasr.UnInvertedField.<init> UnInverted multi-valued field {field=cat,memSize=4474,tindexSize=0,time=3,phase1=3,nTerms=2,bigTerms=2,termInstances=0,uses=0}
[junit4:junit4]   2> 221466 T17 C0 REQ [collection1] webapp=/solr path=/select params={facet.missing=false&facet=true&facet.mincount=0&q=*:*&wt=javabin&facet.pivot=features,cat&facet.pivot=cat,features&facet.pivot=features,cat,inStock&version=2&rows=0} hits=12 status=0 QTime=9 
[junit4:junit4]   2> 221474 T16 C0 REQ [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=1 
[junit4:junit4]   2> 221475 T11 oas.SolrTestCaseJ4.tearDown ###Ending testPivotFacets
[junit4:junit4]   2> 221491 T11 oas.SolrTestCaseJ4.setUp ###Starting testContentStreamRequest
[junit4:junit4]   2> 221496 T19 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 221497 T19 C0 oass.SolrIndexSearcher.<init> Opening Searcher@e22632 realtime
[junit4:junit4]   2> 221498 T19 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1427552728828084224)} 0 3
[junit4:junit4]   2> 221502 T21 C0 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 221502 T21 C0 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_v,generation=31,filenames=[_j.fdx, _j_Memory_0.ram, _j_MockRandom_0.tii, _j.si, _j.fdt, _j.nvm, _j_MockRandom_0.sd, _j_Memory_1.ram, _j_MockRandom_0.tib, _j_MockRandom_0.doc, _j_MockRandom_0.frq, segments_v, _j.nvd, _j_SimpleText_0.pst, _j.fnm, _j_MockRandom_0.pyl, _j_MockRandom_0.skp, _j_MockRandom_0.pos]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_w,generation=32,filenames=[segments_w]
[junit4:junit4]   2> 221503 T21 C0 oasc.SolrDeletionPolicy.updateCommits newest commit = 32[segments_w]
[junit4:junit4]   2> 221503 T21 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 221503 T21 C0 oass.SolrIndexSearcher.<init> Opening Searcher@1da6868 main
[junit4:junit4]   2> 221504 T21 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 221505 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1da6868 main{StandardDirectoryReader(segments_v:79:nrt)}
[junit4:junit4]   2> 221505 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 221511 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1da6868 main{StandardDirectoryReader(segments_v:79:nrt)}
[junit4:junit4]   2> 221512 T21 C0 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 221513 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 221514 T21 C0 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 13
[junit4:junit4]   2> 221517 T18 C0 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 221659 T20 C0 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 221680 T20 C0 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_w,generation=32,filenames=[segments_w]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_x,generation=33,filenames=[_k_MockRandom_0.sd, _k.nvd, _k_MockRandom_0.pos, _k.fdt, _k_MockRandom_0.skp, _k.fdx, _k_MockRandom_0.tiv, _k_Memory_1.ram, _k.fnm, _k_MockRandom_0.doc, _k_SimpleText_0.pst, _k_MockRandom_0.frq, _k_MockRandom_0.pyl, _k.nvm, _k_Memory_0.ram, _k.si, _k_MockRandom_0.tib, segments_x]
[junit4:junit4]   2> 221681 T20 C0 oasc.SolrDeletionPolicy.updateCommits newest commit = 33[_k_MockRandom_0.sd, _k.nvd, _k_MockRandom_0.pos, _k.fdt, _k_MockRandom_0.skp, _k.fdx, _k_MockRandom_0.tiv, _k_Memory_1.ram, _k.fnm, _k_MockRandom_0.doc, _k_SimpleText_0.pst, _k_MockRandom_0.frq, _k_MockRandom_0.pyl, _k.nvm, _k_Memory_0.ram, _k.si, _k_MockRandom_0.tib, segments_x]
[junit4:junit4]   2> 221681 T20 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 221684 T20 C0 oass.SolrIndexSearcher.<init> Opening Searcher@199c55a main
[junit4:junit4]   2> 221684 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@199c55a main{StandardDirectoryReader(segments_x:81:nrt _k(5.0):C10)}
[junit4:junit4]   2> 221684 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 221685 T20 C0 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 221687 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@199c55a main{StandardDirectoryReader(segments_x:81:nrt _k(5.0):C10)}
[junit4:junit4]   2> 221688 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 221689 T20 C0 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {add=[0553573403 (1427552728978030592), 0553579908 (1427552728981176320), 055357342X (1427552728983273472), 0553293354 (1427552728986419200), 0812521390 (1427552728988516352), 0812550706 (1427552728989564928), 0441385532 (1427552728991662080), 0380014300 (1427552728993759232), 0805080481 (1427552728995856384), 080508049X (1427552728997953536)],commit=} 0 162
[junit4:junit4]   2> 221694 T16 C0 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=1 
[junit4:junit4]   2> 221698 T11 oas.SolrTestCaseJ4.tearDown ###Ending testContentStreamRequest
[junit4:junit4]   2> 221761 T11 oas.SolrTestCaseJ4.setUp ###Starting testExampleConfig
[junit4:junit4]   2> 221765 T19 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 221767 T19 C0 oass.SolrIndexSearcher.<init> Opening Searcher@374ed5 realtime
[junit4:junit4]   2> 221769 T19 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1427552729111199744)} 0 4
[junit4:junit4]   2> 221836 T31 oascsi.ConcurrentUpdateSolrServer$Runner.run starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1189cbb
[junit4:junit4]   2> 222092 T21 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1112211111 (1427552729189842944)]} 0 252
[junit4:junit4]   2> 222094 T31 oascsi.ConcurrentUpdateSolrServer$Runner.run Status for: 1112211111 is 200
[junit4:junit4]   2> 222095 T31 oascsi.ConcurrentUpdateSolrServer$Runner.run finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1189cbb
[junit4:junit4]   2> 222099 T20 C0 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 222107 T20 C0 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_x,generation=33,filenames=[_k_MockRandom_0.sd, _k.nvd, _k_MockRandom_0.pos, _k.fdt, _k_MockRandom_0.skp, _k.fdx, _k_MockRandom_0.tiv, _k_Memory_1.ram, _k.fnm, _k_MockRandom_0.doc, _k_SimpleText_0.pst, _k_MockRandom_0.frq, _k_MockRandom_0.pyl, _k.nvm, _k_Memory_0.ram, _k.si, _k_MockRandom_0.tib, segments_x]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_y,generation=34,filenames=[_l.nvm, _l.fnm, _l.si, _l_Memory_1.ram, _l.fdx, _l.fdt, segments_y, _l_Memory_0.ram, _l_SimpleText_0.pst, _l.nvd]
[junit4:junit4]   2> 222107 T20 C0 oasc.SolrDeletionPolicy.updateCommits newest commit = 34[_l.nvm, _l.fnm, _l.si, _l_Memory_1.ram, _l.fdx, _l.fdt, segments_y, _l_Memory_0.ram, _l_SimpleText_0.pst, _l.nvd]
[junit4:junit4]   2> 222108 T20 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 222111 T20 C0 oass.SolrIndexSearcher.<init> Opening Searcher@c042ab main
[junit4:junit4]   2> 222114 T20 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 222115 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@c042ab main{StandardDirectoryReader(segments_y:85:nrt _l(5.0):C1)}
[junit4:junit4]   2> 222115 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 222116 T20 C0 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 222122 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c042ab main{StandardDirectoryReader(segments_y:85:nrt _l(5.0):C1)}
[junit4:junit4]   2> 222124 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 222125 T20 C0 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 26
[junit4:junit4]   2> 222132 T17 C0 oasu.DirectUpdateHandler2.commit start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 222135 T17 C0 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_y,generation=34,filenames=[_l.nvm, _l.fnm, _l.si, _l_Memory_1.ram, _l.fdx, _l.fdt, segments_y, _l_Memory_0.ram, _l_SimpleText_0.pst, _l.nvd]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_z,generation=35,filenames=[_l.nvm, _l.fnm, _l.si, _l_Memory_1.ram, _l.fdx, _l.fdt, segments_z, _l_Memory_0.ram, _l_SimpleText_0.pst, _l.nvd]
[junit4:junit4]   2> 222136 T17 C0 oasc.SolrDeletionPolicy.updateCommits newest commit = 35[_l.nvm, _l.fnm, _l.si, _l_Memory_1.ram, _l.fdx, _l.fdt, segments_z, _l_Memory_0.ram, _l_SimpleText_0.pst, _l.nvd]
[junit4:junit4]   2> 222137 T17 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 222138 T17 C0 oass.SolrIndexSearcher.<init> Opening Searcher@f1bcde main
[junit4:junit4]   2> 222140 T17 C0 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 222140 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@f1bcde main{StandardDirectoryReader(segments_y:85:nrt _l(5.0):C1)}
[junit4:junit4]   2> 222140 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 222147 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@f1bcde main{StandardDirectoryReader(segments_y:85:nrt _l(5.0):C1)}
[junit4:junit4]   2> 222149 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 222149 T17 C0 UPDATE [collection1] webapp=/solr path=/update params={optimize=true&waitSearcher=true&maxSegments=1&wt=javabin&version=2} {optimize=} 0 17
[junit4:junit4]   2> 222158 T16 C0 REQ [collection1] webapp=/solr path=/select params={q=id:1112211111&wt=javabin&version=2} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 222168 T31 oascsi.ConcurrentUpdateSolrServer$Runner.run starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1704ebb
[junit4:junit4]   2> 222423 T19 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2 (1427552729540067328), 3 (1427552729552650240), 4 (1427552729554747392), 5 (1427552729556844544)]} 0 249
[junit4:junit4]   2> 222425 T31 oascsi.ConcurrentUpdateSolrServer$Runner.run Status for: 2 is 200
[junit4:junit4]   2> 222426 T31 oascsi.ConcurrentUpdateSolrServer$Runner.run finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1704ebb
[junit4:junit4]   2> 222431 T21 C0 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 222441 T21 C0 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_z,generation=35,filenames=[_l.nvm, _l.fnm, _l.si, _l_Memory_1.ram, _l.fdx, _l.fdt, segments_z, _l_Memory_0.ram, _l_SimpleText_0.pst, _l.nvd]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_10,generation=36,filenames=[_m_MockRandom_0.tip, _l.fnm, segments_10, _m_Memory_1.ram, _m_MockRandom_0.tim, _l.si, _m.nvm, _m_SimpleText_0.pst, _l.nvd, _l_SimpleText_0.pst, _m.si, _m_MockRandom_0.pos, _m.nvd, _l.nvm, _m_MockRandom_0.doc, _m.fdt, _m_MockRandom_0.sd, _l_Memory_1.ram, _l.fdx, _m.fdx, _l.fdt, _m_Memory_0.ram, _m.fnm, _l_Memory_0.ram]
[junit4:junit4]   2> 222442 T21 C0 oasc.SolrDeletionPolicy.updateCommits newest commit = 36[_m_MockRandom_0.tip, _l.fnm, segments_10, _m_Memory_1.ram, _m_MockRandom_0.tim, _l.si, _m.nvm, _m_SimpleText_0.pst, _l.nvd, _l_SimpleText_0.pst, _m.si, _m_MockRandom_0.pos, _m.nvd, _l.nvm, _m_MockRandom_0.doc, _m.fdt, _m_MockRandom_0.sd, _l_Memory_1.ram, _l.fdx, _m.fdx, _l.fdt, _m_Memory_0.ram, _m.fnm, _l_Memory_0.ram]
[junit4:junit4]   2> 222444 T21 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 222448 T21 C0 oass.SolrIndexSearcher.<init> Opening Searcher@ab4b89 main
[junit4:junit4]   2> 222449 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@ab4b89 main{StandardDirectoryReader(segments_10:87:nrt _l(5.0):C1 _m(5.0):C4)}
[junit4:junit4]   2> 222449 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 222450 T21 C0 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 222452 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@ab4b89 main{StandardDirectoryReader(segments_10:87:nrt _l(5.0):C1 _m(5.0):C4)}
[junit4:junit4]   2> 222453 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 222454 T21 C0 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 23
[junit4:junit4]   2> 222458 T18 C0 oasu.DirectUpdateHandler2.commit start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 222566 T18 C0 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_10,generation=36,filenames=[_m_MockRandom_0.tip, _l.fnm, segments_10, _m_Memory_1.ram, _m_MockRandom_0.tim, _l.si, _m.nvm, _m_SimpleText_0.pst, _l.nvd, _l_SimpleText_0.pst, _m.si, _m_MockRandom_0.pos, _m.nvd, _l.nvm, _m_MockRandom_0.doc, _m.fdt, _m_MockRandom_0.sd, _l_Memory_1.ram, _l.fdx, _m.fdx, _l.fdt, _m_Memory_0.ram, _m.fnm, _l_Memory_0.ram]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_11,generation=37,filenames=[_n_SimpleText_0.pst, segments_11, _n.fdt, _n_MockRandom_0.skp, _n.fdx, _n_Memory_0.ram, _n.nvd, _n_MockRandom_0.sd, _n.si, _n_MockRandom_0.pos, _n_MockRandom_0.pyl, _n_MockRandom_0.doc, _n.nvm, _n_MockRandom_0.tip, _n_Memory_1.ram, _n_MockRandom_0.frq, _n_MockRandom_0.tim, _n.fnm]
[junit4:junit4]   2> 222567 T18 C0 oasc.SolrDeletionPolicy.updateCommits newest commit = 37[_n_SimpleText_0.pst, segments_11, _n.fdt, _n_MockRandom_0.skp, _n.fdx, _n_Memory_0.ram, _n.nvd, _n_MockRandom_0.sd, _n.si, _n_MockRandom_0.pos, _n_MockRandom_0.pyl, _n_MockRandom_0.doc, _n.nvm, _n_MockRandom_0.tip, _n_Memory_1.ram, _n_MockRandom_0.frq, _n_MockRandom_0.tim, _n.fnm]
[junit4:junit4]   2> 222568 T18 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 222570 T18 C0 oass.SolrIndexSearcher.<init> Opening Searcher@63f6ea main
[junit4:junit4]   2> 222571 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@63f6ea main{StandardDirectoryReader(segments_11:89:nrt _n(5.0):C5)}
[junit4:junit4]   2> 222571 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 222572 T18 C0 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 222575 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@63f6ea main{StandardDirectoryReader(segments_11:89:nrt _n(5.0):C5)}
[junit4:junit4]   2> 222575 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 222576 T18 C0 UPDATE [collection1] webapp=/solr path=/update params={optimize=true&waitSearcher=true&maxSegments=1&wt=javabin&version=2} {optimize=} 0 118
[junit4:junit4]   2> 222626 T17 C0 REQ [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=45 
[junit4:junit4]   2> 222639 T16 C0 REQ [collection1] webapp=/solr path=/select params={facet=true&facet.query=price:[*+TO+2]&facet.query=price:[2+TO+4]&facet.query=price:[5+TO+*]&q=*:*&facet.field=inStock&facet.field=price&facet.field=timestamp_dt&wt=javabin&fq=inStock:true&version=2} hits=2 status=0 QTime=2 
[junit4:junit4]   2> 222666 T19 C0 REQ [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=19 
[junit4:junit4]   2> 222668 T11 oas.SolrTestCaseJ4.tearDown ###Ending testExampleConfig
[junit4:junit4]   2> 222680 T11 oas.SolrTestCaseJ4.setUp ###Starting testChineseDefaults
[junit4:junit4]   2> 222684 T21 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 222685 T21 C0 oass.SolrIndexSearcher.<init> Opening Searcher@15d533d realtime
[junit4:junit4]   2> 222685 T21 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1427552730072743936)} 0 3
[junit4:junit4]   2> 222689 T18 C0 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 222690 T18 C0 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_11,generation=37,filenames=[_n_SimpleText_0.pst, segments_11, _n.fdt, _n_MockRandom_0.skp, _n.fdx, _n_Memory_0.ram, _n.nvd, _n_MockRandom_0.sd, _n.si, _n_MockRandom_0.pos, _n_MockRandom_0.pyl, _n_MockRandom_0.doc, _n.nvm, _n_MockRandom_0.tip, _n_Memory_1.ram, _n_MockRandom_0.frq, _n_MockRandom_0.tim, _n.fnm]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_12,generation=38,filenames=[segments_12]
[junit4:junit4]   2> 222690 T18 C0 oasc.SolrDeletionPolicy.updateCommits newest commit = 38[segments_12]
[junit4:junit4]   2> 222690 T18 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 222691 T18 C0 oass.SolrIndexSearcher.<init> Opening Searcher@14e5d57 main
[junit4:junit4]   2> 222692 T18 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 222693 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@14e5d57 main{StandardDirectoryReader(segments_11:91:nrt)}
[junit4:junit4]   2> 222693 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 222693 T18 C0 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 222695 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@14e5d57 main{StandardDirectoryReader(segments_11:91:nrt)}
[junit4:junit4]   2> 222696 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 222696 T18 C0 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 7
[junit4:junit4]   2> 222700 T20 C0 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 222701 T11 oascsi.ConcurrentUpdateSolrServer.request blocking for commit/optimize
[junit4:junit4]   2> 222707 T17 C0 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 222708 T17 C0 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_12,generation=38,filenames=[segments_12]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_13,generation=39,filenames=[_o.fdt, _o.nvm, _o.fnm, _o.fdx, _o_SimpleText_0.pst, _o_Memory_0.ram, segments_13, _o.si, _o_Memory_1.ram, _o.nvd]
[junit4:junit4]   2> 222709 T17 C0 oasc.SolrDeletionPolicy.updateCommits newest commit = 39[_o.fdt, _o.nvm, _o.fnm, _o.fdx, _o_SimpleText_0.pst, _o_Memory_0.ram, segments_13, _o.si, _o_Memory_1.ram, _o.nvd]
[junit4:junit4]   2> 222710 T17 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 222711 T17 C0 oass.SolrIndexSearcher.<init> Opening Searcher@147e0ec main
[junit4:junit4]   2> 222712 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@147e0ec main{StandardDirectoryReader(segments_13:93:nrt _o(5.0):C1)}
[junit4:junit4]   2> 222712 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 222712 T17 C0 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 222714 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@147e0ec main{StandardDirectoryReader(segments_13:93:nrt _o(5.0):C1)}
[junit4:junit4]   2> 222715 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 222716 T17 C0 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {add=[42 (1427552730095812608)],commit=} 0 12
[junit4:junit4]   2> 222722 T16 C0 REQ [collection1] webapp=/solr path=/select params={q=????&wt=javabin&version=2} hits=1 status=0 QTime=3 
[junit4:junit4]   2> 222723 T11 oas.SolrTestCaseJ4.tearDown ###Ending testChineseDefaults
[junit4:junit4]   2> 222733 T11 oas.SolrTestCaseJ4.setUp ###Starting testStreamingRequest
[junit4:junit4]   2> 222738 T19 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 222739 T19 C0 oass.SolrIndexSearcher.<init> Opening Searcher@151b1b7 realtime
[junit4:junit4]   2> 222739 T19 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1427552730129367040)} 0 3
[junit4:junit4]   2> 222743 T21 C0 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 222744 T21 C0 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_13,generation=39,filenames=[_o.fdt, _o.nvm, _o.fnm, _o.fdx, _o_SimpleText_0.pst, _o_Memory_0.ram, segments_13, _o.si, _o_Memory_1.ram, _o.nvd]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_14,generation=40,filenames=[segments_14]
[junit4:junit4]   2> 222744 T21 C0 oasc.SolrDeletionPolicy.updateCommits newest commit = 40[segments_14]
[junit4:junit4]   2> 222745 T21 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 222746 T21 C0 oass.SolrIndexSearcher.<init> Opening Searcher@12d34a6 main
[junit4:junit4]   2> 222746 T21 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 222747 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@12d34a6 main{StandardDirectoryReader(segments_13:95:nrt)}
[junit4:junit4]   2> 222747 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 222750 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@12d34a6 main{StandardDirectoryReader(segments_13:95:nrt)}
[junit4:junit4]   2> 222752 T21 C0 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 222754 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 222754 T21 C0 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 11
[junit4:junit4]   2> 222758 T18 C0 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 222759 T11 oascsi.ConcurrentUpdateSolrServer.request blocking for commit/optimize
[junit4:junit4]   2> 222767 T20 C0 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 222771 T20 C0 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_14,generation=40,filenames=[segments_14]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_15,generation=41,filenames=[_p_SimpleText_0.pst, _p.fdx, _p.nvd, _p.fnm, _p.fdt, _p_Memory_1.ram, _p.si, _p_MockRandom_0.sd, _p_MockRandom_0.tib, _p_MockRandom_0.doc, _p.nvm, _p_MockRandom_0.tiv, segments_15, _p_MockRandom_0.pos, _p_Memory_0.ram]
[junit4:junit4]   2> 222771 T20 C0 oasc.SolrDeletionPolicy.updateCommits newest commit = 41[_p_SimpleText_0.pst, _p.fdx, _p.nvd, _p.fnm, _p.fdt, _p_Memory_1.ram, _p.si, _p_MockRandom_0.sd, _p_MockRandom_0.tib, _p_MockRandom_0.doc, _p.nvm, _p_MockRandom_0.tiv, segments_15, _p_MockRandom_0.pos, _p_Memory_0.ram]
[junit4:junit4]   2> 222772 T20 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 222773 T20 C0 oass.SolrIndexSearcher.<init> Opening Searcher@d88aa2 main
[junit4:junit4]   2> 222775 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@d88aa2 main{StandardDirectoryReader(segments_15:97:nrt _p(5.0):C10)}
[junit4:junit4]   2> 222775 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 222775 T20 C0 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 222777 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@d88aa2 main{StandardDirectoryReader(segments_15:97:nrt _p(5.0):C10)}
[junit4:junit4]   2> 222780 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 222780 T20 C0 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {add=[0 (1427552730155581440), 1 (1427552730158727168), 2 (1427552730158727169), 3 (1427552730159775744), 4 (1427552730159775745), 5 (1427552730159775746), 6 (1427552730159775747), 7 (1427552730160824320), 8 (1427552730160824321), 9 (1427552730160824322)],commit=} 0 19
[junit4:junit4]   2> 222796 T17 C0 REQ [collection1] webapp=/solr path=/select params={fl=id,score,_docid_&q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=12 
[junit4:junit4]   2> 222805 T16 C0 REQ [collection1] webapp=/solr path=/select params={fl=id,score,_docid_&q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=0 
[junit4:junit4]   2> 222806 T11 oas.SolrTestCaseJ4.tearDown ###Ending testStreamingRequest
[junit4:junit4]   2> 222822 T11 oas.SolrTestCaseJ4.setUp ###Starting testErrorHandling
[junit4:junit4]   2> 222889 T19 C0 oasc.SolrException.log Ignoring exception matching ignore_exception
[junit4:junit4]   2> 222889 T19 C0 REQ [collection1] webapp=/solr path=/analysis/field params={analysis.fieldtype=int&wt=javabin&qt=/analysis/field&analysis.fieldvalue=ignore_exception&version=2} status=400 QTime=63 
[junit4:junit4]   2> 222990 T21 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {} 0 97
[junit4:junit4]   2> 222990 T21 C0 oasc.SolrException.log Ignoring exception matching ignore_exception
[junit4:junit4]   2> 222992 T31 oascsi.ConcurrentUpdateSolrServer$Runner.run starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@f2ff9b
[junit4:junit4]   2> 222995 T20 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {} 0 0
[junit4:junit4]   2> 222996 T20 C0 oasc.SolrException.log Ignoring exception matching uniqueKey
[junit4:junit4]   2> 223246 T31 oascsi.ConcurrentUpdateSolrServer$Runner.run Status for: DOCID is 400
[junit4:junit4]   2> 223247 T31 oascsi.ConcurrentUpdateSolrServer$Runner.run finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@f2ff9b
[junit4:junit4]   2> 223248 T11 oas.SolrTestCaseJ4.tearDown ###Ending testErrorHandling
[junit4:junit4]   2> 223272 T11 oas.SolrTestCaseJ4.setUp ###Starting testLukeHandler
[junit4:junit4]   2> 223282 T16 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 223284 T16 C0 oass.SolrIndexSearcher.<init> Opening Searcher@b40443 realtime
[junit4:junit4]   2> 223286 T16 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1427552730698743808)} 0 7
[junit4:junit4]   2> 223288 T31 oascsi.ConcurrentUpdateSolrServer$Runner.run starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@152e9a8
[junit4:junit4]   2> 223290 T33 oascsi.ConcurrentUpdateSolrServer$Runner.run starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@13e86ec
[junit4:junit4]   2> 223290 T34 oascsi.ConcurrentUpdateSolrServer$Runner.run starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@be6858
[junit4:junit4]   2> 223291 T35 oascsi.ConcurrentUpdateSolrServer$Runner.run starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@b471fe
[junit4:junit4]   2> 223292 T36 oascsi.ConcurrentUpdateSolrServer$Runner.run starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@11067af
[junit4:junit4]   2> 223294 T36 oascsi.ConcurrentUpdateSolrServer$Runner.run finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@11067af
[junit4:junit4]   2> 223546 T21 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID0 (1427552730715521024), ID4 (1427552730717618176)]} 0 251
[junit4:junit4]   2> 223546 T20 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID1 (1427552730721812480)]} 0 245
[junit4:junit4]   2> 223548 T31 oascsi.ConcurrentUpdateSolrServer$Runner.run Status for: ID0 is 200
[junit4:junit4]   2> 223549 T33 oascsi.ConcurrentUpdateSolrServer$Runner.run Status for: ID1 is 200
[junit4:junit4]   2> 223550 T33 oascsi.ConcurrentUpdateSolrServer$Runner.run finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@13e86ec
[junit4:junit4]   2> 223550 T31 oascsi.ConcurrentUpdateSolrServer$Runner.run finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@152e9a8
[junit4:junit4]   2> 223555 T16 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID2 (1427552730727055360)]} 0 249
[junit4:junit4]   2> 223557 T34 oascsi.ConcurrentUpdateSolrServer$Runner.run Status for: ID2 is 200
[junit4:junit4]   2> 223557 T19 C0 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID3 (1427552730728103936)]} 0 250
[junit4:junit4]   2> 223561 T34 oascsi.ConcurrentUpdateSolrServer$Runner.run finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@be6858
[junit4:junit4]   2> 223563 T35 oascsi.ConcurrentUpdateSolrServer$Runner.run Status for: ID3 is 200
[junit4:junit4]   2> 223564 T35 oascsi.ConcurrentUpdateSolrServer$Runner.run finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@b471fe
[junit4:junit4]   2> 223571 T18 C0 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 223577 T18 C0 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_15,generation=41,filenames=[_p_SimpleText_0.pst, _p.fdx, _p.nvd, _p.fnm, _p.fdt, _p_Memory_1.ram, _p.si, _p_MockRandom_0.sd, _p_MockRandom_0.tib, _p_MockRandom_0.doc, _p.nvm, _p_MockRandom_0.tiv, segments_15, _p_MockRandom_0.pos, _p_Memory_0.ram]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_16,generation=42,filenames=[_q.si, _q.fdx, _q_Memory_0.ram, _q_Memory_1.ram, _q.fnm, _q.fdt, segments_16]
[junit4:junit4]   2> 223578 T18 C0 oasc.SolrDeletionPolicy.updateCommits newest commit = 42[_q.si, _q.fdx, _q_Memory_0.ram, _q_Memory_1.ram, _q.fnm, _q.fdt, segments_16]
[junit4:junit4]   2> 223579 T18 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 223582 T18 C0 oass.SolrIndexSearcher.<init> Opening Searcher@1f2ae62 main
[junit4:junit4]   2> 223583 T18 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 223585 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1f2ae62 main{StandardDirectoryReader(segments_16:101:nrt _q(5.0):C5)}
[junit4:junit4]   2> 223586 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 223586 T18 C0 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 223588 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1f2ae62 main{StandardDirectoryReader(segments_16:101:nrt _q(5.0):C5)}
[junit4:junit4]   2> 223589 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 223589 T18 C0 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 18
[junit4:junit4]   2> 223594 T17 C0 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=5 status=0 QTime=0 
[junit4:junit4]   2> 223623 T20 C0 REQ [collection1] webapp=/solr path=/admin/luke params={wt=javabin&version=2} status=0 QTime=15 
[junit4:junit4]   2> 223640 T21 C0 REQ [collection1] webapp=/solr path=/admin/luke params={show=schema&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 223678 T11 oas.SolrTestCaseJ4.tearDown ###Ending testLukeHandler
[junit4:junit4]   2> 223686 T11 oas.SolrTestCaseJ4.setUp ###Starting testWaitOptions
[junit4:junit4]   2> 223687 T11 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 223692 T11 oascsi.ConcurrentUpdateSolrServer.request blocking for commit/optimize
[junit4:junit4]   2> 223751 T17 C0 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 223756 T17 C0 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_16,generation=42,filenames=[_q.si, _q.fdx, _q_Memory_0.ram, _q_Memory_1.ram, _q.fnm, _q.fdt, segments_16]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_17,generation=43,filenames=[_q.fdx, _q.fdt, _q.si, _r.fnm, _r.fdt, _r.fdx, _q_Memory_0.ram, _r.si, segments_17, _q.fnm, _q_Memory_1.ram, _r_Memory_1.ram, _r_Memory_0.ram]
[junit4:junit4]   2> 223756 T17 C0 oasc.SolrDeletionPolicy.updateCommits newest commit = 43[_q.fdx, _q.fdt, _q.si, _r.fnm, _r.fdt, _r.fdx, _q_Memory_0.ram, _r.si, segments_17, _q.fnm, _q_Memory_1.ram, _r_Memory_1.ram, _r_Memory_0.ram]
[junit4:junit4]   2> 223757 T17 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 223757 T17 C0 oass.SolrIndexSearcher.<init> Opening Searcher@2c4ede main
[junit4:junit4]   2> 223758 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@2c4ede main{StandardDirectoryReader(segments_17:103:nrt _q(5.0):C5 _r(5.0):C1)}
[junit4:junit4]   2> 223759 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 223759 T17 C0 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 223761 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2c4ede main{StandardDirectoryReader(segments_17:103:nrt _q(5.0):C5 _r(5.0):C1)}
[junit4:junit4]   2> 223761 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 223762 T17 C0 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {add=[42 (1427552731191574528)],commit=} 0 30
[junit4:junit4]   2> 223763 T11 oascsi.ConcurrentUpdateSolrServer.request blocking for commit/optimize
[junit4:junit4]   2> 223765 T37 oascsi.ConcurrentUpdateSolrServer$Runner.run starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@132b73b
[junit4:junit4]   2> 223772 T20 C0 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=false,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 223774 T20 C0 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_17,generation=43,filenames=[_q.fdx, _q.fdt, _q.si, _r.fnm, _r.fdt, _r.fdx, _q_Memory_0.ram, _r.si, segments_17, _q.fnm, _q_Memory_1.ram, _r_Memory_1.ram, _r_Memory_0.ram]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_18,generation=44,filenames=[_s.fdt, _s.si, _q.fdx, _s_Memory_0.ram, _s.fnm, _q.fdt, _s.fdx, _q.si, _r.fnm, _r.fdt, _r.fdx, _s_Memory_1.ram, _q_Memory_0.ram, _r.si, _q.fnm, _q_Memory_1.ram, _r_Memory_1.ram, _r_Memory_0.ram, segments_18]
[junit4:junit4]   2> 223775 T20 C0 oasc.SolrDeletionPolicy.updateCommits newest commit = 44[_s.fdt, _s.si, _q.fdx, _s_Memory_0.ram, _s.fnm, _q.fdt, _s.fdx, _q.si, _r.fnm, _r.fdt, _r.fdx, _s_Memory_1.ram, _q_Memory_0.ram, _r.si, _q.fnm, _q_Memory_1.ram, _r_Memory_1.ram, _r_Memory_0.ram, segments_18]
[junit4:junit4]   2> 223775 T20 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 223776 T20 C0 oass.SolrIndexSearcher.<init> Opening Searcher@8a88a9 main
[junit4:junit4]   2> 223777 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@8a88a9 main{StandardDirectoryReader(segments_18:105:nrt _q(5.0):C5 _r(5.0):C1 _s(5.0):C1)}
[junit4:junit4]   2> 223777 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 223777 T20 C0 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 223779 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@8a88a9 main{StandardDirectoryReader(segments_18:105:nrt _q(5.0):C5 _r(5.0):C1 _s(5.0):C1)}
[junit4:junit4]   2> 223780 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 224189 T20 C0 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=false,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 224192 T20 C0 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_18,generation=44,filenames=[_s.fdt, _s.si, _q.fdx, _s_Memory_0.ram, _s.fnm, _q.fdt, _s.fdx, _q.si, _r.fnm, _r.fdt, _r.fdx, _s_Memory_1.ram, _q_Memory_0.ram, _r.si, _q.fnm, _q_Memory_1.ram, _r_Memory_1.ram, _r_Memory_0.ram, segments_18]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_19,generation=45,filenames=[_s.fdt, _s.si, _q.fdx, _s_Memory_0.ram, _s.fnm, _q.fdt, _s.fdx, _q.si, _r.fnm, _r.fdt, _r.fdx, _s_Memory_1.ram, _q_Memory_0.ram, _r.si, _q.fnm, _q_Memory_1.ram, segments_19, _r_Memory_1.ram, _r_Memory_0.ram]
[junit4:junit4]   2> 224192 T20 C0 oasc.SolrDeletionPolicy.updateCommits newest commit = 45[_s.fdt, _s.si, _q.fdx, _s_Memory_0.ram, _s.fnm, _q.fdt, _s.fdx, _q.si, _r.fnm, _r.fdt, _r.fdx, _s_Memory_1.ram, _q_Memory_0.ram, _r.si, _q.fnm, _q_Memory_1.ram, segments_19, _r_Memory_1.ram, _r_Memory_0.ram]
[junit4:junit4]   2> 224194 T20 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 224195 T20 C0 oass.SolrIndexSearcher.<init> Opening Searcher@a53502 main
[junit4:junit4]   2> 224196 T20 C0 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 224196 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@a53502 main{StandardDirectoryReader(segments_18:105:nrt _q(5.0):C5 _r(5.0):C1 _s(5.0):C1)}
[junit4:junit4]   2> 224197 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 224197 T20 C0 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=false&commit=true&wt=javabin&version=2&softCommit=false} {add=[43 (1427552731214643200)],commit=,commit=} 0 427
[junit4:junit4]   2> 224200 T37 oascsi.ConcurrentUpdateSolrServer$Runner.run Status for: 43 is 200
[junit4:junit4]   2> 224202 T37 oascsi.ConcurrentUpdateSolrServer$Runner.run finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@132b73b
[junit4:junit4]   2> 224203 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@a53502 main{StandardDirectoryReader(segments_18:105:nrt _q(5.0):C5 _r(5.0):C1 _s(5.0):C1)}
[junit4:junit4]   2> 224205 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 224210 T16 C0 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 224212 T16 C0 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_19,generation=45,filenames=[_s.fdt, _s.si, _q.fdx, _s_Memory_0.ram, _s.fnm, _q.fdt, _s.fdx, _q.si, _r.fnm, _r.fdt, _r.fdx, _s_Memory_1.ram, _q_Memory_0.ram, _r.si, _q.fnm, _q_Memory_1.ram, segments_19, _r_Memory_1.ram, _r_Memory_0.ram]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@869470 lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f8834),segFN=segments_1a,generation=46,filenames=[_s.fdt, _t.fnm, _q.fdx, _s.fnm, _q.fdt, segments_1a, _r.fdt, _r.fnm, _q.si, _t.si, _r.fdx, _s_Memory_1.ram, _r_Memory_0.ram, _r_Memory_1.ram, _s.si, _s_Memory_0.ram, _s.fdx, _t_Memory_0.ram, _t_Memory_1.ram, _t.fdx, _q_Memory_0.ram, _r.si, _q_Memory_1.ram, _q.fnm, _t.fdt]
[junit4:junit4]   2> 224213 T16 C0 oasc.SolrDeletionPolicy.updateCommits newest commit = 46[_s.fdt, _t.fnm, _q.fdx, _s.fnm, _q.fdt, segments_1a, _r.fdt, _r.fnm, _q.si, _t.si, _r.fdx, _s_Memory_1.ram, _r_Memory_0.ram, _r_Memory_1.ram, _s.si, _s_Memory_0.ram, _s.fdx, _t_Memory_0.ram, _t_Memory_1.ram, _t.fdx, _q_Memory_0.ram, _r.si, _q_Memory_1.ram, _q.fnm, _t.fdt]
[junit4:junit4]   2> 224213 T16 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 224214 T16 C0 oass.SolrIndexSearcher.<init> Opening Searcher@acd5d4 main
[junit4:junit4]   2> 224216 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@acd5d4 main{StandardDirectoryReader(segments_1a:107:nrt _q(5.0):C5 _r(5.0):C1 _s(5.0):C1 _t(5.0):C1)}
[junit4:junit4]   2> 224216 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 224216 T16 C0 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 224218 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@acd5d4 main{StandardDirectoryReader(segments_1a:107:nrt _q(5.0):C5 _r(5.0):C1 _s(5.0):C1 _t(5.0):C1)}
[junit4:junit4]   2> 224219 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 224219 T16 C0 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {add=[44 (1427552731672870912)],commit=} 0 11
[junit4:junit4]   2> 224220 T37 oascsi.ConcurrentUpdateSolrServer$Runner.run starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@24ed78
[junit4:junit4]   2> 224220 T11 oascsi.ConcurrentUpdateSolrServer.request blocking for commit/optimize


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

egments_1z,generation=71,filenames=[_1f.fdt, _1f.nvd, segments_1z, _1f_Memory_1.ram, _1f.fdx, _1f.fnm, _1f_SimpleText_0.pst, _1f.si, _1f.nvm, _1f_Memory_0.ram]
[junit4:junit4]   2> 229373 T21 C0 oasc.SolrDeletionPolicy.updateCommits newest commit = 71[_1f.fdt, _1f.nvd, segments_1z, _1f_Memory_1.ram, _1f.fdx, _1f.fnm, _1f_SimpleText_0.pst, _1f.si, _1f.nvm, _1f_Memory_0.ram]
[junit4:junit4]   2> 229375 T21 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 229379 T21 C0 oass.SolrIndexSearcher.<init> Opening Searcher@a9fa9c main
[junit4:junit4]   2> 229380 T21 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 229382 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@a9fa9c main{StandardDirectoryReader(segments_1z:169:nrt _1f(5.0):C1)}
[junit4:junit4]   2> 229383 T23 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 229385 T21 C0 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 229387 T23 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@a9fa9c main{StandardDirectoryReader(segments_1z:169:nrt _1f(5.0):C1)}
[junit4:junit4]   2> 229389 T23 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 229390 T21 C0 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 27
[junit4:junit4]   2> 229397 T16 C0 REQ [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]   2> 229405 T18 C0 REQ [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]   2> 229473 T11 oas.SolrTestCaseJ4.tearDown ###Ending testRealtimeGet
[junit4:junit4]   2> 229486 T11 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=20056641
[junit4:junit4]   2> 229486 T11 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@56182f
[junit4:junit4]   2> 229499 T11 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 229500 T11 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 229500 T11 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 229501 T11 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 229507 T11 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 229507 T11 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 229509 T11 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 229509 T11 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 229515 T11 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=65,autocommit maxTime=15000ms,autocommits=1,soft autocommits=3,optimizes=8,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=1,cumulative_adds=136,cumulative_deletesById=5,cumulative_deletesByQuery=27,cumulative_errors=1}
[junit4:junit4]   2> 229516 T11 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4:junit4]   2> 229517 T11 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 229517 T11 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4:junit4]   2> 229520 T11 C0 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 229521 T11 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 229521 T11 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 229521 T11 oasc.CachingDirectoryFactory.close Closing directory when closing factory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 229521 T11 oasc.CachingDirectoryFactory.closeDirectory Closing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513\index
[junit4:junit4]   2> 229523 T11 oasc.CachingDirectoryFactory.close Closing directory when closing factory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 229523 T11 oasc.CachingDirectoryFactory.closeDirectory Closing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleStreamingBinaryTest-1361420151513
[junit4:junit4]   2> 229523 T11 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 229581 T11 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> NOTE: test params are: codec=Lucene42: {store_0_coordinate=PostingsFormat(name=SimpleText), cat=PostingsFormat(name=MockRandom), manufacturedate_dt=PostingsFormat(name=Memory doPackFST= false), id=PostingsFormat(name=Memory doPackFST= true), sequence_i=PostingsFormat(name=Memory doPackFST= true), name=PostingsFormat(name=Memory doPackFST= true), features=PostingsFormat(name=SimpleText), manu_exact=PostingsFormat(name=Memory doPackFST= true), unicode_s=PostingsFormat(name=SimpleText), manu_id_s=PostingsFormat(name=Memory doPackFST= true), payloads=PostingsFormat(name=SimpleText), price_c____currency=PostingsFormat(name=MockRandom), popularity=PostingsFormat(name=SimpleText), genre_s=PostingsFormat(name=Memory doPackFST= true), author_t=PostingsFormat(name=Memory doPackFST= false), text=PostingsFormat(name=SimpleText), _version_=PostingsFormat(name=Memory doPackFST= false), store_1_coordinate=PostingsFormat(name=Memory doPackFST= false), val_pi=PostingsFormat(name=Memory doPackFST= true), series_t=PostingsFormat(name=Memory doPackFST= false), timestamp_dt=PostingsFormat(name=SimpleText), price=PostingsFormat(name=SimpleText), manu=PostingsFormat(name=Memory doPackFST= true), price_f=PostingsFormat(name=MockRandom), multi_ss=PostingsFormat(name=MockRandom), single_s=PostingsFormat(name=Memory doPackFST= false), inStock=PostingsFormat(name=SimpleText), price_c____amount_raw=PostingsFormat(name=MockRandom)}, docValues:{}, sim=DefaultSimilarity, locale=mk_MK, timezone=Europe/Budapest
[junit4:junit4]   2> NOTE: Windows 7 6.1 x86/Sun Microsystems Inc. 1.6.0_38 (32-bit)/cpus=2,threads=7,free=8805760,total=32571392
[junit4:junit4]   2> NOTE: All tests run in this JVM: [SolrExampleStreamingBinaryTest]
[junit4:junit4] Completed in 230.90s, 26 tests, 1 error <<< FAILURES!

[...truncated 143 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:381: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:361: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:39: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:196: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:447: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1202: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:865: There were test failures: 45 suites, 259 tests, 1 error, 1 ignored (1 assumption)

Total time: 61 minutes 9 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Description set: Java: 32bit/jdk1.6.0_38 -server -XX:+UseSerialGC
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message