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 (64bit/jdk1.7.0_15) - Build # 2672 - Failure!
Date Tue, 19 Mar 2013 15:05:46 GMT
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/2672/
Java: 64bit/jdk1.7.0_15 -XX:+UseParallelGC

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

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

Stack Trace:
org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: https://127.0.0.1:60402/solr
	at __randomizedtesting.SeedInfo.seed([7051AB3EF1F8E3AD:B8B1D6340050307B]: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.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:117)
	at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:116)
	at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:102)
	at org.apache.solr.client.solrj.SolrExampleTests.testAddDelete(SolrExampleTests.java:743)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:601)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:722)
Caused by: org.apache.http.NoHttpResponseException: The target server failed to respond
	at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:95)
	at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:62)
	at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:254)
	at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:289)
	at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:252)
	at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:191)
	at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:300)
	at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:127)
	at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:717)
	at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:522)
	at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:906)
	at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:805)
	at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:784)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:353)
	... 45 more




Build Log:
[...truncated 10046 lines...]
[junit4:junit4] Suite: org.apache.solr.client.solrj.SolrExampleBinaryTest
[junit4:junit4]   2> 0 T19 oas.SolrJettyTestBase.beforeSolrJettyTestBase Randomized ssl (true) and clientAuth (false)
[junit4:junit4]   2> 1 T19 oas.SolrTestCaseJ4.initCore ####initCore
[junit4:junit4]   2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\solrtest-SolrExampleBinaryTest-1363705147613
[junit4:junit4]   2> 3 T19 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4:junit4]   2> 435 T19 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 1088 T19 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4:junit4]   2> 1141 T19 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:60402
[junit4:junit4]   2> 1187 T19 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 1188 T19 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 1188 T19 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr
[junit4:junit4]   2> 1189 T19 oasc.CoreContainer$Initializer.initialize looking for solr config file: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr\solr.xml
[junit4:junit4]   2> 1190 T19 oasc.CoreContainer.<init> New CoreContainer 594581735
[junit4:junit4]   2> 1191 T19 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr\'
[junit4:junit4]   2> 1191 T19 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr\'
[junit4:junit4]   2> 1294 T19 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 1294 T19 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 1295 T19 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 1295 T19 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 1295 T19 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 1296 T19 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 1297 T19 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 1297 T19 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 1299 T19 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 1338 T19 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 1634 T19 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 1793 T30 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr\collection1
[junit4:junit4]   2> 1801 T30 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr\collection1\'
[junit4:junit4]   2> 1884 T30 oasc.SolrConfig.initLibs Adding specified lib dirs to ClassLoader
[junit4:junit4]   2> 1986 T30 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 2107 T30 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 2108 T30 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 2161 T30 oass.IndexSchema.readSchema Schema name=example
[junit4:junit4]   2> 3348 T30 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 3378 T30 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3390 T30 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3469 T30 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 3469 T30 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-SolrExampleBinaryTest-1363705147613\
[junit4:junit4]   2> 3501 T30 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@22cf2994
[junit4:junit4]   2> 3509 T30 oasc.SolrCore.initListeners [collection1] Added SolrEventListener for newSearcher: org.apache.solr.core.QuerySenderListener{queries=[]}
[junit4:junit4]   2> 3509 T30 oasc.SolrCore.initListeners [collection1] Added SolrEventListener for firstSearcher: org.apache.solr.core.QuerySenderListener{queries=[{q=static firstSearcher warming in solrconfig.xml}]}
[junit4:junit4]   2> 3510 T30 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 3510 T30 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleBinaryTest-1363705147613\ forceNew: false
[junit4:junit4]   2> 3511 T30 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-SolrExampleBinaryTest-1363705147613\index/
[junit4:junit4]   2> 3511 T30 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleBinaryTest-1363705147613\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 3514 T30 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleBinaryTest-1363705147613\index forceNew: false
[junit4:junit4]   2> 3518 T30 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 3518 T30 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 3520 T30 oasc.SolrCore.initWriters created json: solr.JSONResponseWriter
[junit4:junit4]   2> 3520 T30 oasc.SolrCore.initWriters adding lazy queryResponseWriter: solr.VelocityResponseWriter
[junit4:junit4]   2> 3521 T30 oasc.SolrCore.initWriters created velocity: solr.VelocityResponseWriter
[junit4:junit4]   2> 3544 T30 oasc.SolrCore.initWriters created xslt: solr.XSLTResponseWriter
[junit4:junit4]   2> 3544 T30 oasr.XSLTResponseWriter.init xsltCacheLifetimeSeconds=5
[junit4:junit4]   2> 3548 T30 oasc.RequestHandlers.initHandlersFromConfig created /select: solr.SearchHandler
[junit4:junit4]   2> 3549 T30 oasc.RequestHandlers.initHandlersFromConfig created /query: solr.SearchHandler
[junit4:junit4]   2> 3550 T30 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 3551 T30 oasc.RequestHandlers.initHandlersFromConfig created /browse: solr.SearchHandler
[junit4:junit4]   2> 3552 T30 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 3553 T30 oasc.RequestHandlers.initHandlersFromConfig created /update/json: solr.JsonUpdateRequestHandler
[junit4:junit4]   2> 3554 T30 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: solr.CSVRequestHandler
[junit4:junit4]   2> 3555 T30 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.extraction.ExtractingRequestHandler
[junit4:junit4]   2> 3557 T30 oasc.RequestHandlers.initHandlersFromConfig created /update/extract: solr.extraction.ExtractingRequestHandler
[junit4:junit4]   2> 3558 T30 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.FieldAnalysisRequestHandler
[junit4:junit4]   2> 3558 T30 oasc.RequestHandlers.initHandlersFromConfig created /analysis/field: solr.FieldAnalysisRequestHandler
[junit4:junit4]   2> 3559 T30 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.DocumentAnalysisRequestHandler
[junit4:junit4]   2> 3566 T30 oasc.RequestHandlers.initHandlersFromConfig created /analysis/document: solr.DocumentAnalysisRequestHandler
[junit4:junit4]   2> 3567 T30 oasc.RequestHandlers.initHandlersFromConfig created /admin/: solr.admin.AdminHandlers
[junit4:junit4]   2> 3568 T30 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: solr.PingRequestHandler
[junit4:junit4]   2> 3569 T30 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 3570 T30 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 3573 T30 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
[junit4:junit4]   2> 3573 T30 oasc.RequestHandlers.initHandlersFromConfig created /spell: solr.SearchHandler
[junit4:junit4]   2> 3573 T30 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
[junit4:junit4]   2> 3574 T30 oasc.RequestHandlers.initHandlersFromConfig created /tvrh: solr.SearchHandler
[junit4:junit4]   2> 3574 T30 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
[junit4:junit4]   2> 3579 T30 oasc.RequestHandlers.initHandlersFromConfig created /terms: solr.SearchHandler
[junit4:junit4]   2> 3580 T30 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
[junit4:junit4]   2> 3580 T30 oasc.RequestHandlers.initHandlersFromConfig created /elevate: solr.SearchHandler
[junit4:junit4]   2> 3592 T30 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 3594 T30 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 3596 T30 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 3606 T30 oass.SolrIndexSearcher.<init> Opening Searcher@11b43ab main
[junit4:junit4]   2> 3607 T30 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleBinaryTest-1363705147613\tlog
[junit4:junit4]   2> 3607 T30 oasu.CommitTracker.<init> Hard AutoCommit: if uncommited for 15000ms; 
[junit4:junit4]   2> 3608 T30 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 3611 T30 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 3612 T30 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> 3612 T30 oashc.SpellCheckComponent.inform No queryConverter defined, using default converter
[junit4:junit4]   2> 3615 T30 oashc.QueryElevationComponent.inform Loading QueryElevation from: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr\collection1\conf\elevate.xml
[junit4:junit4]   2> 3646 T30 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 3660 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@11b43ab main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> ASYNC  NEW_CORE C1 name=collection1 org.apache.solr.core.SolrCore@2aaa5483
[junit4:junit4]   2> 3663 T31 C1 REQ [collection1] webapp=null path=null params={event=firstSearcher&q=static+firstSearcher+warming+in+solrconfig.xml&distrib=false} hits=0 status=0 QTime=3 
[junit4:junit4]   2> 3663 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 3664 T31 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default
[junit4:junit4]   2> 3665 T31 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak
[junit4:junit4]   2> 3667 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@11b43ab main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 3673 T30 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 3679 T19 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0
[junit4:junit4]   2> 3679 T19 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 3680 T19 oas.SolrJettyTestBase.createJetty Jetty Assigned Port#60402
[junit4:junit4]   2> 3694 T19 oas.SolrTestCaseJ4.setUp ###Starting testAddDelete
[junit4:junit4]   2> 3729 T19 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 4802 T28 C1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 4803 T28 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 4806 T28 C1 oass.SolrIndexSearcher.<init> Opening Searcher@6f7e421d realtime
[junit4:junit4]   2> 4807 T28 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1429948493890322432)} 0 23
[junit4:junit4]   2> 4892 T28 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 & 222 (1429948493989937152)]} 0 2
[junit4:junit4]   2> 4912 T25 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 4918 T25 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_2,generation=2,filenames=[_0.fnm, _0_Lucene41_0.doc, segments_2, _0.fdx, _0.si, _0_Lucene41_0.tim, _0.fdt, _0_Lucene41_0.tip]
[junit4:junit4]   2> 4919 T25 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[_0.fnm, _0_Lucene41_0.doc, segments_2, _0.fdx, _0.si, _0_Lucene41_0.tim, _0.fdt, _0_Lucene41_0.tip]
[junit4:junit4]   2> 4921 T25 C1 oass.SolrIndexSearcher.<init> Opening Searcher@547b471e main
[junit4:junit4]   2> 4923 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@547b471e main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):C1)}
[junit4:junit4]   2> 4923 T25 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 4924 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 4927 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@547b471e main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):C1)}
[junit4:junit4]   2> 4930 T25 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 18
[junit4:junit4]   2> 4982 T24 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=45 
[junit4:junit4]   2> 5013 T26 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {delete=[0 & 222 (-1429948494116814848)]} 0 2
[junit4:junit4]   2> 5018 T27 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 5068 T27 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_2,generation=2,filenames=[_0.fnm, _0_Lucene41_0.doc, segments_2, _0.fdx, _0.si, _0_Lucene41_0.tim, _0.fdt, _0_Lucene41_0.tip]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_3,generation=3,filenames=[segments_3]
[junit4:junit4]   2> 5069 T27 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 3[segments_3]
[junit4:junit4]   2> 5071 T27 C1 oass.SolrIndexSearcher.<init> Opening Searcher@362dbac8 main
[junit4:junit4]   2> 5072 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@362dbac8 main{StandardDirectoryReader(segments_3:5:nrt)}
[junit4:junit4]   2> 5072 T27 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 5073 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 5076 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@362dbac8 main{StandardDirectoryReader(segments_3:5:nrt)}
[junit4:junit4]   2> 5079 T27 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 61
[junit4:junit4]   2> 5097 T28 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 5104 T29 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 & 222 (1429948494211186688)]} 0 2
[junit4:junit4]   2> 5110 T25 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 5114 T25 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_3,generation=3,filenames=[segments_3]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_4,generation=4,filenames=[_1.fdx, _1_Lucene41_0.doc, _1.fdt, _1.si, _1_Lucene41_0.tim, _1_Lucene41_0.tip, _1.fnm, segments_4]
[junit4:junit4]   2> 5114 T25 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 4[_1.fdx, _1_Lucene41_0.doc, _1.fdt, _1.si, _1_Lucene41_0.tim, _1_Lucene41_0.tip, _1.fnm, segments_4]
[junit4:junit4]   2> 5116 T25 C1 oass.SolrIndexSearcher.<init> Opening Searcher@529fcfc1 main
[junit4:junit4]   2> 5118 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@529fcfc1 main{StandardDirectoryReader(segments_4:7:nrt _1(5.0):C1)}
[junit4:junit4]   2> 5119 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 5119 T25 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 5123 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@529fcfc1 main{StandardDirectoryReader(segments_4:7:nrt _1(5.0):C1)}
[junit4:junit4]   2> 5124 T25 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 14
[junit4:junit4]   2> 5130 T24 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 5251 T26 C1 oass.SolrIndexSearcher.<init> Opening Searcher@1ce2dd88 realtime
[junit4:junit4]   2> 5252 T26 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=id:"0\ \&\ 222" (-1429948494246838272)} 0 116
[junit4:junit4]   2> 5257 T27 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 5258 T27 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_4,generation=4,filenames=[_1.fdx, _1_Lucene41_0.doc, _1.fdt, _1.si, _1_Lucene41_0.tim, _1_Lucene41_0.tip, _1.fnm, segments_4]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_5,generation=5,filenames=[segments_5]
[junit4:junit4]   2> 5259 T27 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 5[segments_5]
[junit4:junit4]   2> 5260 T27 C1 oass.SolrIndexSearcher.<init> Opening Searcher@6dfc7502 main
[junit4:junit4]   2> 5261 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@6dfc7502 main{StandardDirectoryReader(segments_4:9:nrt)}
[junit4:junit4]   2> 5261 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 5267 T27 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 5276 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6dfc7502 main{StandardDirectoryReader(segments_4:9:nrt)}
[junit4:junit4]   2> 5283 T27 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 26
[junit4:junit4]   2> 5290 T28 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 5302 T29 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 & 222 (1429948494418804736)]} 0 2
[junit4:junit4]   2> 5312 T25 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1 & 222 (1429948494429290496)]} 0 2
[junit4:junit4]   2> 19911 T33 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 19914 T33 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_5,generation=5,filenames=[segments_5]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_6,generation=6,filenames=[_2.si, _2_Lucene41_0.tim, _2.fdt, _2_Lucene41_0.doc, _2_Lucene41_0.tip, _2.fdx, segments_6, _2.fnm]
[junit4:junit4]   2> 19914 T33 oasc.SolrDeletionPolicy.updateCommits newest commit = 6[_2.si, _2_Lucene41_0.tim, _2.fdt, _2_Lucene41_0.doc, _2_Lucene41_0.tip, _2.fdx, segments_6, _2.fnm]
[junit4:junit4]   2> 19916 T33 oass.SolrIndexSearcher.<init> Opening Searcher@6f82e5fa realtime
[junit4:junit4]   2> 19916 T33 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 205516 T19 oas.SolrTestCaseJ4.tearDown ###Ending testAddDelete
[junit4:junit4]   2> 205520 T24 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2 & 222 (1429948704362594304)]} 0 3
[junit4:junit4]   2> 205529 T24 C1 oasc.SolrException.log SEVERE null:org.eclipse.jetty.io.EofException
[junit4:junit4]   2> 		at org.eclipse.jetty.server.HttpOutput.write(HttpOutput.java:142)
[junit4:junit4]   2> 		at org.eclipse.jetty.server.HttpOutput.write(HttpOutput.java:107)
[junit4:junit4]   2> 		at org.apache.solr.common.util.FastOutputStream.flush(FastOutputStream.java:214)
[junit4:junit4]   2> 		at org.apache.solr.common.util.FastOutputStream.flushBuffer(FastOutputStream.java:207)
[junit4:junit4]   2> 		at org.apache.solr.common.util.JavaBinCodec.marshal(JavaBinCodec.java:94)
[junit4:junit4]   2> 		at org.apache.solr.response.BinaryResponseWriter.write(BinaryResponseWriter.java:52)
[junit4:junit4]   2> 		at org.apache.solr.servlet.SolrDispatchFilter.writeResponse(SolrDispatchFilter.java:620)
[junit4:junit4]   2> 		at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:358)
[junit4:junit4]   2> 		at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:141)
[junit4:junit4]   2> 		at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1307)
[junit4:junit4]   2> 		at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:135)
[junit4:junit4]   2> 		at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1307)
[junit4:junit4]   2> 		at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:453)
[junit4:junit4]   2> 		at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229)
[junit4:junit4]   2> 		at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
[junit4:junit4]   2> 		at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:275)
[junit4:junit4]   2> 		at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1074)
[junit4:junit4]   2> 		at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:382)
[junit4:junit4]   2> 		at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
[junit4:junit4]   2> 		at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1006)
[junit4:junit4]   2> 		at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
[junit4:junit4]   2> 		at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
[junit4:junit4]   2> 		at org.eclipse.jetty.server.Server.handle(Server.java:365)
[junit4:junit4]   2> 		at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:485)
[junit4:junit4]   2> 		at org.eclipse.jetty.server.AbstractHttpConnection.content(AbstractHttpConnection.java:937)
[junit4:junit4]   2> 		at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.content(AbstractHttpConnection.java:998)
[junit4:junit4]   2> 		at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:948)
[junit4:junit4]   2> 		at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:240)
[junit4:junit4]   2> 		at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
[junit4:junit4]   2> 		at org.eclipse.jetty.io.nio.SslConnection.handle(SslConnection.java:196)
[junit4:junit4]   2> 		at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:628)
[junit4:junit4]   2> 		at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52)
[junit4:junit4]   2> 		at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
[junit4:junit4]   2> 		at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
[junit4:junit4]   2> 		at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]   2> 	
[junit4:junit4]   2> 205531 T24 C1 oasc.SolrException.log SEVERE null:org.eclipse.jetty.io.EofException
[junit4:junit4]   2> 		at org.eclipse.jetty.server.HttpOutput.write(HttpOutput.java:142)
[junit4:junit4]   2> 		at org.eclipse.jetty.server.HttpOutput.write(HttpOutput.java:107)
[junit4:junit4]   2> 		at org.apache.solr.common.util.FastOutputStream.flush(FastOutputStream.java:214)
[junit4:junit4]   2> 		at org.apache.solr.common.util.FastOutputStream.flushBuffer(FastOutputStream.java:207)
[junit4:junit4]   2> 		at org.apache.solr.common.util.JavaBinCodec.marshal(JavaBinCodec.java:94)
[junit4:junit4]   2> 		at org.apache.solr.response.BinaryResponseWriter.write(BinaryResponseWriter.java:52)
[junit4:junit4]   2> 		at org.apache.solr.servlet.SolrDispatchFilter.writeResponse(SolrDispatchFilter.java:620)
[junit4:junit4]   2> 		at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:358)
[junit4:junit4]   2> 		at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:141)
[junit4:junit4]   2> 		at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1307)
[junit4:junit4]   2> 		at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:135)
[junit4:junit4]   2> 		at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1307)
[junit4:junit4]   2> 		at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:453)
[junit4:junit4]   2> 		at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229)
[junit4:junit4]   2> 		at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
[junit4:junit4]   2> 		at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:275)
[junit4:junit4]   2> 		at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1074)
[junit4:junit4]   2> 		at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:382)
[junit4:junit4]   2> 		at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
[junit4:junit4]   2> 		at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1006)
[junit4:junit4]   2> 		at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
[junit4:junit4]   2> 		at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
[junit4:junit4]   2> 		at org.eclipse.jetty.server.Server.handle(Server.java:365)
[junit4:junit4]   2> 		at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:485)
[junit4:junit4]   2> 		at org.eclipse.jetty.server.AbstractHttpConnection.content(AbstractHttpConnection.java:937)
[junit4:junit4]   2> 		at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.content(AbstractHttpConnection.java:998)
[junit4:junit4]   2> 		at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:948)
[junit4:junit4]   2> 		at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:240)
[junit4:junit4]   2> 		at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
[junit4:junit4]   2> 		at org.eclipse.jetty.io.nio.SslConnection.handle(SslConnection.java:196)
[junit4:junit4]   2> 		at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:628)
[junit4:junit4]   2> 		at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52)
[junit4:junit4]   2> 		at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
[junit4:junit4]   2> 		at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
[junit4:junit4]   2> 		at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]   2> 	
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=SolrExampleBinaryTest -Dtests.method=testAddDelete -Dtests.seed=7051AB3EF1F8E3AD -Dtests.slow=true -Dtests.locale=pt_BR -Dtests.timezone=Europe/Vatican -Dtests.file.encoding=UTF-8
[junit4:junit4] ERROR    202s | SolrExampleBinaryTest.testAddDelete <<<
[junit4:junit4]    > Throwable #1: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: https://127.0.0.1:60402/solr
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([7051AB3EF1F8E3AD:B8B1D6340050307B]: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.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:117)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:116)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:102)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.SolrExampleTests.testAddDelete(SolrExampleTests.java:743)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
[junit4:junit4]    > 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4:junit4]    > 	at java.lang.reflect.Method.invoke(Method.java:601)
[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:722)
[junit4:junit4]    > Caused by: org.apache.http.NoHttpResponseException: The target server failed to respond
[junit4:junit4]    > 	at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:95)
[junit4:junit4]    > 	at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:62)
[junit4:junit4]    > 	at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:254)
[junit4:junit4]    > 	at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:289)
[junit4:junit4]    > 	at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:252)
[junit4:junit4]    > 	at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:191)
[junit4:junit4]    > 	at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:300)
[junit4:junit4]    > 	at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:127)
[junit4:junit4]    > 	at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:717)
[junit4:junit4]    > 	at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:522)
[junit4:junit4]    > 	at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:906)
[junit4:junit4]    > 	at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:805)
[junit4:junit4]    > 	at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:784)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:353)
[junit4:junit4]    > 	... 45 more
[junit4:junit4]   2> 205623 T19 oas.SolrTestCaseJ4.setUp ###Starting testStreamingRequest
[junit4:junit4]   2> 205671 T27 C1 oass.SolrIndexSearcher.<init> Opening Searcher@7792980f realtime
[junit4:junit4]   2> 205673 T27 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1429948704502054912)} 0 22
[junit4:junit4]   2> 205681 T28 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 205682 T28 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_6,generation=6,filenames=[_2.si, _2_Lucene41_0.tim, _2.fdt, _2_Lucene41_0.doc, _2_Lucene41_0.tip, _2.fdx, segments_6, _2.fnm]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_7,generation=7,filenames=[segments_7]
[junit4:junit4]   2> 205682 T28 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 7[segments_7]
[junit4:junit4]   2> 205684 T28 C1 oass.SolrIndexSearcher.<init> Opening Searcher@52afa5c2 main
[junit4:junit4]   2> 205684 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@52afa5c2 main{StandardDirectoryReader(segments_6:15:nrt)}
[junit4:junit4]   2> 205686 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 205684 T28 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 205689 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@52afa5c2 main{StandardDirectoryReader(segments_6:15:nrt)}
[junit4:junit4]   2> 205691 T28 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 12
[junit4:junit4]   2> 205699 T29 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 205748 T25 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 205769 T25 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_7,generation=7,filenames=[segments_7]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_8,generation=8,filenames=[_4.fdx, _4.nvd, segments_8, _4_Lucene41_0.doc, _4.fnm, _4_Lucene41_0.pos, _4.si, _4.fdt, _4_Lucene41_0.tip, _4.nvm, _4_Lucene41_0.tim]
[junit4:junit4]   2> 205770 T25 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 8[_4.fdx, _4.nvd, segments_8, _4_Lucene41_0.doc, _4.fnm, _4_Lucene41_0.pos, _4.si, _4.fdt, _4_Lucene41_0.tip, _4.nvm, _4_Lucene41_0.tim]
[junit4:junit4]   2> 205815 T25 C1 oass.SolrIndexSearcher.<init> Opening Searcher@1d6899df main
[junit4:junit4]   2> 205817 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1d6899df main{StandardDirectoryReader(segments_8:17:nrt _4(5.0):C10)}
[junit4:junit4]   2> 205817 T25 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 205817 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 205821 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1d6899df main{StandardDirectoryReader(segments_8:17:nrt _4(5.0):C10)}
[junit4:junit4]   2> 205823 T25 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {add=[0 (1429948704559726592), 1 (1429948704596426752), 2 (1429948704596426753), 3 (1429948704597475328), 4 (1429948704597475329), 5 (1429948704598523904), 6 (1429948704598523905), 7 (1429948704599572480), 8 (1429948704602718208), 9 (1429948704602718209)],commit=} 0 118
[junit4:junit4]   2> 205846 T26 C1 REQ [collection1] webapp=/solr path=/select params={fl=id,score,_docid_&q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=18 
[junit4:junit4]   2> 205858 T24 C1 REQ [collection1] webapp=/solr path=/select params={fl=id,score,_docid_&q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=1 
[junit4:junit4]   2> 205862 T19 oas.SolrTestCaseJ4.tearDown ###Ending testStreamingRequest
[junit4:junit4]   2> 205888 T19 oas.SolrTestCaseJ4.setUp ###Starting testGetEmptyResults
[junit4:junit4]   2> 205896 T27 C1 oass.SolrIndexSearcher.<init> Opening Searcher@66b98ed9 realtime
[junit4:junit4]   2> 205897 T27 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1429948704756858880)} 0 3
[junit4:junit4]   2> 205905 T28 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 205905 T28 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_8,generation=8,filenames=[_4.fdx, _4.nvd, segments_8, _4_Lucene41_0.doc, _4.fnm, _4_Lucene41_0.pos, _4.si, _4.fdt, _4_Lucene41_0.tip, _4.nvm, _4_Lucene41_0.tim]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_9,generation=9,filenames=[segments_9]
[junit4:junit4]   2> 205906 T28 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 9[segments_9]
[junit4:junit4]   2> 205907 T28 C1 oass.SolrIndexSearcher.<init> Opening Searcher@19b2364 main
[junit4:junit4]   2> 205910 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@19b2364 main{StandardDirectoryReader(segments_8:19:nrt)}
[junit4:junit4]   2> 205910 T28 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 205910 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 205918 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@19b2364 main{StandardDirectoryReader(segments_8:19:nrt)}
[junit4:junit4]   2> 205922 T28 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 19
[junit4:junit4]   2> 205935 T25 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id1 (1429948704791461888)]} 0 8
[junit4:junit4]   2> 205949 T26 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id2 (1429948704810336256)]} 0 4
[junit4:junit4]   2> 205956 T24 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 205963 T24 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_9,generation=9,filenames=[segments_9]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_a,generation=10,filenames=[_5.si, _5.fdt, _5.nvm, _5.fnm, _5_Lucene41_0.tim, segments_a, _5_Lucene41_0.pos, _5_Lucene41_0.tip, _5_Lucene41_0.doc, _5.fdx, _5.nvd]
[junit4:junit4]   2> 205965 T24 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 10[_5.si, _5.fdt, _5.nvm, _5.fnm, _5_Lucene41_0.tim, segments_a, _5_Lucene41_0.pos, _5_Lucene41_0.tip, _5_Lucene41_0.doc, _5.fdx, _5.nvd]
[junit4:junit4]   2> 205967 T24 C1 oass.SolrIndexSearcher.<init> Opening Searcher@4018ecf3 main
[junit4:junit4]   2> 205968 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@4018ecf3 main{StandardDirectoryReader(segments_a:21:nrt _5(5.0):C2)}
[junit4:junit4]   2> 205968 T24 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 205970 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 205973 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4018ecf3 main{StandardDirectoryReader(segments_a:21:nrt _5(5.0):C2)}
[junit4:junit4]   2> 205974 T24 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 18
[junit4:junit4]   2> 206004 T27 C1 REQ [collection1] webapp=/solr path=/select params={fl=foofoofoo&q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=23 
[junit4:junit4]   2> 206005 T19 oas.SolrTestCaseJ4.tearDown ###Ending testGetEmptyResults
[junit4:junit4]   2> 206025 T19 oas.SolrTestCaseJ4.setUp ###Starting testPingHandler
[junit4:junit4]   2> 206032 T28 C1 oass.SolrIndexSearcher.<init> Opening Searcher@5dbb9d2e realtime
[junit4:junit4]   2> 206034 T28 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1429948704898416640)} 0 5
[junit4:junit4]   2> 206039 T29 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 206040 T29 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_a,generation=10,filenames=[_5.si, _5.fdt, _5.nvm, _5.fnm, _5_Lucene41_0.tim, segments_a, _5_Lucene41_0.pos, _5_Lucene41_0.tip, _5_Lucene41_0.doc, _5.fdx, _5.nvd]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_b,generation=11,filenames=[segments_b]
[junit4:junit4]   2> 206040 T29 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 11[segments_b]
[junit4:junit4]   2> 206043 T29 C1 oass.SolrIndexSearcher.<init> Opening Searcher@365fc710 main
[junit4:junit4]   2> 206044 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@365fc710 main{StandardDirectoryReader(segments_a:23:nrt)}
[junit4:junit4]   2> 206044 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 206048 T29 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 206050 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@365fc710 main{StandardDirectoryReader(segments_a:23:nrt)}
[junit4:junit4]   2> 206054 T29 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 15
[junit4:junit4]   2> 206063 T25 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=2 
[junit4:junit4]   2> 206092 T26 C1 REQ [collection1] webapp=/solr path=/admin/ping params={wt=javabin&version=2} hits=0 status=0 QTime=15 
[junit4:junit4]   2> 206092 T26 C1 REQ [collection1] webapp=/solr path=/admin/ping params={wt=javabin&version=2} status=0 QTime=15 
[junit4:junit4]   2> 206094 T19 oas.SolrTestCaseJ4.tearDown ###Ending testPingHandler
[junit4:junit4]   2> 206116 T19 oas.SolrTestCaseJ4.setUp ###Starting testAddRetrieve
[junit4:junit4]   2> 206122 T24 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1429948704993837056)} 0 2
[junit4:junit4]   2> 206135 T27 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id1 (1429948705001177088), id2 (1429948705005371392)]} 0 8
[junit4:junit4]   2> 206140 T28 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 206146 T28 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_b,generation=11,filenames=[segments_b]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_c,generation=12,filenames=[_6.nvd, segments_c, _6.fdx, _6_Lucene41_0.doc, _6.si, _6.nvm, _6.fnm, _6_Lucene41_0.tim, _6.fdt, _6_Lucene41_0.tip, _6_Lucene41_0.pos]
[junit4:junit4]   2> 206147 T28 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 12[_6.nvd, segments_c, _6.fdx, _6_Lucene41_0.doc, _6.si, _6.nvm, _6.fnm, _6_Lucene41_0.tim, _6.fdt, _6_Lucene41_0.tip, _6_Lucene41_0.pos]
[junit4:junit4]   2> 206148 T28 C1 oass.SolrIndexSearcher.<init> Opening Searcher@62facd6a main
[junit4:junit4]   2> 206151 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@62facd6a main{StandardDirectoryReader(segments_c:25:nrt _6(5.0):C2)}
[junit4:junit4]   2> 206152 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 206152 T28 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 206156 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@62facd6a main{StandardDirectoryReader(segments_c:25:nrt _6(5.0):C2)}
[junit4:junit4]   2> 206156 T28 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 16
[junit4:junit4]   2> 206346 T29 C1 REQ [collection1] webapp=/solr path=/select params={sort=price+asc&q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=162 
[junit4:junit4]   2> 206361 T26 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id1 (1429948705240252416), id2 (1429948705245495296)]} 0 7
[junit4:junit4]   2> 206367 T24 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 206378 T24 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_c,generation=12,filenames=[_6.nvd, segments_c, _6.fdx, _6_Lucene41_0.doc, _6.si, _6.nvm, _6.fnm, _6_Lucene41_0.tim, _6.fdt, _6_Lucene41_0.tip, _6_Lucene41_0.pos]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_d,generation=13,filenames=[_7.fnm, segments_d, _7.nvd, _7.si, _7_Lucene41_0.tim, _7.fdx, _7_Lucene41_0.pos, _7_Lucene41_0.tip, _7.fdt, _7_Lucene41_0.doc, _7.nvm]
[junit4:junit4]   2> 206379 T24 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 13[_7.fnm, segments_d, _7.nvd, _7.si, _7_Lucene41_0.tim, _7.fdx, _7_Lucene41_0.pos, _7_Lucene41_0.tip, _7.fdt, _7_Lucene41_0.doc, _7.nvm]
[junit4:junit4]   2> 206383 T24 C1 oass.SolrIndexSearcher.<init> Opening Searcher@160e619d main
[junit4:junit4]   2> 206388 T24 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 206389 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@160e619d main{StandardDirectoryReader(segments_d:29:nrt _7(5.0):C2)}
[junit4:junit4]   2> 206390 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 206399 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@160e619d main{StandardDirectoryReader(segments_d:29:nrt _7(5.0):C2)}
[junit4:junit4]   2> 206404 T24 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 37
[junit4:junit4]   2> 206410 T27 C1 REQ [collection1] webapp=/solr path=/select params={sort=price+asc&q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=0 
[junit4:junit4]   2> 206485 T28 C1 REQ [collection1] webapp=/solr path=/select params={sort=price+asc&q=name:h?llo&wt=javabin&version=2} hits=1 status=0 QTime=66 
[junit4:junit4]   2> 206487 T19 oas.SolrTestCaseJ4.tearDown ###Ending testAddRetrieve
[junit4:junit4]   2> 206528 T19 oas.SolrTestCaseJ4.setUp ###Starting testPivotFacetsMissing
[junit4:junit4]   2> 206536 T29 C1 oass.SolrIndexSearcher.<init> Opening Searcher@3000273d realtime
[junit4:junit4]   2> 206537 T29 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1429948705425850368)} 0 5
[junit4:junit4]   2> 206543 T25 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 206545 T25 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_d,generation=13,filenames=[_7.fnm, segments_d, _7.nvd, _7.si, _7_Lucene41_0.tim, _7.fdx, _7_Lucene41_0.pos, _7_Lucene41_0.tip, _7.fdt, _7_Lucene41_0.doc, _7.nvm]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_e,generation=14,filenames=[segments_e]
[junit4:junit4]   2> 206547 T25 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 14[segments_e]
[junit4:junit4]   2> 206548 T25 C1 oass.SolrIndexSearcher.<init> Opening Searcher@4c6041b2 main
[junit4:junit4]   2> 206552 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@4c6041b2 main{StandardDirectoryReader(segments_d:31:nrt)}
[junit4:junit4]   2> 206553 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 206554 T25 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 206557 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4c6041b2 main{StandardDirectoryReader(segments_d:31:nrt)}
[junit4:junit4]   2> 206561 T25 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 18
[junit4:junit4]   2> 206569 T26 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 206602 T24 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1 (1429948705477230592), 2 (1429948705482473472), 3 (1429948705483522048), 4 (1429948705487716352), 5 (1429948705488764928), 6 (1429948705491910656), 7 (1429948705492959232), 8 (1429948705494007808), 9 (1429948705495056384), 10 (1429948705495056385), ... (12 adds)]} 0 22
[junit4:junit4]   2> 206613 T27 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 206627 T27 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_e,generation=14,filenames=[segments_e]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_f,generation=15,filenames=[_8.si, _8_Lucene41_0.doc, segments_f, _8_Lucene41_0.pos, _8.fnm, _8.fdx, _8.nvd, _8_Lucene41_0.tim, _8.nvm, _8.fdt, _8_Lucene41_0.tip]
[junit4:junit4]   2> 206629 T27 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 15[_8.si, _8_Lucene41_0.doc, segments_f, _8_Lucene41_0.pos, _8.fnm, _8.fdx, _8.nvd, _8_Lucene41_0.tim, _8.nvm, _8.fdt, _8_Lucene41_0.tip]
[junit4:junit4]   2> 206632 T27 C1 oass.SolrIndexSearcher.<init> Opening Searcher@5ff1769d main
[junit4:junit4]   2> 206633 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@5ff1769d main{StandardDirectoryReader(segments_f:33:nrt _8(5.0):C12)}
[junit4:junit4]   2> 206634 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 206634 T27 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 206639 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5ff1769d main{StandardDirectoryReader(segments_f:33:nrt _8(5.0):C12)}
[junit4:junit4]   2> 206641 T27 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 29
[junit4:junit4]   2> 206805 T28 C1 oasr.UnInvertedField.<init> UnInverted multi-valued field {field=features,memSize=4521,tindexSize=43,time=14,phase1=14,nTerms=2,bigTerms=2,termInstances=0,uses=0}
[junit4:junit4]   2> 206844 T28 C1 oasr.UnInvertedField.<init> UnInverted multi-valued field {field=cat,memSize=4515,tindexSize=41,time=1,phase1=1,nTerms=2,bigTerms=2,termInstances=0,uses=0}
[junit4:junit4]   2> 206849 T28 C1 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=187 
[junit4:junit4]   2> 206936 T25 C1 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=68 
[junit4:junit4]   2> 206940 T19 oas.SolrTestCaseJ4.tearDown ###Ending testPivotFacetsMissing
[junit4:junit4]   2> 206959 T19 oas.SolrTestCaseJ4.setUp ###Starting testSetNullUpdateOrder
[junit4:junit4]   2> 206968 T26 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[testSetNullUpdateOrder (1429948705878835200)]} 0 5
[junit4:junit4]   2> 206986 T24 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 206993 T24 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_f,generation=15,filenames=[_8.si, _8_Lucene41_0.doc, segments_f, _8_Lucene41_0.pos, _8.fnm, _8.fdx, _8.nvd, _8_Lucene41_0.tim, _8.nvm, _8.fdt, _8_Lucene41_0.tip]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_g,generation=16,filenames=[_9.fnm, _9.fdt, _8.nvd, _9_Lucene41_0.tip, _9.fdx, segments_g, _8.si, _8_Lucene41_0.doc, _8.fnm, _8_Lucene41_0.pos, _9_Lucene41_0.doc, _9.si, _8.fdx, _8_Lucene41_0.tim, _8.nvm, _9_Lucene41_0.tim, _8_Lucene41_0.tip, _8.fdt]
[junit4:junit4]   2> 206994 T24 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 16[_9.fnm, _9.fdt, _8.nvd, _9_Lucene41_0.tip, _9.fdx, segments_g, _8.si, _8_Lucene41_0.doc, _8.fnm, _8_Lucene41_0.pos, _9_Lucene41_0.doc, _9.si, _8.fdx, _8_Lucene41_0.tim, _8.nvm, _9_Lucene41_0.tim, _8_Lucene41_0.tip, _8.fdt]
[junit4:junit4]   2> 207002 T24 C1 oass.SolrIndexSearcher.<init> Opening Searcher@25cd81a main
[junit4:junit4]   2> 207005 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@25cd81a main{StandardDirectoryReader(segments_g:35:nrt _8(5.0):C12 _9(5.0):C1)}
[junit4:junit4]   2> 207006 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 207010 T24 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 207012 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@25cd81a main{StandardDirectoryReader(segments_g:35:nrt _8(5.0):C12 _9(5.0):C1)}
[junit4:junit4]   2> 207013 T24 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 27
[junit4:junit4]   2> 207105 T27 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[testSetNullUpdateOrder (1429948706024587264)]} 0 35
[junit4:junit4]   2> 207112 T28 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 207122 T28 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_g,generation=16,filenames=[_9.fnm, _9.fdt, _8.nvd, _9_Lucene41_0.tip, _9.fdx, segments_g, _8.si, _8_Lucene41_0.doc, _8.fnm, _8_Lucene41_0.pos, _9_Lucene41_0.doc, _9.si, _8.fdx, _8_Lucene41_0.tim, _8.nvm, _9_Lucene41_0.tim, _8_Lucene41_0.tip, _8.fdt]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_h,generation=17,filenames=[_a.fdt, _a.si, _8.nvd, _a.fdx, segments_h, _8.si, _a_Lucene41_0.tim, _8_Lucene41_0.doc, _8.fnm, _8_Lucene41_0.pos, _8.fdx, _8_Lucene41_0.tim, _a_Lucene41_0.tip, _8.nvm, _a.fnm, _a_Lucene41_0.doc, _8_Lucene41_0.tip, _8.fdt]
[junit4:junit4]   2> 207123 T28 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 17[_a.fdt, _a.si, _8.nvd, _a.fdx, segments_h, _8.si, _a_Lucene41_0.tim, _8_Lucene41_0.doc, _8.fnm, _8_Lucene41_0.pos, _8.fdx, _8_Lucene41_0.tim, _a_Lucene41_0.tip, _8.nvm, _a.fnm, _a_Lucene41_0.doc, _8_Lucene41_0.tip, _8.fdt]
[junit4:junit4]   2> 207126 T28 C1 oass.SolrIndexSearcher.<init> Opening Searcher@2e16d41f main
[junit4:junit4]   2> 207128 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@2e16d41f main{StandardDirectoryReader(segments_h:39:nrt _8(5.0):C12 _a(5.0):C1)}
[junit4:junit4]   2> 207130 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 207136 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2e16d41f main{StandardDirectoryReader(segments_h:39:nrt _8(5.0):C12 _a(5.0):C1)}
[junit4:junit4]   2> 207139 T28 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 207141 T28 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 29
[junit4:junit4]   2> 207149 T29 C1 REQ [collection1] webapp=/solr path=/select params={q=id:testSetNullUpdateOrder&wt=javabin&version=2} hits=1 status=0 QTime=2 
[junit4:junit4]   2> 207153 T19 oas.SolrTestCaseJ4.tearDown ###Ending testSetNullUpdateOrder
[junit4:junit4]   2> 207274 T19 oas.SolrTestCaseJ4.setUp ###Starting testLukeHandler
[junit4:junit4]   2> 207284 T25 C1 oass.SolrIndexSearcher.<init> Opening Searcher@42690926 realtime
[junit4:junit4]   2> 207285 T25 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1429948706210185216)} 0 5
[junit4:junit4]   2> 207296 T24 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID0 (1429948706222768128)]} 0 5
[junit4:junit4]   2> 207308 T27 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID1 (1429948706236399616)]} 0 4
[junit4:junit4]   2> 207317 T28 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID2 (1429948706248982528)]} 0 1
[junit4:junit4]   2> 207331 T29 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID3 (1429948706261565440)]} 0 2
[junit4:junit4]   2> 207340 T25 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID4 (1429948706273099776)]} 0 1
[junit4:junit4]   2> 207351 T26 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 207356 T26 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_h,generation=17,filenames=[_a.fdt, _a.si, _8.nvd, _a.fdx, segments_h, _8.si, _a_Lucene41_0.tim, _8_Lucene41_0.doc, _8.fnm, _8_Lucene41_0.pos, _8.fdx, _8_Lucene41_0.tim, _a_Lucene41_0.tip, _8.nvm, _a.fnm, _a_Lucene41_0.doc, _8_Lucene41_0.tip, _8.fdt]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_i,generation=18,filenames=[_b.si, _b_Lucene41_0.tip, _b.fnm, _b.fdt, _b_Lucene41_0.doc, _b_Lucene41_0.tim, segments_i, _b.fdx]
[junit4:junit4]   2> 207358 T26 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 18[_b.si, _b_Lucene41_0.tip, _b.fnm, _b.fdt, _b_Lucene41_0.doc, _b_Lucene41_0.tim, segments_i, _b.fdx]
[junit4:junit4]   2> 207362 T26 C1 oass.SolrIndexSearcher.<init> Opening Searcher@51694079 main
[junit4:junit4]   2> 207366 T26 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 207367 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@51694079 main{StandardDirectoryReader(segments_i:43:nrt _b(5.0):C5)}
[junit4:junit4]   2> 207367 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 207374 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@51694079 main{StandardDirectoryReader(segments_i:43:nrt _b(5.0):C5)}
[junit4:junit4]   2> 207375 T26 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 25
[junit4:junit4]   2> 207387 T24 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=5 status=0 QTime=2 
[junit4:junit4]   2> 207438 T27 C1 REQ [collection1] webapp=/solr path=/admin/luke params={wt=javabin&version=2} status=0 QTime=17 
[junit4:junit4]   2> 207459 T28 C1 REQ [collection1] webapp=/solr path=/admin/luke params={show=schema&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> 207537 T19 oas.SolrTestCaseJ4.tearDown ###Ending testLukeHandler
[junit4:junit4]   2> 207562 T19 oas.SolrTestCaseJ4.setUp ###Starting testAugmentFields
[junit4:junit4]   2> 207572 T25 C1 oass.SolrIndexSearcher.<init> Opening Searcher@6d144e3a realtime
[junit4:junit4]   2> 207573 T25 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1429948706513223680)} 0 5
[junit4:junit4]   2> 207584 T26 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[111 (1429948706525806592)]} 0 3
[junit4:junit4]   2> 207592 T24 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 207597 T24 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_i,generation=18,filenames=[_b.si, _b_Lucene41_0.tip, _b.fnm, _b.fdt, _b_Lucene41_0.doc, _b_Lucene41_0.tim, segments_i, _b.fdx]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_j,generation=19,filenames=[_c.fdt, _c.si, _c.nvm, _c_Lucene41_0.doc, _c_Lucene41_0.tim, _c_Lucene41_0.tip, _c.fnm, _c.fdx, _c.nvd, segments_j, _c_Lucene41_0.pos]
[junit4:junit4]   2> 207597 T24 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 19[_c.fdt, _c.si, _c.nvm, _c_Lucene41_0.doc, _c_Lucene41_0.tim, _c_Lucene41_0.tip, _c.fnm, _c.fdx, _c.nvd, segments_j, _c_Lucene41_0.pos]
[junit4:junit4]   2> 207601 T24 C1 oass.SolrIndexSearcher.<init> Opening Searcher@57b6d9cf main
[junit4:junit4]   2> 207603 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@57b6d9cf main{StandardDirectoryReader(segments_j:47:nrt _c(5.0):C1)}
[junit4:junit4]   2> 207604 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 207605 T24 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 207608 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@57b6d9cf main{StandardDirectoryReader(segments_j:47:nrt _c(5.0):C1)}
[junit4:junit4]   2> 207609 T24 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 17
[junit4:junit4]   2> 207622 T27 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[222 (1429948706563555328)]} 0 6
[junit4:junit4]   2> 207628 T28 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 207638 T28 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_j,generation=19,filenames=[_c.fdt, _c.si, _c.nvm, _c_Lucene41_0.doc, _c_Lucene41_0.tim, _c_Lucene41_0.tip, _c.fnm, _c.fdx, _c.nvd, segments_j, _c_Lucene41_0.pos]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_k,generation=20,filenames=[_c_Lucene41_0.doc, _d.fnm, segments_k, _c.fdx, _d.fdx, _c_Lucene41_0.pos, _c.fdt, _c.si, _d.fdt, _c.nvm, _c_Lucene41_0.tim, _d.nvd, _c_Lucene41_0.tip, _d.si, _d_Lucene41_0.tim, _d.nvm, _d_Lucene41_0.doc, _d_Lucene41_0.tip, _c.fnm, _d_Lucene41_0.pos, _c.nvd]
[junit4:junit4]   2> 207639 T28 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 20[_c_Lucene41_0.doc, _d.fnm, segments_k, _c.fdx, _d.fdx, _c_Lucene41_0.pos, _c.fdt, _c.si, _d.fdt, _c.nvm, _c_Lucene41_0.tim, _d.nvd, _c_Lucene41_0.tip, _d.si, _d_Lucene41_0.tim, _d.nvm, _d_Lucene41_0.doc, _d_Lucene41_0.tip, _c.fnm, _d_Lucene41_0.pos, _c.nvd]
[junit4:junit4]   2> 207642 T28 C1 oass.SolrIndexSearcher.<init> Opening Searcher@70188b41 main
[junit4:junit4]   2> 207644 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@70188b41 main{StandardDirectoryReader(segments_k:49:nrt _c(5.0):C1 _d(5.0):C1)}
[junit4:junit4]   2> 207646 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 207647 T28 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 207652 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@70188b41 main{StandardDirectoryReader(segments_k:49:nrt _c(5.0):C1 _d(5.0):C1)}
[junit4:junit4]   2> 207654 T28 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 27
[junit4:junit4]   2> 207670 T29 C1 REQ [collection1] webapp=/solr path=/select params={sort=price+asc&fl=id,price,[docid],[explain+style%3Dnl],score,aaa:[value+v%3Daaa],ten:[value+v%3D10+t%3Dint]&q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=9 
[junit4:junit4]   2> 207674 T19 oas.SolrTestCaseJ4.tearDown ###Ending testAugmentFields
[junit4:junit4]   2> 207692 T19 oas.SolrTestCaseJ4.setUp ###Starting testExampleConfig
[junit4:junit4]   2> 207700 T25 C1 oass.SolrIndexSearcher.<init> Opening Searcher@7a18603a realtime
[junit4:junit4]   2> 207701 T25 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1429948706647441408)} 0 4
[junit4:junit4]   2> 207709 T24 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1112211111 (1429948706658975744)]} 0 1
[junit4:junit4]   2> 207717 T27 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 207721 T27 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_k,generation=20,filenames=[_c_Lucene41_0.doc, _d.fnm, segments_k, _c.fdx, _d.fdx, _c_Lucene41_0.pos, _c.fdt, _c.si, _d.fdt, _c.nvm, _c_Lucene41_0.tim, _d.nvd, _c_Lucene41_0.tip, _d.si, _d_Lucene41_0.tim, _d.nvm, _d_Lucene41_0.doc, _d_Lucene41_0.tip, _c.fnm, _d_Lucene41_0.pos, _c.nvd]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_l,generation=21,filenames=[_e.nvd, _e_Lucene41_0.doc, _e.si, _e.fdt, _e.nvm, _e.fdx, _e_Lucene41_0.tim, segments_l, _e.fnm, _e_Lucene41_0.tip, _e_Lucene41_0.pos]
[junit4:junit4]   2> 207722 T27 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 21[_e.nvd, _e_Lucene41_0.doc, _e.si, _e.fdt, _e.nvm, _e.fdx, _e_Lucene41_0.tim, segments_l, _e.fnm, _e_Lucene41_0.tip, _e_Lucene41_0.pos]
[junit4:junit4]   2> 207724 T27 C1 oass.SolrIndexSearcher.<init> Opening Searcher@f671723 main
[junit4:junit4]   2> 207727 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@f671723 main{StandardDirectoryReader(segments_l:53:nrt _e(5.0):C1)}
[junit4:junit4]   2> 207727 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 207728 T27 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 207732 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@f671723 main{StandardDirectoryReader(segments_l:53:nrt _e(5.0):C1)}
[junit4:junit4]   2> 207735 T27 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 18
[junit4:junit4]   2> 207740 T28 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 207740 T28 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_l,generation=21,filenames=[_e.nvd, _e_Lucene41_0.doc, _e.si, _e.fdt, _e.nvm, _e.fdx, _e_Lucene41_0.tim, segments_l, _e.fnm, _e_Lucene41_0.tip, _e_Lucene41_0.pos]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_m,generation=22,filenames=[_e.nvd, _e_Lucene41_0.doc, _e.si, _e.fdt, segments_m, _e.nvm, _e.fdx, _e_Lucene41_0.tim, _e.fnm, _e_Lucene41_0.tip, _e_Lucene41_0.pos]
[junit4:junit4]   2> 207741 T28 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 22[_e.nvd, _e_Lucene41_0.doc, _e.si, _e.fdt, segments_m, _e.nvm, _e.fdx, _e_Lucene41_0.tim, _e.fnm, _e_Lucene41_0.tip, _e_Lucene41_0.pos]
[junit4:junit4]   2> 207741 T28 C1 oass.SolrIndexSearcher.<init> Opening Searcher@74396fd4 main
[junit4:junit4]   2> 207743 T28 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 207744 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@74396fd4 main{StandardDirectoryReader(segments_l:53:nrt _e(5.0):C1)}
[junit4:junit4]   2> 207745 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 207749 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@74396fd4 main{StandardDirectoryReader(segments_l:53:nrt _e(5.0):C1)}
[junit4:junit4]   2> 207751 T28 C1 UPDATE [collection1] webapp=/solr path=/update params={optimize=true&waitSearcher=true&maxSegments=1&wt=javabin&version=2} {optimize=} 0 12
[junit4:junit4]   2> 207759 T29 C1 REQ [collection1] webapp=/solr path=/select params={q=id:1112211111&wt=javabin&version=2} hits=1 status=0 QTime=3 
[junit4:junit4]   2> 207786 T26 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2 (1429948706717696000), 3 (1429948706730278912), 4 (1429948706732376064), 5 (1429948706738667520)]} 0 22
[junit4:junit4]   2> 207794 T25 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 207799 T25 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_m,generation=22,filenames=[_e.nvd, _e_Lucene41_0.doc, _e.si, _e.fdt, segments_m, _e.nvm, _e.fdx, _e_Lucene41_0.tim, _e.fnm, _e_Lucene41_0.tip, _e_Lucene41_0.pos]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_n,generation=23,filenames=[_e.nvd, _f_Lucene41_0.pos, _f.nvm, segments_n, _e.nvm, _e.fdx, _f.si, _f.fdt, _f.nvd, _f_Lucene41_0.tip, _f.fdx, _f_Lucene41_0.doc, _e_Lucene41_0.doc, _e.si, _f.fnm, _e.fdt, _f_Lucene41_0.tim, _e_Lucene41_0.tim, _e.fnm, _e_Lucene41_0.tip, _e_Lucene41_0.pos]
[junit4:junit4]   2> 207801 T25 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 23[_e.nvd, _f_Lucene41_0.pos, _f.nvm, segments_n, _e.nvm, _e.fdx, _f.si, _f.fdt, _f.nvd, _f_Lucene41_0.tip, _f.fdx, _f_Lucene41_0.doc, _e_Lucene41_0.doc, _e.si, _f.fnm, _e.fdt, _f_Lucene41_0.tim, _e_Lucene41_0.tim, _e.fnm, _e_Lucene41_0.tip, _e_Lucene41_0.pos]
[junit4:junit4]   2> 207802 T25 C1 oass.SolrIndexSearcher.<init> Opening Searcher@781cd525 main
[junit4:junit4]   2> 207803 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@781cd525 main{StandardDirectoryReader(segments_n:55:nrt _e(5.0):C1 _f(5.0):C4)}
[junit4:junit4]   2> 207806 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 207811 T25 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 207814 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@781cd525 main{StandardDirectoryReader(segments_n:55:nrt _e(5.0):C1 _f(5.0):C4)}
[junit4:junit4]   2> 207815 T25 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 23
[junit4:junit4]   2> 207823 T24 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 207914 T24 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_n,generation=23,filenames=[_e.nvd, _f_Lucene41_0.pos, _f.nvm, segments_n, _e.nvm, _e.fdx, _f.si, _f.fdt, _f.nvd, _f_Lucene41_0.tip, _f.fdx, _f_Lucene41_0.doc, _e_Lucene41_0.doc, _e.si, _f.fnm, _e.fdt, _f_Lucene41_0.tim, _e_Lucene41_0.tim, _e.fnm, _e_Lucene41_0.tip, _e_Lucene41_0.pos]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_o,generation=24,filenames=[_g.fdt, _g.si, _g_Lucene41_0.tip, _g.nvm, _g.fnm, _g.fdx, _g_Lucene41_0.doc, _g_Lucene41_0.tim, segments_o, _g_Lucene41_0.pos, _g.nvd]
[junit4:junit4]   2> 207915 T24 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 24[_g.fdt, _g.si, _g_Lucene41_0.tip, _g.nvm, _g.fnm, _g.fdx, _g_Lucene41_0.doc, _g_Lucene41_0.tim, segments_o, _g_Lucene41_0.pos, _g.nvd]
[junit4:junit4]   2> 207919 T24 C1 oass.SolrIndexSearcher.<init> Opening Searcher@6d385e90 main
[junit4:junit4]   2> 207920 T24 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 207922 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@6d385e90 main{StandardDirectoryReader(segments_o:57:nrt _g(5.0):C5)}
[junit4:junit4]   2> 207922 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 208117 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6d385e90 main{StandardDirectoryReader(segments_o:57:nrt _g(5.0):C5)}
[junit4:junit4]   2> 208118 T24 C1 UPDATE [collection1] webapp=/solr path=/update params={optimize=true&waitSearcher=true&maxSegments=1&wt=javabin&version=2} {optimize=} 0 295
[junit4:junit4]   2> 208247 T28 C1 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=117 
[junit4:junit4]   2> 208274 T29 C1 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=4 
[junit4:junit4]   2> 208308 T26 C1 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=22 
[junit4:junit4]   2> 208311 T19 oas.SolrTestCaseJ4.tearDown ###Ending testExampleConfig
[junit4:junit4]   2> 208335 T19 oas.SolrTestCaseJ4.setUp ###Starting testUpdateMultiValuedField
[junit4:junit4]   2> 208346 T25 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123 (1429948707323772928)]} 0 4
[junit4:junit4]   2> 208354 T24 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 208359 T24 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_o,generation=24,filenames=[_g.fdt, _g.si, _g_Lucene41_0.tip, _g.nvm, _g.fnm, _g.fdx, _g_Lucene41_0.doc, _g_Lucene41_0.tim, segments_o, _g_Lucene41_0.pos, _g.nvd]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_p,generation=25,filenames=[_g.fdt, _h_Lucene41_0.tip, _g.si, _h.fnm, _g.nvm, _h_Lucene41_0.tim, _g.fdx, _g_Lucene41_0.pos, _g.nvd, _g.fnm, _g_Lucene41_0.tip, _h.fdt, segments_p, _g_Lucene41_0.doc, _h.si, _h.fdx, _h_Lucene41_0.doc, _g_Lucene41_0.tim]
[junit4:junit4]   2> 208361 T24 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 25[_g.fdt, _h_Lucene41_0.tip, _g.si, _h.fnm, _g.nvm, _h_Lucene41_0.tim, _g.fdx, _g_Lucene41_0.pos, _g.nvd, _g.fnm, _g_Lucene41_0.tip, _h.fdt, segments_p, _g_Lucene41_0.doc, _h.si, _h.fdx, _h_Lucene41_0.doc, _g_Lucene41_0.tim]
[junit4:junit4]   2> 208363 T24 C1 oass.SolrIndexSearcher.<init> Opening Searcher@5d214849 main
[junit4:junit4]   2> 208367 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@5d214849 main{StandardDirectoryReader(segments_p:59:nrt _g(5.0):C5 _h(5.0):C1)}
[junit4:junit4]   2> 208367 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 208369 T24 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 208371 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5d214849 main{StandardDirectoryReader(segments_p:59:nrt _g(5.0):C5 _h(5.0):C1)}
[junit4:junit4]   2> 208373 T24 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 21
[junit4:junit4]   2> 208381 T27 C1 REQ [collection1] webapp=/solr path=/select params={q=id:123&wt=javabin&version=2} hits=1 status=0 QTime=2 
[junit4:junit4]   2> 208396 T28 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123 (1429948707374104576)]} 0 6
[junit4:junit4]   2> 208402 T29 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 208410 T29 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_p,generation=25,filenames=[_g.fdt, _h_Lucene41_0.tip, _g.si, _h.fnm, _g.nvm, _h_Lucene41_0.tim, _g.fdx, _g_Lucene41_0.pos, _g.nvd, _g.fnm, _g_Lucene41_0.tip, _h.fdt, segments_p, _g_Lucene41_0.doc, _h.si, _h.fdx, _h_Lucene41_0.doc, _g_Lucene41_0.tim]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_q,generation=26,filenames=[_g.fdt, _g.si, _g.nvm, _i_Lucene41_0.doc, _g.fdx, _i_Lucene41_0.tip, _g_Lucene41_0.pos, _g.nvd, _i.si, _g.fnm, _g_Lucene41_0.tip, _i_Lucene41_0.tim, segments_q, _g_Lucene41_0.doc, _g_Lucene41_0.tim, _i.fdx, _i.fnm, _i.fdt]
[junit4:junit4]   2> 208412 T29 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 26[_g.fdt, _g.si, _g.nvm, _i_Lucene41_0.doc, _g.fdx, _i_Lucene41_0.tip, _g_Lucene41_0.pos, _g.nvd, _i.si, _g.fnm, _g_Lucene41_0.tip, _i_Lucene41_0.tim, segments_q, _g_Lucene41_0.doc, _g_Lucene41_0.tim, _i.fdx, _i.fnm, _i.fdt]
[junit4:junit4]   2> 208413 T29 C1 oass.SolrIndexSearcher.<init> Opening Searcher@549154f9 main
[junit4:junit4]   2> 208416 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@549154f9 main{StandardDirectoryReader(segments_q:63:nrt _g(5.0):C5 _i(5.0):C1)}
[junit4:junit4]   2> 208417 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 208418 T29 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 208420 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@549154f9 main{StandardDirectoryReader(segments_q:63:nrt _g(5.0):C5 _i(5.0):C1)}
[junit4:junit4]   2> 208421 T29 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 20
[junit4:junit4]   2> 208431 T26 C1 REQ [collection1] webapp=/solr path=/select params={q=id:123&wt=javabin&version=2} hits=1 status=0 QTime=2 
[junit4:junit4]   2> 208441 T25 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123 (1429948707423387648)]} 0 5
[junit4:junit4]   2> 208448 T24 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 208452 T24 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_q,generation=26,filenames=[_g.fdt, _g.si, _g.nvm, _i_Lucene41_0.doc, _g.fdx, _i_Lucene41_0.tip, _g_Lucene41_0.pos, _g.nvd, _i.si, _g.fnm, _g_Lucene41_0.tip, _i_Lucene41_0.tim, segments_q, _g_Lucene41_0.doc, _g_Lucene41_0.tim, _i.fdx, _i.fnm, _i.fdt]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_r,generation=27,filenames=[_g.fdt, _j.fdx, _g.si, _g.nvm, _j.si, _j.fdt, _g.fdx, _g_Lucene41_0.pos, _j_Lucene41_0.tim, _g.nvd, _g.fnm, _g_Lucene41_0.tip, segments_r, _j_Lucene41_0.tip, _j.fnm, _g_Lucene41_0.doc, _g_Lucene41_0.tim, _j_Lucene41_0.doc]
[junit4:junit4]   2> 208453 T24 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 27[_g.fdt, _j.fdx, _g.si, _g.nvm, _j.si, _j.fdt, _g.fdx, _g_Lucene41_0.pos, _j_Lucene41_0.tim, _g.nvd, _g.fnm, _g_Lucene41_0.tip, segments_r, _j_Lucene41_0.tip, _j.fnm, _g_Lucene41_0.doc, _g_Lucene41_0.tim, _j_Lucene41_0.doc]
[junit4:junit4]   2> 208455 T24 C1 oass.SolrIndexSearcher.<init> Opening Searcher@782256a2 main
[junit4:junit4]   2> 208457 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@782256a2 main{StandardDirectoryReader(segments_r:67:nrt _g(5.0):C5 _j(5.0):C1)}
[junit4:junit4]   2> 208459 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 208461 T24 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 208461 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@782256a2 main{StandardDirectoryReader(segments_r:67:nrt _g(5.0):C5 _j(5.0):C1)}
[junit4:junit4]   2> 208463 T24 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 16
[junit4:junit4]   2> 208472 T27 C1 REQ [collection1] webapp=/solr path=/select params={q=id:123&wt=javabin&version=2} hits=1 status=0 QTime=2 
[junit4:junit4]   2> 208475 T19 oas.SolrTestCaseJ4.tearDown ###Ending testUpdateMultiValuedField
[junit4:junit4]   2> 208491 T19 oas.SolrTestCaseJ4.setUp ###Starting testCommitWithinOnAdd
[junit4:junit4]   2> 208499 T28 C1 oass.SolrIndexSearcher.<init> Opening Searcher@7a33bcbe realtime
[junit4:junit4]   2> 208499 T28 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1429948707484205056)} 0 4
[junit4:junit4]   2> 208507 T29 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 208509 T29 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_r,generation=27,filenames=[_g.fdt, _j.fdx, _g.si, _g.nvm, _j.si, _j.fdt, _g.fdx, _g_Lucene41_0.pos, _j_Lucene41_0.tim, _g.nvd, _g.fnm, _g_Lucene41_0.tip, segments_r, _j_Lucene41_0.tip, _j.fnm, _g_Lucene41_0.doc, _g_Lucene41_0.tim, _j_Lucene41_0.doc]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_s,generation=28,filenames=[segments_s]
[junit4:junit4]   2> 208509 T29 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 28[segments_s]
[junit4:junit4]   2> 208510 T29 C1 oass.SolrIndexSearcher.<init> Opening Searcher@2fb82978 main
[junit4:junit4]   2> 208513 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@2fb82978 main{StandardDirectoryReader(segments_r:69:nrt)}
[junit4:junit4]   2> 208513 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 208517 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2fb82978 main{StandardDirectoryReader(segments_r:69:nrt)}
[junit4:junit4]   2> 208521 T29 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 208522 T29 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 15
[junit4:junit4]   2> 208529 T25 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 208540 T24 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id3 (1429948707527196672)]} 0 6
[junit4:junit4]   2> 208550 T27 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 209227 T35 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4:junit4]   2> 209235 T35 oass.SolrIndexSearcher.<init> Opening Searcher@5f5091f2 main
[junit4:junit4]   2> 209237 T35 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 209237 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@5f5091f2 main{StandardDirectoryReader(segments_s:71:nrt _k(5.0):C1)}
[junit4:junit4]   2> 209237 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 209241 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5f5091f2 main{StandardDirectoryReader(segments_s:71:nrt _k(5.0):C1)}
[junit4:junit4]   2> 209935 T26 C1 REQ [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 209945 T25 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id4 (1429948709001494528)]} 0 3
[junit4:junit4]   2> 210633 T35 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4:junit4]   2> 210712 T35 oass.SolrIndexSearcher.<init> Opening Searcher@50f4109e main
[junit4:junit4]   2> 210715 T35 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 210715 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@50f4109e main{StandardDirectoryReader(segments_s:73:nrt _k(5.0):C1 _l(5.0):C1)}
[junit4:junit4]   2> 210715 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 210718 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@50f4109e main{StandardDirectoryReader(segments_s:73:nrt _k(5.0):C1 _l(5.0):C1)}
[junit4:junit4]   2> 211330 T28 C1 REQ [collection1] webapp=/solr path=/select params={q=id:id4&wt=javabin&version=2} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 211331 T19 oas.SolrTestCaseJ4.tearDown ###Ending testCommitWithinOnAdd
[junit4:junit4]   2> 211353 T19 oas.SolrTestCaseJ4.setUp ###Starting testRealtimeGet
[junit4:junit4]   2> 211361 T29 C1 oass.SolrIndexSearcher.<init> Opening Searcher@54b772a7 realtime
[junit4:junit4]   2> 211361 T29 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1429948710486278144)} 0 4
[junit4:junit4]   2> 211369 T26 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[DOCID (1429948710496763904)]} 0 1
[junit4:junit4]   2> 211378 T25 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 211384 T25 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_s,generation=28,filenames=[segments_s]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_t,generation=29,filenames=[_m.si, _m_Lucene41_0.tim, segments_t, _m.nvd, _m.fdt, _m_Lucene41_0.doc, _m_Lucene41_0.tip, _m_Lucene41_0.pos, _m.fdx, _m.nvm, _m.fnm]
[junit4:junit4]   2> 211385 T25 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 29[_m.si, _m_Lucene41_0.tim, segments_t, _m.nvd, _m.fdt, _m_Lucene41_0.doc, _m_Lucene41_0.tip, _m_Lucene41_0.pos, _m.fdx, _m.nvm, _m.fnm]
[junit4:junit4]   2> 211385 T25 C1 oass.SolrIndexSearcher.<init> Opening Searcher@544e4ae8 main
[junit4:junit4]   2> 211389 T25 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 211389 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@544e4ae8 main{StandardDirectoryReader(segments_t:77:nrt _m(5.0):C1)}
[junit4:junit4]   2> 211389 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 211392 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@544e4ae8 main{StandardDirectoryReader(segments_t:77:nrt _m(5.0):C1)}
[junit4:junit4]   2> 211393 T25 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 19
[junit4:junit4]   2> 211400 T24 C1 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=0 
[junit4:junit4]   2> 211413 T27 C1 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=1 
[junit4:junit4]   2> 211482 T19 oas.SolrTestCaseJ4.tearDown ###Ending testRealtimeGet
[junit4:junit4]   2> 211500 T19 oas.SolrTestCaseJ4.setUp ###Starting testCommitWithinOnDelete
[junit4:junit4]   2> 211506 T28 C1 oass.SolrIndexSearcher.<init> Opening Searcher@43c67d6f realtime
[junit4:junit4]   2> 211509 T28 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1429948710639370240)} 0 5
[junit4:junit4]   2> 211514 T26 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 211514 T26 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_t,generation=29,filenames=[_m.si, _m_Lucene41_0.tim, segments_t, _m.nvd, _m.fdt, _m_Lucene41_0.doc, _m_Lucene41_0.tip, _m_Lucene41_0.pos, _m.fdx, _m.nvm, _m.fnm]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_u,generation=30,filenames=[segments_u]
[junit4:junit4]   2> 211514 T26 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 30[segments_u]
[junit4:junit4]   2> 211516 T26 C1 oass.SolrIndexSearcher.<init> Opening Searcher@6102962a main
[junit4:junit4]   2> 211518 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@6102962a main{StandardDirectoryReader(segments_t:79:nrt)}
[junit4:junit4]   2> 211518 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 211520 T26 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 211523 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6102962a main{StandardDirectoryReader(segments_t:79:nrt)}
[junit4:junit4]   2> 211523 T26 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 10
[junit4:junit4]   2> 211529 T25 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 211537 T24 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id3 (1429948710671876096)]} 0 4
[junit4:junit4]   2> 211545 T27 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 211549 T27 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_u,generation=30,filenames=[segments_u]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_v,generation=31,filenames=[_n_Lucene41_0.doc, segments_v, _n_Lucene41_0.tim, _n.si, _n_Lucene41_0.pos, _n_Lucene41_0.tip, _n.fdt, _n.nvm, _n.fdx, _n.fnm, _n.nvd]
[junit4:junit4]   2> 211549 T27 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 31[_n_Lucene41_0.doc, segments_v, _n_Lucene41_0.tim, _n.si, _n_Lucene41_0.pos, _n_Lucene41_0.tip, _n.fdt, _n.nvm, _n.fdx, _n.fnm, _n.nvd]
[junit4:junit4]   2> 211552 T27 C1 oass.SolrIndexSearcher.<init> Opening Searcher@5c6c5317 main
[junit4:junit4]   2> 211555 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@5c6c5317 main{StandardDirectoryReader(segments_v:81:nrt _n(5.0):C1)}
[junit4:junit4]   2> 211556 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 211556 T27 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 211558 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5c6c5317 main{StandardDirectoryReader(segments_v:81:nrt _n(5.0):C1)}
[junit4:junit4]   2> 211559 T27 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 15
[junit4:junit4]   2> 211564 T29 C1 REQ [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 211572 T28 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {delete=[id3 (-1429948710709624832)]} 0 2
[junit4:junit4]   2> 211578 T26 C1 REQ [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=2 
[junit4:junit4]   2> 211928 T25 C1 REQ [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=2 
[junit4:junit4]   2> 212279 T27 C1 REQ [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 212632 T29 C1 REQ [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 212949 T35 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4:junit4]   2> 212951 T35 oass.SolrIndexSearcher.<init> Opening Searcher@7e292531 main
[junit4:junit4]   2> 212951 T35 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 212952 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@7e292531 main{StandardDirectoryReader(segments_v:83:nrt)}
[junit4:junit4]   2> 212953 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 212957 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7e292531 main{StandardDirectoryReader(segments_v:83:nrt)}
[junit4:junit4]   2> 212982 T26 C1 REQ [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 212984 T19 oas.SolrTestCaseJ4.tearDown ###Ending testCommitWithinOnDelete
[junit4:junit4]   2> 213005 T19 oas.SolrTestCaseJ4.setUp ###Starting testErrorHandling
[junit4:junit4]   2> 213114 T24 C1 oasc.SolrException.log Ignoring exception matching ignore_exception
[junit4:junit4]   2> 213116 T24 C1 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=105 
[junit4:junit4]   2> 213258 T27 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {} 0 135
[junit4:junit4]   2> 213259 T27 C1 oasc.SolrException.log Ignoring exception matching ignore_exception
[junit4:junit4]   2> 213267 T28 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {} 0 1
[junit4:junit4]   2> 213267 T28 C1 oasc.SolrException.log Ignoring exception matching uniqueKey
[junit4:junit4]   2> 213271 T19 oas.SolrTestCaseJ4.tearDown ###Ending testErrorHandling
[junit4:junit4]   2> 213286 T19 oas.SolrTestCaseJ4.setUp ###Starting testChineseDefaults
[junit4:junit4]   2> 213291 T29 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1429948712512126976)} 0 1
[junit4:junit4]   2> 213297 T26 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 213297 T26 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_v,generation=31,filenames=[_n_Lucene41_0.doc, segments_v, _n_Lucene41_0.tim, _n.si, _n_Lucene41_0.pos, _n_Lucene41_0.tip, _n.fdt, _n.nvm, _n.fdx, _n.fnm, _n.nvd]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_w,generation=32,filenames=[segments_w]
[junit4:junit4]   2> 213298 T26 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 32[segments_w]
[junit4:junit4]   2> 213299 T26 C1 oass.SolrIndexSearcher.<init> Opening Searcher@64b50674 main
[junit4:junit4]   2> 213299 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@64b50674 main{StandardDirectoryReader(segments_v:83:nrt)}
[junit4:junit4]   2> 213299 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 213301 T26 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 213302 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@64b50674 main{StandardDirectoryReader(segments_v:83:nrt)}
[junit4:junit4]   2> 213302 T26 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 5
[junit4:junit4]   2> 213310 T24 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 213318 T25 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 213322 T25 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_w,generation=32,filenames=[segments_w]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_x,generation=33,filenames=[_o.fdt, _o_Lucene41_0.tim, _o.nvm, _o.fnm, _o.fdx, _o_Lucene41_0.tip, _o.si, _o.nvd, _o_Lucene41_0.doc, segments_x, _o_Lucene41_0.pos]
[junit4:junit4]   2> 213322 T25 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 33[_o.fdt, _o_Lucene41_0.tim, _o.nvm, _o.fnm, _o.fdx, _o_Lucene41_0.tip, _o.si, _o.nvd, _o_Lucene41_0.doc, segments_x, _o_Lucene41_0.pos]
[junit4:junit4]   2> 213324 T25 C1 oass.SolrIndexSearcher.<init> Opening Searcher@6a9c3d70 main
[junit4:junit4]   2> 213325 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@6a9c3d70 main{StandardDirectoryReader(segments_x:85:nrt _o(5.0):C1)}
[junit4:junit4]   2> 213325 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 213326 T25 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 213326 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6a9c3d70 main{StandardDirectoryReader(segments_x:85:nrt _o(5.0):C1)}
[junit4:junit4]   2> 213328 T25 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {add=[42 (1429948712539389952)],commit=} 0 12
[junit4:junit4]   2> 213334 T27 C1 REQ [collection1] webapp=/solr path=/select params={q=????&wt=javabin&version=2} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 213336 T19 oas.SolrTestCaseJ4.tearDown ###Ending testChineseDefaults
[junit4:junit4]   2> 213355 T19 oas.SolrTestCaseJ4.setUp ###Starting testStatistics
[junit4:junit4]   2> 213360 T28 C1 oass.SolrIndexSearcher.<init> Opening Searcher@3b0b9e3a realtime
[junit4:junit4]   2> 213363 T28 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1429948712584478720)} 0 4
[junit4:junit4]   2> 213368 T29 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 213368 T29 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_x,generation=33,filenames=[_o.fdt, _o_Lucene41_0.tim, _o.nvm, _o.fnm, _o.fdx, _o_Lucene41_0.tip, _o.si, _o.nvd, _o_Lucene41_0.doc, segments_x, _o_Lucene41_0.pos]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_y,generation=34,filenames=[segments_y]
[junit4:junit4]   2> 213368 T29 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 34[segments_y]
[junit4:junit4]   2> 213369 T29 C1 oass.SolrIndexSearcher.<init> Opening Searcher@59697540 main
[junit4:junit4]   2> 213372 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@59697540 main{StandardDirectoryReader(segments_x:87:nrt)}
[junit4:junit4]   2> 213372 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 213375 T29 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 213375 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@59697540 main{StandardDirectoryReader(segments_x:87:nrt)}
[junit4:junit4]   2> 213377 T29 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 9
[junit4:junit4]   2> 213383 T26 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=2 
[junit4:junit4]   2> 213392 T24 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc0 (1429948712614887424)]} 0 4
[junit4:junit4]   2> 213402 T25 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc1 (1429948712625373184)]} 0 4
[junit4:junit4]   2> 213407 T27 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc2 (1429948712634810368)]} 0 0
[junit4:junit4]   2> 213414 T28 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc3 (1429948712642150400)]} 0 2
[junit4:junit4]   2> 213422 T29 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc4 (1429948712650539008)]} 0 1
[junit4:junit4]   2> 213430 T26 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc5 (1429948712657879040)]} 0 1
[junit4:junit4]   2> 213438 T24 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc6 (1429948712666267648)]} 0 1
[junit4:junit4]   2> 213449 T25 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc7 (1429948712676753408)]} 0 2
[junit4:junit4]   2> 213460 T27 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc8 (1429948712688287744)]} 0 2
[junit4:junit4]   2> 213465 T28 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc9 (1429948712695627776)]} 0 0
[junit4:junit4]   2> 213472 T29 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 213477 T29 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_y,generation=34,filenames=[segments_y]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_z,generation=35,filenames=[_p.nvm, _p.fdx, _p_Lucene41_0.doc, _p_Lucene41_0.tim, _p.nvd, _p.fnm, _p.fdt, _p_Lucene41_0.pos, _p_Lucene41_0.tip, _p.si, segments_z]
[junit4:junit4]   2> 213478 T29 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 35[_p.nvm, _p.fdx, _p_Lucene41_0.doc, _p_Lucene41_0.tim, _p.nvd, _p.fnm, _p.fdt, _p_Lucene41_0.pos, _p_Lucene41_0.tip, _p.si, segments_z]
[junit4:junit4]   2> 213480 T29 C1 oass.SolrIndexSearcher.<init> Opening Searcher@5f2b56c1 main
[junit4:junit4]   2> 213481 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@5f2b56c1 main{StandardDirectoryReader(segments_z:89:nrt _p(5.0):C10)}
[junit4:junit4]   2> 213481 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 213482 T29 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 213485 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5f2b56c1 main{StandardDirectoryReader(segments_z:89:nrt _p(5.0):C10)}
[junit4:junit4]   2> 213486 T29 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 14
[junit4:junit4]   2> 213492 T26 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=0 
[junit4:junit4]   2> 213563 T24 C1 REQ [collection1] webapp=/solr path=/select params={stats.field=val_pi&stats=true&q=*:*&wt=javabin&version=2&rows=0} hits=10 status=0 QTime=63 
[junit4:junit4]   2> 213581 T27 C1 oass.SolrIndexSearcher.<init> Opening Searcher@56e767ca realtime
[junit4:junit4]   2> 213581 T27 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1429948712813068288)} 0 6
[junit4:junit4]   2> 213587 T28 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 213587 T28 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_z,generation=35,filenames=[_p.nvm, _p.fdx, _p_Lucene41_0.doc, _p_Lucene41_0.tim, _p.nvd, _p.fnm, _p.fdt, _p_Lucene41_0.pos, _p_Lucene41_0.tip, _p.si, segments_z]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_10,generation=36,filenames=[segments_10]
[junit4:junit4]   2> 213587 T28 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 36[segments_10]
[junit4:junit4]   2> 213589 T28 C1 oass.SolrIndexSearcher.<init> Opening Searcher@688245a6 main
[junit4:junit4]   2> 213591 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@688245a6 main{StandardDirectoryReader(segments_z:91:nrt)}
[junit4:junit4]   2> 213593 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 213593 T28 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 213594 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@688245a6 main{StandardDirectoryReader(segments_z:91:nrt)}
[junit4:junit4]   2> 213596 T28 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 10
[junit4:junit4]   2> 213602 T29 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 213610 T26 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc10 (1429948712845574144)]} 0 4
[junit4:junit4]   2> 213620 T24 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc11 (1429948712853962752)]} 0 6
[junit4:junit4]   2> 213625 T25 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc12 (1429948712863399936)]} 0 1
[junit4:junit4]   2> 213633 T27 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc13 (1429948712870739968)]} 0 2
[junit4:junit4]   2> 213640 T28 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc14 (1429948712879128576)]} 0 1
[junit4:junit4]   2> 213647 T29 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 213651 T29 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_10,generation=36,filenames=[segments_10]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_11,generation=37,filenames=[_q.si, _q_Lucene41_0.pos, _q.nvm, _q_Lucene41_0.tip, segments_11, _q_Lucene41_0.tim, _q.fdx, _q_Lucene41_0.doc, _q.fnm, _q.fdt, _q.nvd]
[junit4:junit4]   2> 213652 T29 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 37[_q.si, _q_Lucene41_0.pos, _q.nvm, _q_Lucene41_0.tip, segments_11, _q_Lucene41_0.tim, _q.fdx, _q_Lucene41_0.doc, _q.fnm, _q.fdt, _q.nvd]
[junit4:junit4]   2> 213653 T29 C1 oass.SolrIndexSearcher.<init> Opening Searcher@21eb79af main
[junit4:junit4]   2> 213656 T29 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 213656 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@21eb79af main{StandardDirectoryReader(segments_11:93:nrt _q(5.0):C5)}
[junit4:junit4]   2> 213656 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 213659 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@21eb79af main{StandardDirectoryReader(segments_11:93:nrt _q(5.0):C5)}
[junit4:junit4]   2> 213661 T29 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 16
[junit4:junit4]   2> 213670 T26 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=5 status=0 QTime=2 
[junit4:junit4]   2> 213675 T24 C1 REQ [collection1] webapp=/solr path=/select params={stats.field=val_pi&stats=true&q=*:*&wt=javabin&version=2&rows=0} hits=5 status=0 QTime=0 
[junit4:junit4]   2> 213682 T24 C1 oass.SolrIndexSearcher.<init> Opening Searcher@66212948 realtime
[junit4:junit4]   2> 213683 T24 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1429948712921071616)} 0 4
[junit4:junit4]   2> 213690 T27 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 213690 T27 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_11,generation=37,filenames=[_q.si, _q_Lucene41_0.pos, _q.nvm, _q_Lucene41_0.tip, segments_11, _q_Lucene41_0.tim, _q.fdx, _q_Lucene41_0.doc, _q.fnm, _q.fdt, _q.nvd]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_12,generation=38,filenames=[segments_12]
[junit4:junit4]   2> 213691 T27 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 38[segments_12]
[junit4:junit4]   2> 213691 T27 C1 oass.SolrIndexSearcher.<init> Opening Searcher@7e1fa11d main
[junit4:junit4]   2> 213692 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@7e1fa11d main{StandardDirectoryReader(segments_11:95:nrt)}
[junit4:junit4]   2> 213694 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 213695 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7e1fa11d main{StandardDirectoryReader(segments_11:95:nrt)}
[junit4:junit4]   2> 213699 T27 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 213701 T27 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 11
[junit4:junit4]   2> 213706 T28 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 213715 T29 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc0 (1429948712955674624)]} 0 5
[junit4:junit4]   2> 213723 T26 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc1 (1429948712964063232)]} 0 4
[junit4:junit4]   2> 213731 T24 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc2 (1429948712973500416)]} 0 1
[junit4:junit4]   2> 213738 T25 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc3 (1429948712980840448)]} 0 1
[junit4:junit4]   2> 213745 T27 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc4 (1429948712988180480)]} 0 1
[junit4:junit4]   2> 213753 T28 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc5 (1429948712997617664)]} 0 3
[junit4:junit4]   2> 213761 T29 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc6 (1429948713006006272)]} 0 3
[junit4:junit4]   2> 213769 T26 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc7 (1429948713013346304)]} 0 1
[junit4:junit4]   2> 213775 T24 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc8 (1429948713020686336)]} 0 2
[junit4:junit4]   2> 213783 T25 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc9 (1429948713026977792)]} 0 3
[junit4:junit4]   2> 213788 T27 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 213793 T27 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_12,generation=38,filenames=[segments_12]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_13,generation=39,filenames=[_r.fdt, _r.fnm, _r_Lucene41_0.pos, _r.fdx, _r_Lucene41_0.tip, _r.nvd, _r_Lucene41_0.tim, segments_13, _r.si, _r_Lucene41_0.doc, _r.nvm]
[junit4:junit4]   2> 213795 T27 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 39[_r.fdt, _r.fnm, _r_Lucene41_0.pos, _r.fdx, _r_Lucene41_0.tip, _r.nvd, _r_Lucene41_0.tim, segments_13, _r.si, _r_Lucene41_0.doc, _r.nvm]
[junit4:junit4]   2> 213797 T27 C1 oass.SolrIndexSearcher.<init> Opening Searcher@30f8e65a main
[junit4:junit4]   2> 213799 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@30f8e65a main{StandardDirectoryReader(segments_13:97:nrt _r(5.0):C10)}
[junit4:junit4]   2> 213800 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 213800 T27 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 213804 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@30f8e65a main{StandardDirectoryReader(segments_13:97:nrt _r(5.0):C10)}
[junit4:junit4]   2> 213806 T27 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 18
[junit4:junit4]   2> 213842 T28 C1 REQ [collection1] webapp=/solr path=/select params={q=inStock:true&wt=javabin&version=2} hits=5 status=0 QTime=1 
[junit4:junit4]   2> 213851 T29 C1 REQ [collection1] webapp=/solr path=/select params={q=inStock:false&wt=javabin&version=2} hits=5 status=0 QTime=0 
[junit4:junit4]   2> 213894 T26 C1 REQ [collection1] webapp=/solr path=/select params={f.val_pi.stats.facet=inStock&stats.field=val_pi&stats=true&q=*:*&wt=javabin&version=2&rows=0} hits=10 status=0 QTime=36 
[junit4:junit4]   2> 213897 T19 oas.SolrTestCaseJ4.tearDown ###Ending testStatistics
[junit4:junit4]   2> 213915 T19 oas.SolrTestCaseJ4.setUp ###Starting testContentStreamRequest
[junit4:junit4]   2> 213921 T24 C1 oass.SolrIndexSearcher.<init> Opening Searcher@15b619ee realtime
[junit4:junit4]   2> 213923 T24 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1429948713171681280)} 0 6
[junit4:junit4]   2> 213928 T24 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 213929 T24 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_13,generation=39,filenames=[_r.fdt, _r.fnm, _r_Lucene41_0.pos, _r.fdx, _r_Lucene41_0.tip, _r.nvd, _r_Lucene41_0.tim, segments_13, _r.si, _r_Lucene41_0.doc, _r.nvm]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_14,generation=40,filenames=[segments_14]
[junit4:junit4]   2> 213929 T24 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 40[segments_14]
[junit4:junit4]   2> 213931 T24 C1 oass.SolrIndexSearcher.<init> Opening Searcher@6452a25c main
[junit4:junit4]   2> 213933 T24 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 213935 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@6452a25c main{StandardDirectoryReader(segments_13:99:nrt)}
[junit4:junit4]   2> 213935 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 213937 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6452a25c main{StandardDirectoryReader(segments_13:99:nrt)}
[junit4:junit4]   2> 213939 T24 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 12
[junit4:junit4]   2> 213947 T27 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=2 
[junit4:junit4]   2> 214064 T28 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 214079 T28 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_14,generation=40,filenames=[segments_14]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_15,generation=41,filenames=[_s_Lucene41_0.doc, _s.fdx, _s_Lucene41_0.tim, _s.fdt, _s.si, _s.nvm, _s.fnm, segments_15, _s_Lucene41_0.pos, _s_Lucene41_0.tip, _s.nvd]
[junit4:junit4]   2> 214080 T28 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 41[_s_Lucene41_0.doc, _s.fdx, _s_Lucene41_0.tim, _s.fdt, _s.si, _s.nvm, _s.fnm, segments_15, _s_Lucene41_0.pos, _s_Lucene41_0.tip, _s.nvd]
[junit4:junit4]   2> 214083 T28 C1 oass.SolrIndexSearcher.<init> Opening Searcher@d3724cf main
[junit4:junit4]   2> 214084 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@d3724cf main{StandardDirectoryReader(segments_15:101:nrt _s(5.0):C10)}
[junit4:junit4]   2> 214085 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 214085 T28 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 214095 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@d3724cf main{StandardDirectoryReader(segments_15:101:nrt _s(5.0):C10)}
[junit4:junit4]   2> 214096 T28 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {add=[0553573403 (1429948713295413248), 0553579908 (1429948713300656128), 055357342X (1429948713303801856), 0553293354 (1429948713306947584), 0812521390 (1429948713309044736), 0812550706 (1429948713312190464), 0441385532 (1429948713314287616), 0380014300 (1429948713316384768), 0805080481 (1429948713319530496), 080508049X (1429948713320579072)],commit=} 0 126
[junit4:junit4]   2> 214102 T26 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=2 
[junit4:junit4]   2> 214108 T19 oas.SolrTestCaseJ4.tearDown ###Ending testContentStreamRequest
[junit4:junit4]   2> 214141 T19 oas.SolrTestCaseJ4.setUp ###Starting testMultiContentStreamRequest
[junit4:junit4]   2> 214147 T25 C1 oass.SolrIndexSearcher.<init> Opening Searcher@1ce00c07 realtime
[junit4:junit4]   2> 214149 T25 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1429948713408659456)} 0 6
[junit4:junit4]   2> 214154 T24 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 214154 T24 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_15,generation=41,filenames=[_s_Lucene41_0.doc, _s.fdx, _s_Lucene41_0.tim, _s.fdt, _s.si, _s.nvm, _s.fnm, segments_15, _s_Lucene41_0.pos, _s_Lucene41_0.tip, _s.nvd]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_16,generation=42,filenames=[segments_16]
[junit4:junit4]   2> 214154 T24 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 42[segments_16]
[junit4:junit4]   2> 214155 T24 C1 oass.SolrIndexSearcher.<init> Opening Searcher@6183aa28 main
[junit4:junit4]   2> 214158 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@6183aa28 main{StandardDirectoryReader(segments_15:103:nrt)}
[junit4:junit4]   2> 214158 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 214161 T24 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 214162 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6183aa28 main{StandardDirectoryReader(segments_15:103:nrt)}
[junit4:junit4]   2> 214164 T24 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 11
[junit4:junit4]   2> 214169 T27 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 214426 T29 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 214469 T29 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_16,generation=42,filenames=[segments_16]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_17,generation=43,filenames=[_t_Lucene41_0.pay, _t_Lucene41_0.tim, _t.si, _t_Lucene41_0.tip, _t.fnm, _t_Lucene41_0.doc, _t.nvd, _t.nvm, _t.fdx, _t_Lucene41_0.pos, segments_17, _t.fdt]
[junit4:junit4]   2> 214470 T29 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 43[_t_Lucene41_0.pay, _t_Lucene41_0.tim, _t.si, _t_Lucene41_0.tip, _t.fnm, _t_Lucene41_0.doc, _t.nvd, _t.nvm, _t.fdx, _t_Lucene41_0.pos, segments_17, _t.fdt]
[junit4:junit4]   2> 214475 T29 C1 oass.SolrIndexSearcher.<init> Opening Searcher@616a00ab main
[junit4:junit4]   2> 214477 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@616a00ab main{StandardDirectoryReader(segments_17:105:nrt _t(5.0):C5)}
[junit4:junit4]   2> 214479 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 214479 T29 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 214481 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@616a00ab main{StandardDirectoryReader(segments_17:105:nrt _t(5.0):C5)}
[junit4:junit4]   2> 214484 T29 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&echoParams=ALL&commit=true&a=?&wt=javabin&version=2&softCommit=false} {add=[SP2514N (1429948713654026240), 6H500F0 (1429948713669754880), TWINX2048-3200PRO (1429948713674997760), VS1GB400C3 (1429948713694920704), VDBDB1A16 (1429948713699115008)],commit=} 0 105
[junit4:junit4]   2> 214492 T26 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=5 status=0 QTime=3 
[junit4:junit4]   2> 214496 T19 oas.SolrTestCaseJ4.tearDown ###Ending testMultiContentStreamRequest
[junit4:junit4]   2> 214526 T19 oas.SolrTestCaseJ4.setUp ###Starting testQueryWithParams
[junit4:junit4]   2> 214550 T25 C1 REQ [collection1] webapp=/solr path=/select params={q=query&debug=true&wt=javabin&version=2} hits=0 status=0 QTime=18 
[junit4:junit4]   2> 214551 T19 oas.SolrTestCaseJ4.tearDown ###Ending testQueryWithParams
[junit4:junit4]   2> 214573 T19 oas.SolrTestCaseJ4.setUp ###Starting testUpdateField
[junit4:junit4]   2> 214581 T24 C1 oass.SolrIndexSearcher.<init> Opening Searcher@6da179f7 realtime
[junit4:junit4]   2> 214584 T24 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1429948713862692864)} 0 6
[junit4:junit4]   2> 214590 T27 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 214592 T27 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_17,generation=43,filenames=[_t_Lucene41_0.pay, _t_Lucene41_0.tim, _t.si, _t_Lucene41_0.tip, _t.fnm, _t_Lucene41_0.doc, _t.nvd, _t.nvm, _t.fdx, _t_Lucene41_0.pos, segments_17, _t.fdt]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_18,generation=44,filenames=[segments_18]
[junit4:junit4]   2> 214593 T27 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 44[segments_18]
[junit4:junit4]   2> 214594 T27 C1 oass.SolrIndexSearcher.<init> Opening Searcher@7220d28b main
[junit4:junit4]   2> 214596 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@7220d28b main{StandardDirectoryReader(segments_17:107:nrt)}
[junit4:junit4]   2> 214598 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 214676 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7220d28b main{StandardDirectoryReader(segments_17:107:nrt)}
[junit4:junit4]   2> 214680 T27 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 214680 T27 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 90
[junit4:junit4]   2> 214718 T29 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[unique (1429948714005299200)]} 0 4
[junit4:junit4]   2> 214726 T28 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 214733 T28 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_18,generation=44,filenames=[segments_18]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_19,generation=45,filenames=[_u_Lucene41_0.tip, _u.fnm, _u_Lucene41_0.pos, _u_Lucene41_0.tim, _u_Lucene41_0.doc, _u.nvm, _u.fdx, _u.si, _u.fdt, _u.nvd, segments_19]
[junit4:junit4]   2> 214733 T28 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 45[_u_Lucene41_0.tip, _u.fnm, _u_Lucene41_0.pos, _u_Lucene41_0.tim, _u_Lucene41_0.doc, _u.nvm, _u.fdx, _u.si, _u.fdt, _u.nvd, segments_19]
[junit4:junit4]   2> 214737 T28 C1 oass.SolrIndexSearcher.<init> Opening Searcher@4e2ccc7b main
[junit4:junit4]   2> 214740 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@4e2ccc7b main{StandardDirectoryReader(segments_19:109:nrt _u(5.0):C1)}
[junit4:junit4]   2> 214740 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 214742 T28 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 214742 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4e2ccc7b main{StandardDirectoryReader(segments_19:109:nrt _u(5.0):C1)}
[junit4:junit4]   2> 214745 T28 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 19
[junit4:junit4]   2> 214752 T26 C1 REQ [collection1] webapp=/solr path=/select params={fl=id,price_f,name,_version_&q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=2 
[junit4:junit4]   2> 214760 T25 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {} 0 1
[junit4:junit4]   2> 214771 T24 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[unique (1429948714060873728)]} 0 4
[junit4:junit4]   2> 214777 T27 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 214784 T27 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_19,generation=45,filenames=[_u_Lucene41_0.tip, _u.fnm, _u_Lucene41_0.pos, _u_Lucene41_0.tim, _u_Lucene41_0.doc, _u.nvm, _u.fdx, _u.si, _u.fdt, _u.nvd, segments_19]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_1a,generation=46,filenames=[_v.nvd, segments_1a, _v_Lucene41_0.tip, _v.fdx, _v.fdt, _v_Lucene41_0.doc, _v.si, _v_Lucene41_0.tim, _v_Lucene41_0.pos, _v.nvm, _v.fnm]
[junit4:junit4]   2> 214785 T27 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 46[_v.nvd, segments_1a, _v_Lucene41_0.tip, _v.fdx, _v.fdt, _v_Lucene41_0.doc, _v.si, _v_Lucene41_0.tim, _v_Lucene41_0.pos, _v.nvm, _v.fnm]
[junit4:junit4]   2> 214859 T27 C1 oass.SolrIndexSearcher.<init> Opening Searcher@30497b4c main
[junit4:junit4]   2> 214861 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@30497b4c main{StandardDirectoryReader(segments_1a:113:nrt _v(5.0):C1)}
[junit4:junit4]   2> 214861 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 214862 T27 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 214865 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@30497b4c main{StandardDirectoryReader(segments_1a:113:nrt _v(5.0):C1)}
[junit4:junit4]   2> 214866 T27 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 90
[junit4:junit4]   2> 214873 T29 C1 REQ [collection1] webapp=/solr path=/select params={fl=id,price_f,name,_version_&q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 214884 T28 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[unique (1429948714179362816)]} 0 4
[junit4:junit4]   2> 214890 T26 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 214894 T26 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_1a,generation=46,filenames=[_v.nvd, segments_1a, _v_Lucene41_0.tip, _v.fdx, _v.fdt, _v_Lucene41_0.doc, _v.si, _v_Lucene41_0.tim, _v_Lucene41_0.pos, _v.nvm, _v.fnm]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_1b,generation=47,filenames=[_w.si, _w.fdt, _w_Lucene41_0.tip, _w_Lucene41_0.pos, _w.nvd, _w_Lucene41_0.tim, _w.fdx, _w.nvm, _w.fnm, segments_1b, _w_Lucene41_0.doc]
[junit4:junit4]   2> 214899 T26 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 47[_w.si, _w.fdt, _w_Lucene41_0.tip, _w_Lucene41_0.pos, _w.nvd, _w_Lucene41_0.tim, _w.fdx, _w.nvm, _w.fnm, segments_1b, _w_Lucene41_0.doc]
[junit4:junit4]   2> 214900 T26 C1 oass.SolrIndexSearcher.<init> Opening Searcher@4f955775 main
[junit4:junit4]   2> 214900 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@4f955775 main{StandardDirectoryReader(segments_1b:117:nrt _w(5.0):C1)}
[junit4:junit4]   2> 214900 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 214904 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4f955775 main{StandardDirectoryReader(segments_1b:117:nrt _w(5.0):C1)}
[junit4:junit4]   2> 214905 T26 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 214907 T26 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 18
[junit4:junit4]   2> 214912 T24 C1 REQ [collection1] webapp=/solr path=/select params={fl=id,price_f,name,_version_&q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 214913 T19 oas.SolrTestCaseJ4.tearDown ###Ending testUpdateField
[junit4:junit4]   2> 214935 T19 oas.SolrTestCaseJ4.setUp ###Starting testUpdateRequestWithParameters
[junit4:junit4]   2> 214939 T19 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 215033 T28 C1 oass.SolrIndexSearcher.<init> Opening Searcher@6b404342 realtime
[junit4:junit4]   2> 215036 T28 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1429948714336649216)} 0 6
[junit4:junit4]   2> 215044 T25 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 215045 T25 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_1b,generation=47,filenames=[_w.si, _w.fdt, _w_Lucene41_0.tip, _w_Lucene41_0.pos, _w.nvd, _w_Lucene41_0.tim, _w.fdx, _w.nvm, _w.fnm, segments_1b, _w_Lucene41_0.doc]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_1c,generation=48,filenames=[segments_1c]
[junit4:junit4]   2> 215047 T25 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 48[segments_1c]
[junit4:junit4]   2> 215048 T25 C1 oass.SolrIndexSearcher.<init> Opening Searcher@66828c9a main
[junit4:junit4]   2> 215049 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@66828c9a main{StandardDirectoryReader(segments_1b:119:nrt)}
[junit4:junit4]   2> 215049 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 215053 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@66828c9a main{StandardDirectoryReader(segments_1b:119:nrt)}
[junit4:junit4]   2> 215055 T25 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 215056 T25 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 12
[junit4:junit4]   2> 215064 T26 C1 UPDATE [collection1] webapp=/solr path=/update params={overwrite=false&wt=javabin&version=2} {add=[id1 (1429948714369155072)]} 0 3
[junit4:junit4]   2> 215087 T24 C1 UPDATE [collection1] webapp=/solr path=/update params={overwrite=false&wt=javabin&version=2} {add=[id1 (1429948714380689408)]} 0 16
[junit4:junit4]   2> 215096 T27 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 215099 T27 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_1c,generation=48,filenames=[segments_1c]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_1d,generation=49,filenames=[_x.fdt, _x.fnm, _x.si, _x_Lucene41_0.doc, _x.fdx, _x_Lucene41_0.tip, _x_Lucene41_0.tim, segments_1d]
[junit4:junit4]   2> 215100 T27 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 49[_x.fdt, _x.fnm, _x.si, _x_Lucene41_0.doc, _x.fdx, _x_Lucene41_0.tip, _x_Lucene41_0.tim, segments_1d]
[junit4:junit4]   2> 215102 T27 C1 oass.SolrIndexSearcher.<init> Opening Searcher@6b5638a main
[junit4:junit4]   2> 215103 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@6b5638a main{StandardDirectoryReader(segments_1d:121:nrt _x(5.0):C2)}
[junit4:junit4]   2> 215103 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 215103 T27 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 215106 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6b5638a main{StandardDirectoryReader(segments_1d:121:nrt _x(5.0):C2)}
[junit4:junit4]   2> 215109 T27 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 14
[junit4:junit4]   2> 215114 T29 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=0 
[junit4:junit4]   2> 215117 T19 oas.SolrTestCaseJ4.tearDown ###Ending testUpdateRequestWithParameters
[junit4:junit4]   2> 215139 T19 oas.SolrTestCaseJ4.setUp ###Starting testFaceting
[junit4:junit4]   2> 215148 T28 C1 oass.SolrIndexSearcher.<init> Opening Searcher@7c36deae realtime
[junit4:junit4]   2> 215148 T28 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1429948714457235456)} 0 4
[junit4:junit4]   2> 215154 T25 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 215154 T25 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_1d,generation=49,filenames=[_x.fdt, _x.fnm, _x.si, _x_Lucene41_0.doc, _x.fdx, _x_Lucene41_0.tip, _x_Lucene41_0.tim, segments_1d]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_1e,generation=50,filenames=[segments_1e]
[junit4:junit4]   2> 215156 T25 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 50[segments_1e]
[junit4:junit4]   2> 215157 T25 C1 oass.SolrIndexSearcher.<init> Opening Searcher@96f3ed8 main
[junit4:junit4]   2> 215158 T25 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 215158 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@96f3ed8 main{StandardDirectoryReader(segments_1d:123:nrt)}
[junit4:junit4]   2> 215160 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 215179 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@96f3ed8 main{StandardDirectoryReader(segments_1d:123:nrt)}
[junit4:junit4]   2> 215249 T25 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 95
[junit4:junit4]   2> 215257 T26 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 215285 T24 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1 (1429948714580967424), 2 (1429948714596696064), 3 (1429948714596696065), 4 (1429948714597744640), 5 (1429948714599841792), 6 (1429948714600890368), 7 (1429948714600890369), 8 (1429948714600890370), 9 (1429948714602987520), 10 (1429948714604036096)]} 0 22
[junit4:junit4]   2> 215293 T27 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 215298 T27 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_1e,generation=50,filenames=[segments_1e]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_1f,generation=51,filenames=[_y.fdt, _y.nvd, _y_Lucene41_0.tip, _y.si, _y.fdx, _y_Lucene41_0.pos, segments_1f, _y.nvm, _y.fnm, _y_Lucene41_0.doc, _y_Lucene41_0.tim]
[junit4:junit4]   2> 215298 T27 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 51[_y.fdt, _y.nvd, _y_Lucene41_0.tip, _y.si, _y.fdx, _y_Lucene41_0.pos, segments_1f, _y.nvm, _y.fnm, _y_Lucene41_0.doc, _y_Lucene41_0.tim]
[junit4:junit4]   2> 215302 T27 C1 oass.SolrIndexSearcher.<init> Opening Searcher@26d7950d main
[junit4:junit4]   2> 215305 T27 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 215306 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@26d7950d main{StandardDirectoryReader(segments_1f:125:nrt _y(5.0):C10)}
[junit4:junit4]   2> 215306 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 215310 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@26d7950d main{StandardDirectoryReader(segments_1f:125:nrt _y(5.0):C10)}
[junit4:junit4]   2> 215312 T27 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 19
[junit4:junit4]   2> 215320 T28 C1 oasr.UnInvertedField.<init> UnInverted multi-valued field {field=features,memSize=4564,tindexSize=44,time=0,phase1=0,nTerms=4,bigTerms=2,termInstances=4,uses=0}
[junit4:junit4]   2> 215321 T28 C1 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=1 
[junit4:junit4]   2> 215328 T25 C1 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=0 
[junit4:junit4]   2> 215336 T26 C1 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> 215341 T24 C1 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> 215344 T19 oas.SolrTestCaseJ4.tearDown ###Ending testFaceting
[junit4:junit4]   2> 215360 T19 oas.SolrTestCaseJ4.setUp ###Starting testPivotFacets
[junit4:junit4]   2> 215367 T29 C1 oass.SolrIndexSearcher.<init> Opening Searcher@a41bf09 realtime
[junit4:junit4]   2> 215370 T29 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1429948714688970752)} 0 4
[junit4:junit4]   2> 215375 T27 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 215375 T27 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_1f,generation=51,filenames=[_y.fdt, _y.nvd, _y_Lucene41_0.tip, _y.si, _y.fdx, _y_Lucene41_0.pos, segments_1f, _y.nvm, _y.fnm, _y_Lucene41_0.doc, _y_Lucene41_0.tim]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_1g,generation=52,filenames=[segments_1g]
[junit4:junit4]   2> 215376 T27 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 52[segments_1g]
[junit4:junit4]   2> 215378 T27 C1 oass.SolrIndexSearcher.<init> Opening Searcher@63cf0ffb main
[junit4:junit4]   2> 215379 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@63cf0ffb main{StandardDirectoryReader(segments_1f:127:nrt)}
[junit4:junit4]   2> 215379 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 215380 T27 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 215383 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@63cf0ffb main{StandardDirectoryReader(segments_1f:127:nrt)}
[junit4:junit4]   2> 215384 T27 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 9
[junit4:junit4]   2> 215390 T28 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 215409 T25 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1 (1429948714719379456), 2 (1429948714722525184), 3 (1429948714723573760), 4 (1429948714725670912), 5 (1429948714726719488), 6 (1429948714726719489), 7 (1429948714727768064), 8 (1429948714729865216), 9 (1429948714730913792), 10 (1429948714731962368), ... (12 adds)]} 0 15
[junit4:junit4]   2> 215415 T26 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 215422 T26 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_1g,generation=52,filenames=[segments_1g]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_1h,generation=53,filenames=[_z.fdt, _z.nvm, _z_Lucene41_0.pos, _z.fdx, _z_Lucene41_0.tim, _z_Lucene41_0.doc, segments_1h, _z.si, _z_Lucene41_0.tip, _z.nvd, _z.fnm]
[junit4:junit4]   2> 215422 T26 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 53[_z.fdt, _z.nvm, _z_Lucene41_0.pos, _z.fdx, _z_Lucene41_0.tim, _z_Lucene41_0.doc, segments_1h, _z.si, _z_Lucene41_0.tip, _z.nvd, _z.fnm]
[junit4:junit4]   2> 215425 T26 C1 oass.SolrIndexSearcher.<init> Opening Searcher@7cac5ecd main
[junit4:junit4]   2> 215426 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@7cac5ecd main{StandardDirectoryReader(segments_1h:129:nrt _z(5.0):C12)}
[junit4:junit4]   2> 215426 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 215426 T26 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 215430 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7cac5ecd main{StandardDirectoryReader(segments_1h:129:nrt _z(5.0):C12)}
[junit4:junit4]   2> 215432 T26 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 17
[junit4:junit4]   2> 215438 T24 C1 oasr.UnInvertedField.<init> UnInverted multi-valued field {field=features,memSize=4521,tindexSize=43,time=0,phase1=0,nTerms=2,bigTerms=2,termInstances=0,uses=0}
[junit4:junit4]   2> 215440 T24 C1 oasr.UnInvertedField.<init> UnInverted multi-valued field {field=cat,memSize=4515,tindexSize=41,time=0,phase1=0,nTerms=2,bigTerms=2,termInstances=0,uses=0}
[junit4:junit4]   2> 215442 T24 C1 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=5 
[junit4:junit4]   2> 215450 T29 C1 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=2 
[junit4:junit4]   2> 215453 T19 oas.SolrTestCaseJ4.tearDown ###Ending testPivotFacets
[junit4:junit4]   2> 215473 T19 oas.SolrTestCaseJ4.setUp ###Starting testSetNullUpdates
[junit4:junit4]   2> 215480 T27 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[testSetNullUpdates (1429948714807459840)]} 0 1
[junit4:junit4]   2> 215487 T28 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 215489 T28 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_1h,generation=53,filenames=[_z.fdt, _z.nvm, _z_Lucene41_0.pos, _z.fdx, _z_Lucene41_0.tim, _z_Lucene41_0.doc, segments_1h, _z.si, _z_Lucene41_0.tip, _z.nvd, _z.fnm]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_1i,generation=54,filenames=[_10.fnm, _z.fdt, _10.fdt, _z.fdx, _z_Lucene41_0.tim, _10_Lucene41_0.tip, segments_1i, _z.si, _z.nvd, _10_Lucene41_0.tim, _z.fnm, _10.fdx, _10.si, _z.nvm, _z_Lucene41_0.pos, _10_Lucene41_0.doc, _z_Lucene41_0.doc, _z_Lucene41_0.tip]
[junit4:junit4]   2> 215489 T28 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 54[_10.fnm, _z.fdt, _10.fdt, _z.fdx, _z_Lucene41_0.tim, _10_Lucene41_0.tip, segments_1i, _z.si, _z.nvd, _10_Lucene41_0.tim, _z.fnm, _10.fdx, _10.si, _z.nvm, _z_Lucene41_0.pos, _10_Lucene41_0.doc, _z_Lucene41_0.doc, _z_Lucene41_0.tip]
[junit4:junit4]   2> 215492 T28 C1 oass.SolrIndexSearcher.<init> Opening Searcher@3865a7c4 main
[junit4:junit4]   2> 215495 T28 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 215495 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@3865a7c4 main{StandardDirectoryReader(segments_1i:131:nrt _z(5.0):C12 _10(5.0):C1)}
[junit4:junit4]   2> 215496 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 215498 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3865a7c4 main{StandardDirectoryReader(segments_1i:131:nrt _z(5.0):C12 _10(5.0):C1)}
[junit4:junit4]   2> 215498 T28 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 13
[junit4:junit4]   2> 215507 T25 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[testSetNullUpdates (1429948714833674240)]} 0 4
[junit4:junit4]   2> 215512 T26 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 215523 T26 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_1i,generation=54,filenames=[_10.fnm, _z.fdt, _10.fdt, _z.fdx, _z_Lucene41_0.tim, _10_Lucene41_0.tip, segments_1i, _z.si, _z.nvd, _10_Lucene41_0.tim, _z.fnm, _10.fdx, _10.si, _z.nvm, _z_Lucene41_0.pos, _10_Lucene41_0.doc, _z_Lucene41_0.doc, _z_Lucene41_0.tip]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_1j,generation=55,filenames=[_z.fdt, _11_Lucene41_0.tip, _11.si, _11_Lucene41_0.tim, _z.fdx, _11.fdx, segments_1j, _z_Lucene41_0.tim, _z.si, _11.fdt, _z.nvd, _11_Lucene41_0.doc, _z.fnm, _z.nvm, _z_Lucene41_0.pos, _11.fnm, _z_Lucene41_0.doc, _z_Lucene41_0.tip]
[junit4:junit4]   2> 215523 T26 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 55[_z.fdt, _11_Lucene41_0.tip, _11.si, _11_Lucene41_0.tim, _z.fdx, _11.fdx, segments_1j, _z_Lucene41_0.tim, _z.si, _11.fdt, _z.nvd, _11_Lucene41_0.doc, _z.fnm, _z.nvm, _z_Lucene41_0.pos, _11.fnm, _z_Lucene41_0.doc, _z_Lucene41_0.tip]
[junit4:junit4]   2> 215526 T26 C1 oass.SolrIndexSearcher.<init> Opening Searcher@62ea9dd9 main
[junit4:junit4]   2> 215527 T26 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 215527 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@62ea9dd9 main{StandardDirectoryReader(segments_1j:135:nrt _z(5.0):C12 _11(5.0):C1)}
[junit4:junit4]   2> 215529 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 215530 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@62ea9dd9 main{StandardDirectoryReader(segments_1j:135:nrt _z(5.0):C12 _11(5.0):C1)}
[junit4:junit4]   2> 215531 T26 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 19
[junit4:junit4]   2> 215538 T24 C1 REQ [collection1] webapp=/solr path=/select params={q=id:testSetNullUpdates&wt=javabin&version=2} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 215541 T19 oas.SolrTestCaseJ4.tearDown ###Ending testSetNullUpdates
[junit4:junit4]   2> 215559 T19 oas.SolrTestCaseJ4.setUp ###Starting testUnicode
[junit4:junit4]   2> 215569 T29 C1 oass.SolrIndexSearcher.<init> Opening Searcher@57921a3f realtime
[junit4:junit4]   2> 215569 T29 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1429948714897637376)} 0 4
[junit4:junit4]   2> 215590 T27 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1429948714917560320), 1 (1429948714918608896), 2 (1429948714918608897), 3 (1429948714921754624), 4 (1429948714921754625), 5 (1429948714922803200)]} 0 6
[junit4:junit4]   2> 215597 T28 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 215600 T28 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_1j,generation=55,filenames=[_z.fdt, _11_Lucene41_0.tip, _11.si, _11_Lucene41_0.tim, _z.fdx, _11.fdx, segments_1j, _z_Lucene41_0.tim, _z.si, _11.fdt, _z.nvd, _11_Lucene41_0.doc, _z.fnm, _z.nvm, _z_Lucene41_0.pos, _11.fnm, _z_Lucene41_0.doc, _z_Lucene41_0.tip]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_1k,generation=56,filenames=[_12.si, _12_Lucene41_0.doc, _12_Lucene41_0.tip, _12_Lucene41_0.tim, _12.fnm, segments_1k, _12.fdt, _12.fdx]
[junit4:junit4]   2> 215603 T28 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 56[_12.si, _12_Lucene41_0.doc, _12_Lucene41_0.tip, _12_Lucene41_0.tim, _12.fnm, segments_1k, _12.fdt, _12.fdx]
[junit4:junit4]   2> 215604 T28 C1 oass.SolrIndexSearcher.<init> Opening Searcher@1ae3c86b main
[junit4:junit4]   2> 215607 T28 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 215607 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1ae3c86b main{StandardDirectoryReader(segments_1k:139:nrt _12(5.0):C6)}
[junit4:junit4]   2> 215607 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 215609 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ae3c86b main{StandardDirectoryReader(segments_1k:139:nrt _12(5.0):C6)}
[junit4:junit4]   2> 215611 T28 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 15
[junit4:junit4]   2> 215617 T25 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2&rows=6} hits=6 status=0 QTime=1 
[junit4:junit4]   2> 215628 T26 C1 oass.SolrIndexSearcher.<init> Opening Searcher@14c02506 realtime
[junit4:junit4]   2> 215629 T26 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1429948714962649088)} 0 4
[junit4:junit4]   2> 215639 T24 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 (1429948714972086272), 1 (1429948714974183424), 2 (1429948714974183425), 3 (1429948714975232000), 4 (1429948714975232001)]} 0 4
[junit4:junit4]   2> 215646 T29 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 215650 T29 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_1k,generation=56,filenames=[_12.si, _12_Lucene41_0.doc, _12_Lucene41_0.tip, _12_Lucene41_0.tim, _12.fnm, segments_1k, _12.fdt, _12.fdx]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_1l,generation=57,filenames=[segments_1l, _13_Lucene41_0.tip, _13_Lucene41_0.tim, _13_Lucene41_0.doc, _13.si, _13.fdt, _13.fnm, _13.fdx]
[junit4:junit4]   2> 215650 T29 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 57[segments_1l, _13_Lucene41_0.tip, _13_Lucene41_0.tim, _13_Lucene41_0.doc, _13.si, _13.fdt, _13.fnm, _13.fdx]
[junit4:junit4]   2> 215651 T29 C1 oass.SolrIndexSearcher.<init> Opening Searcher@11b9981a main
[junit4:junit4]   2> 215654 T29 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 215655 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@11b9981a main{StandardDirectoryReader(segments_1l:143:nrt _13(5.0):C5)}
[junit4:junit4]   2> 215655 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 215658 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@11b9981a main{StandardDirectoryReader(segments_1l:143:nrt _13(5.0):C5)}
[junit4:junit4]   2> 215659 T29 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 13
[junit4:junit4]   2> 215670 T27 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2&rows=5} hits=5 status=0 QTime=1 
[junit4:junit4]   2> 215678 T28 C1 oass.SolrIndexSearcher.<init> Opening Searcher@12175750 realtime
[junit4:junit4]   2> 215679 T28 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1429948715015077888)} 0 4
[junit4:junit4]   2> 215689 T25 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 (1429948715024515072), 1 (1429948715024515073), 2 (1429948715025563648), 3 (1429948715025563649)]} 0 4
[junit4:junit4]   2> 215694 T26 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 215698 T26 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_1l,generation=57,filenames=[segments_1l, _13_Lucene41_0.tip, _13_Lucene41_0.tim, _13_Lucene41_0.doc, _13.si, _13.fdt, _13.fnm, _13.fdx]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1568484c lockFactory=org.apache.lucene.store.NativeFSLockFactory@747946a4),segFN=segments_1m,generation=58,filenames=[_14.si, _14_Lucene41_0.tip, segments_1m, _14_Lucene41_0.tim, _14.fdx, _14_Lucene41_0.doc, _14.fnm, _14.fdt]
[junit4:junit4]   2> 215698 T26 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 58[_14.si, _14_Lucene41_0.tip, segments_1m, _14_Lucene41_0.tim, _14.fdx, _14_Lucene41_0.doc, _14.fnm, _14.fdt]
[junit4:junit4]   2> 215701 T26 C1 oass.SolrIndexSearcher.<init> Opening Searcher@77324fc2 main
[junit4:junit4]   2> 215702 T26 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 215705 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@77324fc2 main{StandardDirectoryReader(segments_1m:147:nrt _14(5.0):C4)}
[junit4:junit4]   2> 215705 T31 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 215706 T31 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@77324fc2 main{StandardDirectoryReader(segments_1m:147:nrt _14(5.0):C4)}
[junit4:junit4]   2> 215708 T26 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 14
[junit4:junit4]   2> 215714 T29 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2&rows=4} hits=4 status=0 QTime=1 
[junit4:junit4]   2> 215717 T19 oas.SolrTestCaseJ4.tearDown ###Ending testUnicode
[junit4:junit4]   2> 215739 T19 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=594581735
[junit4:junit4]   2> 215753 T19 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@2aaa5483
[junit4:junit4]   2> 215784 T19 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=58,autocommit maxTime=15000ms,autocommits=1,soft autocommits=3,optimizes=2,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=1,cumulative_adds=139,cumulative_deletesById=2,cumulative_deletesByQuery=27,cumulative_errors=1}
[junit4:junit4]   2> 215787 T19 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4:junit4]   2> 215788 T19 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 215790 T19 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4:junit4]   2> 215796 T19 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 215796 T19 oasc.CachingDirectoryFactory.close Closing directory when closing factory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleBinaryTest-1363705147613\
[junit4:junit4]   2> 215798 T19 oasc.CachingDirectoryFactory.closeDirectory Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleBinaryTest-1363705147613\
[junit4:junit4]   2> 215798 T19 oasc.CachingDirectoryFactory.close Closing directory when closing factory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleBinaryTest-1363705147613\index
[junit4:junit4]   2> 215799 T19 oasc.CachingDirectoryFactory.closeDirectory Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleBinaryTest-1363705147613\index
[junit4:junit4]   2> 215800 T19 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 215862 T19 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> NOTE: test params are: codec=Lucene42: {}, docValues:{}, sim=DefaultSimilarity, locale=pt_BR, timezone=Europe/Vatican
[junit4:junit4]   2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.7.0_15 (64-bit)/cpus=2,threads=1,free=50324912,total=108724224
[junit4:junit4]   2> NOTE: All tests run in this JVM: [TestXMLEscaping, TermsResponseTest, TestHash, SolrExampleBinaryTest]
[junit4:junit4] Completed in 215.99s, 26 tests, 1 error <<< FAILURES!

[...truncated 134 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:375: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:355: 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:449: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1210: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:874: There were test failures: 45 suites, 266 tests, 1 error, 1 ignored (1 assumption)

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



Mime
View raw message