lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@thetaphi.de>
Subject [JENKINS] Lucene-Solr-4.x-Linux (64bit/jdk1.7.0_15) - Build # 4469 - Failure!
Date Wed, 27 Feb 2013 02:01:27 GMT
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/4469/
Java: 64bit/jdk1.7.0_15 -XX:+UseConcMarkSweepGC

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

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

Stack Trace:
org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: https://127.0.0.1:60773/solr
	at __randomizedtesting.SeedInfo.seed([65E8702BAA9F74FB:9241434F06B2AE13]: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.testStatistics(SolrExampleTests.java:804)
	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 10066 lines...]
[junit4:junit4] Suite: org.apache.solr.client.solrj.SolrExampleBinaryTest
[junit4:junit4]   2> 0 T51 oas.SolrJettyTestBase.beforeSolrJettyTestBase Randomized ssl (true) and clientAuth (false)
[junit4:junit4]   2> 1 T51 oas.SolrTestCaseJ4.initCore ####initCore
[junit4:junit4]   2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/./solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 1 T51 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4:junit4]   2> 2 T51 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 5 T51 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4:junit4]   2> 28 T51 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:60773
[junit4:junit4]   2> 29 T51 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 29 T51 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 29 T51 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr
[junit4:junit4]   2> 30 T51 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/solr.xml
[junit4:junit4]   2> 30 T51 oasc.CoreContainer.<init> New CoreContainer 1856241576
[junit4:junit4]   2> 30 T51 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/'
[junit4:junit4]   2> 31 T51 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/'
[junit4:junit4]   2> 50 T51 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 51 T51 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 51 T51 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 51 T51 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 52 T51 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 52 T51 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 52 T51 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 53 T51 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 53 T51 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 53 T51 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 60 T51 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 77 T61 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1
[junit4:junit4]   2> 79 T61 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/'
[junit4:junit4]   2> 122 T61 oasc.SolrConfig.initLibs Adding specified lib dirs to ClassLoader
[junit4:junit4]   2> 189 T61 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_42
[junit4:junit4]   2> 258 T61 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 258 T61 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 299 T61 oass.IndexSchema.readSchema Schema name=example
[junit4:junit4]   2> 867 T61 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 895 T61 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 905 T61 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 962 T61 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/
[junit4:junit4]   2> 962 T61 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a66cceb
[junit4:junit4]   2> 963 T61 oasc.SolrCore.initListeners [collection1] Added SolrEventListener for newSearcher: org.apache.solr.core.QuerySenderListener{queries=[]}
[junit4:junit4]   2> 963 T61 oasc.SolrCore.initListeners [collection1] Added SolrEventListener for firstSearcher: org.apache.solr.core.QuerySenderListener{queries=[{q=static firstSearcher warming in solrconfig.xml}]}
[junit4:junit4]   2> 964 T61 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 965 T61 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164 forceNew:false
[junit4:junit4]   2> 965 T61 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 966 T61 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index/
[junit4:junit4]   2> 966 T61 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 967 T61 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index forceNew:false
[junit4:junit4]   2> 968 T61 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 969 T61 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 969 T61 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 969 T61 oasc.SolrCore.initWriters created json: solr.JSONResponseWriter
[junit4:junit4]   2> 970 T61 oasc.SolrCore.initWriters adding lazy queryResponseWriter: solr.VelocityResponseWriter
[junit4:junit4]   2> 970 T61 oasc.SolrCore.initWriters created velocity: solr.VelocityResponseWriter
[junit4:junit4]   2> 970 T61 oasc.SolrCore.initWriters created xslt: solr.XSLTResponseWriter
[junit4:junit4]   2> 970 T61 oasr.XSLTResponseWriter.init xsltCacheLifetimeSeconds=5
[junit4:junit4]   2> 972 T61 oasc.RequestHandlers.initHandlersFromConfig created /select: solr.SearchHandler
[junit4:junit4]   2> 973 T61 oasc.RequestHandlers.initHandlersFromConfig created /query: solr.SearchHandler
[junit4:junit4]   2> 974 T61 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 974 T61 oasc.RequestHandlers.initHandlersFromConfig created /browse: solr.SearchHandler
[junit4:junit4]   2> 975 T61 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 975 T61 oasc.RequestHandlers.initHandlersFromConfig created /update/json: solr.JsonUpdateRequestHandler
[junit4:junit4]   2> 976 T61 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: solr.CSVRequestHandler
[junit4:junit4]   2> 976 T61 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.extraction.ExtractingRequestHandler
[junit4:junit4]   2> 977 T61 oasc.RequestHandlers.initHandlersFromConfig created /update/extract: solr.extraction.ExtractingRequestHandler
[junit4:junit4]   2> 977 T61 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.FieldAnalysisRequestHandler
[junit4:junit4]   2> 977 T61 oasc.RequestHandlers.initHandlersFromConfig created /analysis/field: solr.FieldAnalysisRequestHandler
[junit4:junit4]   2> 977 T61 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.DocumentAnalysisRequestHandler
[junit4:junit4]   2> 978 T61 oasc.RequestHandlers.initHandlersFromConfig created /analysis/document: solr.DocumentAnalysisRequestHandler
[junit4:junit4]   2> 978 T61 oasc.RequestHandlers.initHandlersFromConfig created /admin/: solr.admin.AdminHandlers
[junit4:junit4]   2> 979 T61 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: solr.PingRequestHandler
[junit4:junit4]   2> 979 T61 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 980 T61 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 980 T61 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
[junit4:junit4]   2> 981 T61 oasc.RequestHandlers.initHandlersFromConfig created /spell: solr.SearchHandler
[junit4:junit4]   2> 981 T61 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
[junit4:junit4]   2> 981 T61 oasc.RequestHandlers.initHandlersFromConfig created /tvrh: solr.SearchHandler
[junit4:junit4]   2> 981 T61 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
[junit4:junit4]   2> 982 T61 oasc.RequestHandlers.initHandlersFromConfig created /terms: solr.SearchHandler
[junit4:junit4]   2> 982 T61 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
[junit4:junit4]   2> 982 T61 oasc.RequestHandlers.initHandlersFromConfig created /elevate: solr.SearchHandler
[junit4:junit4]   2> 990 T61 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 991 T61 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 992 T61 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 996 T61 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 996 T61 oass.SolrIndexSearcher.<init> Opening Searcher@7bd3c61b main
[junit4:junit4]   2> 997 T61 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/tlog
[junit4:junit4]   2> 997 T61 oasu.CommitTracker.<init> Hard AutoCommit: if uncommited for 15000ms; 
[junit4:junit4]   2> 997 T61 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 998 T61 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 998 T61 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> 999 T61 oashc.SpellCheckComponent.inform No queryConverter defined, using default converter
[junit4:junit4]   2> 999 T61 oashc.QueryElevationComponent.inform Loading QueryElevation from: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/conf/elevate.xml
[junit4:junit4]   2> 1010 T61 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 1010 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@7bd3c61b main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 1010 T61 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> ASYNC  NEW_CORE C1 name=collection1 org.apache.solr.core.SolrCore@37f7466f
[junit4:junit4]   2> 1012 T62 C1 REQ [collection1] webapp=null path=null params={event=firstSearcher&q=static+firstSearcher+warming+in+solrconfig.xml&distrib=false} hits=0 status=0 QTime=2 
[junit4:junit4]   2> 1012 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 1012 T62 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default
[junit4:junit4]   2> 1012 T62 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak
[junit4:junit4]   2> 1014 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7bd3c61b main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 1015 T61 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 1017 T61 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 1017 T51 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1
[junit4:junit4]   2> 1018 T51 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 1018 T51 oas.SolrJettyTestBase.createJetty Jetty Assigned Port#60773
[junit4:junit4]   2> 1026 T51 oas.SolrTestCaseJ4.setUp ###Starting testUnicode
[junit4:junit4]   2> 1026 T51 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 1091 T59 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 1093 T59 C1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 1093 T59 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 1094 T59 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 1095 T59 C1 oass.SolrIndexSearcher.<init> Opening Searcher@65f673ad realtime
[junit4:junit4]   2> 1095 T59 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1428087353118818304)} 0 4
[junit4:junit4]   2> 1139 T60 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1428087353156567040), 1 (1428087353157615616), 2 (1428087353158664192), 3 (1428087353158664193), 4 (1428087353159712768), 5 (1428087353159712769), 6 (1428087353159712770), 7 (1428087353160761344), 8 (1428087353160761345), 9 (1428087353161809920), ... (28 adds)]} 0 12
[junit4:junit4]   2> 1143 T58 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 1154 T58 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_2,generation=2,filenames=[_0.tis, _0.fnm, _0.tii, _0.frq, segments_2, _0.fdx, _0.si, _0.fdt]
[junit4:junit4]   2> 1155 T58 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[_0.tis, _0.fnm, _0.tii, _0.frq, segments_2, _0.fdx, _0.si, _0.fdt]
[junit4:junit4]   2> 1155 T58 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 1171 T58 C1 oass.SolrIndexSearcher.<init> Opening Searcher@29749e2e main
[junit4:junit4]   2> 1171 T58 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 1172 T58 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 1172 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@29749e2e main{StandardDirectoryReader(segments_2:3:nrt _0(4.2):C28)}
[junit4:junit4]   2> 1172 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 1175 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@29749e2e main{StandardDirectoryReader(segments_2:3:nrt _0(4.2):C28)}
[junit4:junit4]   2> 1176 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 1176 T58 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 33
[junit4:junit4]   2> 1181 T57 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2&rows=28} hits=28 status=0 QTime=1 
[junit4:junit4]   2> 1192 T59 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 1202 T59 C1 oass.SolrIndexSearcher.<init> Opening Searcher@6241678e realtime
[junit4:junit4]   2> 1203 T59 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1428087353223675904)} 0 12
[junit4:junit4]   2> 1225 T60 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1428087353242550272), 1 (1428087353243598848), 2 (1428087353243598849), 3 (1428087353244647424), 4 (1428087353245696000), 5 (1428087353245696001), 6 (1428087353246744576), 7 (1428087353246744577), 8 (1428087353247793152), 9 (1428087353247793153), ... (28 adds)]} 0 16
[junit4:junit4]   2> 1229 T57 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 1233 T57 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_2,generation=2,filenames=[_0.tis, _0.fnm, _0.tii, _0.frq, segments_2, _0.fdx, _0.si, _0.fdt]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_3,generation=3,filenames=[_1.fdx, _1.tis, _1.frq, _1.fdt, _1.si, _1.tii, _1.fnm, segments_3]
[junit4:junit4]   2> 1234 T57 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 3[_1.fdx, _1.tis, _1.frq, _1.fdt, _1.si, _1.tii, _1.fnm, segments_3]
[junit4:junit4]   2> 1234 T57 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 1235 T57 C1 oass.SolrIndexSearcher.<init> Opening Searcher@3cab4f33 main
[junit4:junit4]   2> 1236 T57 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 1236 T57 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 1236 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@3cab4f33 main{StandardDirectoryReader(segments_3:7:nrt _1(4.2):C28)}
[junit4:junit4]   2> 1237 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 1240 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3cab4f33 main{StandardDirectoryReader(segments_3:7:nrt _1(4.2):C28)}
[junit4:junit4]   2> 1241 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 1241 T57 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 12
[junit4:junit4]   2> 1245 T59 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2&rows=28} hits=28 status=0 QTime=0 
[junit4:junit4]   2> 1254 T58 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 1256 T58 C1 oass.SolrIndexSearcher.<init> Opening Searcher@71b8f079 realtime
[junit4:junit4]   2> 1257 T58 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1428087353289736192)} 0 3
[junit4:junit4]   2> 1283 T60 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1428087353299173376), 1 (1428087353300221952), 2 (1428087353300221953), 3 (1428087353301270528), 4 (1428087353308610560), 5 (1428087353311756288), 6 (1428087353314902016), 7 (1428087353314902017), 8 (1428087353315950592), 9 (1428087353315950593), ... (17 adds)]} 0 20
[junit4:junit4]   2> 1288 T59 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 1291 T59 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_3,generation=3,filenames=[_1.fdx, _1.tis, _1.frq, _1.fdt, _1.si, _1.tii, _1.fnm, segments_3]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_4,generation=4,filenames=[_2.si, _2.tis, _2.fdt, _2.frq, _2.tii, _2.fdx, _2.fnm, segments_4]
[junit4:junit4]   2> 1291 T59 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 4[_2.si, _2.tis, _2.fdt, _2.frq, _2.tii, _2.fdx, _2.fnm, segments_4]
[junit4:junit4]   2> 1292 T59 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 1293 T59 C1 oass.SolrIndexSearcher.<init> Opening Searcher@f2f02a3 main
[junit4:junit4]   2> 1294 T59 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 1294 T59 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 1294 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@f2f02a3 main{StandardDirectoryReader(segments_4:11:nrt _2(4.2):C17)}
[junit4:junit4]   2> 1295 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 1297 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@f2f02a3 main{StandardDirectoryReader(segments_4:11:nrt _2(4.2):C17)}
[junit4:junit4]   2> 1297 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 1298 T59 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 10
[junit4:junit4]   2> 1302 T58 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2&rows=17} hits=17 status=0 QTime=1 
[junit4:junit4]   2> 1311 T60 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 1313 T60 C1 oass.SolrIndexSearcher.<init> Opening Searcher@29ccbdc realtime
[junit4:junit4]   2> 1314 T60 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1428087353349505024)} 0 3
[junit4:junit4]   2> 1322 T57 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 (1428087353356845056), 1 (1428087353357893632), 2 (1428087353358942208), 3 (1428087353358942209), 4 (1428087353359990784), 5 (1428087353359990785), 6 (1428087353359990786)]} 0 4
[junit4:junit4]   2> 1326 T59 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 1331 T59 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_4,generation=4,filenames=[_2.si, _2.tis, _2.fdt, _2.frq, _2.tii, _2.fdx, _2.fnm, segments_4]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_5,generation=5,filenames=[_3.fdx, _3.tii, _3.frq, _3.si, _3.fdt, _3.fnm, segments_5, _3.tis]
[junit4:junit4]   2> 1331 T59 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 5[_3.fdx, _3.tii, _3.frq, _3.si, _3.fdt, _3.fnm, segments_5, _3.tis]
[junit4:junit4]   2> 1332 T59 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 1333 T59 C1 oass.SolrIndexSearcher.<init> Opening Searcher@40d0f56e main
[junit4:junit4]   2> 1335 T59 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 1335 T59 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 1336 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@40d0f56e main{StandardDirectoryReader(segments_5:15:nrt _3(4.2):C7)}
[junit4:junit4]   2> 1336 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 1339 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@40d0f56e main{StandardDirectoryReader(segments_5:15:nrt _3(4.2):C7)}
[junit4:junit4]   2> 1341 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 1341 T59 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 15
[junit4:junit4]   2> 1345 T58 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2&rows=7} hits=7 status=0 QTime=0 
[junit4:junit4]   2> 1352 T60 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 1354 T60 C1 oass.SolrIndexSearcher.<init> Opening Searcher@38a1ac96 realtime
[junit4:junit4]   2> 1354 T60 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1428087353391448064)} 0 3
[junit4:junit4]   2> 1373 T57 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1428087353401933824), 1 (1428087353402982400), 2 (1428087353402982401), 3 (1428087353404030976), 4 (1428087353404030977), 5 (1428087353405079552), 6 (1428087353405079553), 7 (1428087353406128128), 8 (1428087353407176704), 9 (1428087353407176705), ... (23 adds)]} 0 12
[junit4:junit4]   2> 1377 T59 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 1381 T59 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_5,generation=5,filenames=[_3.fdx, _3.tii, _3.frq, _3.si, _3.fdt, _3.fnm, segments_5, _3.tis]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_6,generation=6,filenames=[_4.fdx, _4.tii, _4.frq, _4.fnm, _4.si, _4.fdt, _4.tis, segments_6]
[junit4:junit4]   2> 1381 T59 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 6[_4.fdx, _4.tii, _4.frq, _4.fnm, _4.si, _4.fdt, _4.tis, segments_6]
[junit4:junit4]   2> 1382 T59 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 1383 T59 C1 oass.SolrIndexSearcher.<init> Opening Searcher@e804fb main
[junit4:junit4]   2> 1383 T59 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 1384 T59 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 1384 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@e804fb main{StandardDirectoryReader(segments_6:19:nrt _4(4.2):C23)}
[junit4:junit4]   2> 1384 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 1388 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@e804fb main{StandardDirectoryReader(segments_6:19:nrt _4(4.2):C23)}
[junit4:junit4]   2> 1389 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 1389 T59 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 12
[junit4:junit4]   2> 1394 T58 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2&rows=23} hits=23 status=0 QTime=0 
[junit4:junit4]   2> 1404 T60 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 1406 T60 C1 oass.SolrIndexSearcher.<init> Opening Searcher@58212d6f realtime
[junit4:junit4]   2> 1406 T60 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1428087353445974016)} 0 3
[junit4:junit4]   2> 1428 T57 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1428087353455411200), 1 (1428087353465896960), 2 (1428087353466945536), 3 (1428087353466945537), 4 (1428087353466945538), 5 (1428087353467994112), 6 (1428087353467994113), 7 (1428087353467994114), 8 (1428087353469042688), 9 (1428087353469042689), ... (17 adds)]} 0 16
[junit4:junit4]   2> 1432 T59 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 1435 T59 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_6,generation=6,filenames=[_4.fdx, _4.tii, _4.frq, _4.fnm, _4.si, _4.fdt, _4.tis, segments_6]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_7,generation=7,filenames=[_5.si, _5.fdt, _5.fnm, segments_7, _5.tis, _5.fdx, _5.frq, _5.tii]
[junit4:junit4]   2> 1436 T59 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 7[_5.si, _5.fdt, _5.fnm, segments_7, _5.tis, _5.fdx, _5.frq, _5.tii]
[junit4:junit4]   2> 1436 T59 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 1437 T59 C1 oass.SolrIndexSearcher.<init> Opening Searcher@39490831 main
[junit4:junit4]   2> 1438 T59 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 1438 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@39490831 main{StandardDirectoryReader(segments_7:23:nrt _5(4.2):C17)}
[junit4:junit4]   2> 1438 T59 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 1439 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 1442 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@39490831 main{StandardDirectoryReader(segments_7:23:nrt _5(4.2):C17)}
[junit4:junit4]   2> 1443 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 1443 T59 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 11
[junit4:junit4]   2> 1448 T60 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2&rows=17} hits=17 status=0 QTime=1 
[junit4:junit4]   2> 1456 T57 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 1458 T57 C1 oass.SolrIndexSearcher.<init> Opening Searcher@6b8d773 realtime
[junit4:junit4]   2> 1459 T57 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1428087353500499968)} 0 4
[junit4:junit4]   2> 1473 T58 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 (1428087353509937152), 1 (1428087353509937153), 2 (1428087353510985728), 3 (1428087353510985729), 4 (1428087353510985730), 5 (1428087353514131456), 6 (1428087353514131457), 7 (1428087353515180032), 8 (1428087353515180033), 9 (1428087353515180034), ... (18 adds)]} 0 10
[junit4:junit4]   2> 1478 T59 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 1482 T59 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_7,generation=7,filenames=[_5.si, _5.fdt, _5.fnm, segments_7, _5.tis, _5.fdx, _5.frq, _5.tii]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_8,generation=8,filenames=[_6.tis, segments_8, _6.frq, _6.fdx, _6.tii, _6.si, _6.fnm, _6.fdt]
[junit4:junit4]   2> 1482 T59 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 8[_6.tis, segments_8, _6.frq, _6.fdx, _6.tii, _6.si, _6.fnm, _6.fdt]
[junit4:junit4]   2> 1483 T59 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 1485 T59 C1 oass.SolrIndexSearcher.<init> Opening Searcher@329ca4e main
[junit4:junit4]   2> 1485 T59 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 1486 T59 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 1486 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@329ca4e main{StandardDirectoryReader(segments_8:27:nrt _6(4.2):C18)}
[junit4:junit4]   2> 1486 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 1490 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@329ca4e main{StandardDirectoryReader(segments_8:27:nrt _6(4.2):C18)}
[junit4:junit4]   2> 1491 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 1491 T59 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 14
[junit4:junit4]   2> 1495 T60 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2&rows=18} hits=18 status=0 QTime=0 
[junit4:junit4]   2> 1501 T57 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 1503 T57 C1 oass.SolrIndexSearcher.<init> Opening Searcher@227ec5dd realtime
[junit4:junit4]   2> 1503 T57 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1428087353547685888)} 0 3
[junit4:junit4]   2> 1510 T58 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1428087353555025920), 1 (1428087353556074496), 2 (1428087353556074497), 3 (1428087353557123072), 4 (1428087353557123073), 5 (1428087353558171648), 6 (1428087353558171649)]} 0 3
[junit4:junit4]   2> 1515 T59 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 1518 T59 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_8,generation=8,filenames=[_6.tis, segments_8, _6.frq, _6.fdx, _6.tii, _6.si, _6.fnm, _6.fdt]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_9,generation=9,filenames=[segments_9, _7.fnm, _7.tis, _7.tii, _7.frq, _7.si, _7.fdx, _7.fdt]
[junit4:junit4]   2> 1519 T59 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 9[segments_9, _7.fnm, _7.tis, _7.tii, _7.frq, _7.si, _7.fdx, _7.fdt]
[junit4:junit4]   2> 1519 T59 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 1520 T59 C1 oass.SolrIndexSearcher.<init> Opening Searcher@3000644e main
[junit4:junit4]   2> 1521 T59 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 1521 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@3000644e main{StandardDirectoryReader(segments_9:31:nrt _7(4.2):C7)}
[junit4:junit4]   2> 1522 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 1521 T59 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 1525 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3000644e main{StandardDirectoryReader(segments_9:31:nrt _7(4.2):C7)}
[junit4:junit4]   2> 1526 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 1526 T59 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 11
[junit4:junit4]   2> 1533 T60 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2&rows=7} hits=7 status=0 QTime=1 
[junit4:junit4]   2> 1540 T57 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 1542 T57 C1 oass.SolrIndexSearcher.<init> Opening Searcher@bc09aba realtime
[junit4:junit4]   2> 1542 T57 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1428087353589628928)} 0 2
[junit4:junit4]   2> 1552 T58 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1428087353596968960), 1 (1428087353598017536), 2 (1428087353598017537), 3 (1428087353599066112), 4 (1428087353599066113), 5 (1428087353600114688), 6 (1428087353600114689), 7 (1428087353600114690), 8 (1428087353601163264), 9 (1428087353601163265), ... (12 adds)]} 0 5
[junit4:junit4]   2> 1556 T59 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 1559 T59 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_9,generation=9,filenames=[segments_9, _7.fnm, _7.tis, _7.tii, _7.frq, _7.si, _7.fdx, _7.fdt]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_a,generation=10,filenames=[_8.si, _8.tis, _8.fnm, _8.tii, segments_a, _8.frq, _8.fdx, _8.fdt]
[junit4:junit4]   2> 1559 T59 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 10[_8.si, _8.tis, _8.fnm, _8.tii, segments_a, _8.frq, _8.fdx, _8.fdt]
[junit4:junit4]   2> 1560 T59 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 1560 T59 C1 oass.SolrIndexSearcher.<init> Opening Searcher@465e2e1c main
[junit4:junit4]   2> 1561 T59 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 1562 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@465e2e1c main{StandardDirectoryReader(segments_a:35:nrt _8(4.2):C12)}
[junit4:junit4]   2> 1562 T59 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 1562 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 1565 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@465e2e1c main{StandardDirectoryReader(segments_a:35:nrt _8(4.2):C12)}
[junit4:junit4]   2> 1566 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 1567 T59 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 11
[junit4:junit4]   2> 1571 T60 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2&rows=12} hits=12 status=0 QTime=1 
[junit4:junit4]   2> 1578 T57 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 1579 T57 C1 oass.SolrIndexSearcher.<init> Opening Searcher@17ff81fd realtime
[junit4:junit4]   2> 1580 T57 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1428087353628426240)} 0 3
[junit4:junit4]   2> 1594 T58 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 (1428087353636814848), 1 (1428087353636814849), 2 (1428087353637863424), 3 (1428087353637863425), 4 (1428087353637863426), 5 (1428087353638912000), 6 (1428087353638912001), 7 (1428087353638912002), 8 (1428087353639960576), 9 (1428087353639960577), ... (25 adds)]} 0 9
[junit4:junit4]   2> 1599 T59 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 1604 T59 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_a,generation=10,filenames=[_8.si, _8.tis, _8.fnm, _8.tii, segments_a, _8.frq, _8.fdx, _8.fdt]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_b,generation=11,filenames=[_9.tis, segments_b, _9.fnm, _9.fdt, _9.si, _9.tii, _9.fdx, _9.frq]
[junit4:junit4]   2> 1605 T59 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 11[_9.tis, segments_b, _9.fnm, _9.fdt, _9.si, _9.tii, _9.fdx, _9.frq]
[junit4:junit4]   2> 1606 T59 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 1607 T59 C1 oass.SolrIndexSearcher.<init> Opening Searcher@600d7969 main
[junit4:junit4]   2> 1607 T59 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 1608 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@600d7969 main{StandardDirectoryReader(segments_b:39:nrt _9(4.2):C25)}
[junit4:junit4]   2> 1608 T59 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 1608 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 1612 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@600d7969 main{StandardDirectoryReader(segments_b:39:nrt _9(4.2):C25)}
[junit4:junit4]   2> 1613 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 1613 T59 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 14
[junit4:junit4]   2> 1617 T60 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2&rows=25} hits=25 status=0 QTime=0 
[junit4:junit4]   2> 1620 T51 oas.SolrTestCaseJ4.tearDown ###Ending testUnicode
[junit4:junit4]   2> 1629 T51 oas.SolrTestCaseJ4.setUp ###Starting testCommitWithinOnDelete
[junit4:junit4]   2> 1632 T57 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 1634 T57 C1 oass.SolrIndexSearcher.<init> Opening Searcher@45dd7f52 realtime
[junit4:junit4]   2> 1635 T57 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1428087353686097920)} 0 3
[junit4:junit4]   2> 1638 T58 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 1639 T58 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_b,generation=11,filenames=[_9.tis, segments_b, _9.fnm, _9.fdt, _9.si, _9.tii, _9.fdx, _9.frq]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_c,generation=12,filenames=[segments_c]
[junit4:junit4]   2> 1639 T58 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 12[segments_c]
[junit4:junit4]   2> 1640 T58 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 1640 T58 C1 oass.SolrIndexSearcher.<init> Opening Searcher@1153eaff main
[junit4:junit4]   2> 1641 T58 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 1641 T58 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 1641 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1153eaff main{StandardDirectoryReader(segments_b:41:nrt)}
[junit4:junit4]   2> 1641 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 1644 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1153eaff main{StandardDirectoryReader(segments_b:41:nrt)}
[junit4:junit4]   2> 1645 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 1646 T58 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 8
[junit4:junit4]   2> 1650 T59 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 1655 T60 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id3 (1428087353708118016)]} 0 2
[junit4:junit4]   2> 1658 T57 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 1661 T57 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_c,generation=12,filenames=[segments_c]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_d,generation=13,filenames=[_a.prx, segments_d, _a.tii, _a.frq, _a.fdt, _a.si, _a.nrm, _a.fdx, _a.tis, _a.fnm]
[junit4:junit4]   2> 1661 T57 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 13[_a.prx, segments_d, _a.tii, _a.frq, _a.fdt, _a.si, _a.nrm, _a.fdx, _a.tis, _a.fnm]
[junit4:junit4]   2> 1662 T57 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 1666 T57 C1 oass.SolrIndexSearcher.<init> Opening Searcher@52233a85 main
[junit4:junit4]   2> 1666 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@52233a85 main{StandardDirectoryReader(segments_d:43:nrt _a(4.2):C1)}
[junit4:junit4]   2> 1666 T57 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 1667 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 1672 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@52233a85 main{StandardDirectoryReader(segments_d:43:nrt _a(4.2):C1)}
[junit4:junit4]   2> 1673 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 1674 T57 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 16
[junit4:junit4]   2> 1678 T58 C1 REQ [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 1683 T59 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {delete=[id3 (-1428087353738526720)]} 0 1
[junit4:junit4]   2> 1686 T60 C1 REQ [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 1940 T57 C1 REQ [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 2194 T60 C1 REQ [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 2449 T57 C1 REQ [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 2683 T64 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4:junit4]   2> 2683 T64 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 2684 T64 oass.SolrIndexSearcher.<init> Opening Searcher@1ea55e00 main
[junit4:junit4]   2> 2685 T64 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 2685 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1ea55e00 main{StandardDirectoryReader(segments_d:45:nrt)}
[junit4:junit4]   2> 2685 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 2687 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ea55e00 main{StandardDirectoryReader(segments_d:45:nrt)}
[junit4:junit4]   2> 2703 T60 C1 REQ [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 2704 T51 oas.SolrTestCaseJ4.tearDown ###Ending testCommitWithinOnDelete
[junit4:junit4]   2> 2706 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 2714 T51 oas.SolrTestCaseJ4.setUp ###Starting testPivotFacets
[junit4:junit4]   2> 2717 T58 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 2717 T58 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1428087354823802880)} 0 1
[junit4:junit4]   2> 2721 T59 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 2721 T59 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_d,generation=13,filenames=[_a.prx, segments_d, _a.tii, _a.frq, _a.fdt, _a.si, _a.nrm, _a.fdx, _a.tis, _a.fnm]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_e,generation=14,filenames=[segments_e]
[junit4:junit4]   2> 2722 T59 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 14[segments_e]
[junit4:junit4]   2> 2722 T59 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 2723 T59 C1 oass.SolrIndexSearcher.<init> Opening Searcher@e15a325 main
[junit4:junit4]   2> 2723 T59 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 2723 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@e15a325 main{StandardDirectoryReader(segments_d:45:nrt)}
[junit4:junit4]   2> 2724 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 2726 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@e15a325 main{StandardDirectoryReader(segments_d:45:nrt)}
[junit4:junit4]   2> 2726 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 2726 T59 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 5
[junit4:junit4]   2> 2730 T57 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 2740 T60 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1 (1428087354840580096), 2 (1428087354842677248), 3 (1428087354842677249), 4 (1428087354843725824), 5 (1428087354843725825), 6 (1428087354844774400), 7 (1428087354844774401), 8 (1428087354845822976), 9 (1428087354845822977), 10 (1428087354846871552), ... (12 adds)]} 0 7
[junit4:junit4]   2> 2744 T58 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 2746 T58 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_e,generation=14,filenames=[segments_e]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_f,generation=15,filenames=[_b.tii, _b.frq, segments_f, _b.si, _b.prx, _b.fnm, _b.fdt, _b.nrm, _b.tis, _b.fdx]
[junit4:junit4]   2> 2747 T58 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 15[_b.tii, _b.frq, segments_f, _b.si, _b.prx, _b.fnm, _b.fdt, _b.nrm, _b.tis, _b.fdx]
[junit4:junit4]   2> 2747 T58 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 2748 T58 C1 oass.SolrIndexSearcher.<init> Opening Searcher@6a45642e main
[junit4:junit4]   2> 2749 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@6a45642e main{StandardDirectoryReader(segments_f:47:nrt _b(4.2):C12)}
[junit4:junit4]   2> 2749 T58 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 2749 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 2751 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6a45642e main{StandardDirectoryReader(segments_f:47:nrt _b(4.2):C12)}
[junit4:junit4]   2> 2752 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 2752 T58 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 8
[junit4:junit4]   2> 2760 T59 C1 oasr.UnInvertedField.<init> UnInverted multi-valued field {field=features,memSize=4521,tindexSize=43,time=4,phase1=4,nTerms=2,bigTerms=2,termInstances=0,uses=0}
[junit4:junit4]   2> 2761 T59 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> 2764 T59 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=8 
[junit4:junit4]   2> 2772 T57 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=3 
[junit4:junit4]   2> 2774 T51 oas.SolrTestCaseJ4.tearDown ###Ending testPivotFacets
[junit4:junit4]   2> 2783 T51 oas.SolrTestCaseJ4.setUp ###Starting testGetEmptyResults
[junit4:junit4]   2> 2786 T60 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 2788 T60 C1 oass.SolrIndexSearcher.<init> Opening Searcher@13b86fd realtime
[junit4:junit4]   2> 2788 T60 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1428087354896154624)} 0 2
[junit4:junit4]   2> 2792 T58 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 2792 T58 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_f,generation=15,filenames=[_b.tii, _b.frq, segments_f, _b.si, _b.prx, _b.fnm, _b.fdt, _b.nrm, _b.tis, _b.fdx]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_g,generation=16,filenames=[segments_g]
[junit4:junit4]   2> 2793 T58 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 16[segments_g]
[junit4:junit4]   2> 2793 T58 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 2794 T58 C1 oass.SolrIndexSearcher.<init> Opening Searcher@4189b8f7 main
[junit4:junit4]   2> 2794 T58 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 2794 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@4189b8f7 main{StandardDirectoryReader(segments_f:49:nrt)}
[junit4:junit4]   2> 2795 T58 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 2795 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 2797 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4189b8f7 main{StandardDirectoryReader(segments_f:49:nrt)}
[junit4:junit4]   2> 2797 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 2798 T58 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 7
[junit4:junit4]   2> 2803 T59 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id1 (1428087354911883264)]} 0 2
[junit4:junit4]   2> 2807 T57 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id2 (1428087354917126144)]} 0 1
[junit4:junit4]   2> 2810 T60 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 2812 T60 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_g,generation=16,filenames=[segments_g]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_h,generation=17,filenames=[_c.fdt, _c.si, _c.tis, _c.prx, _c.nrm, _c.fnm, _c.tii, _c.fdx, _c.frq, segments_h]
[junit4:junit4]   2> 2812 T60 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 17[_c.fdt, _c.si, _c.tis, _c.prx, _c.nrm, _c.fnm, _c.tii, _c.fdx, _c.frq, segments_h]
[junit4:junit4]   2> 2813 T60 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 2814 T60 C1 oass.SolrIndexSearcher.<init> Opening Searcher@51861b5a main
[junit4:junit4]   2> 2814 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@51861b5a main{StandardDirectoryReader(segments_h:51:nrt _c(4.2):C2)}
[junit4:junit4]   2> 2814 T60 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 2814 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 2817 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@51861b5a main{StandardDirectoryReader(segments_h:51:nrt _c(4.2):C2)}
[junit4:junit4]   2> 2817 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 2817 T60 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 7
[junit4:junit4]   2> 2821 T58 C1 REQ [collection1] webapp=/solr path=/select params={fl=foofoofoo&q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=0 
[junit4:junit4]   2> 2822 T51 oas.SolrTestCaseJ4.tearDown ###Ending testGetEmptyResults
[junit4:junit4]   2> 2832 T51 oas.SolrTestCaseJ4.setUp ###Starting testContentStreamRequest
[junit4:junit4]   2> 2835 T59 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 2836 T59 C1 oass.SolrIndexSearcher.<init> Opening Searcher@116a74ae realtime
[junit4:junit4]   2> 2837 T59 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1428087354947534848)} 0 3
[junit4:junit4]   2> 2840 T57 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 2841 T57 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_h,generation=17,filenames=[_c.fdt, _c.si, _c.tis, _c.prx, _c.nrm, _c.fnm, _c.tii, _c.fdx, _c.frq, segments_h]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_i,generation=18,filenames=[segments_i]
[junit4:junit4]   2> 2841 T57 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 18[segments_i]
[junit4:junit4]   2> 2842 T57 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 2843 T57 C1 oass.SolrIndexSearcher.<init> Opening Searcher@4bf22b9b main
[junit4:junit4]   2> 2843 T57 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 2843 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@4bf22b9b main{StandardDirectoryReader(segments_h:53:nrt)}
[junit4:junit4]   2> 2844 T57 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 2844 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 2846 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4bf22b9b main{StandardDirectoryReader(segments_h:53:nrt)}
[junit4:junit4]   2> 2847 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 2847 T57 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 7
[junit4:junit4]   2> 2853 T60 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 2876 T57 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 2880 T57 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_i,generation=18,filenames=[segments_i]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_j,generation=19,filenames=[_d.frq, _d.fdt, _d.tii, _d.si, _d.nrm, _d.fnm, _d.tis, _d.prx, segments_j, _d.fdx]
[junit4:junit4]   2> 2881 T57 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 19[_d.frq, _d.fdt, _d.tii, _d.si, _d.nrm, _d.fnm, _d.tis, _d.prx, segments_j, _d.fdx]
[junit4:junit4]   2> 2881 T57 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 2882 T57 C1 oass.SolrIndexSearcher.<init> Opening Searcher@2a728ed1 main
[junit4:junit4]   2> 2883 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@2a728ed1 main{StandardDirectoryReader(segments_j:55:nrt _d(4.2):C10)}
[junit4:junit4]   2> 2883 T57 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 2883 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 2885 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2a728ed1 main{StandardDirectoryReader(segments_j:55:nrt _d(4.2):C10)}
[junit4:junit4]   2> 2886 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 2886 T57 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {add=[0553573403 (1428087354974797824), 0553579908 (1428087354977943552), 055357342X (1428087354978992128), 0553293354 (1428087354981089280), 0812521390 (1428087354982137856), 0812550706 (1428087354983186432), 0441385532 (1428087354985283584), 0380014300 (1428087354986332160), 0805080481 (1428087354987380736), 080508049X (1428087354989477888)],commit=} 0 25
[junit4:junit4]   2> 2890 T58 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=1 
[junit4:junit4]   2> 2893 T51 oas.SolrTestCaseJ4.tearDown ###Ending testContentStreamRequest
[junit4:junit4]   2> 2902 T51 oas.SolrTestCaseJ4.setUp ###Starting testSetNullUpdates
[junit4:junit4]   2> 2906 T60 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[testSetNullUpdates (1428087355020935168)]} 0 1
[junit4:junit4]   2> 2909 T59 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 2912 T59 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_j,generation=19,filenames=[_d.frq, _d.fdt, _d.tii, _d.si, _d.nrm, _d.fnm, _d.tis, _d.prx, segments_j, _d.fdx]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_k,generation=20,filenames=[_d.frq, _d.tii, _e.frq, _d.fnm, _e.tis, segments_k, _e.fdx, _d.prx, _d.fdx, _e.tii, _d.fdt, _e.si, _e.fdt, _d.nrm, _d.si, _d.tis, _e.fnm]
[junit4:junit4]   2> 2912 T59 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 20[_d.frq, _d.tii, _e.frq, _d.fnm, _e.tis, segments_k, _e.fdx, _d.prx, _d.fdx, _e.tii, _d.fdt, _e.si, _e.fdt, _d.nrm, _d.si, _d.tis, _e.fnm]
[junit4:junit4]   2> 2912 T59 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 2913 T59 C1 oass.SolrIndexSearcher.<init> Opening Searcher@301836e9 main
[junit4:junit4]   2> 2914 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@301836e9 main{StandardDirectoryReader(segments_k:57:nrt _d(4.2):C10 _e(4.2):C1)}
[junit4:junit4]   2> 2914 T59 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 2914 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 2916 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@301836e9 main{StandardDirectoryReader(segments_k:57:nrt _d(4.2):C10 _e(4.2):C1)}
[junit4:junit4]   2> 2917 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 2917 T59 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 8
[junit4:junit4]   2> 2923 T57 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[testSetNullUpdates (1428087355038760960)]} 0 2
[junit4:junit4]   2> 2927 T58 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 2930 T58 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_k,generation=20,filenames=[_d.frq, _d.tii, _e.frq, _d.fnm, _e.tis, segments_k, _e.fdx, _d.prx, _d.fdx, _e.tii, _d.fdt, _e.si, _e.fdt, _d.nrm, _d.si, _d.tis, _e.fnm]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_l,generation=21,filenames=[_d.frq, _f.tis, _d.tii, _d.fnm, segments_l, _d.prx, _f.si, _f.fdt, _d.fdx, _d.fdt, _f.fdx, _f.fnm, _d.nrm, _d.si, _f.tii, _d.tis, _f.frq]
[junit4:junit4]   2> 2930 T58 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 21[_d.frq, _f.tis, _d.tii, _d.fnm, segments_l, _d.prx, _f.si, _f.fdt, _d.fdx, _d.fdt, _f.fdx, _f.fnm, _d.nrm, _d.si, _f.tii, _d.tis, _f.frq]
[junit4:junit4]   2> 2931 T58 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 2931 T58 C1 oass.SolrIndexSearcher.<init> Opening Searcher@29a59249 main
[junit4:junit4]   2> 2932 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@29a59249 main{StandardDirectoryReader(segments_l:61:nrt _d(4.2):C10 _f(4.2):C1)}
[junit4:junit4]   2> 2932 T58 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 2932 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 2935 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@29a59249 main{StandardDirectoryReader(segments_l:61:nrt _d(4.2):C10 _f(4.2):C1)}
[junit4:junit4]   2> 2935 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 2935 T58 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 8
[junit4:junit4]   2> 2940 T60 C1 REQ [collection1] webapp=/solr path=/select params={q=id:testSetNullUpdates&wt=javabin&version=2} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 2942 T51 oas.SolrTestCaseJ4.tearDown ###Ending testSetNullUpdates
[junit4:junit4]   2> 2951 T51 oas.SolrTestCaseJ4.setUp ###Starting testMultiContentStreamRequest
[junit4:junit4]   2> 2954 T59 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 2956 T59 C1 oass.SolrIndexSearcher.<init> Opening Searcher@4e5152fc realtime
[junit4:junit4]   2> 2956 T59 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1428087355071266816)} 0 3
[junit4:junit4]   2> 2960 T57 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 2960 T57 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_l,generation=21,filenames=[_d.frq, _f.tis, _d.tii, _d.fnm, segments_l, _d.prx, _f.si, _f.fdt, _d.fdx, _d.fdt, _f.fdx, _f.fnm, _d.nrm, _d.si, _f.tii, _d.tis, _f.frq]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_m,generation=22,filenames=[segments_m]
[junit4:junit4]   2> 2961 T57 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 22[segments_m]
[junit4:junit4]   2> 2962 T57 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 2962 T57 C1 oass.SolrIndexSearcher.<init> Opening Searcher@11530d63 main
[junit4:junit4]   2> 2962 T57 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 2963 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@11530d63 main{StandardDirectoryReader(segments_l:63:nrt)}
[junit4:junit4]   2> 2963 T57 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 2963 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 2965 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@11530d63 main{StandardDirectoryReader(segments_l:63:nrt)}
[junit4:junit4]   2> 2966 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 2966 T57 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 6
[junit4:junit4]   2> 2970 T58 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 2994 T60 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 3001 T60 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_m,generation=22,filenames=[segments_m]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_n,generation=23,filenames=[_g.fdt, _g.prx, _g.si, _g.tis, _g.fnm, _g.fdx, _g.nrm, _g.tii, segments_n, _g.frq]
[junit4:junit4]   2> 3001 T60 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 23[_g.fdt, _g.prx, _g.si, _g.tis, _g.fnm, _g.fdx, _g.nrm, _g.tii, segments_n, _g.frq]
[junit4:junit4]   2> 3002 T60 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 3003 T60 C1 oass.SolrIndexSearcher.<init> Opening Searcher@7cce9f20 main
[junit4:junit4]   2> 3004 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@7cce9f20 main{StandardDirectoryReader(segments_n:65:nrt _g(4.2):C5)}
[junit4:junit4]   2> 3004 T60 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3004 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 3006 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7cce9f20 main{StandardDirectoryReader(segments_n:65:nrt _g(4.2):C5)}
[junit4:junit4]   2> 3007 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 3007 T60 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&echoParams=ALL&commit=true&a=ሴ&wt=javabin&version=2&softCommit=false} {add=[SP2514N (1428087355097481216), 6H500F0 (1428087355102724096), TWINX2048-3200PRO (1428087355105869824), VS1GB400C3 (1428087355109015552), VDBDB1A16 (1428087355112161280)],commit=} 0 29
[junit4:junit4]   2> 3011 T59 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=5 status=0 QTime=1 
[junit4:junit4]   2> 3013 T51 oas.SolrTestCaseJ4.tearDown ###Ending testMultiContentStreamRequest
[junit4:junit4]   2> 3022 T51 oas.SolrTestCaseJ4.setUp ###Starting testLukeHandler
[junit4:junit4]   2> 3025 T57 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 3027 T57 C1 oass.SolrIndexSearcher.<init> Opening Searcher@103a41fc realtime
[junit4:junit4]   2> 3027 T57 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1428087355146764288)} 0 2
[junit4:junit4]   2> 3031 T58 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID0 (1428087355152007168)]} 0 1
[junit4:junit4]   2> 3035 T60 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID1 (1428087355156201472)]} 0 1
[junit4:junit4]   2> 3038 T59 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID2 (1428087355160395776)]} 0 0
[junit4:junit4]   2> 3042 T57 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID3 (1428087355164590080)]} 0 0
[junit4:junit4]   2> 3046 T58 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID4 (1428087355168784384)]} 0 0
[junit4:junit4]   2> 3050 T60 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 3052 T60 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_n,generation=23,filenames=[_g.fdt, _g.prx, _g.si, _g.tis, _g.fnm, _g.fdx, _g.nrm, _g.tii, segments_n, _g.frq]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_o,generation=24,filenames=[_h.fnm, _h.fdt, _h.si, _h.fdx, segments_o, _h.tii, _h.frq, _h.tis]
[junit4:junit4]   2> 3052 T60 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 24[_h.fnm, _h.fdt, _h.si, _h.fdx, segments_o, _h.tii, _h.frq, _h.tis]
[junit4:junit4]   2> 3053 T60 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 3054 T60 C1 oass.SolrIndexSearcher.<init> Opening Searcher@5878bda2 main
[junit4:junit4]   2> 3054 T60 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 3055 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@5878bda2 main{StandardDirectoryReader(segments_o:69:nrt _h(4.2):C5)}
[junit4:junit4]   2> 3055 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 3055 T60 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3058 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5878bda2 main{StandardDirectoryReader(segments_o:69:nrt _h(4.2):C5)}
[junit4:junit4]   2> 3059 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 3059 T60 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 9
[junit4:junit4]   2> 3063 T59 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=5 status=0 QTime=0 
[junit4:junit4]   2> 3068 T57 C1 REQ [collection1] webapp=/solr path=/admin/luke params={wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 3074 T58 C1 REQ [collection1] webapp=/solr path=/admin/luke params={show=schema&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 3084 T51 oas.SolrTestCaseJ4.tearDown ###Ending testLukeHandler
[junit4:junit4]   2> 3092 T51 oas.SolrTestCaseJ4.setUp ###Starting testRealtimeGet
[junit4:junit4]   2> 3096 T60 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 3097 T60 C1 oass.SolrIndexSearcher.<init> Opening Searcher@3542cbc9 realtime
[junit4:junit4]   2> 3098 T60 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1428087355220164608)} 0 3
[junit4:junit4]   2> 3102 T59 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[DOCID (1428087355226456064)]} 0 1
[junit4:junit4]   2> 3106 T57 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 3108 T57 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_o,generation=24,filenames=[_h.fnm, _h.fdt, _h.si, _h.fdx, segments_o, _h.tii, _h.frq, _h.tis]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_p,generation=25,filenames=[_i.tis, _i.nrm, segments_p, _i.frq, _i.fdx, _i.tii, _i.fnm, _i.si, _i.fdt, _i.prx]
[junit4:junit4]   2> 3108 T57 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 25[_i.tis, _i.nrm, segments_p, _i.frq, _i.fdx, _i.tii, _i.fnm, _i.si, _i.fdt, _i.prx]
[junit4:junit4]   2> 3109 T57 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 3109 T57 C1 oass.SolrIndexSearcher.<init> Opening Searcher@482fac40 main
[junit4:junit4]   2> 3110 T57 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 3111 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@482fac40 main{StandardDirectoryReader(segments_p:73:nrt _i(4.2):C1)}
[junit4:junit4]   2> 3111 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 3111 T57 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3114 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@482fac40 main{StandardDirectoryReader(segments_p:73:nrt _i(4.2):C1)}
[junit4:junit4]   2> 3115 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 3115 T57 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 9
[junit4:junit4]   2> 3119 T58 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> 3123 T60 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=0 
[junit4:junit4]   2> 3125 T51 oas.SolrTestCaseJ4.tearDown ###Ending testRealtimeGet
[junit4:junit4]   2> 3133 T51 oas.SolrTestCaseJ4.setUp ###Starting testChineseDefaults
[junit4:junit4]   2> 3136 T59 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 3138 T59 C1 oass.SolrIndexSearcher.<init> Opening Searcher@241558bd realtime
[junit4:junit4]   2> 3138 T59 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1428087355263156224)} 0 2
[junit4:junit4]   2> 3142 T57 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 3143 T57 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_p,generation=25,filenames=[_i.tis, _i.nrm, segments_p, _i.frq, _i.fdx, _i.tii, _i.fnm, _i.si, _i.fdt, _i.prx]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_q,generation=26,filenames=[segments_q]
[junit4:junit4]   2> 3143 T57 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 26[segments_q]
[junit4:junit4]   2> 3144 T57 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 3144 T57 C1 oass.SolrIndexSearcher.<init> Opening Searcher@bd720d5 main
[junit4:junit4]   2> 3144 T57 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 3145 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@bd720d5 main{StandardDirectoryReader(segments_p:75:nrt)}
[junit4:junit4]   2> 3145 T57 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3145 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 3148 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@bd720d5 main{StandardDirectoryReader(segments_p:75:nrt)}
[junit4:junit4]   2> 3149 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 3150 T57 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 8
[junit4:junit4]   2> 3153 T58 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 3158 T60 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 3160 T60 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_q,generation=26,filenames=[segments_q]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_r,generation=27,filenames=[_j.fdx, _j.tis, _j.si, _j.fdt, segments_r, _j.fnm, _j.nrm, _j.tii, _j.prx, _j.frq]
[junit4:junit4]   2> 3160 T60 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 27[_j.fdx, _j.tis, _j.si, _j.fdt, segments_r, _j.fnm, _j.nrm, _j.tii, _j.prx, _j.frq]
[junit4:junit4]   2> 3161 T60 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 3162 T60 C1 oass.SolrIndexSearcher.<init> Opening Searcher@4675cfea main
[junit4:junit4]   2> 3162 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@4675cfea main{StandardDirectoryReader(segments_r:77:nrt _j(4.2):C1)}
[junit4:junit4]   2> 3162 T60 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3163 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 3166 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4675cfea main{StandardDirectoryReader(segments_r:77:nrt _j(4.2):C1)}
[junit4:junit4]   2> 3167 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 3167 T60 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {add=[42 (1428087355285176320)],commit=} 0 10
[junit4:junit4]   2> 3173 T59 C1 REQ [collection1] webapp=/solr path=/select params={q=北京大学&wt=javabin&version=2} hits=1 status=0 QTime=2 
[junit4:junit4]   2> 3175 T51 oas.SolrTestCaseJ4.tearDown ###Ending testChineseDefaults
[junit4:junit4]   2> 3183 T51 oas.SolrTestCaseJ4.setUp ###Starting testPingHandler
[junit4:junit4]   2> 3186 T57 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 3188 T57 C1 oass.SolrIndexSearcher.<init> Opening Searcher@5d3ad90d realtime
[junit4:junit4]   2> 3188 T57 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1428087355315585024)} 0 2
[junit4:junit4]   2> 3192 T58 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 3193 T58 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_r,generation=27,filenames=[_j.fdx, _j.tis, _j.si, _j.fdt, segments_r, _j.fnm, _j.nrm, _j.tii, _j.prx, _j.frq]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_s,generation=28,filenames=[segments_s]
[junit4:junit4]   2> 3193 T58 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 28[segments_s]
[junit4:junit4]   2> 3194 T58 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 3195 T58 C1 oass.SolrIndexSearcher.<init> Opening Searcher@20c7c08f main
[junit4:junit4]   2> 3196 T58 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 3196 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@20c7c08f main{StandardDirectoryReader(segments_r:79:nrt)}
[junit4:junit4]   2> 3196 T58 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3197 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 3200 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@20c7c08f main{StandardDirectoryReader(segments_r:79:nrt)}
[junit4:junit4]   2> 3201 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 3201 T58 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 9
[junit4:junit4]   2> 3205 T60 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 3210 T59 C1 REQ [collection1] webapp=/solr path=/admin/ping params={wt=javabin&version=2} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 3211 T59 C1 REQ [collection1] webapp=/solr path=/admin/ping params={wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 3212 T51 oas.SolrTestCaseJ4.tearDown ###Ending testPingHandler
[junit4:junit4]   2> 3221 T51 oas.SolrTestCaseJ4.setUp ###Starting testAddDelete
[junit4:junit4]   2> 3225 T58 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 3226 T58 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1428087355355430912)} 0 2
[junit4:junit4]   2> 3230 T60 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 & 222 (1428087355361722368)]} 0 0
[junit4:junit4]   2> 3234 T57 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 3236 T57 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_s,generation=28,filenames=[segments_s]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_t,generation=29,filenames=[_k.tii, segments_t, _k.fdt, _k.fdx, _k.frq, _k.fnm, _k.si, _k.tis]
[junit4:junit4]   2> 3236 T57 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 29[_k.tii, segments_t, _k.fdt, _k.fdx, _k.frq, _k.fnm, _k.si, _k.tis]
[junit4:junit4]   2> 3237 T57 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 3238 T57 C1 oass.SolrIndexSearcher.<init> Opening Searcher@3a56ad08 main
[junit4:junit4]   2> 3238 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@3a56ad08 main{StandardDirectoryReader(segments_t:81:nrt _k(4.2):C1)}
[junit4:junit4]   2> 3238 T57 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3238 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 3241 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3a56ad08 main{StandardDirectoryReader(segments_t:81:nrt _k(4.2):C1)}
[junit4:junit4]   2> 3242 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 3243 T57 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 9
[junit4:junit4]   2> 3247 T59 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 3251 T58 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {delete=[0 & 222 (-1428087355382693888)]} 0 1
[junit4:junit4]   2> 3255 T57 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 3256 T57 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_t,generation=29,filenames=[_k.tii, segments_t, _k.fdt, _k.fdx, _k.frq, _k.fnm, _k.si, _k.tis]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_u,generation=30,filenames=[segments_u]
[junit4:junit4]   2> 3257 T57 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 30[segments_u]
[junit4:junit4]   2> 3257 T57 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 3258 T57 C1 oass.SolrIndexSearcher.<init> Opening Searcher@63be8dd3 main
[junit4:junit4]   2> 3258 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@63be8dd3 main{StandardDirectoryReader(segments_u:83:nrt)}
[junit4:junit4]   2> 3258 T57 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3259 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 3262 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@63be8dd3 main{StandardDirectoryReader(segments_u:83:nrt)}
[junit4:junit4]   2> 3263 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 3263 T57 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 8
[junit4:junit4]   2> 3267 T59 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 3271 T60 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 & 222 (1428087355403665408)]} 0 1
[junit4:junit4]   2> 3276 T58 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 3278 T58 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_u,generation=30,filenames=[segments_u]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_v,generation=31,filenames=[segments_v, _l.tis, _l.fnm, _l.si, _l.fdx, _l.fdt, _l.frq, _l.tii]
[junit4:junit4]   2> 3279 T58 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 31[segments_v, _l.tis, _l.fnm, _l.si, _l.fdx, _l.fdt, _l.frq, _l.tii]
[junit4:junit4]   2> 3279 T58 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 3280 T58 C1 oass.SolrIndexSearcher.<init> Opening Searcher@22324b59 main
[junit4:junit4]   2> 3281 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@22324b59 main{StandardDirectoryReader(segments_v:85:nrt _l(4.2):C1)}
[junit4:junit4]   2> 3281 T58 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3281 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 3284 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@22324b59 main{StandardDirectoryReader(segments_v:85:nrt _l(4.2):C1)}
[junit4:junit4]   2> 3285 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 3286 T58 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 11
[junit4:junit4]   2> 3290 T57 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 3294 T59 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 3295 T59 C1 oass.SolrIndexSearcher.<init> Opening Searcher@1db77509 realtime
[junit4:junit4]   2> 3296 T59 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=id:"0\ \&\ 222" (-1428087355427782656)} 0 3
[junit4:junit4]   2> 3300 T60 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 3300 T60 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_v,generation=31,filenames=[segments_v, _l.tis, _l.fnm, _l.si, _l.fdx, _l.fdt, _l.frq, _l.tii]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_w,generation=32,filenames=[segments_w]
[junit4:junit4]   2> 3301 T60 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 32[segments_w]
[junit4:junit4]   2> 3301 T60 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 3302 T60 C1 oass.SolrIndexSearcher.<init> Opening Searcher@5093c8c0 main
[junit4:junit4]   2> 3302 T60 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 3303 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@5093c8c0 main{StandardDirectoryReader(segments_v:87:nrt)}
[junit4:junit4]   2> 3303 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 3303 T60 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3306 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5093c8c0 main{StandardDirectoryReader(segments_v:87:nrt)}
[junit4:junit4]   2> 3307 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 3307 T60 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 8
[junit4:junit4]   2> 3311 T58 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 3316 T57 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 & 222 (1428087355450851328)]} 0 1
[junit4:junit4]   2> 3319 T59 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1 & 222 (1428087355455045632)]} 0 0
[junit4:junit4]   2> 3323 T60 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2 & 222 (1428087355459239936)]} 0 0
[junit4:junit4]   2> 3327 T58 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 3329 T58 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_w,generation=32,filenames=[segments_w]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_x,generation=33,filenames=[_m.si, _m.tis, _m.fdt, _m.frq, _m.fdx, _m.tii, _m.fnm, segments_x]
[junit4:junit4]   2> 3330 T58 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 33[_m.si, _m.tis, _m.fdt, _m.frq, _m.fdx, _m.tii, _m.fnm, segments_x]
[junit4:junit4]   2> 3331 T58 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 3332 T58 C1 oass.SolrIndexSearcher.<init> Opening Searcher@11df2e2b main
[junit4:junit4]   2> 3333 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@11df2e2b main{StandardDirectoryReader(segments_x:89:nrt _m(4.2):C3)}
[junit4:junit4]   2> 3333 T58 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3333 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 3336 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@11df2e2b main{StandardDirectoryReader(segments_x:89:nrt _m(4.2):C3)}
[junit4:junit4]   2> 3337 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 3337 T58 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 10
[junit4:junit4]   2> 3342 T57 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=3 status=0 QTime=0 
[junit4:junit4]   2> 3348 T59 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {delete=[0 & 222 (-1428087355484405760), 1 & 222 (-1428087355485454336), 2 & 222 (-1428087355485454337)]} 0 1
[junit4:junit4]   2> 3352 T60 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 3354 T60 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_x,generation=33,filenames=[_m.si, _m.tis, _m.fdt, _m.frq, _m.fdx, _m.tii, _m.fnm, segments_x]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_y,generation=34,filenames=[segments_y]
[junit4:junit4]   2> 3354 T60 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 34[segments_y]
[junit4:junit4]   2> 3355 T60 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 3355 T60 C1 oass.SolrIndexSearcher.<init> Opening Searcher@4f60ff43 main
[junit4:junit4]   2> 3356 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@4f60ff43 main{StandardDirectoryReader(segments_y:91:nrt)}
[junit4:junit4]   2> 3356 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 3356 T60 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3359 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4f60ff43 main{StandardDirectoryReader(segments_y:91:nrt)}
[junit4:junit4]   2> 3360 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 3360 T60 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 8
[junit4:junit4]   2> 3364 T58 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 3365 T51 oas.SolrTestCaseJ4.tearDown ###Ending testAddDelete
[junit4:junit4]   2> 3379 T51 oas.SolrTestCaseJ4.setUp ###Starting testAugmentFields
[junit4:junit4]   2> 3383 T57 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 3383 T57 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1428087355521105920)} 0 1
[junit4:junit4]   2> 3388 T59 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[111 (1428087355526348800)]} 0 1
[junit4:junit4]   2> 3392 T60 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 3394 T60 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_y,generation=34,filenames=[segments_y]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_z,generation=35,filenames=[_n.tis, _n.nrm, _n.si, _n.prx, _n.fdt, _n.frq, _n.tii, _n.fdx, segments_z, _n.fnm]
[junit4:junit4]   2> 3395 T60 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 35[_n.tis, _n.nrm, _n.si, _n.prx, _n.fdt, _n.frq, _n.tii, _n.fdx, segments_z, _n.fnm]
[junit4:junit4]   2> 3395 T60 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 3396 T60 C1 oass.SolrIndexSearcher.<init> Opening Searcher@73faf1d6 main
[junit4:junit4]   2> 3396 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@73faf1d6 main{StandardDirectoryReader(segments_z:93:nrt _n(4.2):C1)}
[junit4:junit4]   2> 3397 T60 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3397 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 3400 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@73faf1d6 main{StandardDirectoryReader(segments_z:93:nrt _n(4.2):C1)}
[junit4:junit4]   2> 3401 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 3401 T60 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 9
[junit4:junit4]   2> 3407 T58 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[222 (1428087355545223168)]} 0 2
[junit4:junit4]   2> 3411 T57 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 3415 T57 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_z,generation=35,filenames=[_n.tis, _n.nrm, _n.si, _n.prx, _n.fdt, _n.frq, _n.tii, _n.fdx, segments_z, _n.fnm]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_10,generation=36,filenames=[_n.nrm, _o.fdt, _o.fnm, _o.fdx, segments_10, _n.prx, _n.fdt, _n.fdx, _o.tis, _n.tis, _o.prx, _n.si, _o.nrm, _n.frq, _o.tii, _o.si, _n.tii, _o.frq, _n.fnm]
[junit4:junit4]   2> 3415 T57 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 36[_n.nrm, _o.fdt, _o.fnm, _o.fdx, segments_10, _n.prx, _n.fdt, _n.fdx, _o.tis, _n.tis, _o.prx, _n.si, _o.nrm, _n.frq, _o.tii, _o.si, _n.tii, _o.frq, _n.fnm]
[junit4:junit4]   2> 3416 T57 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 3417 T57 C1 oass.SolrIndexSearcher.<init> Opening Searcher@1c18c548 main
[junit4:junit4]   2> 3418 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1c18c548 main{StandardDirectoryReader(segments_10:95:nrt _n(4.2):C1 _o(4.2):C1)}
[junit4:junit4]   2> 3418 T57 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3418 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 3421 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1c18c548 main{StandardDirectoryReader(segments_10:95:nrt _n(4.2):C1 _o(4.2):C1)}
[junit4:junit4]   2> 3421 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 3421 T57 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 10
[junit4:junit4]   2> 3427 T59 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=2 
[junit4:junit4]   2> 3429 T51 oas.SolrTestCaseJ4.tearDown ###Ending testAugmentFields
[junit4:junit4]   2> 3438 T51 oas.SolrTestCaseJ4.setUp ###Starting testStreamingRequest
[junit4:junit4]   2> 3441 T60 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 3444 T60 C1 oass.SolrIndexSearcher.<init> Opening Searcher@580a298b realtime
[junit4:junit4]   2> 3444 T60 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1428087355582971904)} 0 4
[junit4:junit4]   2> 3449 T58 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 3450 T58 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_10,generation=36,filenames=[_n.nrm, _o.fdt, _o.fnm, _o.fdx, segments_10, _n.prx, _n.fdt, _n.fdx, _o.tis, _n.tis, _o.prx, _n.si, _o.nrm, _n.frq, _o.tii, _o.si, _n.tii, _o.frq, _n.fnm]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_11,generation=37,filenames=[segments_11]
[junit4:junit4]   2> 3450 T58 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 37[segments_11]
[junit4:junit4]   2> 3451 T58 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 3456 T58 C1 oass.SolrIndexSearcher.<init> Opening Searcher@402b0fea main
[junit4:junit4]   2> 3456 T58 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 3457 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@402b0fea main{StandardDirectoryReader(segments_10:97:nrt)}
[junit4:junit4]   2> 3457 T58 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3457 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 3459 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@402b0fea main{StandardDirectoryReader(segments_10:97:nrt)}
[junit4:junit4]   2> 3460 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 3460 T58 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 11
[junit4:junit4]   2> 3464 T57 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 3472 T59 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 3474 T59 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_11,generation=37,filenames=[segments_11]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_12,generation=38,filenames=[_p.fdx, _p.tis, _p.nrm, _p.fnm, _p.fdt, _p.tii, segments_12, _p.si, _p.frq, _p.prx]
[junit4:junit4]   2> 3474 T59 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 38[_p.fdx, _p.tis, _p.nrm, _p.fnm, _p.fdt, _p.tii, segments_12, _p.si, _p.frq, _p.prx]
[junit4:junit4]   2> 3474 T59 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 3475 T59 C1 oass.SolrIndexSearcher.<init> Opening Searcher@3555c746 main
[junit4:junit4]   2> 3476 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@3555c746 main{StandardDirectoryReader(segments_12:99:nrt _p(4.2):C10)}
[junit4:junit4]   2> 3476 T59 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3476 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 3478 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3555c746 main{StandardDirectoryReader(segments_12:99:nrt _p(4.2):C10)}
[junit4:junit4]   2> 3478 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 3479 T59 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {add=[0 (1428087355610234880), 1 (1428087355611283456), 2 (1428087355612332032), 3 (1428087355612332033), 4 (1428087355612332034), 5 (1428087355613380608), 6 (1428087355613380609), 7 (1428087355613380610), 8 (1428087355614429184), 9 (1428087355614429185)],commit=} 0 12
[junit4:junit4]   2> 3483 T60 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> 3487 T58 C1 REQ [collection1] webapp=/solr path=/select params={fl=id,score,_docid_&q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=0 
[junit4:junit4]   2> 3488 T51 oas.SolrTestCaseJ4.tearDown ###Ending testStreamingRequest
[junit4:junit4]   2> 3496 T51 oas.SolrTestCaseJ4.setUp ###Starting testCommitWithinOnAdd
[junit4:junit4]   2> 3500 T57 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 3501 T57 C1 oass.SolrIndexSearcher.<init> Opening Searcher@39e8b722 realtime
[junit4:junit4]   2> 3501 T57 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1428087355643789312)} 0 2
[junit4:junit4]   2> 3505 T59 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 3506 T59 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_12,generation=38,filenames=[_p.fdx, _p.tis, _p.nrm, _p.fnm, _p.fdt, _p.tii, segments_12, _p.si, _p.frq, _p.prx]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_13,generation=39,filenames=[segments_13]
[junit4:junit4]   2> 3506 T59 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 39[segments_13]
[junit4:junit4]   2> 3507 T59 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 3507 T59 C1 oass.SolrIndexSearcher.<init> Opening Searcher@325cff23 main
[junit4:junit4]   2> 3507 T59 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 3508 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@325cff23 main{StandardDirectoryReader(segments_12:101:nrt)}
[junit4:junit4]   2> 3508 T59 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3508 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 3510 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@325cff23 main{StandardDirectoryReader(segments_12:101:nrt)}
[junit4:junit4]   2> 3511 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 3511 T59 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 6
[junit4:junit4]   2> 3514 T60 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 3519 T58 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id3 (1428087355663712256)]} 0 1
[junit4:junit4]   2> 3523 T57 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 4019 T64 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4:junit4]   2> 4020 T64 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 4022 T64 oass.SolrIndexSearcher.<init> Opening Searcher@1193e3a3 main
[junit4:junit4]   2> 4022 T64 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 4022 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1193e3a3 main{StandardDirectoryReader(segments_13:103:nrt _q(4.2):C1)}
[junit4:junit4]   2> 4022 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 4024 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1193e3a3 main{StandardDirectoryReader(segments_13:103:nrt _q(4.2):C1)}
[junit4:junit4]   2> 4024 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 4527 T58 C1 REQ [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 4532 T59 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id4 (1428087356725919744)]} 0 1
[junit4:junit4]   2> 5032 T64 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4:junit4]   2> 5032 T64 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 5035 T64 oass.SolrIndexSearcher.<init> Opening Searcher@7ad93d7b main
[junit4:junit4]   2> 5035 T64 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 5035 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@7ad93d7b main{StandardDirectoryReader(segments_13:105:nrt _q(4.2):C1 _r(4.2):C1)}
[junit4:junit4]   2> 5036 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 5037 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7ad93d7b main{StandardDirectoryReader(segments_13:105:nrt _q(4.2):C1 _r(4.2):C1)}
[junit4:junit4]   2> 5038 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 5537 T59 C1 REQ [collection1] webapp=/solr path=/select params={q=id:id4&wt=javabin&version=2} hits=1 status=0 QTime=2 
[junit4:junit4]   2> 5538 T51 oas.SolrTestCaseJ4.tearDown ###Ending testCommitWithinOnAdd
[junit4:junit4]   2> 5547 T51 oas.SolrTestCaseJ4.setUp ###Starting testUpdateField
[junit4:junit4]   2> 5550 T60 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 5552 T60 C1 oass.SolrIndexSearcher.<init> Opening Searcher@3c406294 realtime
[junit4:junit4]   2> 5553 T60 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1428087357794418688)} 0 3
[junit4:junit4]   2> 5556 T57 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 5557 T57 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_13,generation=39,filenames=[segments_13]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_14,generation=40,filenames=[segments_14]
[junit4:junit4]   2> 5557 T57 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 40[segments_14]
[junit4:junit4]   2> 5557 T57 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 5558 T57 C1 oass.SolrIndexSearcher.<init> Opening Searcher@3d7581b8 main
[junit4:junit4]   2> 5558 T57 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 5559 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@3d7581b8 main{StandardDirectoryReader(segments_13:107:nrt)}
[junit4:junit4]   2> 5559 T57 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 5559 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 5560 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3d7581b8 main{StandardDirectoryReader(segments_13:107:nrt)}
[junit4:junit4]   2> 5561 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 5561 T57 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 5
[junit4:junit4]   2> 5566 T58 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[unique (1428087357810147328)]} 0 1
[junit4:junit4]   2> 5570 T59 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 5571 T59 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_14,generation=40,filenames=[segments_14]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_15,generation=41,filenames=[_s.fdx, _s.tis, _s.fdt, _s.si, _s.prx, _s.fnm, segments_15, _s.nrm, _s.frq, _s.tii]
[junit4:junit4]   2> 5572 T59 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 41[_s.fdx, _s.tis, _s.fdt, _s.si, _s.prx, _s.fnm, segments_15, _s.nrm, _s.frq, _s.tii]
[junit4:junit4]   2> 5572 T59 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 5573 T59 C1 oass.SolrIndexSearcher.<init> Opening Searcher@639292b0 main
[junit4:junit4]   2> 5573 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@639292b0 main{StandardDirectoryReader(segments_15:109:nrt _s(4.2):C1)}
[junit4:junit4]   2> 5574 T59 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 5574 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 5575 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@639292b0 main{StandardDirectoryReader(segments_15:109:nrt _s(4.2):C1)}
[junit4:junit4]   2> 5576 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 5576 T59 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 7
[junit4:junit4]   2> 5580 T60 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> 5584 T57 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {} 0 1
[junit4:junit4]   2> 5590 T58 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[unique (1428087357834264576)]} 0 3
[junit4:junit4]   2> 5593 T59 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 5595 T59 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_15,generation=41,filenames=[_s.fdx, _s.tis, _s.fdt, _s.si, _s.prx, _s.fnm, segments_15, _s.nrm, _s.frq, _s.tii]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_16,generation=42,filenames=[_t.si, _t.tii, _t.fnm, _t.fdx, _t.tis, _t.nrm, _t.fdt, _t.frq, _t.prx, segments_16]
[junit4:junit4]   2> 5595 T59 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 42[_t.si, _t.tii, _t.fnm, _t.fdx, _t.tis, _t.nrm, _t.fdt, _t.frq, _t.prx, segments_16]
[junit4:junit4]   2> 5596 T59 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 5597 T59 C1 oass.SolrIndexSearcher.<init> Opening Searcher@13fb04ae main
[junit4:junit4]   2> 5598 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@13fb04ae main{StandardDirectoryReader(segments_16:113:nrt _t(4.2):C1)}
[junit4:junit4]   2> 5598 T59 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 5598 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 5600 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@13fb04ae main{StandardDirectoryReader(segments_16:113:nrt _t(4.2):C1)}
[junit4:junit4]   2> 5600 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 5600 T59 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 7
[junit4:junit4]   2> 5604 T60 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> 5609 T57 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[unique (1428087357854187520)]} 0 2
[junit4:junit4]   2> 5612 T58 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 5614 T58 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_16,generation=42,filenames=[_t.si, _t.tii, _t.fnm, _t.fdx, _t.tis, _t.nrm, _t.fdt, _t.frq, _t.prx, segments_16]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_17,generation=43,filenames=[_u.nrm, _u.fnm, _u.tis, _u.prx, _u.fdx, _u.frq, _u.si, _u.fdt, _u.tii, segments_17]
[junit4:junit4]   2> 5615 T58 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 43[_u.nrm, _u.fnm, _u.tis, _u.prx, _u.fdx, _u.frq, _u.si, _u.fdt, _u.tii, segments_17]
[junit4:junit4]   2> 5615 T58 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 5616 T58 C1 oass.SolrIndexSearcher.<init> Opening Searcher@c3e710d main
[junit4:junit4]   2> 5617 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@c3e710d main{StandardDirectoryReader(segments_17:117:nrt _u(4.2):C1)}
[junit4:junit4]   2> 5617 T58 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 5617 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 5619 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c3e710d main{StandardDirectoryReader(segments_17:117:nrt _u(4.2):C1)}
[junit4:junit4]   2> 5619 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 5619 T58 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 7
[junit4:junit4]   2> 5623 T60 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> 5624 T51 oas.SolrTestCaseJ4.tearDown ###Ending testUpdateField
[junit4:junit4]   2> 5632 T51 oas.SolrTestCaseJ4.setUp ###Starting testUpdateMultiValuedField
[junit4:junit4]   2> 5636 T57 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123 (1428087357883547648)]} 0 1
[junit4:junit4]   2> 5639 T59 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 5641 T59 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_17,generation=43,filenames=[_u.nrm, _u.fnm, _u.tis, _u.prx, _u.fdx, _u.frq, _u.si, _u.fdt, _u.tii, segments_17]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_18,generation=44,filenames=[_v.fdt, _v.fdx, _v.si, _u.tis, _u.frq, _u.fdx, _v.tis, _v.frq, _u.fnm, _u.nrm, _v.tii, _u.prx, _u.fdt, _u.si, _v.fnm, _u.tii, segments_18]
[junit4:junit4]   2> 5642 T59 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 44[_v.fdt, _v.fdx, _v.si, _u.tis, _u.frq, _u.fdx, _v.tis, _v.frq, _u.fnm, _u.nrm, _v.tii, _u.prx, _u.fdt, _u.si, _v.fnm, _u.tii, segments_18]
[junit4:junit4]   2> 5642 T59 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 5643 T59 C1 oass.SolrIndexSearcher.<init> Opening Searcher@55cf91a1 main
[junit4:junit4]   2> 5643 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@55cf91a1 main{StandardDirectoryReader(segments_18:119:nrt _u(4.2):C1 _v(4.2):C1)}
[junit4:junit4]   2> 5643 T59 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 5644 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 5645 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@55cf91a1 main{StandardDirectoryReader(segments_18:119:nrt _u(4.2):C1 _v(4.2):C1)}
[junit4:junit4]   2> 5646 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 5646 T59 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 7
[junit4:junit4]   2> 5651 T58 C1 REQ [collection1] webapp=/solr path=/select params={q=id:123&wt=javabin&version=2} hits=1 status=0 QTime=2 
[junit4:junit4]   2> 5656 T60 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123 (1428087357904519168)]} 0 2
[junit4:junit4]   2> 5660 T57 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 5662 T57 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_18,generation=44,filenames=[_v.fdt, _v.fdx, _v.si, _u.tis, _u.frq, _u.fdx, _v.tis, _v.frq, _u.fnm, _u.nrm, _v.tii, _u.prx, _u.fdt, _u.si, _v.fnm, _u.tii, segments_18]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_19,generation=45,filenames=[_w.tis, _w.si, _w.fdt, _w.frq, _u.tis, _w.fdx, _u.frq, _u.fdx, _u.fnm, _u.nrm, _w.tii, _u.prx, _u.fdt, _u.si, _w.fnm, _u.tii, segments_19]
[junit4:junit4]   2> 5662 T57 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 45[_w.tis, _w.si, _w.fdt, _w.frq, _u.tis, _w.fdx, _u.frq, _u.fdx, _u.fnm, _u.nrm, _w.tii, _u.prx, _u.fdt, _u.si, _w.fnm, _u.tii, segments_19]
[junit4:junit4]   2> 5663 T57 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 5664 T57 C1 oass.SolrIndexSearcher.<init> Opening Searcher@2f16bc14 main
[junit4:junit4]   2> 5664 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@2f16bc14 main{StandardDirectoryReader(segments_19:123:nrt _u(4.2):C1 _w(4.2):C1)}
[junit4:junit4]   2> 5664 T57 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 5664 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 5666 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2f16bc14 main{StandardDirectoryReader(segments_19:123:nrt _u(4.2):C1 _w(4.2):C1)}
[junit4:junit4]   2> 5666 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 5667 T57 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 8
[junit4:junit4]   2> 5671 T59 C1 REQ [collection1] webapp=/solr path=/select params={q=id:123&wt=javabin&version=2} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 5676 T58 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123 (1428087357925490688)]} 0 2
[junit4:junit4]   2> 5679 T60 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 5681 T60 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_19,generation=45,filenames=[_w.tis, _w.si, _w.fdt, _w.frq, _u.tis, _w.fdx, _u.frq, _u.fdx, _u.fnm, _u.nrm, _w.tii, _u.prx, _u.fdt, _u.si, _w.fnm, _u.tii, segments_19]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_1a,generation=46,filenames=[_x.fnm, _x.fdt, _x.si, _x.fdx, _u.tis, _x.frq, _u.frq, _u.fdx, _x.tii, segments_1a, _u.fnm, _u.nrm, _u.prx, _u.fdt, _u.si, _u.tii, _x.tis]
[junit4:junit4]   2> 5682 T60 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 46[_x.fnm, _x.fdt, _x.si, _x.fdx, _u.tis, _x.frq, _u.frq, _u.fdx, _x.tii, segments_1a, _u.fnm, _u.nrm, _u.prx, _u.fdt, _u.si, _u.tii, _x.tis]
[junit4:junit4]   2> 5682 T60 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 5683 T60 C1 oass.SolrIndexSearcher.<init> Opening Searcher@5d40b2a6 main
[junit4:junit4]   2> 5684 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@5d40b2a6 main{StandardDirectoryReader(segments_1a:127:nrt _u(4.2):C1 _x(4.2):C1)}
[junit4:junit4]   2> 5684 T60 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 5684 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 5685 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5d40b2a6 main{StandardDirectoryReader(segments_1a:127:nrt _u(4.2):C1 _x(4.2):C1)}
[junit4:junit4]   2> 5686 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 5686 T60 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 7
[junit4:junit4]   2> 5691 T57 C1 REQ [collection1] webapp=/solr path=/select params={q=id:123&wt=javabin&version=2} hits=1 status=0 QTime=2 
[junit4:junit4]   2> 5692 T51 oas.SolrTestCaseJ4.tearDown ###Ending testUpdateMultiValuedField
[junit4:junit4]   2> 5700 T51 oas.SolrTestCaseJ4.setUp ###Starting testExampleConfig
[junit4:junit4]   2> 5703 T59 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 5705 T59 C1 oass.SolrIndexSearcher.<init> Opening Searcher@7ac7c9cc realtime
[junit4:junit4]   2> 5705 T59 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1428087357954850816)} 0 3
[junit4:junit4]   2> 5709 T58 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1112211111 (1428087357960093696)]} 0 1
[junit4:junit4]   2> 5712 T60 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 5714 T60 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_1a,generation=46,filenames=[_x.fnm, _x.fdt, _x.si, _x.fdx, _u.tis, _x.frq, _u.frq, _u.fdx, _x.tii, segments_1a, _u.fnm, _u.nrm, _u.prx, _u.fdt, _u.si, _u.tii, _x.tis]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_1b,generation=47,filenames=[_y.fdt, _y.tis, _y.si, _y.fdx, _y.nrm, _y.frq, _y.fnm, _y.prx, _y.tii, segments_1b]
[junit4:junit4]   2> 5714 T60 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 47[_y.fdt, _y.tis, _y.si, _y.fdx, _y.nrm, _y.frq, _y.fnm, _y.prx, _y.tii, segments_1b]
[junit4:junit4]   2> 5715 T60 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 5716 T60 C1 oass.SolrIndexSearcher.<init> Opening Searcher@37b2a42d main
[junit4:junit4]   2> 5716 T60 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 5717 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@37b2a42d main{StandardDirectoryReader(segments_1b:131:nrt _y(4.2):C1)}
[junit4:junit4]   2> 5717 T60 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 5717 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 5719 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@37b2a42d main{StandardDirectoryReader(segments_1b:131:nrt _y(4.2):C1)}
[junit4:junit4]   2> 5719 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 5719 T60 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 7
[junit4:junit4]   2> 5723 T57 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 5724 T57 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_1b,generation=47,filenames=[_y.fdt, _y.tis, _y.si, _y.fdx, _y.nrm, _y.frq, _y.fnm, _y.prx, _y.tii, segments_1b]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_1c,generation=48,filenames=[_y.fdt, _y.tis, _y.si, _y.fdx, _y.nrm, _y.frq, _y.fnm, _y.prx, _y.tii, segments_1c]
[junit4:junit4]   2> 5724 T57 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 48[_y.fdt, _y.tis, _y.si, _y.fdx, _y.nrm, _y.frq, _y.fnm, _y.prx, _y.tii, segments_1c]
[junit4:junit4]   2> 5724 T57 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 5725 T57 C1 oass.SolrIndexSearcher.<init> Opening Searcher@625dc5ad main
[junit4:junit4]   2> 5725 T57 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 5725 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@625dc5ad main{StandardDirectoryReader(segments_1b:131:nrt _y(4.2):C1)}
[junit4:junit4]   2> 5726 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 5727 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@625dc5ad main{StandardDirectoryReader(segments_1b:131:nrt _y(4.2):C1)}
[junit4:junit4]   2> 5727 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 5727 T57 C1 UPDATE [collection1] webapp=/solr path=/update params={optimize=true&waitSearcher=true&maxSegments=1&wt=javabin&version=2} {optimize=} 0 4
[junit4:junit4]   2> 5731 T59 C1 REQ [collection1] webapp=/solr path=/select params={q=id:1112211111&wt=javabin&version=2} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 5738 T58 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2 (1428087357988405248), 3 (1428087357989453824), 4 (1428087357990502400), 5 (1428087357990502401)]} 0 3
[junit4:junit4]   2> 5741 T57 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 5744 T57 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_1c,generation=48,filenames=[_y.fdt, _y.tis, _y.si, _y.fdx, _y.nrm, _y.frq, _y.fnm, _y.prx, _y.tii, segments_1c]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_1d,generation=49,filenames=[_z.fdt, _z.tii, _z.frq, _z.prx, _y.tis, _z.fdx, _y.si, _y.frq, _z.si, _z.tis, segments_1d, _z.fnm, _y.prx, _z.nrm, _y.tii, _y.fdt, _y.nrm, _y.fdx, _y.fnm]
[junit4:junit4]   2> 5744 T57 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 49[_z.fdt, _z.tii, _z.frq, _z.prx, _y.tis, _z.fdx, _y.si, _y.frq, _z.si, _z.tis, segments_1d, _z.fnm, _y.prx, _z.nrm, _y.tii, _y.fdt, _y.nrm, _y.fdx, _y.fnm]
[junit4:junit4]   2> 5745 T57 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 5746 T57 C1 oass.SolrIndexSearcher.<init> Opening Searcher@6e7dc3b7 main
[junit4:junit4]   2> 5746 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@6e7dc3b7 main{StandardDirectoryReader(segments_1d:133:nrt _y(4.2):C1 _z(4.2):C4)}
[junit4:junit4]   2> 5746 T57 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 5746 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 5748 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6e7dc3b7 main{StandardDirectoryReader(segments_1d:133:nrt _y(4.2):C1 _z(4.2):C4)}
[junit4:junit4]   2> 5748 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 5749 T57 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 8
[junit4:junit4]   2> 5752 T59 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 5761 T59 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_1d,generation=49,filenames=[_z.fdt, _z.tii, _z.frq, _z.prx, _y.tis, _z.fdx, _y.si, _y.frq, _z.si, _z.tis, segments_1d, _z.fnm, _y.prx, _z.nrm, _y.tii, _y.fdt, _y.nrm, _y.fdx, _y.fnm]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_1e,generation=50,filenames=[_10.si, _10.fnm, _10.prx, _10.fdt, _10.tii, _10.frq, _10.nrm, segments_1e, _10.tis, _10.fdx]
[junit4:junit4]   2> 5761 T59 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 50[_10.si, _10.fnm, _10.prx, _10.fdt, _10.tii, _10.frq, _10.nrm, segments_1e, _10.tis, _10.fdx]
[junit4:junit4]   2> 5762 T59 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 5763 T59 C1 oass.SolrIndexSearcher.<init> Opening Searcher@580416e4 main
[junit4:junit4]   2> 5763 T59 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 5763 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@580416e4 main{StandardDirectoryReader(segments_1e:135:nrt _10(4.2):C5)}
[junit4:junit4]   2> 5764 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 5765 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@580416e4 main{StandardDirectoryReader(segments_1e:135:nrt _10(4.2):C5)}
[junit4:junit4]   2> 5766 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 5766 T59 C1 UPDATE [collection1] webapp=/solr path=/update params={optimize=true&waitSearcher=true&maxSegments=1&wt=javabin&version=2} {optimize=} 0 14
[junit4:junit4]   2> 5773 T58 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=4 
[junit4:junit4]   2> 5780 T60 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=3 
[junit4:junit4]   2> 5784 T57 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=1 
[junit4:junit4]   2> 5785 T51 oas.SolrTestCaseJ4.tearDown ###Ending testExampleConfig
[junit4:junit4]   2> 5795 T51 oas.SolrTestCaseJ4.setUp ###Starting testAddRetrieve
[junit4:junit4]   2> 5798 T59 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 5799 T59 C1 oass.SolrIndexSearcher.<init> Opening Searcher@63282cef realtime
[junit4:junit4]   2> 5800 T59 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1428087358054465536)} 0 2
[junit4:junit4]   2> 5805 T58 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id1 (1428087358059708416), id2 (1428087358060756992)]} 0 2
[junit4:junit4]   2> 5808 T60 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 5810 T60 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_1e,generation=50,filenames=[_10.si, _10.fnm, _10.prx, _10.fdt, _10.tii, _10.frq, _10.nrm, segments_1e, _10.tis, _10.fdx]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_1f,generation=51,filenames=[_11.tii, _11.nrm, _11.si, _11.fdx, _11.fnm, _11.tis, _11.prx, _11.fdt, segments_1f, _11.frq]
[junit4:junit4]   2> 5810 T60 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 51[_11.tii, _11.nrm, _11.si, _11.fdx, _11.fnm, _11.tis, _11.prx, _11.fdt, segments_1f, _11.frq]
[junit4:junit4]   2> 5810 T60 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 5811 T60 C1 oass.SolrIndexSearcher.<init> Opening Searcher@4096711f main
[junit4:junit4]   2> 5812 T60 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 5812 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@4096711f main{StandardDirectoryReader(segments_1f:139:nrt _11(4.2):C2)}
[junit4:junit4]   2> 5812 T60 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 5813 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 5814 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4096711f main{StandardDirectoryReader(segments_1f:139:nrt _11(4.2):C2)}
[junit4:junit4]   2> 5815 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 5815 T60 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 7
[junit4:junit4]   2> 5819 T57 C1 REQ [collection1] webapp=/solr path=/select params={sort=price+asc&q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=1 
[junit4:junit4]   2> 5825 T59 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id1 (1428087358079631360), id2 (1428087358081728512)]} 0 3
[junit4:junit4]   2> 5829 T58 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 5832 T58 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_1f,generation=51,filenames=[_11.tii, _11.nrm, _11.si, _11.fdx, _11.fnm, _11.tis, _11.prx, _11.fdt, segments_1f, _11.frq]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_1g,generation=52,filenames=[_12.si, _12.fnm, _12.prx, _12.fdt, _12.tis, segments_1g, _12.tii, _12.fdx, _12.frq, _12.nrm]
[junit4:junit4]   2> 5832 T58 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 52[_12.si, _12.fnm, _12.prx, _12.fdt, _12.tis, segments_1g, _12.tii, _12.fdx, _12.frq, _12.nrm]
[junit4:junit4]   2> 5833 T58 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 5833 T58 C1 oass.SolrIndexSearcher.<init> Opening Searcher@1a2124c8 main
[junit4:junit4]   2> 5834 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1a2124c8 main{StandardDirectoryReader(segments_1g:143:nrt _12(4.2):C2)}
[junit4:junit4]   2> 5834 T58 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 5834 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 5836 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1a2124c8 main{StandardDirectoryReader(segments_1g:143:nrt _12(4.2):C2)}
[junit4:junit4]   2> 5836 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 5837 T58 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 8
[junit4:junit4]   2> 5840 T60 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> 5845 T57 C1 REQ [collection1] webapp=/solr path=/select params={sort=price+asc&q=name:h𩬅llo&wt=javabin&version=2} hits=1 status=0 QTime=2 
[junit4:junit4]   2> 5847 T51 oas.SolrTestCaseJ4.tearDown ###Ending testAddRetrieve
[junit4:junit4]   2> 5857 T51 oas.SolrTestCaseJ4.setUp ###Starting testStatistics
[junit4:junit4]   2> 5860 T59 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 5861 T59 C1 oass.SolrIndexSearcher.<init> Opening Searcher@2f0c37a9 realtime
[junit4:junit4]   2> 5862 T59 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1428087358118428672)} 0 3
[junit4:junit4]   2> 5865 T58 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 5865 T58 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_1g,generation=52,filenames=[_12.si, _12.fnm, _12.prx, _12.fdt, _12.tis, segments_1g, _12.tii, _12.fdx, _12.frq, _12.nrm]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_1h,generation=53,filenames=[segments_1h]
[junit4:junit4]   2> 5866 T58 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 53[segments_1h]
[junit4:junit4]   2> 5866 T58 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 5866 T58 C1 oass.SolrIndexSearcher.<init> Opening Searcher@4ae22b2 main
[junit4:junit4]   2> 5867 T58 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 5867 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@4ae22b2 main{StandardDirectoryReader(segments_1g:145:nrt)}
[junit4:junit4]   2> 5867 T58 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 5868 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 5870 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4ae22b2 main{StandardDirectoryReader(segments_1g:145:nrt)}
[junit4:junit4]   2> 5871 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 5871 T58 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 6
[junit4:junit4]   2> 5875 T60 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 5879 T57 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc0 (1428087358138351616)]} 0 1
[junit4:junit4]   2> 20879 T63 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 20881 T63 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_1h,generation=53,filenames=[segments_1h]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_1i,generation=54,filenames=[_13.prx, _13.nrm, _13.si, _13.tii, segments_1i, _13.fdt, _13.fnm, _13.frq, _13.fdx, _13.tis]
[junit4:junit4]   2> 20881 T63 oasc.SolrDeletionPolicy.updateCommits newest commit = 54[_13.prx, _13.nrm, _13.si, _13.tii, segments_1i, _13.fdt, _13.fnm, _13.frq, _13.fdx, _13.tis]
[junit4:junit4]   2> 20882 T63 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 20882 T63 oass.SolrIndexSearcher.<init> Opening Searcher@54639901 realtime
[junit4:junit4]   2> 20883 T63 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 206719 T51 oas.SolrTestCaseJ4.tearDown ###Ending testStatistics
[junit4:junit4]   2> 206742 T58 C1 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc1 (1428087568721772544)]} 0 42
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=SolrExampleBinaryTest -Dtests.method=testStatistics -Dtests.seed=65E8702BAA9F74FB -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ar_SA -Dtests.timezone=America/Curacao -Dtests.file.encoding=UTF-8
[junit4:junit4] ERROR    202s J1 | SolrExampleBinaryTest.testStatistics <<<
[junit4:junit4]    > Throwable #1: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: https://127.0.0.1:60773/solr
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([65E8702BAA9F74FB:9241434F06B2AE13]: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.testStatistics(SolrExampleTests.java:804)
[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

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

ionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_1m,generation=58,filenames=[_16.tii, _16.fnm, _16.prx, segments_1m, _16.si, _16.fdt, _16.fdx, _16.frq, _16.tis, _16.nrm]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_1n,generation=59,filenames=[segments_1n]
[junit4:junit4]   2> 208487 T60 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 59[segments_1n]
[junit4:junit4]   2> 208488 T60 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 208488 T60 C1 oass.SolrIndexSearcher.<init> Opening Searcher@dd34039 main
[junit4:junit4]   2> 208489 T60 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 208489 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@dd34039 main{StandardDirectoryReader(segments_1m:159:nrt)}
[junit4:junit4]   2> 208489 T60 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 208490 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 208493 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@dd34039 main{StandardDirectoryReader(segments_1m:159:nrt)}
[junit4:junit4]   2> 208494 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 208494 T60 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 8
[junit4:junit4]   2> 208500 T59 C1 UPDATE [collection1] webapp=/solr path=/update params={overwrite=false&wt=javabin&version=2} {add=[id1 (1428087570600820736)]} 0 1
[junit4:junit4]   2> 208503 T58 C1 UPDATE [collection1] webapp=/solr path=/update params={overwrite=false&wt=javabin&version=2} {add=[id1 (1428087570606063616)]} 0 0
[junit4:junit4]   2> 208507 T57 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 208509 T57 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_1n,generation=59,filenames=[segments_1n]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2204a360 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2a1b1137),segFN=segments_1o,generation=60,filenames=[_17.si, _17.fdx, _17.tis, _17.frq, segments_1o, _17.fdt, _17.fnm, _17.tii]
[junit4:junit4]   2> 208509 T57 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 60[_17.si, _17.fdx, _17.tis, _17.frq, segments_1o, _17.fdt, _17.fnm, _17.tii]
[junit4:junit4]   2> 208510 T57 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 208510 T57 C1 oass.SolrIndexSearcher.<init> Opening Searcher@2f77ffb main
[junit4:junit4]   2> 208511 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@2f77ffb main{StandardDirectoryReader(segments_1o:161:nrt _17(4.2):C2)}
[junit4:junit4]   2> 208511 T62 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 208511 T57 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 208514 T62 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2f77ffb main{StandardDirectoryReader(segments_1o:161:nrt _17(4.2):C2)}
[junit4:junit4]   2> 208514 T62 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 208515 T57 C1 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 8
[junit4:junit4]   2> 208519 T60 C1 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=1 
[junit4:junit4]   2> 208520 T51 oas.SolrTestCaseJ4.tearDown ###Ending testUpdateRequestWithParameters
[junit4:junit4]   2> 208613 T51 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1856241576
[junit4:junit4]   2> 208614 T51 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@37f7466f
[junit4:junit4]   2> 208628 T51 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 208629 T51 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 208635 T51 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 208636 T51 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 208644 T51 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=60,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=281,cumulative_deletesById=5,cumulative_deletesByQuery=32,cumulative_errors=1}
[junit4:junit4]   2> 208644 T51 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4:junit4]   2> 208645 T51 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 208645 T51 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4:junit4]   2> 208647 T51 C1 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 208648 T51 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 208648 T51 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 208649 T51 oasc.CachingDirectoryFactory.close Closing directory when closing factory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 208649 T51 oasc.CachingDirectoryFactory.closeDirectory Closing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164
[junit4:junit4]   2> 208650 T51 oasc.CachingDirectoryFactory.close Closing directory when closing factory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 208650 T51 oasc.CachingDirectoryFactory.closeDirectory Closing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1361930229164/index
[junit4:junit4]   2> 208651 T51 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 208704 T51 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> NOTE: test params are: codec=Lucene3x, sim=DefaultSimilarity, locale=ar_SA, timezone=America/Curacao
[junit4:junit4]   2> NOTE: Linux 3.2.0-38-generic amd64/Oracle Corporation 1.7.0_15 (64-bit)/cpus=8,threads=1,free=71976128,total=126418944
[junit4:junit4]   2> NOTE: All tests run in this JVM: [NamedListTest, IteratorChainTest, SolrExampleStreamingBinaryTest, SolrExampleBinaryTest]
[junit4:junit4] Completed on J1 in 208.76s, 25 tests, 1 error <<< FAILURES!

[...truncated 31 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:381: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:361: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build.xml:196: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/common-build.xml:447: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1202: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:865: There were test failures: 45 suites, 259 tests, 1 error

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



Mime
View raw message