lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@thetaphi.de>
Subject [JENKINS] Lucene-Solr-trunk-Linux (64bit/jdk1.7.0_15) - Build # 4464 - Failure!
Date Sun, 24 Feb 2013 23:45:56 GMT
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/4464/
Java: 64bit/jdk1.7.0_15 -XX:+UseSerialGC

1 tests failed.
REGRESSION:  org.apache.solr.client.solrj.SolrExampleXMLTest.testUnicode

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

Stack Trace:
org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: https://127.0.0.1:44785/solr
	at __randomizedtesting.SeedInfo.seed([A897EA75089FC86A:B15B5E7FAA25199D]: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.commit(SolrServer.java:168)
	at org.apache.solr.client.solrj.SolrServer.commit(SolrServer.java:146)
	at org.apache.solr.client.solrj.SolrExampleTests.testUnicode(SolrExampleTests.java:364)
	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 9973 lines...]
[junit4:junit4] Suite: org.apache.solr.client.solrj.SolrExampleXMLTest
[junit4:junit4]   2> 0 T72 oas.SolrJettyTestBase.beforeSolrJettyTestBase Randomized ssl (true) and clientAuth (false)
[junit4:junit4]   2> 1 T72 oas.SolrTestCaseJ4.initCore ####initCore
[junit4:junit4]   2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/./solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 2 T72 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4:junit4]   2> 9 T72 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 251 T72 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4:junit4]   2> 256 T72 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:44785
[junit4:junit4]   2> 256 T72 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 257 T72 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 257 T72 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr
[junit4:junit4]   2> 257 T72 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/solr.xml
[junit4:junit4]   2> 258 T72 oasc.CoreContainer.<init> New CoreContainer 1208204611
[junit4:junit4]   2> 258 T72 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/'
[junit4:junit4]   2> 258 T72 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/'
[junit4:junit4]   2> 277 T72 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 278 T72 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 278 T72 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 278 T72 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 279 T72 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 279 T72 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 279 T72 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 280 T72 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 280 T72 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 280 T72 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 287 T72 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 303 T82 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1
[junit4:junit4]   2> 304 T82 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/'
[junit4:junit4]   2> 328 T82 oasc.SolrConfig.initLibs Adding specified lib dirs to ClassLoader
[junit4:junit4]   2> 375 T82 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 444 T82 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 444 T82 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 464 T82 oass.IndexSchema.readSchema Schema name=example
[junit4:junit4]   2> 924 T82 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 937 T82 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 944 T82 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 968 T82 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/
[junit4:junit4]   2> 969 T82 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2bcc6e39
[junit4:junit4]   2> 969 T82 oasc.SolrCore.initListeners [collection1] Added SolrEventListener for newSearcher: org.apache.solr.core.QuerySenderListener{queries=[]}
[junit4:junit4]   2> 970 T82 oasc.SolrCore.initListeners [collection1] Added SolrEventListener for firstSearcher: org.apache.solr.core.QuerySenderListener{queries=[{q=static firstSearcher warming in solrconfig.xml}]}
[junit4:junit4]   2> 970 T82 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 971 T82 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861 forceNew:false
[junit4:junit4]   2> 971 T82 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 972 T82 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index/
[junit4:junit4]   2> 972 T82 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 973 T82 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index forceNew:false
[junit4:junit4]   2> 975 T82 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 975 T82 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 976 T82 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 976 T82 oasc.SolrCore.initWriters created json: solr.JSONResponseWriter
[junit4:junit4]   2> 977 T82 oasc.SolrCore.initWriters adding lazy queryResponseWriter: solr.VelocityResponseWriter
[junit4:junit4]   2> 977 T82 oasc.SolrCore.initWriters created velocity: solr.VelocityResponseWriter
[junit4:junit4]   2> 977 T82 oasc.SolrCore.initWriters created xslt: solr.XSLTResponseWriter
[junit4:junit4]   2> 978 T82 oasr.XSLTResponseWriter.init xsltCacheLifetimeSeconds=5
[junit4:junit4]   2> 981 T82 oasc.RequestHandlers.initHandlersFromConfig created /select: solr.SearchHandler
[junit4:junit4]   2> 981 T82 oasc.RequestHandlers.initHandlersFromConfig created /query: solr.SearchHandler
[junit4:junit4]   2> 982 T82 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 983 T82 oasc.RequestHandlers.initHandlersFromConfig created /browse: solr.SearchHandler
[junit4:junit4]   2> 984 T82 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 985 T82 oasc.RequestHandlers.initHandlersFromConfig created /update/json: solr.JsonUpdateRequestHandler
[junit4:junit4]   2> 986 T82 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: solr.CSVRequestHandler
[junit4:junit4]   2> 986 T82 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.extraction.ExtractingRequestHandler
[junit4:junit4]   2> 987 T82 oasc.RequestHandlers.initHandlersFromConfig created /update/extract: solr.extraction.ExtractingRequestHandler
[junit4:junit4]   2> 987 T82 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.FieldAnalysisRequestHandler
[junit4:junit4]   2> 988 T82 oasc.RequestHandlers.initHandlersFromConfig created /analysis/field: solr.FieldAnalysisRequestHandler
[junit4:junit4]   2> 988 T82 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.DocumentAnalysisRequestHandler
[junit4:junit4]   2> 988 T82 oasc.RequestHandlers.initHandlersFromConfig created /analysis/document: solr.DocumentAnalysisRequestHandler
[junit4:junit4]   2> 989 T82 oasc.RequestHandlers.initHandlersFromConfig created /admin/: solr.admin.AdminHandlers
[junit4:junit4]   2> 989 T82 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: solr.PingRequestHandler
[junit4:junit4]   2> 990 T82 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 990 T82 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 991 T82 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
[junit4:junit4]   2> 991 T82 oasc.RequestHandlers.initHandlersFromConfig created /spell: solr.SearchHandler
[junit4:junit4]   2> 991 T82 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
[junit4:junit4]   2> 991 T82 oasc.RequestHandlers.initHandlersFromConfig created /tvrh: solr.SearchHandler
[junit4:junit4]   2> 992 T82 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
[junit4:junit4]   2> 992 T82 oasc.RequestHandlers.initHandlersFromConfig created /terms: solr.SearchHandler
[junit4:junit4]   2> 992 T82 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
[junit4:junit4]   2> 993 T82 oasc.RequestHandlers.initHandlersFromConfig created /elevate: solr.SearchHandler
[junit4:junit4]   2> 995 T82 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 997 T82 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 998 T82 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 1002 T82 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 1002 T82 oass.SolrIndexSearcher.<init> Opening Searcher@44702455 main
[junit4:junit4]   2> 1003 T82 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/tlog
[junit4:junit4]   2> 1004 T82 oasu.CommitTracker.<init> Hard AutoCommit: if uncommited for 15000ms; 
[junit4:junit4]   2> 1004 T82 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 1005 T82 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 1006 T82 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> 1006 T82 oashc.SpellCheckComponent.inform No queryConverter defined, using default converter
[junit4:junit4]   2> 1007 T82 oashc.QueryElevationComponent.inform Loading QueryElevation from: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/conf/elevate.xml
[junit4:junit4]   2> 1020 T82 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 1020 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@44702455 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 1020 T82 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> ASYNC  NEW_CORE C4 name=collection1 org.apache.solr.core.SolrCore@28f4f2d
[junit4:junit4]   2> 1022 T83 C4 REQ [collection1] webapp=null path=null params={event=firstSearcher&q=static+firstSearcher+warming+in+solrconfig.xml&distrib=false} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 1022 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 1022 T83 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default
[junit4:junit4]   2> 1022 T83 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak
[junit4:junit4]   2> 1023 T83 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@44702455 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 1025 T82 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 1028 T82 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 1028 T72 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1
[junit4:junit4]   2> 1029 T72 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 1029 T72 oas.SolrJettyTestBase.createJetty Jetty Assigned Port#44785
[junit4:junit4]   2> 1037 T72 oas.SolrTestCaseJ4.setUp ###Starting testAugmentFields
[junit4:junit4]   2> 1038 T72 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 1134 T80 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 1136 T80 C4 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 1136 T80 C4 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 1137 T80 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 1137 T80 C4 oass.SolrIndexSearcher.<init> Opening Searcher@29993773 realtime
[junit4:junit4]   2> 1138 T80 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1427897684322156544)} 0 5
[junit4:junit4]   2> 1144 T81 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[111 (1427897684331593728)]} 0 2
[junit4:junit4]   2> 1149 T79 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 1155 T79 C4 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_2,generation=2,filenames=[_0_NestedPulsing_0.doc, _0.fnm, _0_MockFixedIntBlock_0.pos, _0.nvm, _0_MockFixedIntBlock_0.tib, _0_NestedPulsing_0.tip, _0_NestedPulsing_0.tim, _0_MockFixedIntBlock_0.frq, _0_Direct_0.doc, _0_NestedPulsing_0.pos, _0_Pulsing41_0.doc, _0_Pulsing41_0.pos, _0_MockFixedIntBlock_0.pyl, _0_MockFixedIntBlock_0.skp, _0_Pulsing41_0.tim, _0_MockFixedIntBlock_0.doc, _0_Pulsing41_0.tip, _0_MockFixedIntBlock_0.tii, _0.nvd, _0_Direct_0.pos, segments_2, _0.fdx, _0_Direct_0.tip, _0.si, _0_Direct_0.tim, _0.fdt]
[junit4:junit4]   2> 1155 T79 C4 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[_0_NestedPulsing_0.doc, _0.fnm, _0_MockFixedIntBlock_0.pos, _0.nvm, _0_MockFixedIntBlock_0.tib, _0_NestedPulsing_0.tip, _0_NestedPulsing_0.tim, _0_MockFixedIntBlock_0.frq, _0_Direct_0.doc, _0_NestedPulsing_0.pos, _0_Pulsing41_0.doc, _0_Pulsing41_0.pos, _0_MockFixedIntBlock_0.pyl, _0_MockFixedIntBlock_0.skp, _0_Pulsing41_0.tim, _0_MockFixedIntBlock_0.doc, _0_Pulsing41_0.tip, _0_MockFixedIntBlock_0.tii, _0.nvd, _0_Direct_0.pos, segments_2, _0.fdx, _0_Direct_0.tip, _0.si, _0_Direct_0.tim, _0.fdt]
[junit4:junit4]   2> 1156 T79 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 1165 T79 C4 oass.SolrIndexSearcher.<init> Opening Searcher@5141ecf1 main
[junit4:junit4]   2> 1166 T79 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 1166 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@5141ecf1 main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):C1)}
[junit4:junit4]   2> 1166 T79 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 1167 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 1168 T83 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5141ecf1 main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):C1)}
[junit4:junit4]   2> 1169 T83 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 1169 T79 C4 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 20
[junit4:junit4]   2> 1176 T78 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[222 (1427897684364099584)]} 0 2
[junit4:junit4]   2> 1181 T80 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 1195 T80 C4 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_2,generation=2,filenames=[_0_NestedPulsing_0.doc, _0.fnm, _0_MockFixedIntBlock_0.pos, _0.nvm, _0_MockFixedIntBlock_0.tib, _0_NestedPulsing_0.tip, _0_NestedPulsing_0.tim, _0_MockFixedIntBlock_0.frq, _0_Direct_0.doc, _0_NestedPulsing_0.pos, _0_Pulsing41_0.doc, _0_Pulsing41_0.pos, _0_MockFixedIntBlock_0.pyl, _0_MockFixedIntBlock_0.skp, _0_Pulsing41_0.tim, _0_MockFixedIntBlock_0.doc, _0_Pulsing41_0.tip, _0_MockFixedIntBlock_0.tii, _0.nvd, _0_Direct_0.pos, segments_2, _0.fdx, _0_Direct_0.tip, _0.si, _0_Direct_0.tim, _0.fdt]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_3,generation=3,filenames=[_1_Direct_0.pos, _1_NestedPulsing_0.tip, _1_MockFixedIntBlock_0.pyl, _0_MockFixedIntBlock_0.tib, _1.fnm, _0_NestedPulsing_0.pos, _0_Pulsing41_0.tim, _0_Pulsing41_0.tip, _0_MockFixedIntBlock_0.tii, _1_Direct_0.tim, _1_NestedPulsing_0.tim, _1_Direct_0.tip, _0_NestedPulsing_0.doc, _1.nvm, _0.fnm, _1_MockFixedIntBlock_0.frq, _1.nvd, _1_NestedPulsing_0.pos, _0_MockFixedIntBlock_0.pos, _1_Pulsing41_0.doc, _0_Direct_0.doc, _1.fdx, _1_Pulsing41_0.tip, _1_MockFixedIntBlock_0.skp, _1.fdt, _1_Pulsing41_0.tim, _1_MockFixedIntBlock_0.pos, _1_Direct_0.doc, _1_MockFixedIntBlock_0.doc, _0_NestedPulsing_0.tip, _0_NestedPulsing_0.tim, _1_NestedPulsing_0.doc, _0_MockFixedIntBlock_0.pyl, _0_MockFixedIntBlock_0.skp, _0.nvd, _0.si, _1_MockFixedIntBlock_0.tii, _0.nvm, _1_Pulsing41_0.pos, _0_MockFixedIntBlock_0.frq, _0_Pulsing41_0.doc, _0_Pulsing41_0.pos, _0_MockFixedIntBlock_0.doc, _1.si, _1_MockFixedIntBlock_0.tib, _0_Direct_0.pos, _0.fdx, _0_Direct_0.tip, _0_Direct_0.tim, segments_3, _0.fdt]
[junit4:junit4]   2> 1195 T80 C4 oasc.SolrDeletionPolicy.updateCommits newest commit = 3[_1_Direct_0.pos, _1_NestedPulsing_0.tip, _1_MockFixedIntBlock_0.pyl, _0_MockFixedIntBlock_0.tib, _1.fnm, _0_NestedPulsing_0.pos, _0_Pulsing41_0.tim, _0_Pulsing41_0.tip, _0_MockFixedIntBlock_0.tii, _1_Direct_0.tim, _1_NestedPulsing_0.tim, _1_Direct_0.tip, _0_NestedPulsing_0.doc, _1.nvm, _0.fnm, _1_MockFixedIntBlock_0.frq, _1.nvd, _1_NestedPulsing_0.pos, _0_MockFixedIntBlock_0.pos, _1_Pulsing41_0.doc, _0_Direct_0.doc, _1.fdx, _1_Pulsing41_0.tip, _1_MockFixedIntBlock_0.skp, _1.fdt, _1_Pulsing41_0.tim, _1_MockFixedIntBlock_0.pos, _1_Direct_0.doc, _1_MockFixedIntBlock_0.doc, _0_NestedPulsing_0.tip, _0_NestedPulsing_0.tim, _1_NestedPulsing_0.doc, _0_MockFixedIntBlock_0.pyl, _0_MockFixedIntBlock_0.skp, _0.nvd, _0.si, _1_MockFixedIntBlock_0.tii, _0.nvm, _1_Pulsing41_0.pos, _0_MockFixedIntBlock_0.frq, _0_Pulsing41_0.doc, _0_Pulsing41_0.pos, _0_MockFixedIntBlock_0.doc, _1.si, _1_MockFixedIntBlock_0.tib, _0_Direct_0.pos, _0.fdx, _0_Direct_0.tip, _0_Direct_0.tim, segments_3, _0.fdt]
[junit4:junit4]   2> 1196 T80 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 1198 T80 C4 oass.SolrIndexSearcher.<init> Opening Searcher@13df5a0d main
[junit4:junit4]   2> 1199 T80 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 1199 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@13df5a0d main{StandardDirectoryReader(segments_3:5:nrt _0(5.0):C1 _1(5.0):C1)}
[junit4:junit4]   2> 1199 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 1201 T83 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@13df5a0d main{StandardDirectoryReader(segments_3:5:nrt _0(5.0):C1 _1(5.0):C1)}
[junit4:junit4]   2> 1202 T83 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 1202 T80 C4 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 21
[junit4:junit4]   2> 1210 T81 C4 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=xml&version=2.2} hits=2 status=0 QTime=2 
[junit4:junit4]   2> 1214 T72 oas.SolrTestCaseJ4.tearDown ###Ending testAugmentFields
[junit4:junit4]   2> 1222 T72 oas.SolrTestCaseJ4.setUp ###Starting testPingHandler
[junit4:junit4]   2> 1226 T79 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 1227 T79 C4 oass.SolrIndexSearcher.<init> Opening Searcher@37900c04 realtime
[junit4:junit4]   2> 1227 T79 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1427897684417576960)} 0 2
[junit4:junit4]   2> 1232 T78 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 1232 T78 C4 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_3,generation=3,filenames=[_1_Direct_0.pos, _1_NestedPulsing_0.tip, _1_MockFixedIntBlock_0.pyl, _0_MockFixedIntBlock_0.tib, _1.fnm, _0_NestedPulsing_0.pos, _0_Pulsing41_0.tim, _0_Pulsing41_0.tip, _0_MockFixedIntBlock_0.tii, _1_Direct_0.tim, _1_NestedPulsing_0.tim, _1_Direct_0.tip, _0_NestedPulsing_0.doc, _1.nvm, _0.fnm, _1_MockFixedIntBlock_0.frq, _1.nvd, _1_NestedPulsing_0.pos, _0_MockFixedIntBlock_0.pos, _1_Pulsing41_0.doc, _0_Direct_0.doc, _1.fdx, _1_Pulsing41_0.tip, _1_MockFixedIntBlock_0.skp, _1.fdt, _1_Pulsing41_0.tim, _1_MockFixedIntBlock_0.pos, _1_Direct_0.doc, _1_MockFixedIntBlock_0.doc, _0_NestedPulsing_0.tip, _0_NestedPulsing_0.tim, _1_NestedPulsing_0.doc, _0_MockFixedIntBlock_0.pyl, _0_MockFixedIntBlock_0.skp, _0.nvd, _0.si, _1_MockFixedIntBlock_0.tii, _0.nvm, _1_Pulsing41_0.pos, _0_MockFixedIntBlock_0.frq, _0_Pulsing41_0.doc, _0_Pulsing41_0.pos, _0_MockFixedIntBlock_0.doc, _1.si, _1_MockFixedIntBlock_0.tib, _0_Direct_0.pos, _0.fdx, _0_Direct_0.tip, _0_Direct_0.tim, segments_3, _0.fdt]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_4,generation=4,filenames=[segments_4]
[junit4:junit4]   2> 1233 T78 C4 oasc.SolrDeletionPolicy.updateCommits newest commit = 4[segments_4]
[junit4:junit4]   2> 1234 T78 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 1234 T78 C4 oass.SolrIndexSearcher.<init> Opening Searcher@5e820512 main
[junit4:junit4]   2> 1235 T78 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 1235 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@5e820512 main{StandardDirectoryReader(segments_3:7:nrt)}
[junit4:junit4]   2> 1235 T78 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 1235 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 1237 T83 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5e820512 main{StandardDirectoryReader(segments_3:7:nrt)}
[junit4:junit4]   2> 1237 T83 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 1238 T78 C4 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 6
[junit4:junit4]   2> 1242 T80 C4 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 1247 T81 C4 REQ [collection1] webapp=/solr path=/admin/ping params={wt=xml&version=2.2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 1248 T81 C4 REQ [collection1] webapp=/solr path=/admin/ping params={wt=xml&version=2.2} status=0 QTime=1 
[junit4:junit4]   2> 1249 T72 oas.SolrTestCaseJ4.tearDown ###Ending testPingHandler
[junit4:junit4]   2> 1257 T72 oas.SolrTestCaseJ4.setUp ###Starting testFaceting
[junit4:junit4]   2> 1261 T79 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 1261 T79 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1427897684454277120)} 0 1
[junit4:junit4]   2> 1266 T78 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 1267 T78 C4 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_4,generation=4,filenames=[segments_4]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_5,generation=5,filenames=[segments_5]
[junit4:junit4]   2> 1267 T78 C4 oasc.SolrDeletionPolicy.updateCommits newest commit = 5[segments_5]
[junit4:junit4]   2> 1268 T78 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 1268 T78 C4 oass.SolrIndexSearcher.<init> Opening Searcher@1c9fd9b2 main
[junit4:junit4]   2> 1269 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1c9fd9b2 main{StandardDirectoryReader(segments_3:7:nrt)}
[junit4:junit4]   2> 1269 T78 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 1269 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 1271 T83 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1c9fd9b2 main{StandardDirectoryReader(segments_3:7:nrt)}
[junit4:junit4]   2> 1271 T83 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 1272 T78 C4 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 6
[junit4:junit4]   2> 1276 T80 C4 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 1284 T81 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[1 (1427897684476297216), 2 (1427897684477345792), 3 (1427897684478394368), 4 (1427897684478394369), 5 (1427897684478394370), 6 (1427897684478394371), 7 (1427897684479442944), 8 (1427897684479442945), 9 (1427897684479442946), 10 (1427897684479442947)]} 0 3
[junit4:junit4]   2> 1289 T79 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 1293 T79 C4 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_5,generation=5,filenames=[segments_5]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_6,generation=6,filenames=[_2_NestedPulsing_0.tim, _2_Direct_0.doc, _2_Direct_0.tip, _2.si, _2_Direct_0.tim, _2_MockFixedIntBlock_0.skp, _2.fdt, _2_Direct_0.pos, _2_MockFixedIntBlock_0.tii, _2.fdx, _2.fnm, _2_MockFixedIntBlock_0.tib, _2.nvm, _2_MockFixedIntBlock_0.pyl, _2_MockFixedIntBlock_0.pos, _2_NestedPulsing_0.pos, _2.nvd, _2_MockFixedIntBlock_0.frq, _2_MockFixedIntBlock_0.doc, segments_6, _2_NestedPulsing_0.tip, _2_NestedPulsing_0.doc]
[junit4:junit4]   2> 1293 T79 C4 oasc.SolrDeletionPolicy.updateCommits newest commit = 6[_2_NestedPulsing_0.tim, _2_Direct_0.doc, _2_Direct_0.tip, _2.si, _2_Direct_0.tim, _2_MockFixedIntBlock_0.skp, _2.fdt, _2_Direct_0.pos, _2_MockFixedIntBlock_0.tii, _2.fdx, _2.fnm, _2_MockFixedIntBlock_0.tib, _2.nvm, _2_MockFixedIntBlock_0.pyl, _2_MockFixedIntBlock_0.pos, _2_NestedPulsing_0.pos, _2.nvd, _2_MockFixedIntBlock_0.frq, _2_MockFixedIntBlock_0.doc, segments_6, _2_NestedPulsing_0.tip, _2_NestedPulsing_0.doc]
[junit4:junit4]   2> 1294 T79 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 1296 T79 C4 oass.SolrIndexSearcher.<init> Opening Searcher@7a7227e6 main
[junit4:junit4]   2> 1297 T79 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 1297 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@7a7227e6 main{StandardDirectoryReader(segments_6:9:nrt _2(5.0):C10)}
[junit4:junit4]   2> 1297 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 1299 T83 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7a7227e6 main{StandardDirectoryReader(segments_6:9:nrt _2(5.0):C10)}
[junit4:junit4]   2> 1299 T83 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 1299 T79 C4 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 10
[junit4:junit4]   2> 1305 T78 C4 oasr.UnInvertedField.<init> UnInverted multi-valued field {field=features,memSize=4520,tindexSize=0,time=1,phase1=1,nTerms=4,bigTerms=2,termInstances=4,uses=0}
[junit4:junit4]   2> 1306 T78 C4 REQ [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=0&q=*:*&facet.field=features&wt=xml&version=2.2&rows=0} hits=10 status=0 QTime=2 
[junit4:junit4]   2> 1312 T80 C4 REQ [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=0&q=*:*&facet.field=features&wt=xml&fq=features:two&version=2.2&rows=0} hits=5 status=0 QTime=1 
[junit4:junit4]   2> 1317 T81 C4 REQ [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=4&q=*:*&facet.field=features&wt=xml&fq=features:two&version=2.2&rows=0} hits=5 status=0 QTime=1 
[junit4:junit4]   2> 1322 T79 C4 REQ [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=-1&q=*:*&facet.field=features&wt=xml&fq=features:two&version=2.2&rows=0} hits=5 status=0 QTime=1 
[junit4:junit4]   2> 1324 T72 oas.SolrTestCaseJ4.tearDown ###Ending testFaceting
[junit4:junit4]   2> 1332 T72 oas.SolrTestCaseJ4.setUp ###Starting testPivotFacetsMissing
[junit4:junit4]   2> 1335 T78 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 1336 T78 C4 oass.SolrIndexSearcher.<init> Opening Searcher@53307de3 realtime
[junit4:junit4]   2> 1337 T78 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1427897684532920320)} 0 2
[junit4:junit4]   2> 1341 T80 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 1341 T80 C4 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_6,generation=6,filenames=[_2_NestedPulsing_0.tim, _2_Direct_0.doc, _2_Direct_0.tip, _2.si, _2_Direct_0.tim, _2_MockFixedIntBlock_0.skp, _2.fdt, _2_Direct_0.pos, _2_MockFixedIntBlock_0.tii, _2.fdx, _2.fnm, _2_MockFixedIntBlock_0.tib, _2.nvm, _2_MockFixedIntBlock_0.pyl, _2_MockFixedIntBlock_0.pos, _2_NestedPulsing_0.pos, _2.nvd, _2_MockFixedIntBlock_0.frq, _2_MockFixedIntBlock_0.doc, segments_6, _2_NestedPulsing_0.tip, _2_NestedPulsing_0.doc]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_7,generation=7,filenames=[segments_7]
[junit4:junit4]   2> 1341 T80 C4 oasc.SolrDeletionPolicy.updateCommits newest commit = 7[segments_7]
[junit4:junit4]   2> 1342 T80 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 1342 T80 C4 oass.SolrIndexSearcher.<init> Opening Searcher@181eec3 main
[junit4:junit4]   2> 1343 T80 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 1343 T80 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 1343 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@181eec3 main{StandardDirectoryReader(segments_6:11:nrt)}
[junit4:junit4]   2> 1344 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 1345 T83 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@181eec3 main{StandardDirectoryReader(segments_6:11:nrt)}
[junit4:junit4]   2> 1396 T83 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 1396 T80 C4 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 56
[junit4:junit4]   2> 1400 T81 C4 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 1410 T79 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[1 (1427897684607369216), 2 (1427897684608417792), 3 (1427897684608417793), 4 (1427897684609466368), 5 (1427897684609466369), 6 (1427897684609466370), 7 (1427897684610514944), 8 (1427897684610514945), 9 (1427897684610514946), 10 (1427897684610514947), ... (12 adds)]} 0 4
[junit4:junit4]   2> 1415 T78 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 1419 T78 C4 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_7,generation=7,filenames=[segments_7]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_8,generation=8,filenames=[_3_Direct_0.doc, _3_MockFixedIntBlock_0.skp, _3_MockFixedIntBlock_0.frq, _3_MockFixedIntBlock_0.doc, _3.fdt, _3.fnm, _3_MockFixedIntBlock_0.pyl, _3.nvd, _3_Pulsing41_0.tim, _3.nvm, _3_Pulsing41_0.tip, _3_Direct_0.tip, _3_NestedPulsing_0.pos, _3_MockFixedIntBlock_0.tib, _3_NestedPulsing_0.tim, _3_MockFixedIntBlock_0.pos, _3.fdx, _3_NestedPulsing_0.doc, _3_Pulsing41_0.pos, segments_8, _3_NestedPulsing_0.tip, _3.si, _3_Direct_0.pos, _3_Pulsing41_0.doc, _3_Direct_0.tim, _3_MockFixedIntBlock_0.tii]
[junit4:junit4]   2> 1419 T78 C4 oasc.SolrDeletionPolicy.updateCommits newest commit = 8[_3_Direct_0.doc, _3_MockFixedIntBlock_0.skp, _3_MockFixedIntBlock_0.frq, _3_MockFixedIntBlock_0.doc, _3.fdt, _3.fnm, _3_MockFixedIntBlock_0.pyl, _3.nvd, _3_Pulsing41_0.tim, _3.nvm, _3_Pulsing41_0.tip, _3_Direct_0.tip, _3_NestedPulsing_0.pos, _3_MockFixedIntBlock_0.tib, _3_NestedPulsing_0.tim, _3_MockFixedIntBlock_0.pos, _3.fdx, _3_NestedPulsing_0.doc, _3_Pulsing41_0.pos, segments_8, _3_NestedPulsing_0.tip, _3.si, _3_Direct_0.pos, _3_Pulsing41_0.doc, _3_Direct_0.tim, _3_MockFixedIntBlock_0.tii]
[junit4:junit4]   2> 1420 T78 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 1423 T78 C4 oass.SolrIndexSearcher.<init> Opening Searcher@5b52e370 main
[junit4:junit4]   2> 1423 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@5b52e370 main{StandardDirectoryReader(segments_8:13:nrt _3(5.0):C12)}
[junit4:junit4]   2> 1423 T78 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 1424 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 1425 T83 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5b52e370 main{StandardDirectoryReader(segments_8:13:nrt _3(5.0):C12)}
[junit4:junit4]   2> 1425 T83 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 1426 T78 C4 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 11
[junit4:junit4]   2> 1430 T80 C4 oasr.UnInvertedField.<init> UnInverted multi-valued field {field=features,memSize=4478,tindexSize=0,time=0,phase1=0,nTerms=2,bigTerms=2,termInstances=0,uses=0}
[junit4:junit4]   2> 1431 T80 C4 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> 1434 T80 C4 REQ [collection1] webapp=/solr path=/select params={facet.missing=true&facet=true&facet.mincount=0&q=*:*&wt=xml&facet.pivot=features,cat&facet.pivot=cat,features&facet.pivot=features,cat,inStock&version=2.2&rows=0} hits=12 status=0 QTime=4 
[junit4:junit4]   2> 1443 T81 C4 REQ [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=0&q=*:*&wt=xml&fq={!tag%3Dmytag}-(features:bbb+AND+cat:a+AND+inStock:true)&facet.pivot={!ex%3Dmytag+key%3Dmykey}features,cat&version=2.2&rows=0} hits=11 status=0 QTime=1 
[junit4:junit4]   2> 1445 T72 oas.SolrTestCaseJ4.tearDown ###Ending testPivotFacetsMissing
[junit4:junit4]   2> 1453 T72 oas.SolrTestCaseJ4.setUp ###Starting testLukeHandler
[junit4:junit4]   2> 1457 T79 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 1458 T79 C4 oass.SolrIndexSearcher.<init> Opening Searcher@50c92f6b realtime
[junit4:junit4]   2> 1458 T79 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1427897684659798016)} 0 2
[junit4:junit4]   2> 1463 T80 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[ID0 (1427897684666089472)]} 0 1
[junit4:junit4]   2> 1467 T81 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[ID1 (1427897684671332352)]} 0 0
[junit4:junit4]   2> 1471 T78 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[ID2 (1427897684675526656)]} 0 0
[junit4:junit4]   2> 1475 T79 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[ID3 (1427897684679720960)]} 0 0
[junit4:junit4]   2> 1479 T80 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[ID4 (1427897684683915264)]} 0 0
[junit4:junit4]   2> 1484 T81 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 1486 T81 C4 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_8,generation=8,filenames=[_3_Direct_0.doc, _3_MockFixedIntBlock_0.skp, _3_MockFixedIntBlock_0.frq, _3_MockFixedIntBlock_0.doc, _3.fdt, _3.fnm, _3_MockFixedIntBlock_0.pyl, _3.nvd, _3_Pulsing41_0.tim, _3.nvm, _3_Pulsing41_0.tip, _3_Direct_0.tip, _3_NestedPulsing_0.pos, _3_MockFixedIntBlock_0.tib, _3_NestedPulsing_0.tim, _3_MockFixedIntBlock_0.pos, _3.fdx, _3_NestedPulsing_0.doc, _3_Pulsing41_0.pos, segments_8, _3_NestedPulsing_0.tip, _3.si, _3_Direct_0.pos, _3_Pulsing41_0.doc, _3_Direct_0.tim, _3_MockFixedIntBlock_0.tii]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_9,generation=9,filenames=[_4.fdx, segments_9, _4_MockFixedIntBlock_0.tib, _4_NestedPulsing_0.doc, _4_MockFixedIntBlock_0.doc, _4_MockFixedIntBlock_0.skp, _4_NestedPulsing_0.tip, _4_MockFixedIntBlock_0.tii, _4.fnm, _4_NestedPulsing_0.tim, _4.si, _4.fdt]
[junit4:junit4]   2> 1486 T81 C4 oasc.SolrDeletionPolicy.updateCommits newest commit = 9[_4.fdx, segments_9, _4_MockFixedIntBlock_0.tib, _4_NestedPulsing_0.doc, _4_MockFixedIntBlock_0.doc, _4_MockFixedIntBlock_0.skp, _4_NestedPulsing_0.tip, _4_MockFixedIntBlock_0.tii, _4.fnm, _4_NestedPulsing_0.tim, _4.si, _4.fdt]
[junit4:junit4]   2> 1487 T81 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 1488 T81 C4 oass.SolrIndexSearcher.<init> Opening Searcher@46e4a675 main
[junit4:junit4]   2> 1489 T81 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 1489 T81 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 1489 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@46e4a675 main{StandardDirectoryReader(segments_9:17:nrt _4(5.0):C5)}
[junit4:junit4]   2> 1490 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 1491 T83 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@46e4a675 main{StandardDirectoryReader(segments_9:17:nrt _4(5.0):C5)}
[junit4:junit4]   2> 1492 T83 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 1492 T81 C4 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 8
[junit4:junit4]   2> 1496 T78 C4 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=5 status=0 QTime=0 
[junit4:junit4]   2> 1501 T79 C4 REQ [collection1] webapp=/solr path=/admin/luke params={wt=xml&version=2.2} status=0 QTime=0 
[junit4:junit4]   2> 1508 T80 C4 REQ [collection1] webapp=/solr path=/admin/luke params={show=schema&wt=xml&version=2.2} status=0 QTime=1 
[junit4:junit4]   2> 1576 T72 oas.SolrTestCaseJ4.tearDown ###Ending testLukeHandler
[junit4:junit4]   2> 1584 T72 oas.SolrTestCaseJ4.setUp ###Starting testChineseDefaults
[junit4:junit4]   2> 1588 T78 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 1589 T78 C4 oass.SolrIndexSearcher.<init> Opening Searcher@596ba564 realtime
[junit4:junit4]   2> 1590 T78 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1427897684797161472)} 0 3
[junit4:junit4]   2> 1595 T79 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 1596 T79 C4 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_9,generation=9,filenames=[_4.fdx, segments_9, _4_MockFixedIntBlock_0.tib, _4_NestedPulsing_0.doc, _4_MockFixedIntBlock_0.doc, _4_MockFixedIntBlock_0.skp, _4_NestedPulsing_0.tip, _4_MockFixedIntBlock_0.tii, _4.fnm, _4_NestedPulsing_0.tim, _4.si, _4.fdt]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_a,generation=10,filenames=[segments_a]
[junit4:junit4]   2> 1596 T79 C4 oasc.SolrDeletionPolicy.updateCommits newest commit = 10[segments_a]
[junit4:junit4]   2> 1597 T79 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 1597 T79 C4 oass.SolrIndexSearcher.<init> Opening Searcher@78211410 main
[junit4:junit4]   2> 1598 T79 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 1598 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@78211410 main{StandardDirectoryReader(segments_9:19:nrt)}
[junit4:junit4]   2> 1598 T79 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 1598 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 1600 T83 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@78211410 main{StandardDirectoryReader(segments_9:19:nrt)}
[junit4:junit4]   2> 1601 T83 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 1601 T79 C4 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 6
[junit4:junit4]   2> 1606 T81 C4 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 1611 T80 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 1615 T80 C4 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_a,generation=10,filenames=[segments_a]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_b,generation=11,filenames=[_5_Direct_0.tim, _5_NestedPulsing_0.tip, _5.fnm, _5.fdt, _5_NestedPulsing_0.tim, _5_MockFixedIntBlock_0.frq, segments_b, _5_MockFixedIntBlock_0.tib, _5_MockFixedIntBlock_0.pos, _5_MockFixedIntBlock_0.pyl, _5_MockFixedIntBlock_0.doc, _5_NestedPulsing_0.doc, _5_Direct_0.doc, _5.si, _5.nvm, _5_MockFixedIntBlock_0.skp, _5.fdx, _5_Direct_0.pos, _5.nvd, _5_Direct_0.tip, _5_MockFixedIntBlock_0.tii, _5_NestedPulsing_0.pos]
[junit4:junit4]   2> 1615 T80 C4 oasc.SolrDeletionPolicy.updateCommits newest commit = 11[_5_Direct_0.tim, _5_NestedPulsing_0.tip, _5.fnm, _5.fdt, _5_NestedPulsing_0.tim, _5_MockFixedIntBlock_0.frq, segments_b, _5_MockFixedIntBlock_0.tib, _5_MockFixedIntBlock_0.pos, _5_MockFixedIntBlock_0.pyl, _5_MockFixedIntBlock_0.doc, _5_NestedPulsing_0.doc, _5_Direct_0.doc, _5.si, _5.nvm, _5_MockFixedIntBlock_0.skp, _5.fdx, _5_Direct_0.pos, _5.nvd, _5_Direct_0.tip, _5_MockFixedIntBlock_0.tii, _5_NestedPulsing_0.pos]
[junit4:junit4]   2> 1615 T80 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 1668 T80 C4 oass.SolrIndexSearcher.<init> Opening Searcher@62b3d4ff main
[junit4:junit4]   2> 1668 T80 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 1668 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@62b3d4ff main{StandardDirectoryReader(segments_b:21:nrt _5(5.0):C1)}
[junit4:junit4]   2> 1669 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 1670 T83 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@62b3d4ff main{StandardDirectoryReader(segments_b:21:nrt _5(5.0):C1)}
[junit4:junit4]   2> 1671 T83 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 1671 T80 C4 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {add=[42 (1427897684821278720)],commit=} 0 61
[junit4:junit4]   2> 1676 T78 C4 REQ [collection1] webapp=/solr path=/select params={q=北京大学&wt=xml&version=2.2} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 1679 T72 oas.SolrTestCaseJ4.tearDown ###Ending testChineseDefaults
[junit4:junit4]   2> 1689 T72 oas.SolrTestCaseJ4.setUp ###Starting testCommitWithinOnDelete
[junit4:junit4]   2> 1693 T79 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 1694 T79 C4 oass.SolrIndexSearcher.<init> Opening Searcher@41e3bda realtime
[junit4:junit4]   2> 1695 T79 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1427897684907261952)} 0 3
[junit4:junit4]   2> 1699 T81 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 1700 T81 C4 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_b,generation=11,filenames=[_5_Direct_0.tim, _5_NestedPulsing_0.tip, _5.fnm, _5.fdt, _5_NestedPulsing_0.tim, _5_MockFixedIntBlock_0.frq, segments_b, _5_MockFixedIntBlock_0.tib, _5_MockFixedIntBlock_0.pos, _5_MockFixedIntBlock_0.pyl, _5_MockFixedIntBlock_0.doc, _5_NestedPulsing_0.doc, _5_Direct_0.doc, _5.si, _5.nvm, _5_MockFixedIntBlock_0.skp, _5.fdx, _5_Direct_0.pos, _5.nvd, _5_Direct_0.tip, _5_MockFixedIntBlock_0.tii, _5_NestedPulsing_0.pos]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_c,generation=12,filenames=[segments_c]
[junit4:junit4]   2> 1700 T81 C4 oasc.SolrDeletionPolicy.updateCommits newest commit = 12[segments_c]
[junit4:junit4]   2> 1700 T81 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 1701 T81 C4 oass.SolrIndexSearcher.<init> Opening Searcher@4ec99007 main
[junit4:junit4]   2> 1701 T81 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 1702 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@4ec99007 main{StandardDirectoryReader(segments_b:23:nrt)}
[junit4:junit4]   2> 1702 T81 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 1702 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 1704 T83 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4ec99007 main{StandardDirectoryReader(segments_b:23:nrt)}
[junit4:junit4]   2> 1705 T83 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 1705 T81 C4 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 6
[junit4:junit4]   2> 1709 T80 C4 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 1715 T78 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[id3 (1427897684930330624)]} 0 2
[junit4:junit4]   2> 1719 T79 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 1724 T79 C4 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_c,generation=12,filenames=[segments_c]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_d,generation=13,filenames=[_6.nvd, _6_MockFixedIntBlock_0.doc, segments_d, _6_MockFixedIntBlock_0.pyl, _6_Pulsing41_0.tim, _6_Direct_0.pos, _6_MockFixedIntBlock_0.frq, _6.fdx, _6_Pulsing41_0.tip, _6_Direct_0.tip, _6.nvm, _6_Direct_0.tim, _6_MockFixedIntBlock_0.tib, _6_MockFixedIntBlock_0.tii, _6_Pulsing41_0.doc, _6_MockFixedIntBlock_0.skp, _6_MockFixedIntBlock_0.pos, _6_Pulsing41_0.pos, _6_NestedPulsing_0.tip, _6_NestedPulsing_0.doc, _6.si, _6.fnm, _6.fdt, _6_NestedPulsing_0.pos, _6_NestedPulsing_0.tim, _6_Direct_0.doc]
[junit4:junit4]   2> 1724 T79 C4 oasc.SolrDeletionPolicy.updateCommits newest commit = 13[_6.nvd, _6_MockFixedIntBlock_0.doc, segments_d, _6_MockFixedIntBlock_0.pyl, _6_Pulsing41_0.tim, _6_Direct_0.pos, _6_MockFixedIntBlock_0.frq, _6.fdx, _6_Pulsing41_0.tip, _6_Direct_0.tip, _6.nvm, _6_Direct_0.tim, _6_MockFixedIntBlock_0.tib, _6_MockFixedIntBlock_0.tii, _6_Pulsing41_0.doc, _6_MockFixedIntBlock_0.skp, _6_MockFixedIntBlock_0.pos, _6_Pulsing41_0.pos, _6_NestedPulsing_0.tip, _6_NestedPulsing_0.doc, _6.si, _6.fnm, _6.fdt, _6_NestedPulsing_0.pos, _6_NestedPulsing_0.tim, _6_Direct_0.doc]
[junit4:junit4]   2> 1725 T79 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 1727 T79 C4 oass.SolrIndexSearcher.<init> Opening Searcher@1148392c main
[junit4:junit4]   2> 1728 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1148392c main{StandardDirectoryReader(segments_d:25:nrt _6(5.0):C1)}
[junit4:junit4]   2> 1728 T79 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 1728 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 1730 T83 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1148392c main{StandardDirectoryReader(segments_d:25:nrt _6(5.0):C1)}
[junit4:junit4]   2> 1731 T83 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 1731 T79 C4 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 12
[junit4:junit4]   2> 1736 T81 C4 REQ [collection1] webapp=/solr path=/select params={q=id:id3&wt=xml&version=2.2} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 1741 T80 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {delete=[id3 (-1427897684958642176)]} 0 1
[junit4:junit4]   2> 1746 T78 C4 REQ [collection1] webapp=/solr path=/select params={q=id:id3&wt=xml&version=2.2} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 2001 T81 C4 REQ [collection1] webapp=/solr path=/select params={q=id:id3&wt=xml&version=2.2} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 2256 T78 C4 REQ [collection1] webapp=/solr path=/select params={q=id:id3&wt=xml&version=2.2} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 2510 T79 C4 REQ [collection1] webapp=/solr path=/select params={q=id:id3&wt=xml&version=2.2} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 2741 T85 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4:junit4]   2> 2742 T85 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 2743 T85 oass.SolrIndexSearcher.<init> Opening Searcher@24909ce5 main
[junit4:junit4]   2> 2743 T85 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 2743 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@24909ce5 main{StandardDirectoryReader(segments_d:27:nrt)}
[junit4:junit4]   2> 2744 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 2745 T83 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@24909ce5 main{StandardDirectoryReader(segments_d:27:nrt)}
[junit4:junit4]   2> 2746 T83 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 2765 T78 C4 REQ [collection1] webapp=/solr path=/select params={q=id:id3&wt=xml&version=2.2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 2767 T72 oas.SolrTestCaseJ4.tearDown ###Ending testCommitWithinOnDelete
[junit4:junit4]   2> 2777 T72 oas.SolrTestCaseJ4.setUp ###Starting testErrorHandling
[junit4:junit4]   2> 2780 T80 C4 oasc.SolrException.log Ignoring exception matching ignore_exception
[junit4:junit4]   2> 2781 T80 C4 REQ [collection1] webapp=/solr path=/analysis/field params={analysis.fieldtype=int&wt=xml&qt=/analysis/field&analysis.fieldvalue=ignore_exception&version=2.2} status=400 QTime=1 
[junit4:junit4]   2> 2787 T79 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {} 0 2
[junit4:junit4]   2> 2788 T79 C4 oasc.SolrException.log Ignoring exception matching ignore_exception
[junit4:junit4]   2> 2792 T81 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {} 0 0
[junit4:junit4]   2> 2793 T81 C4 oasc.SolrException.log SEVERE org.apache.solr.common.SolrException: Document contains multiple values for uniqueKey field: id=[DOCID, DOCID2]
[junit4:junit4]   2> 		at org.apache.solr.update.AddUpdateCommand.getIndexedId(AddUpdateCommand.java:91)
[junit4:junit4]   2> 		at org.apache.solr.update.processor.DistributedUpdateProcessor.versionAdd(DistributedUpdateProcessor.java:468)
[junit4:junit4]   2> 		at org.apache.solr.update.processor.DistributedUpdateProcessor.processAdd(DistributedUpdateProcessor.java:350)
[junit4:junit4]   2> 		at org.apache.solr.update.processor.LogUpdateProcessor.processAdd(LogUpdateProcessorFactory.java:100)
[junit4:junit4]   2> 		at org.apache.solr.handler.loader.XMLLoader.processUpdate(XMLLoader.java:246)
[junit4:junit4]   2> 		at org.apache.solr.handler.loader.XMLLoader.load(XMLLoader.java:173)
[junit4:junit4]   2> 		at org.apache.solr.handler.UpdateRequestHandler$1.load(UpdateRequestHandler.java:92)
[junit4:junit4]   2> 		at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:74)
[junit4:junit4]   2> 		at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:135)
[junit4:junit4]   2> 		at org.apache.solr.core.SolrCore.execute(SolrCore.java:1808)
[junit4:junit4]   2> 		at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:448)
[junit4:junit4]   2> 		at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:269)
[junit4:junit4]   2> 		at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1307)
[junit4:junit4]   2> 		at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:123)
[junit4:junit4]   2> 		at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1307)
[junit4:junit4]   2> 		at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:453)
[junit4:junit4]   2> 		at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229)
[junit4:junit4]   2> 		at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
[junit4:junit4]   2> 		at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:275)
[junit4:junit4]   2> 		at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1074)
[junit4:junit4]   2> 		at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:382)
[junit4:junit4]   2> 		at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
[junit4:junit4]   2> 		at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1006)
[junit4:junit4]   2> 		at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
[junit4:junit4]   2> 		at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
[junit4:junit4]   2> 		at org.eclipse.jetty.server.Server.handle(Server.java:365)
[junit4:junit4]   2> 		at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:485)
[junit4:junit4]   2> 		at org.eclipse.jetty.server.AbstractHttpConnection.content(AbstractHttpConnection.java:937)
[junit4:junit4]   2> 		at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.content(AbstractHttpConnection.java:998)
[junit4:junit4]   2> 		at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:948)
[junit4:junit4]   2> 		at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:240)
[junit4:junit4]   2> 		at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
[junit4:junit4]   2> 		at org.eclipse.jetty.io.nio.SslConnection.handle(SslConnection.java:196)
[junit4:junit4]   2> 		at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:628)
[junit4:junit4]   2> 		at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52)
[junit4:junit4]   2> 		at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
[junit4:junit4]   2> 		at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
[junit4:junit4]   2> 		at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]   2> 	
[junit4:junit4]   2> 2796 T72 oas.SolrTestCaseJ4.tearDown ###Ending testErrorHandling
[junit4:junit4]   2> 2805 T72 oas.SolrTestCaseJ4.setUp ###Starting testUpdateField
[junit4:junit4]   2> 2808 T78 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 2809 T78 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1427897686077472768)} 0 1
[junit4:junit4]   2> 2813 T80 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 2813 T80 C4 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_d,generation=13,filenames=[_6.nvd, _6_MockFixedIntBlock_0.doc, segments_d, _6_MockFixedIntBlock_0.pyl, _6_Pulsing41_0.tim, _6_Direct_0.pos, _6_MockFixedIntBlock_0.frq, _6.fdx, _6_Pulsing41_0.tip, _6_Direct_0.tip, _6.nvm, _6_Direct_0.tim, _6_MockFixedIntBlock_0.tib, _6_MockFixedIntBlock_0.tii, _6_Pulsing41_0.doc, _6_MockFixedIntBlock_0.skp, _6_MockFixedIntBlock_0.pos, _6_Pulsing41_0.pos, _6_NestedPulsing_0.tip, _6_NestedPulsing_0.doc, _6.si, _6.fnm, _6.fdt, _6_NestedPulsing_0.pos, _6_NestedPulsing_0.tim, _6_Direct_0.doc]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_e,generation=14,filenames=[segments_e]
[junit4:junit4]   2> 2814 T80 C4 oasc.SolrDeletionPolicy.updateCommits newest commit = 14[segments_e]
[junit4:junit4]   2> 2814 T80 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 2815 T80 C4 oass.SolrIndexSearcher.<init> Opening Searcher@4306806f main
[junit4:junit4]   2> 2815 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@4306806f main{StandardDirectoryReader(segments_d:27:nrt)}
[junit4:junit4]   2> 2815 T80 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 2816 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 2818 T83 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4306806f main{StandardDirectoryReader(segments_d:27:nrt)}
[junit4:junit4]   2> 2818 T83 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 2819 T80 C4 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 7
[junit4:junit4]   2> 2825 T79 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[unique (1427897686093201408)]} 0 2
[junit4:junit4]   2> 2829 T81 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 2835 T81 C4 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_e,generation=14,filenames=[segments_e]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_f,generation=15,filenames=[_7_Direct_0.doc, _7_MockFixedIntBlock_0.frq, _7.si, _7_NestedPulsing_0.doc, _7.fnm, _7_MockFixedIntBlock_0.tib, _7_NestedPulsing_0.pos, _7_Direct_0.tim, _7_MockFixedIntBlock_0.tii, _7_Pulsing41_0.doc, _7_Direct_0.tip, _7_Direct_0.pos, _7_MockFixedIntBlock_0.pos, _7_MockFixedIntBlock_0.skp, segments_f, _7_MockFixedIntBlock_0.pyl, _7.fdx, _7_Pulsing41_0.pos, _7_MockFixedIntBlock_0.doc, _7.fdt, _7_Pulsing41_0.tip, _7.nvd, _7_NestedPulsing_0.tim, _7_NestedPulsing_0.tip, _7_Pulsing41_0.tim, _7.nvm]
[junit4:junit4]   2> 2836 T81 C4 oasc.SolrDeletionPolicy.updateCommits newest commit = 15[_7_Direct_0.doc, _7_MockFixedIntBlock_0.frq, _7.si, _7_NestedPulsing_0.doc, _7.fnm, _7_MockFixedIntBlock_0.tib, _7_NestedPulsing_0.pos, _7_Direct_0.tim, _7_MockFixedIntBlock_0.tii, _7_Pulsing41_0.doc, _7_Direct_0.tip, _7_Direct_0.pos, _7_MockFixedIntBlock_0.pos, _7_MockFixedIntBlock_0.skp, segments_f, _7_MockFixedIntBlock_0.pyl, _7.fdx, _7_Pulsing41_0.pos, _7_MockFixedIntBlock_0.doc, _7.fdt, _7_Pulsing41_0.tip, _7.nvd, _7_NestedPulsing_0.tim, _7_NestedPulsing_0.tip, _7_Pulsing41_0.tim, _7.nvm]
[junit4:junit4]   2> 2836 T81 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 2839 T81 C4 oass.SolrIndexSearcher.<init> Opening Searcher@47e53534 main
[junit4:junit4]   2> 2840 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@47e53534 main{StandardDirectoryReader(segments_f:29:nrt _7(5.0):C1)}
[junit4:junit4]   2> 2840 T81 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 2841 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 2843 T83 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@47e53534 main{StandardDirectoryReader(segments_f:29:nrt _7(5.0):C1)}
[junit4:junit4]   2> 2843 T83 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 2844 T81 C4 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 15
[junit4:junit4]   2> 2848 T78 C4 REQ [collection1] webapp=/solr path=/select params={fl=id,price_f,name,_version_&q=*:*&wt=xml&version=2.2} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 2853 T80 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {} 0 0
[junit4:junit4]   2> 2859 T79 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[unique (1427897686129901568)]} 0 2
[junit4:junit4]   2> 2863 T81 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 2869 T81 C4 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_f,generation=15,filenames=[_7_Direct_0.doc, _7_MockFixedIntBlock_0.frq, _7.si, _7_NestedPulsing_0.doc, _7.fnm, _7_MockFixedIntBlock_0.tib, _7_NestedPulsing_0.pos, _7_Direct_0.tim, _7_MockFixedIntBlock_0.tii, _7_Pulsing41_0.doc, _7_Direct_0.tip, _7_Direct_0.pos, _7_MockFixedIntBlock_0.pos, _7_MockFixedIntBlock_0.skp, segments_f, _7_MockFixedIntBlock_0.pyl, _7.fdx, _7_Pulsing41_0.pos, _7_MockFixedIntBlock_0.doc, _7.fdt, _7_Pulsing41_0.tip, _7.nvd, _7_NestedPulsing_0.tim, _7_NestedPulsing_0.tip, _7_Pulsing41_0.tim, _7.nvm]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_g,generation=16,filenames=[_8_MockFixedIntBlock_0.tii, _8_MockFixedIntBlock_0.pyl, _8_Direct_0.doc, _8_MockFixedIntBlock_0.skp, _8_Pulsing41_0.pos, _8_NestedPulsing_0.doc, _8_MockFixedIntBlock_0.tib, _8.si, _8_Pulsing41_0.tim, _8_Pulsing41_0.tip, _8_MockFixedIntBlock_0.frq, _8_NestedPulsing_0.pos, _8_Direct_0.pos, _8.nvd, segments_g, _8.fnm, _8_Direct_0.tim, _8.fdx, _8_NestedPulsing_0.tip, _8_MockFixedIntBlock_0.pos, _8.nvm, _8_Direct_0.tip, _8_MockFixedIntBlock_0.doc, _8_Pulsing41_0.doc, _8_NestedPulsing_0.tim, _8.fdt]
[junit4:junit4]   2> 2870 T81 C4 oasc.SolrDeletionPolicy.updateCommits newest commit = 16[_8_MockFixedIntBlock_0.tii, _8_MockFixedIntBlock_0.pyl, _8_Direct_0.doc, _8_MockFixedIntBlock_0.skp, _8_Pulsing41_0.pos, _8_NestedPulsing_0.doc, _8_MockFixedIntBlock_0.tib, _8.si, _8_Pulsing41_0.tim, _8_Pulsing41_0.tip, _8_MockFixedIntBlock_0.frq, _8_NestedPulsing_0.pos, _8_Direct_0.pos, _8.nvd, segments_g, _8.fnm, _8_Direct_0.tim, _8.fdx, _8_NestedPulsing_0.tip, _8_MockFixedIntBlock_0.pos, _8.nvm, _8_Direct_0.tip, _8_MockFixedIntBlock_0.doc, _8_Pulsing41_0.doc, _8_NestedPulsing_0.tim, _8.fdt]
[junit4:junit4]   2> 2871 T81 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 2874 T81 C4 oass.SolrIndexSearcher.<init> Opening Searcher@bc09aba main
[junit4:junit4]   2> 2875 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@bc09aba main{StandardDirectoryReader(segments_g:33:nrt _8(5.0):C1)}
[junit4:junit4]   2> 2875 T81 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 2876 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 2878 T83 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@bc09aba main{StandardDirectoryReader(segments_g:33:nrt _8(5.0):C1)}
[junit4:junit4]   2> 2929 T83 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 2929 T81 C4 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 66
[junit4:junit4]   2> 2934 T80 C4 REQ [collection1] webapp=/solr path=/select params={fl=id,price_f,name,_version_&q=*:*&wt=xml&version=2.2} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 2940 T79 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[unique (1427897686214836224)]} 0 2
[junit4:junit4]   2> 2945 T81 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 2951 T81 C4 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_g,generation=16,filenames=[_8_MockFixedIntBlock_0.tii, _8_MockFixedIntBlock_0.pyl, _8_Direct_0.doc, _8_MockFixedIntBlock_0.skp, _8_Pulsing41_0.pos, _8_NestedPulsing_0.doc, _8_MockFixedIntBlock_0.tib, _8.si, _8_Pulsing41_0.tim, _8_Pulsing41_0.tip, _8_MockFixedIntBlock_0.frq, _8_NestedPulsing_0.pos, _8_Direct_0.pos, _8.nvd, segments_g, _8.fnm, _8_Direct_0.tim, _8.fdx, _8_NestedPulsing_0.tip, _8_MockFixedIntBlock_0.pos, _8.nvm, _8_Direct_0.tip, _8_MockFixedIntBlock_0.doc, _8_Pulsing41_0.doc, _8_NestedPulsing_0.tim, _8.fdt]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_h,generation=17,filenames=[_9_MockFixedIntBlock_0.skp, _9_Pulsing41_0.pos, _9.nvd, _9_Direct_0.pos, _9_MockFixedIntBlock_0.doc, _9_MockFixedIntBlock_0.frq, _9_NestedPulsing_0.tip, _9_NestedPulsing_0.tim, _9.si, _9.nvm, _9_MockFixedIntBlock_0.pyl, _9_NestedPulsing_0.pos, _9.fnm, _9_Pulsing41_0.tip, _9.fdt, _9_Pulsing41_0.tim, _9_NestedPulsing_0.doc, _9.fdx, segments_h, _9_MockFixedIntBlock_0.pos, _9_Pulsing41_0.doc, _9_MockFixedIntBlock_0.tii, _9_Direct_0.tim, _9_MockFixedIntBlock_0.tib, _9_Direct_0.doc, _9_Direct_0.tip]
[junit4:junit4]   2> 2952 T81 C4 oasc.SolrDeletionPolicy.updateCommits newest commit = 17[_9_MockFixedIntBlock_0.skp, _9_Pulsing41_0.pos, _9.nvd, _9_Direct_0.pos, _9_MockFixedIntBlock_0.doc, _9_MockFixedIntBlock_0.frq, _9_NestedPulsing_0.tip, _9_NestedPulsing_0.tim, _9.si, _9.nvm, _9_MockFixedIntBlock_0.pyl, _9_NestedPulsing_0.pos, _9.fnm, _9_Pulsing41_0.tip, _9.fdt, _9_Pulsing41_0.tim, _9_NestedPulsing_0.doc, _9.fdx, segments_h, _9_MockFixedIntBlock_0.pos, _9_Pulsing41_0.doc, _9_MockFixedIntBlock_0.tii, _9_Direct_0.tim, _9_MockFixedIntBlock_0.tib, _9_Direct_0.doc, _9_Direct_0.tip]
[junit4:junit4]   2> 2953 T81 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 2956 T81 C4 oass.SolrIndexSearcher.<init> Opening Searcher@32601ddc main
[junit4:junit4]   2> 2957 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@32601ddc main{StandardDirectoryReader(segments_h:37:nrt _9(5.0):C1)}
[junit4:junit4]   2> 2957 T81 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 2957 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 2959 T83 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@32601ddc main{StandardDirectoryReader(segments_h:37:nrt _9(5.0):C1)}
[junit4:junit4]   2> 2960 T83 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 2961 T81 C4 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 16
[junit4:junit4]   2> 2966 T78 C4 REQ [collection1] webapp=/solr path=/select params={fl=id,price_f,name,_version_&q=*:*&wt=xml&version=2.2} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 2968 T72 oas.SolrTestCaseJ4.tearDown ###Ending testUpdateField
[junit4:junit4]   2> 2977 T72 oas.SolrTestCaseJ4.setUp ###Starting testContentStreamRequest
[junit4:junit4]   2> 2981 T80 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 2983 T80 C4 oass.SolrIndexSearcher.<init> Opening Searcher@2a77e346 realtime
[junit4:junit4]   2> 2991 T80 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1427897686258876416)} 0 11
[junit4:junit4]   2> 2997 T79 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 2998 T79 C4 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_h,generation=17,filenames=[_9_MockFixedIntBlock_0.skp, _9_Pulsing41_0.pos, _9.nvd, _9_Direct_0.pos, _9_MockFixedIntBlock_0.doc, _9_MockFixedIntBlock_0.frq, _9_NestedPulsing_0.tip, _9_NestedPulsing_0.tim, _9.si, _9.nvm, _9_MockFixedIntBlock_0.pyl, _9_NestedPulsing_0.pos, _9.fnm, _9_Pulsing41_0.tip, _9.fdt, _9_Pulsing41_0.tim, _9_NestedPulsing_0.doc, _9.fdx, segments_h, _9_MockFixedIntBlock_0.pos, _9_Pulsing41_0.doc, _9_MockFixedIntBlock_0.tii, _9_Direct_0.tim, _9_MockFixedIntBlock_0.tib, _9_Direct_0.doc, _9_Direct_0.tip]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_i,generation=18,filenames=[segments_i]
[junit4:junit4]   2> 2998 T79 C4 oasc.SolrDeletionPolicy.updateCommits newest commit = 18[segments_i]
[junit4:junit4]   2> 2999 T79 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 2999 T79 C4 oass.SolrIndexSearcher.<init> Opening Searcher@735ea310 main
[junit4:junit4]   2> 3000 T79 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 3000 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@735ea310 main{StandardDirectoryReader(segments_h:39:nrt)}
[junit4:junit4]   2> 3000 T79 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3001 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 3003 T83 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@735ea310 main{StandardDirectoryReader(segments_h:39:nrt)}
[junit4:junit4]   2> 3004 T83 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 3004 T79 C4 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 7
[junit4:junit4]   2> 3008 T81 C4 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 3028 T78 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 3036 T78 C4 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_i,generation=18,filenames=[segments_i]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_j,generation=19,filenames=[_a_MockFixedIntBlock_0.tii, _a_MockFixedIntBlock_0.pos, _a_NestedPulsing_0.tim, _a_MockFixedIntBlock_0.frq, _a_Pulsing41_0.pos, _a_MockFixedIntBlock_0.pyl, _a.fdt, _a.si, _a_NestedPulsing_0.doc, _a_Pulsing41_0.doc, segments_j, _a.fdx, _a_Direct_0.tip, _a_Direct_0.doc, _a.nvm, _a_Direct_0.pos, _a_MockFixedIntBlock_0.skp, _a_Direct_0.tim, _a_NestedPulsing_0.pos, _a_NestedPulsing_0.tip, _a.nvd, _a_Pulsing41_0.tim, _a_MockFixedIntBlock_0.doc, _a_MockFixedIntBlock_0.tib, _a_Pulsing41_0.tip, _a.fnm]
[junit4:junit4]   2> 3036 T78 C4 oasc.SolrDeletionPolicy.updateCommits newest commit = 19[_a_MockFixedIntBlock_0.tii, _a_MockFixedIntBlock_0.pos, _a_NestedPulsing_0.tim, _a_MockFixedIntBlock_0.frq, _a_Pulsing41_0.pos, _a_MockFixedIntBlock_0.pyl, _a.fdt, _a.si, _a_NestedPulsing_0.doc, _a_Pulsing41_0.doc, segments_j, _a.fdx, _a_Direct_0.tip, _a_Direct_0.doc, _a.nvm, _a_Direct_0.pos, _a_MockFixedIntBlock_0.skp, _a_Direct_0.tim, _a_NestedPulsing_0.pos, _a_NestedPulsing_0.tip, _a.nvd, _a_Pulsing41_0.tim, _a_MockFixedIntBlock_0.doc, _a_MockFixedIntBlock_0.tib, _a_Pulsing41_0.tip, _a.fnm]
[junit4:junit4]   2> 3037 T78 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 3040 T78 C4 oass.SolrIndexSearcher.<init> Opening Searcher@2638dd58 main
[junit4:junit4]   2> 3041 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@2638dd58 main{StandardDirectoryReader(segments_j:41:nrt _a(5.0):C10)}
[junit4:junit4]   2> 3041 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 3041 T78 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3042 T83 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2638dd58 main{StandardDirectoryReader(segments_j:41:nrt _a(5.0):C10)}
[junit4:junit4]   2> 3043 T83 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 3043 T78 C4 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {add=[0553573403 (1427897686296625152), 0553579908 (1427897686298722304), 055357342X (1427897686299770880), 0553293354 (1427897686300819456), 0812521390 (1427897686301868032), 0812550706 (1427897686302916608), 0441385532 (1427897686303965184), 0380014300 (1427897686306062336), 0805080481 (1427897686307110912), 080508049X (1427897686307110913)],commit=} 0 27
[junit4:junit4]   2> 3047 T80 C4 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=10 status=0 QTime=0 
[junit4:junit4]   2> 3052 T72 oas.SolrTestCaseJ4.tearDown ###Ending testContentStreamRequest
[junit4:junit4]   2> 3060 T72 oas.SolrTestCaseJ4.setUp ###Starting testStatistics
[junit4:junit4]   2> 3064 T79 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 3066 T79 C4 oass.SolrIndexSearcher.<init> Opening Searcher@1cb9602 realtime
[junit4:junit4]   2> 3066 T79 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1427897686344859648)} 0 3
[junit4:junit4]   2> 3071 T81 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 3072 T81 C4 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_j,generation=19,filenames=[_a_MockFixedIntBlock_0.tii, _a_MockFixedIntBlock_0.pos, _a_NestedPulsing_0.tim, _a_MockFixedIntBlock_0.frq, _a_Pulsing41_0.pos, _a_MockFixedIntBlock_0.pyl, _a.fdt, _a.si, _a_NestedPulsing_0.doc, _a_Pulsing41_0.doc, segments_j, _a.fdx, _a_Direct_0.tip, _a_Direct_0.doc, _a.nvm, _a_Direct_0.pos, _a_MockFixedIntBlock_0.skp, _a_Direct_0.tim, _a_NestedPulsing_0.pos, _a_NestedPulsing_0.tip, _a.nvd, _a_Pulsing41_0.tim, _a_MockFixedIntBlock_0.doc, _a_MockFixedIntBlock_0.tib, _a_Pulsing41_0.tip, _a.fnm]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_k,generation=20,filenames=[segments_k]
[junit4:junit4]   2> 3072 T81 C4 oasc.SolrDeletionPolicy.updateCommits newest commit = 20[segments_k]
[junit4:junit4]   2> 3073 T81 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 3074 T81 C4 oass.SolrIndexSearcher.<init> Opening Searcher@5aab7e5a main
[junit4:junit4]   2> 3074 T81 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 3075 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@5aab7e5a main{StandardDirectoryReader(segments_j:43:nrt)}
[junit4:junit4]   2> 3075 T81 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3075 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 3077 T83 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5aab7e5a main{StandardDirectoryReader(segments_j:43:nrt)}
[junit4:junit4]   2> 3128 T83 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 3129 T81 C4 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 58
[junit4:junit4]   2> 3133 T78 C4 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 3139 T80 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc0 (1427897686423502848)]} 0 1
[junit4:junit4]   2> 3144 T79 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc1 (1427897686428745728)]} 0 1
[junit4:junit4]   2> 3149 T81 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc2 (1427897686433988608)]} 0 1
[junit4:junit4]   2> 3154 T78 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc3 (1427897686439231488)]} 0 1
[junit4:junit4]   2> 3159 T80 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc4 (1427897686444474368)]} 0 1
[junit4:junit4]   2> 3164 T79 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc5 (1427897686449717248)]} 0 1
[junit4:junit4]   2> 3169 T81 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc6 (1427897686454960128)]} 0 1
[junit4:junit4]   2> 3173 T78 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc7 (1427897686460203008)]} 0 0
[junit4:junit4]   2> 3178 T80 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc8 (1427897686464397312)]} 0 1
[junit4:junit4]   2> 3182 T79 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc9 (1427897686468591616)]} 0 1
[junit4:junit4]   2> 3186 T81 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 3191 T81 C4 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_k,generation=20,filenames=[segments_k]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_l,generation=21,filenames=[_b_Direct_0.tip, _b_Direct_0.tim, _b_Direct_0.pos, _b_MockFixedIntBlock_0.pos, _b_MockFixedIntBlock_0.tib, _b_MockFixedIntBlock_0.pyl, segments_l, _b_NestedPulsing_0.tim, _b_MockFixedIntBlock_0.frq, _b_MockFixedIntBlock_0.doc, _b_NestedPulsing_0.doc, _b.si, _b_NestedPulsing_0.tip, _b_NestedPulsing_0.pos, _b.nvm, _b.fnm, _b_MockFixedIntBlock_0.tii, _b.fdt, _b_Direct_0.doc, _b.fdx, _b.nvd, _b_MockFixedIntBlock_0.skp]
[junit4:junit4]   2> 3191 T81 C4 oasc.SolrDeletionPolicy.updateCommits newest commit = 21[_b_Direct_0.tip, _b_Direct_0.tim, _b_Direct_0.pos, _b_MockFixedIntBlock_0.pos, _b_MockFixedIntBlock_0.tib, _b_MockFixedIntBlock_0.pyl, segments_l, _b_NestedPulsing_0.tim, _b_MockFixedIntBlock_0.frq, _b_MockFixedIntBlock_0.doc, _b_NestedPulsing_0.doc, _b.si, _b_NestedPulsing_0.tip, _b_NestedPulsing_0.pos, _b.nvm, _b.fnm, _b_MockFixedIntBlock_0.tii, _b.fdt, _b_Direct_0.doc, _b.fdx, _b.nvd, _b_MockFixedIntBlock_0.skp]
[junit4:junit4]   2> 3192 T81 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 3194 T81 C4 oass.SolrIndexSearcher.<init> Opening Searcher@64125470 main
[junit4:junit4]   2> 3194 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@64125470 main{StandardDirectoryReader(segments_l:45:nrt _b(5.0):C10)}
[junit4:junit4]   2> 3194 T81 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3194 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 3196 T83 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@64125470 main{StandardDirectoryReader(segments_l:45:nrt _b(5.0):C10)}
[junit4:junit4]   2> 3197 T83 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 3197 T81 C4 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 11
[junit4:junit4]   2> 3201 T78 C4 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=10 status=0 QTime=0 
[junit4:junit4]   2> 3207 T80 C4 REQ [collection1] webapp=/solr path=/select params={stats.field=val_pi&stats=true&q=*:*&wt=xml&version=2.2&rows=0} hits=10 status=0 QTime=1 
[junit4:junit4]   2> 3212 T79 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 3213 T79 C4 oass.SolrIndexSearcher.<init> Opening Searcher@25055146 realtime
[junit4:junit4]   2> 3214 T79 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1427897686501097472)} 0 3
[junit4:junit4]   2> 3218 T81 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 3219 T81 C4 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_l,generation=21,filenames=[_b_Direct_0.tip, _b_Direct_0.tim, _b_Direct_0.pos, _b_MockFixedIntBlock_0.pos, _b_MockFixedIntBlock_0.tib, _b_MockFixedIntBlock_0.pyl, segments_l, _b_NestedPulsing_0.tim, _b_MockFixedIntBlock_0.frq, _b_MockFixedIntBlock_0.doc, _b_NestedPulsing_0.doc, _b.si, _b_NestedPulsing_0.tip, _b_NestedPulsing_0.pos, _b.nvm, _b.fnm, _b_MockFixedIntBlock_0.tii, _b.fdt, _b_Direct_0.doc, _b.fdx, _b.nvd, _b_MockFixedIntBlock_0.skp]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_m,generation=22,filenames=[segments_m]
[junit4:junit4]   2> 3219 T81 C4 oasc.SolrDeletionPolicy.updateCommits newest commit = 22[segments_m]
[junit4:junit4]   2> 3220 T81 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 3220 T81 C4 oass.SolrIndexSearcher.<init> Opening Searcher@36276359 main
[junit4:junit4]   2> 3220 T81 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 3221 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@36276359 main{StandardDirectoryReader(segments_l:47:nrt)}
[junit4:junit4]   2> 3221 T81 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3221 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 3224 T83 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@36276359 main{StandardDirectoryReader(segments_l:47:nrt)}
[junit4:junit4]   2> 3224 T83 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 3225 T81 C4 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 8
[junit4:junit4]   2> 3230 T78 C4 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 3236 T80 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc10 (1427897686525214720)]} 0 2
[junit4:junit4]   2> 3241 T79 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc11 (1427897686530457600)]} 0 1
[junit4:junit4]   2> 3245 T81 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc12 (1427897686535700480)]} 0 0
[junit4:junit4]   2> 3250 T78 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc13 (1427897686539894784)]} 0 1
[junit4:junit4]   2> 3255 T80 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc14 (1427897686545137664)]} 0 1
[junit4:junit4]   2> 3259 T79 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 3263 T79 C4 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_m,generation=22,filenames=[segments_m]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_n,generation=23,filenames=[_c_NestedPulsing_0.tip, _c_MockFixedIntBlock_0.frq, _c_Direct_0.doc, _c_NestedPulsing_0.doc, segments_n, _c_Direct_0.tip, _c_MockFixedIntBlock_0.pos, _c_MockFixedIntBlock_0.pyl, _c.fdx, _c_MockFixedIntBlock_0.doc, _c_MockFixedIntBlock_0.tii, _c.fdt, _c.si, _c_Direct_0.pos, _c_NestedPulsing_0.pos, _c_Direct_0.tim, _c.nvm, _c_MockFixedIntBlock_0.tib, _c_NestedPulsing_0.tim, _c.fnm, _c.nvd, _c_MockFixedIntBlock_0.skp]
[junit4:junit4]   2> 3263 T79 C4 oasc.SolrDeletionPolicy.updateCommits newest commit = 23[_c_NestedPulsing_0.tip, _c_MockFixedIntBlock_0.frq, _c_Direct_0.doc, _c_NestedPulsing_0.doc, segments_n, _c_Direct_0.tip, _c_MockFixedIntBlock_0.pos, _c_MockFixedIntBlock_0.pyl, _c.fdx, _c_MockFixedIntBlock_0.doc, _c_MockFixedIntBlock_0.tii, _c.fdt, _c.si, _c_Direct_0.pos, _c_NestedPulsing_0.pos, _c_Direct_0.tim, _c.nvm, _c_MockFixedIntBlock_0.tib, _c_NestedPulsing_0.tim, _c.fnm, _c.nvd, _c_MockFixedIntBlock_0.skp]
[junit4:junit4]   2> 3264 T79 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 3266 T79 C4 oass.SolrIndexSearcher.<init> Opening Searcher@4e3b9bed main
[junit4:junit4]   2> 3267 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@4e3b9bed main{StandardDirectoryReader(segments_n:49:nrt _c(5.0):C5)}
[junit4:junit4]   2> 3267 T79 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3268 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 3269 T83 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4e3b9bed main{StandardDirectoryReader(segments_n:49:nrt _c(5.0):C5)}
[junit4:junit4]   2> 3270 T83 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 3270 T79 C4 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 11
[junit4:junit4]   2> 3275 T81 C4 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=5 status=0 QTime=1 
[junit4:junit4]   2> 3280 T78 C4 REQ [collection1] webapp=/solr path=/select params={stats.field=val_pi&stats=true&q=*:*&wt=xml&version=2.2&rows=0} hits=5 status=0 QTime=0 
[junit4:junit4]   2> 3286 T80 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 3287 T80 C4 oass.SolrIndexSearcher.<init> Opening Searcher@35895b43 realtime
[junit4:junit4]   2> 3288 T80 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1427897686577643520)} 0 3
[junit4:junit4]   2> 3292 T79 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 3292 T79 C4 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_n,generation=23,filenames=[_c_NestedPulsing_0.tip, _c_MockFixedIntBlock_0.frq, _c_Direct_0.doc, _c_NestedPulsing_0.doc, segments_n, _c_Direct_0.tip, _c_MockFixedIntBlock_0.pos, _c_MockFixedIntBlock_0.pyl, _c.fdx, _c_MockFixedIntBlock_0.doc, _c_MockFixedIntBlock_0.tii, _c.fdt, _c.si, _c_Direct_0.pos, _c_NestedPulsing_0.pos, _c_Direct_0.tim, _c.nvm, _c_MockFixedIntBlock_0.tib, _c_NestedPulsing_0.tim, _c.fnm, _c.nvd, _c_MockFixedIntBlock_0.skp]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_o,generation=24,filenames=[segments_o]
[junit4:junit4]   2> 3293 T79 C4 oasc.SolrDeletionPolicy.updateCommits newest commit = 24[segments_o]
[junit4:junit4]   2> 3293 T79 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 3294 T79 C4 oass.SolrIndexSearcher.<init> Opening Searcher@5bd13aa main
[junit4:junit4]   2> 3294 T79 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 3294 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@5bd13aa main{StandardDirectoryReader(segments_n:51:nrt)}
[junit4:junit4]   2> 3295 T79 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3295 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 3297 T83 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5bd13aa main{StandardDirectoryReader(segments_n:51:nrt)}
[junit4:junit4]   2> 3297 T83 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 3298 T79 C4 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 7
[junit4:junit4]   2> 3302 T81 C4 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 3307 T78 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc0 (1427897686599663616)]} 0 1
[junit4:junit4]   2> 3312 T80 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc1 (1427897686605955072)]} 0 1
[junit4:junit4]   2> 3317 T79 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc2 (1427897686610149376)]} 0 1
[junit4:junit4]   2> 3321 T81 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc3 (1427897686615392256)]} 0 0
[junit4:junit4]   2> 3327 T78 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc4 (1427897686619586560)]} 0 2
[junit4:junit4]   2> 3332 T80 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc5 (1427897686625878016)]} 0 1
[junit4:junit4]   2> 3337 T81 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc6 (1427897686632169472)]} 0 1
[junit4:junit4]   2> 3342 T78 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc7 (1427897686637412352)]} 0 1
[junit4:junit4]   2> 3347 T80 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc8 (1427897686641606656)]} 0 1
[junit4:junit4]   2> 3352 T79 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc9 (1427897686646849536)]} 0 1
[junit4:junit4]   2> 3356 T81 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 3362 T81 C4 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_o,generation=24,filenames=[segments_o]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_p,generation=25,filenames=[_d_MockFixedIntBlock_0.skp, _d_MockFixedIntBlock_0.tib, _d_Direct_0.tip, _d_Direct_0.pos, _d.fnm, _d_MockFixedIntBlock_0.doc, _d_Direct_0.doc, _d_NestedPulsing_0.tim, _d_NestedPulsing_0.tip, _d.fdx, _d_Direct_0.tim, _d_NestedPulsing_0.doc, _d.fdt, _d_NestedPulsing_0.pos, _d.nvd, segments_p, _d_MockFixedIntBlock_0.pyl, _d.si, _d.nvm, _d_MockFixedIntBlock_0.frq, _d_MockFixedIntBlock_0.pos, _d_MockFixedIntBlock_0.tii]
[junit4:junit4]   2> 3362 T81 C4 oasc.SolrDeletionPolicy.updateCommits newest commit = 25[_d_MockFixedIntBlock_0.skp, _d_MockFixedIntBlock_0.tib, _d_Direct_0.tip, _d_Direct_0.pos, _d.fnm, _d_MockFixedIntBlock_0.doc, _d_Direct_0.doc, _d_NestedPulsing_0.tim, _d_NestedPulsing_0.tip, _d.fdx, _d_Direct_0.tim, _d_NestedPulsing_0.doc, _d.fdt, _d_NestedPulsing_0.pos, _d.nvd, segments_p, _d_MockFixedIntBlock_0.pyl, _d.si, _d.nvm, _d_MockFixedIntBlock_0.frq, _d_MockFixedIntBlock_0.pos, _d_MockFixedIntBlock_0.tii]
[junit4:junit4]   2> 3363 T81 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 3366 T81 C4 oass.SolrIndexSearcher.<init> Opening Searcher@396994d5 main
[junit4:junit4]   2> 3367 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@396994d5 main{StandardDirectoryReader(segments_p:53:nrt _d(5.0):C10)}
[junit4:junit4]   2> 3367 T81 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3367 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 3369 T83 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@396994d5 main{StandardDirectoryReader(segments_p:53:nrt _d(5.0):C10)}
[junit4:junit4]   2> 3370 T83 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 3370 T81 C4 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 14
[junit4:junit4]   2> 3374 T78 C4 REQ [collection1] webapp=/solr path=/select params={q=inStock:true&wt=xml&version=2.2} hits=5 status=0 QTime=0 
[junit4:junit4]   2> 3380 T80 C4 REQ [collection1] webapp=/solr path=/select params={q=inStock:false&wt=xml&version=2.2} hits=5 status=0 QTime=1 
[junit4:junit4]   2> 3386 T79 C4 REQ [collection1] webapp=/solr path=/select params={f.val_pi.stats.facet=inStock&stats.field=val_pi&stats=true&q=*:*&wt=xml&version=2.2&rows=0} hits=10 status=0 QTime=1 
[junit4:junit4]   2> 3388 T72 oas.SolrTestCaseJ4.tearDown ###Ending testStatistics
[junit4:junit4]   2> 3398 T72 oas.SolrTestCaseJ4.setUp ###Starting testAddDelete
[junit4:junit4]   2> 3402 T81 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 3403 T81 C4 oass.SolrIndexSearcher.<init> Opening Searcher@7e9a1d35 realtime
[junit4:junit4]   2> 3404 T81 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1427897686699278336)} 0 3
[junit4:junit4]   2> 3409 T78 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 & 222 (1427897686706618368)]} 0 1
[junit4:junit4]   2> 3413 T80 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 3416 T80 C4 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_p,generation=25,filenames=[_d_MockFixedIntBlock_0.skp, _d_MockFixedIntBlock_0.tib, _d_Direct_0.tip, _d_Direct_0.pos, _d.fnm, _d_MockFixedIntBlock_0.doc, _d_Direct_0.doc, _d_NestedPulsing_0.tim, _d_NestedPulsing_0.tip, _d.fdx, _d_Direct_0.tim, _d_NestedPulsing_0.doc, _d.fdt, _d_NestedPulsing_0.pos, _d.nvd, segments_p, _d_MockFixedIntBlock_0.pyl, _d.si, _d.nvm, _d_MockFixedIntBlock_0.frq, _d_MockFixedIntBlock_0.pos, _d_MockFixedIntBlock_0.tii]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_q,generation=26,filenames=[_e_MockFixedIntBlock_0.tii, _e_NestedPulsing_0.tip, _e.si, _e_MockFixedIntBlock_0.doc, segments_q, _e.fdt, _e_NestedPulsing_0.tim, _e_MockFixedIntBlock_0.tib, _e.fdx, _e_NestedPulsing_0.doc, _e_MockFixedIntBlock_0.skp, _e.fnm]
[junit4:junit4]   2> 3417 T80 C4 oasc.SolrDeletionPolicy.updateCommits newest commit = 26[_e_MockFixedIntBlock_0.tii, _e_NestedPulsing_0.tip, _e.si, _e_MockFixedIntBlock_0.doc, segments_q, _e.fdt, _e_NestedPulsing_0.tim, _e_MockFixedIntBlock_0.tib, _e.fdx, _e_NestedPulsing_0.doc, _e_MockFixedIntBlock_0.skp, _e.fnm]
[junit4:junit4]   2> 3418 T80 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 3420 T80 C4 oass.SolrIndexSearcher.<init> Opening Searcher@14cc2c8 main
[junit4:junit4]   2> 3421 T80 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 3421 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@14cc2c8 main{StandardDirectoryReader(segments_q:57:nrt _e(5.0):C1)}
[junit4:junit4]   2> 3422 T80 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3422 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 3423 T83 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@14cc2c8 main{StandardDirectoryReader(segments_q:57:nrt _e(5.0):C1)}
[junit4:junit4]   2> 3424 T83 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 3424 T80 C4 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 11
[junit4:junit4]   2> 3429 T79 C4 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 3434 T81 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {delete=[0 & 222 (-1427897686732832768)]} 0 1
[junit4:junit4]   2> 3438 T78 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 3440 T78 C4 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_q,generation=26,filenames=[_e_MockFixedIntBlock_0.tii, _e_NestedPulsing_0.tip, _e.si, _e_MockFixedIntBlock_0.doc, segments_q, _e.fdt, _e_NestedPulsing_0.tim, _e_MockFixedIntBlock_0.tib, _e.fdx, _e_NestedPulsing_0.doc, _e_MockFixedIntBlock_0.skp, _e.fnm]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_r,generation=27,filenames=[segments_r]
[junit4:junit4]   2> 3440 T78 C4 oasc.SolrDeletionPolicy.updateCommits newest commit = 27[segments_r]
[junit4:junit4]   2> 3440 T78 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 3441 T78 C4 oass.SolrIndexSearcher.<init> Opening Searcher@57b9f3bd main
[junit4:junit4]   2> 3442 T78 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3442 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@57b9f3bd main{StandardDirectoryReader(segments_r:59:nrt)}
[junit4:junit4]   2> 3442 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 3444 T83 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@57b9f3bd main{StandardDirectoryReader(segments_r:59:nrt)}
[junit4:junit4]   2> 3444 T83 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 3445 T78 C4 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 7
[junit4:junit4]   2> 3449 T80 C4 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 3454 T79 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 & 222 (1427897686753804288)]} 0 1
[junit4:junit4]   2> 3458 T81 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 3460 T81 C4 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_r,generation=27,filenames=[segments_r]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_s,generation=28,filenames=[_f_MockFixedIntBlock_0.doc, _f.fdx, _f_NestedPulsing_0.tip, _f_MockFixedIntBlock_0.tib, segments_s, _f_NestedPulsing_0.tim, _f_MockFixedIntBlock_0.tii, _f.fnm, _f_MockFixedIntBlock_0.skp, _f_NestedPulsing_0.doc, _f.si, _f.fdt]
[junit4:junit4]   2> 3461 T81 C4 oasc.SolrDeletionPolicy.updateCommits newest commit = 28[_f_MockFixedIntBlock_0.doc, _f.fdx, _f_NestedPulsing_0.tip, _f_MockFixedIntBlock_0.tib, segments_s, _f_NestedPulsing_0.tim, _f_MockFixedIntBlock_0.tii, _f.fnm, _f_MockFixedIntBlock_0.skp, _f_NestedPulsing_0.doc, _f.si, _f.fdt]
[junit4:junit4]   2> 3462 T81 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 3463 T81 C4 oass.SolrIndexSearcher.<init> Opening Searcher@26657102 main
[junit4:junit4]   2> 3463 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@26657102 main{StandardDirectoryReader(segments_s:61:nrt _f(5.0):C1)}
[junit4:junit4]   2> 3463 T81 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3464 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 3466 T83 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@26657102 main{StandardDirectoryReader(segments_s:61:nrt _f(5.0):C1)}
[junit4:junit4]   2> 3466 T83 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 3467 T81 C4 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 9
[junit4:junit4]   2> 3471 T80 C4 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 3476 T79 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 3477 T79 C4 oass.SolrIndexSearcher.<init> Opening Searcher@79f6e8a0 realtime
[junit4:junit4]   2> 3477 T79 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=id:"0\ \&\ 222" (-1427897686776872960)} 0 2
[junit4:junit4]   2> 3482 T78 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 3482 T78 C4 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_s,generation=28,filenames=[_f_MockFixedIntBlock_0.doc, _f.fdx, _f_NestedPulsing_0.tip, _f_MockFixedIntBlock_0.tib, segments_s, _f_NestedPulsing_0.tim, _f_MockFixedIntBlock_0.tii, _f.fnm, _f_MockFixedIntBlock_0.skp, _f_NestedPulsing_0.doc, _f.si, _f.fdt]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_t,generation=29,filenames=[segments_t]
[junit4:junit4]   2> 3483 T78 C4 oasc.SolrDeletionPolicy.updateCommits newest commit = 29[segments_t]
[junit4:junit4]   2> 3483 T78 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 3484 T78 C4 oass.SolrIndexSearcher.<init> Opening Searcher@67a0e65 main
[junit4:junit4]   2> 3484 T78 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 3484 T78 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3485 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@67a0e65 main{StandardDirectoryReader(segments_s:63:nrt)}
[junit4:junit4]   2> 3485 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 3487 T83 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@67a0e65 main{StandardDirectoryReader(segments_s:63:nrt)}
[junit4:junit4]   2> 3488 T83 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 3488 T78 C4 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 7
[junit4:junit4]   2> 3492 T81 C4 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 3497 T80 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 & 222 (1427897686798893056)]} 0 1
[junit4:junit4]   2> 3501 T79 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[1 & 222 (1427897686804135936)]} 0 0
[junit4:junit4]   2> 3507 T78 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[2 & 222 (1427897686809378816)]} 0 1
[junit4:junit4]   2> 3511 T81 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 3513 T81 C4 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_t,generation=29,filenames=[segments_t]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_u,generation=30,filenames=[_g.fdt, _g.si, segments_u, _g_NestedPulsing_0.doc, _g.fnm, _g.fdx, _g_MockFixedIntBlock_0.tib, _g_MockFixedIntBlock_0.doc, _g_MockFixedIntBlock_0.skp, _g_NestedPulsing_0.tim, _g_MockFixedIntBlock_0.tii, _g_NestedPulsing_0.tip]
[junit4:junit4]   2> 3514 T81 C4 oasc.SolrDeletionPolicy.updateCommits newest commit = 30[_g.fdt, _g.si, segments_u, _g_NestedPulsing_0.doc, _g.fnm, _g.fdx, _g_MockFixedIntBlock_0.tib, _g_MockFixedIntBlock_0.doc, _g_MockFixedIntBlock_0.skp, _g_NestedPulsing_0.tim, _g_MockFixedIntBlock_0.tii, _g_NestedPulsing_0.tip]
[junit4:junit4]   2> 3514 T81 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 3515 T81 C4 oass.SolrIndexSearcher.<init> Opening Searcher@50f06b52 main
[junit4:junit4]   2> 3516 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@50f06b52 main{StandardDirectoryReader(segments_u:65:nrt _g(5.0):C3)}
[junit4:junit4]   2> 3516 T81 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3517 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 3519 T83 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@50f06b52 main{StandardDirectoryReader(segments_u:65:nrt _g(5.0):C3)}
[junit4:junit4]   2> 3520 T83 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 3520 T81 C4 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 9
[junit4:junit4]   2> 3524 T80 C4 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=3 status=0 QTime=0 
[junit4:junit4]   2> 3530 T79 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {delete=[0 & 222 (-1427897686833496064), 1 & 222 (-1427897686833496065), 2 & 222 (-1427897686833496066)]} 0 1
[junit4:junit4]   2> 3535 T78 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 3537 T78 C4 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_u,generation=30,filenames=[_g.fdt, _g.si, segments_u, _g_NestedPulsing_0.doc, _g.fnm, _g.fdx, _g_MockFixedIntBlock_0.tib, _g_MockFixedIntBlock_0.doc, _g_MockFixedIntBlock_0.skp, _g_NestedPulsing_0.tim, _g_MockFixedIntBlock_0.tii, _g_NestedPulsing_0.tip]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_v,generation=31,filenames=[segments_v]
[junit4:junit4]   2> 3537 T78 C4 oasc.SolrDeletionPolicy.updateCommits newest commit = 31[segments_v]
[junit4:junit4]   2> 3538 T78 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 3539 T78 C4 oass.SolrIndexSearcher.<init> Opening Searcher@2692dfbe main
[junit4:junit4]   2> 3539 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@2692dfbe main{StandardDirectoryReader(segments_v:67:nrt)}
[junit4:junit4]   2> 3540 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 3540 T78 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3541 T83 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2692dfbe main{StandardDirectoryReader(segments_v:67:nrt)}
[junit4:junit4]   2> 3542 T83 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 3542 T78 C4 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 7
[junit4:junit4]   2> 3546 T81 C4 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 3548 T72 oas.SolrTestCaseJ4.tearDown ###Ending testAddDelete
[junit4:junit4]   2> 3555 T72 oas.SolrTestCaseJ4.setUp ###Starting testSetNullUpdates
[junit4:junit4]   2> 3559 T80 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[testSetNullUpdates (1427897686863904768)]} 0 1
[junit4:junit4]   2> 3563 T79 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 3566 T79 C4 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_v,generation=31,filenames=[segments_v]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_w,generation=32,filenames=[_h_MockFixedIntBlock_0.tib, _h.fnm, _h_MockFixedIntBlock_0.tii, _h_NestedPulsing_0.tip, _h_Pulsing41_0.tim, _h_Pulsing41_0.tip, _h_Pulsing41_0.doc, _h_MockFixedIntBlock_0.skp, _h_NestedPulsing_0.tim, _h.fdt, _h.si, _h.fdx, _h_NestedPulsing_0.doc, _h_MockFixedIntBlock_0.doc, segments_w]
[junit4:junit4]   2> 3566 T79 C4 oasc.SolrDeletionPolicy.updateCommits newest commit = 32[_h_MockFixedIntBlock_0.tib, _h.fnm, _h_MockFixedIntBlock_0.tii, _h_NestedPulsing_0.tip, _h_Pulsing41_0.tim, _h_Pulsing41_0.tip, _h_Pulsing41_0.doc, _h_MockFixedIntBlock_0.skp, _h_NestedPulsing_0.tim, _h.fdt, _h.si, _h.fdx, _h_NestedPulsing_0.doc, _h_MockFixedIntBlock_0.doc, segments_w]
[junit4:junit4]   2> 3567 T79 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 3568 T79 C4 oass.SolrIndexSearcher.<init> Opening Searcher@648fa93b main
[junit4:junit4]   2> 3569 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@648fa93b main{StandardDirectoryReader(segments_w:69:nrt _h(5.0):C1)}
[junit4:junit4]   2> 3569 T79 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3569 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 3571 T83 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@648fa93b main{StandardDirectoryReader(segments_w:69:nrt _h(5.0):C1)}
[junit4:junit4]   2> 3571 T83 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 3571 T79 C4 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 8
[junit4:junit4]   2> 3577 T78 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[testSetNullUpdates (1427897686882779136)]} 0 2
[junit4:junit4]   2> 3581 T81 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 3584 T81 C4 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_w,generation=32,filenames=[_h_MockFixedIntBlock_0.tib, _h.fnm, _h_MockFixedIntBlock_0.tii, _h_NestedPulsing_0.tip, _h_Pulsing41_0.tim, _h_Pulsing41_0.tip, _h_Pulsing41_0.doc, _h_MockFixedIntBlock_0.skp, _h_NestedPulsing_0.tim, _h.fdt, _h.si, _h.fdx, _h_NestedPulsing_0.doc, _h_MockFixedIntBlock_0.doc, segments_w]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_x,generation=33,filenames=[_i_MockFixedIntBlock_0.tib, _i_NestedPulsing_0.tim, _i_MockFixedIntBlock_0.doc, _i_NestedPulsing_0.doc, _i.fdx, _i_MockFixedIntBlock_0.tii, _i.fnm, _i.si, _i.fdt, _i_NestedPulsing_0.tip, segments_x, _i_MockFixedIntBlock_0.skp]
[junit4:junit4]   2> 3585 T81 C4 oasc.SolrDeletionPolicy.updateCommits newest commit = 33[_i_MockFixedIntBlock_0.tib, _i_NestedPulsing_0.tim, _i_MockFixedIntBlock_0.doc, _i_NestedPulsing_0.doc, _i.fdx, _i_MockFixedIntBlock_0.tii, _i.fnm, _i.si, _i.fdt, _i_NestedPulsing_0.tip, segments_x, _i_MockFixedIntBlock_0.skp]
[junit4:junit4]   2> 3585 T81 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 3586 T81 C4 oass.SolrIndexSearcher.<init> Opening Searcher@c86c441 main
[junit4:junit4]   2> 3587 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@c86c441 main{StandardDirectoryReader(segments_x:73:nrt _i(5.0):C1)}
[junit4:junit4]   2> 3587 T81 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3587 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 3589 T83 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c86c441 main{StandardDirectoryReader(segments_x:73:nrt _i(5.0):C1)}
[junit4:junit4]   2> 3590 T83 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 3591 T81 C4 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 10
[junit4:junit4]   2> 3595 T80 C4 REQ [collection1] webapp=/solr path=/select params={q=id:testSetNullUpdates&wt=xml&version=2.2} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 3597 T72 oas.SolrTestCaseJ4.tearDown ###Ending testSetNullUpdates
[junit4:junit4]   2> 3605 T72 oas.SolrTestCaseJ4.setUp ###Starting testRealtimeGet
[junit4:junit4]   2> 3608 T79 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 3609 T79 C4 oass.SolrIndexSearcher.<init> Opening Searcher@1a1e5304 realtime
[junit4:junit4]   2> 3610 T79 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1427897686916333568)} 0 2
[junit4:junit4]   2> 3615 T78 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[DOCID (1427897686922625024)]} 0 1
[junit4:junit4]   2> 3619 T81 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 3622 T81 C4 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_x,generation=33,filenames=[_i_MockFixedIntBlock_0.tib, _i_NestedPulsing_0.tim, _i_MockFixedIntBlock_0.doc, _i_NestedPulsing_0.doc, _i.fdx, _i_MockFixedIntBlock_0.tii, _i.fnm, _i.si, _i.fdt, _i_NestedPulsing_0.tip, segments_x, _i_MockFixedIntBlock_0.skp]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_y,generation=34,filenames=[_j_Direct_0.tip, _j.fdx, _j_NestedPulsing_0.tip, _j_MockFixedIntBlock_0.tii, _j_Direct_0.tim, _j.si, _j.fdt, _j_MockFixedIntBlock_0.skp, _j_MockFixedIntBlock_0.doc, _j_NestedPulsing_0.doc, _j.nvm, _j_NestedPulsing_0.pos, _j_Direct_0.pos, _j_MockFixedIntBlock_0.tib, _j_NestedPulsing_0.tim, _j_Direct_0.doc, _j.nvd, _j.fnm, _j_MockFixedIntBlock_0.pos, _j_MockFixedIntBlock_0.frq, _j_MockFixedIntBlock_0.pyl, segments_y]
[junit4:junit4]   2> 3623 T81 C4 oasc.SolrDeletionPolicy.updateCommits newest commit = 34[_j_Direct_0.tip, _j.fdx, _j_NestedPulsing_0.tip, _j_MockFixedIntBlock_0.tii, _j_Direct_0.tim, _j.si, _j.fdt, _j_MockFixedIntBlock_0.skp, _j_MockFixedIntBlock_0.doc, _j_NestedPulsing_0.doc, _j.nvm, _j_NestedPulsing_0.pos, _j_Direct_0.pos, _j_MockFixedIntBlock_0.tib, _j_NestedPulsing_0.tim, _j_Direct_0.doc, _j.nvd, _j.fnm, _j_MockFixedIntBlock_0.pos, _j_MockFixedIntBlock_0.frq, _j_MockFixedIntBlock_0.pyl, segments_y]
[junit4:junit4]   2> 3624 T81 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 3626 T81 C4 oass.SolrIndexSearcher.<init> Opening Searcher@4bb95ca4 main
[junit4:junit4]   2> 3626 T81 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 3627 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@4bb95ca4 main{StandardDirectoryReader(segments_y:77:nrt _j(5.0):C1)}
[junit4:junit4]   2> 3627 T81 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3627 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 3629 T83 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4bb95ca4 main{StandardDirectoryReader(segments_y:77:nrt _j(5.0):C1)}
[junit4:junit4]   2> 3630 T83 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 3630 T81 C4 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 11
[junit4:junit4]   2> 3635 T80 C4 REQ [collection1] webapp=/solr path=/get params={id=DOCID&fl=id,name,aaa:[value+v%3Daaa]&wt=javabin&qt=/get&version=2} status=0 QTime=1 
[junit4:junit4]   2> 3639 T79 C4 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> 3641 T72 oas.SolrTestCaseJ4.tearDown ###Ending testRealtimeGet
[junit4:junit4]   2> 3649 T72 oas.SolrTestCaseJ4.setUp ###Starting testPivotFacets
[junit4:junit4]   2> 3653 T78 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 3654 T78 C4 oass.SolrIndexSearcher.<init> Opening Searcher@2eba4fff realtime
[junit4:junit4]   2> 3655 T78 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1427897686963519488)} 0 3
[junit4:junit4]   2> 3659 T81 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 3660 T81 C4 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_y,generation=34,filenames=[_j_Direct_0.tip, _j.fdx, _j_NestedPulsing_0.tip, _j_MockFixedIntBlock_0.tii, _j_Direct_0.tim, _j.si, _j.fdt, _j_MockFixedIntBlock_0.skp, _j_MockFixedIntBlock_0.doc, _j_NestedPulsing_0.doc, _j.nvm, _j_NestedPulsing_0.pos, _j_Direct_0.pos, _j_MockFixedIntBlock_0.tib, _j_NestedPulsing_0.tim, _j_Direct_0.doc, _j.nvd, _j.fnm, _j_MockFixedIntBlock_0.pos, _j_MockFixedIntBlock_0.frq, _j_MockFixedIntBlock_0.pyl, segments_y]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_z,generation=35,filenames=[segments_z]
[junit4:junit4]   2> 3660 T81 C4 oasc.SolrDeletionPolicy.updateCommits newest commit = 35[segments_z]
[junit4:junit4]   2> 3661 T81 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 3661 T81 C4 oass.SolrIndexSearcher.<init> Opening Searcher@510a97fb main
[junit4:junit4]   2> 3662 T81 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 3663 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@510a97fb main{StandardDirectoryReader(segments_y:79:nrt)}
[junit4:junit4]   2> 3663 T81 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3663 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 3664 T83 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@510a97fb main{StandardDirectoryReader(segments_y:79:nrt)}
[junit4:junit4]   2> 3665 T83 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 3665 T81 C4 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 6
[junit4:junit4]   2> 3669 T80 C4 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 3678 T79 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[1 (1427897686984491008), 2 (1427897686985539584), 3 (1427897686986588160), 4 (1427897686986588161), 5 (1427897686986588162), 6 (1427897686987636736), 7 (1427897686987636737), 8 (1427897686987636738), 9 (1427897686988685312), 10 (1427897686988685313), ... (12 adds)]} 0 5
[junit4:junit4]   2> 3682 T78 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 3686 T78 C4 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_z,generation=35,filenames=[segments_z]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_10,generation=36,filenames=[_k_Direct_0.tip, _k_NestedPulsing_0.tip, _k.nvd, _k_MockFixedIntBlock_0.doc, segments_10, _k.fdt, _k_Pulsing41_0.tip, _k.fdx, _k_NestedPulsing_0.pos, _k.fnm, _k_Direct_0.tim, _k_Pulsing41_0.tim, _k_Pulsing41_0.doc, _k_NestedPulsing_0.tim, _k_NestedPulsing_0.doc, _k_MockFixedIntBlock_0.pos, _k_Pulsing41_0.pos, _k_MockFixedIntBlock_0.tib, _k_MockFixedIntBlock_0.skp, _k_MockFixedIntBlock_0.pyl, _k_Direct_0.pos, _k.nvm, _k_MockFixedIntBlock_0.tii, _k_Direct_0.doc, _k.si, _k_MockFixedIntBlock_0.frq]
[junit4:junit4]   2> 3686 T78 C4 oasc.SolrDeletionPolicy.updateCommits newest commit = 36[_k_Direct_0.tip, _k_NestedPulsing_0.tip, _k.nvd, _k_MockFixedIntBlock_0.doc, segments_10, _k.fdt, _k_Pulsing41_0.tip, _k.fdx, _k_NestedPulsing_0.pos, _k.fnm, _k_Direct_0.tim, _k_Pulsing41_0.tim, _k_Pulsing41_0.doc, _k_NestedPulsing_0.tim, _k_NestedPulsing_0.doc, _k_MockFixedIntBlock_0.pos, _k_Pulsing41_0.pos, _k_MockFixedIntBlock_0.tib, _k_MockFixedIntBlock_0.skp, _k_MockFixedIntBlock_0.pyl, _k_Direct_0.pos, _k.nvm, _k_MockFixedIntBlock_0.tii, _k_Direct_0.doc, _k.si, _k_MockFixedIntBlock_0.frq]
[junit4:junit4]   2> 3687 T78 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 3697 T78 C4 oass.SolrIndexSearcher.<init> Opening Searcher@67573d5f main
[junit4:junit4]   2> 3698 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@67573d5f main{StandardDirectoryReader(segments_10:81:nrt _k(5.0):C12)}
[junit4:junit4]   2> 3698 T78 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3698 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 3699 T83 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@67573d5f main{StandardDirectoryReader(segments_10:81:nrt _k(5.0):C12)}
[junit4:junit4]   2> 3700 T83 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 3700 T78 C4 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 19
[junit4:junit4]   2> 3705 T81 C4 oasr.UnInvertedField.<init> UnInverted multi-valued field {field=features,memSize=4478,tindexSize=0,time=0,phase1=0,nTerms=2,bigTerms=2,termInstances=0,uses=0}
[junit4:junit4]   2> 3706 T81 C4 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> 3707 T81 C4 REQ [collection1] webapp=/solr path=/select params={facet.missing=false&facet=true&facet.mincount=0&q=*:*&wt=xml&facet.pivot=features,cat&facet.pivot=cat,features&facet.pivot=features,cat,inStock&version=2.2&rows=0} hits=12 status=0 QTime=2 
[junit4:junit4]   2> 3714 T80 C4 REQ [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=0&q=*:*&wt=xml&fq={!tag%3Dmytag}-(features:bbb+AND+cat:a+AND+inStock:true)&facet.pivot={!ex%3Dmytag+key%3Dmykey}features,cat&version=2.2&rows=0} hits=11 status=0 QTime=2 
[junit4:junit4]   2> 3715 T72 oas.SolrTestCaseJ4.tearDown ###Ending testPivotFacets
[junit4:junit4]   2> 3724 T72 oas.SolrTestCaseJ4.setUp ###Starting testUpdateMultiValuedField
[junit4:junit4]   2> 3728 T79 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[123 (1427897687041114112)]} 0 1
[junit4:junit4]   2> 3733 T78 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 3736 T78 C4 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_10,generation=36,filenames=[_k_Direct_0.tip, _k_NestedPulsing_0.tip, _k.nvd, _k_MockFixedIntBlock_0.doc, segments_10, _k.fdt, _k_Pulsing41_0.tip, _k.fdx, _k_NestedPulsing_0.pos, _k.fnm, _k_Direct_0.tim, _k_Pulsing41_0.tim, _k_Pulsing41_0.doc, _k_NestedPulsing_0.tim, _k_NestedPulsing_0.doc, _k_MockFixedIntBlock_0.pos, _k_Pulsing41_0.pos, _k_MockFixedIntBlock_0.tib, _k_MockFixedIntBlock_0.skp, _k_MockFixedIntBlock_0.pyl, _k_Direct_0.pos, _k.nvm, _k_MockFixedIntBlock_0.tii, _k_Direct_0.doc, _k.si, _k_MockFixedIntBlock_0.frq]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_11,generation=37,filenames=[_k_Direct_0.tip, _k_MockFixedIntBlock_0.doc, _l.fnm, _l.si, _k.fdt, segments_11, _k_Pulsing41_0.tip, _k.fdx, _k.fnm, _l_NestedPulsing_0.tip, _k_Pulsing41_0.tim, _k_Direct_0.tim, _l_NestedPulsing_0.tim, _l_MockFixedIntBlock_0.doc, _k_NestedPulsing_0.doc, _l_MockFixedIntBlock_0.tii, _k_Pulsing41_0.pos, _k.nvm, _l_MockFixedIntBlock_0.tib, _l_MockFixedIntBlock_0.skp, _k.si, _k_MockFixedIntBlock_0.frq, _k.nvd, _k_NestedPulsing_0.tip, _l_NestedPulsing_0.doc, _k_NestedPulsing_0.pos, _k_Pulsing41_0.doc, _k_NestedPulsing_0.tim, _k_MockFixedIntBlock_0.pos, _k_MockFixedIntBlock_0.tib, _l.fdx, _k_MockFixedIntBlock_0.skp, _k_MockFixedIntBlock_0.pyl, _k_Direct_0.pos, _l.fdt, _k_MockFixedIntBlock_0.tii, _k_Direct_0.doc]
[junit4:junit4]   2> 3737 T78 C4 oasc.SolrDeletionPolicy.updateCommits newest commit = 37[_k_Direct_0.tip, _k_MockFixedIntBlock_0.doc, _l.fnm, _l.si, _k.fdt, segments_11, _k_Pulsing41_0.tip, _k.fdx, _k.fnm, _l_NestedPulsing_0.tip, _k_Pulsing41_0.tim, _k_Direct_0.tim, _l_NestedPulsing_0.tim, _l_MockFixedIntBlock_0.doc, _k_NestedPulsing_0.doc, _l_MockFixedIntBlock_0.tii, _k_Pulsing41_0.pos, _k.nvm, _l_MockFixedIntBlock_0.tib, _l_MockFixedIntBlock_0.skp, _k.si, _k_MockFixedIntBlock_0.frq, _k.nvd, _k_NestedPulsing_0.tip, _l_NestedPulsing_0.doc, _k_NestedPulsing_0.pos, _k_Pulsing41_0.doc, _k_NestedPulsing_0.tim, _k_MockFixedIntBlock_0.pos, _k_MockFixedIntBlock_0.tib, _l.fdx, _k_MockFixedIntBlock_0.skp, _k_MockFixedIntBlock_0.pyl, _k_Direct_0.pos, _l.fdt, _k_MockFixedIntBlock_0.tii, _k_Direct_0.doc]
[junit4:junit4]   2> 3737 T78 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 3738 T78 C4 oass.SolrIndexSearcher.<init> Opening Searcher@1fc94d46 main
[junit4:junit4]   2> 3739 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1fc94d46 main{StandardDirectoryReader(segments_11:83:nrt _k(5.0):C12 _l(5.0):C1)}
[junit4:junit4]   2> 3739 T78 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3739 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 3742 T83 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1fc94d46 main{StandardDirectoryReader(segments_11:83:nrt _k(5.0):C12 _l(5.0):C1)}
[junit4:junit4]   2> 3742 T83 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 3743 T78 C4 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 10
[junit4:junit4]   2> 3747 T80 C4 REQ [collection1] webapp=/solr path=/select params={q=id:123&wt=xml&version=2.2} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 3752 T79 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[123 (1427897687066279936)]} 0 1
[junit4:junit4]   2> 3756 T78 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 3760 T78 C4 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_11,generation=37,filenames=[_k_Direct_0.tip, _k_MockFixedIntBlock_0.doc, _l.fnm, _l.si, _k.fdt, segments_11, _k_Pulsing41_0.tip, _k.fdx, _k.fnm, _l_NestedPulsing_0.tip, _k_Pulsing41_0.tim, _k_Direct_0.tim, _l_NestedPulsing_0.tim, _l_MockFixedIntBlock_0.doc, _k_NestedPulsing_0.doc, _l_MockFixedIntBlock_0.tii, _k_Pulsing41_0.pos, _k.nvm, _l_MockFixedIntBlock_0.tib, _l_MockFixedIntBlock_0.skp, _k.si, _k_MockFixedIntBlock_0.frq, _k.nvd, _k_NestedPulsing_0.tip, _l_NestedPulsing_0.doc, _k_NestedPulsing_0.pos, _k_Pulsing41_0.doc, _k_NestedPulsing_0.tim, _k_MockFixedIntBlock_0.pos, _k_MockFixedIntBlock_0.tib, _l.fdx, _k_MockFixedIntBlock_0.skp, _k_MockFixedIntBlock_0.pyl, _k_Direct_0.pos, _l.fdt, _k_MockFixedIntBlock_0.tii, _k_Direct_0.doc]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_12,generation=38,filenames=[_k_Direct_0.tip, _k_MockFixedIntBlock_0.doc, _m_NestedPulsing_0.doc, _k.fdt, _m_MockFixedIntBlock_0.tii, _k_Pulsing41_0.tip, _k.fdx, _m_NestedPulsing_0.tim, _k.fnm, _k_Pulsing41_0.tim, _k_Direct_0.tim, _m_NestedPulsing_0.tip, _m_MockFixedIntBlock_0.tib, _k_NestedPulsing_0.doc, _m.si, _k_Pulsing41_0.pos, _k.nvm, _m_MockFixedIntBlock_0.skp, _m.fnm, _k.si, _m_Pulsing41_0.tip, _k_MockFixedIntBlock_0.frq, _m_MockFixedIntBlock_0.doc, _m_Pulsing41_0.doc, _k.nvd, _k_NestedPulsing_0.tip, _m_Pulsing41_0.tim, _k_NestedPulsing_0.pos, _k_Pulsing41_0.doc, _k_NestedPulsing_0.tim, _k_MockFixedIntBlock_0.pos, _k_MockFixedIntBlock_0.tib, _m.fdt, _k_MockFixedIntBlock_0.skp, _k_MockFixedIntBlock_0.pyl, _m.fdx, _k_Direct_0.pos, segments_12, _k_MockFixedIntBlock_0.tii, _k_Direct_0.doc]
[junit4:junit4]   2> 3760 T78 C4 oasc.SolrDeletionPolicy.updateCommits newest commit = 38[_k_Direct_0.tip, _k_MockFixedIntBlock_0.doc, _m_NestedPulsing_0.doc, _k.fdt, _m_MockFixedIntBlock_0.tii, _k_Pulsing41_0.tip, _k.fdx, _m_NestedPulsing_0.tim, _k.fnm, _k_Pulsing41_0.tim, _k_Direct_0.tim, _m_NestedPulsing_0.tip, _m_MockFixedIntBlock_0.tib, _k_NestedPulsing_0.doc, _m.si, _k_Pulsing41_0.pos, _k.nvm, _m_MockFixedIntBlock_0.skp, _m.fnm, _k.si, _m_Pulsing41_0.tip, _k_MockFixedIntBlock_0.frq, _m_MockFixedIntBlock_0.doc, _m_Pulsing41_0.doc, _k.nvd, _k_NestedPulsing_0.tip, _m_Pulsing41_0.tim, _k_NestedPulsing_0.pos, _k_Pulsing41_0.doc, _k_NestedPulsing_0.tim, _k_MockFixedIntBlock_0.pos, _k_MockFixedIntBlock_0.tib, _m.fdt, _k_MockFixedIntBlock_0.skp, _k_MockFixedIntBlock_0.pyl, _m.fdx, _k_Direct_0.pos, segments_12, _k_MockFixedIntBlock_0.tii, _k_Direct_0.doc]
[junit4:junit4]   2> 3761 T78 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 3763 T78 C4 oass.SolrIndexSearcher.<init> Opening Searcher@24372a82 main
[junit4:junit4]   2> 3763 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@24372a82 main{StandardDirectoryReader(segments_12:87:nrt _k(5.0):C12 _m(5.0):C1)}
[junit4:junit4]   2> 3763 T78 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3763 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 3765 T83 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@24372a82 main{StandardDirectoryReader(segments_12:87:nrt _k(5.0):C12 _m(5.0):C1)}
[junit4:junit4]   2> 3766 T83 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 3766 T78 C4 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 10
[junit4:junit4]   2> 3771 T81 C4 REQ [collection1] webapp=/solr path=/select params={q=id:123&wt=xml&version=2.2} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 3777 T80 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[123 (1427897687092494336)]} 0 1
[junit4:junit4]   2> 3782 T79 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 3786 T79 C4 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_12,generation=38,filenames=[_k_Direct_0.tip, _k_MockFixedIntBlock_0.doc, _m_NestedPulsing_0.doc, _k.fdt, _m_MockFixedIntBlock_0.tii, _k_Pulsing41_0.tip, _k.fdx, _m_NestedPulsing_0.tim, _k.fnm, _k_Pulsing41_0.tim, _k_Direct_0.tim, _m_NestedPulsing_0.tip, _m_MockFixedIntBlock_0.tib, _k_NestedPulsing_0.doc, _m.si, _k_Pulsing41_0.pos, _k.nvm, _m_MockFixedIntBlock_0.skp, _m.fnm, _k.si, _m_Pulsing41_0.tip, _k_MockFixedIntBlock_0.frq, _m_MockFixedIntBlock_0.doc, _m_Pulsing41_0.doc, _k.nvd, _k_NestedPulsing_0.tip, _m_Pulsing41_0.tim, _k_NestedPulsing_0.pos, _k_Pulsing41_0.doc, _k_NestedPulsing_0.tim, _k_MockFixedIntBlock_0.pos, _k_MockFixedIntBlock_0.tib, _m.fdt, _k_MockFixedIntBlock_0.skp, _k_MockFixedIntBlock_0.pyl, _m.fdx, _k_Direct_0.pos, segments_12, _k_MockFixedIntBlock_0.tii, _k_Direct_0.doc]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_13,generation=39,filenames=[_k_Direct_0.tip, _k_MockFixedIntBlock_0.doc, _n_Pulsing41_0.doc, _n_MockFixedIntBlock_0.skp, _k.fdt, _n_Pulsing41_0.tip, _k_Pulsing41_0.tip, _k.fdx, _k.fnm, _n_MockFixedIntBlock_0.doc, _k_Pulsing41_0.tim, _k_Direct_0.tim, _k_NestedPulsing_0.doc, _n_NestedPulsing_0.tip, _k_Pulsing41_0.pos, _n.si, _n_NestedPulsing_0.tim, _n_NestedPulsing_0.doc, _k.nvm, _k.si, _n.fnm, _k_MockFixedIntBlock_0.frq, _k.nvd, _k_NestedPulsing_0.tip, _n.fdt, _n_MockFixedIntBlock_0.tii, _n.fdx, _k_NestedPulsing_0.pos, _k_Pulsing41_0.doc, _n_MockFixedIntBlock_0.tib, _k_NestedPulsing_0.tim, _k_MockFixedIntBlock_0.pos, _k_MockFixedIntBlock_0.tib, _k_MockFixedIntBlock_0.skp, _k_MockFixedIntBlock_0.pyl, segments_13, _k_Direct_0.pos, _k_MockFixedIntBlock_0.tii, _k_Direct_0.doc, _n_Pulsing41_0.tim]
[junit4:junit4]   2> 3787 T79 C4 oasc.SolrDeletionPolicy.updateCommits newest commit = 39[_k_Direct_0.tip, _k_MockFixedIntBlock_0.doc, _n_Pulsing41_0.doc, _n_MockFixedIntBlock_0.skp, _k.fdt, _n_Pulsing41_0.tip, _k_Pulsing41_0.tip, _k.fdx, _k.fnm, _n_MockFixedIntBlock_0.doc, _k_Pulsing41_0.tim, _k_Direct_0.tim, _k_NestedPulsing_0.doc, _n_NestedPulsing_0.tip, _k_Pulsing41_0.pos, _n.si, _n_NestedPulsing_0.tim, _n_NestedPulsing_0.doc, _k.nvm, _k.si, _n.fnm, _k_MockFixedIntBlock_0.frq, _k.nvd, _k_NestedPulsing_0.tip, _n.fdt, _n_MockFixedIntBlock_0.tii, _n.fdx, _k_NestedPulsing_0.pos, _k_Pulsing41_0.doc, _n_MockFixedIntBlock_0.tib, _k_NestedPulsing_0.tim, _k_MockFixedIntBlock_0.pos, _k_MockFixedIntBlock_0.tib, _k_MockFixedIntBlock_0.skp, _k_MockFixedIntBlock_0.pyl, segments_13, _k_Direct_0.pos, _k_MockFixedIntBlock_0.tii, _k_Direct_0.doc, _n_Pulsing41_0.tim]
[junit4:junit4]   2> 3788 T79 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 3790 T79 C4 oass.SolrIndexSearcher.<init> Opening Searcher@4c53953f main
[junit4:junit4]   2> 3791 T79 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3792 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@4c53953f main{StandardDirectoryReader(segments_13:91:nrt _k(5.0):C12 _n(5.0):C1)}
[junit4:junit4]   2> 3792 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 3794 T83 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4c53953f main{StandardDirectoryReader(segments_13:91:nrt _k(5.0):C12 _n(5.0):C1)}
[junit4:junit4]   2> 3795 T83 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 3796 T79 C4 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 15
[junit4:junit4]   2> 3802 T78 C4 REQ [collection1] webapp=/solr path=/select params={q=id:123&wt=xml&version=2.2} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 3804 T72 oas.SolrTestCaseJ4.tearDown ###Ending testUpdateMultiValuedField
[junit4:junit4]   2> 3816 T72 oas.SolrTestCaseJ4.setUp ###Starting testExampleConfig
[junit4:junit4]   2> 3820 T81 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 3821 T81 C4 oass.SolrIndexSearcher.<init> Opening Searcher@3c406294 realtime
[junit4:junit4]   2> 3822 T81 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1427897687137583104)} 0 3
[junit4:junit4]   2> 3828 T80 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[1112211111 (1427897687145971712)]} 0 1
[junit4:junit4]   2> 3832 T79 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 3837 T79 C4 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_13,generation=39,filenames=[_k_Direct_0.tip, _k_MockFixedIntBlock_0.doc, _n_Pulsing41_0.doc, _n_MockFixedIntBlock_0.skp, _k.fdt, _n_Pulsing41_0.tip, _k_Pulsing41_0.tip, _k.fdx, _k.fnm, _n_MockFixedIntBlock_0.doc, _k_Pulsing41_0.tim, _k_Direct_0.tim, _k_NestedPulsing_0.doc, _n_NestedPulsing_0.tip, _k_Pulsing41_0.pos, _n.si, _n_NestedPulsing_0.tim, _n_NestedPulsing_0.doc, _k.nvm, _k.si, _n.fnm, _k_MockFixedIntBlock_0.frq, _k.nvd, _k_NestedPulsing_0.tip, _n.fdt, _n_MockFixedIntBlock_0.tii, _n.fdx, _k_NestedPulsing_0.pos, _k_Pulsing41_0.doc, _n_MockFixedIntBlock_0.tib, _k_NestedPulsing_0.tim, _k_MockFixedIntBlock_0.pos, _k_MockFixedIntBlock_0.tib, _k_MockFixedIntBlock_0.skp, _k_MockFixedIntBlock_0.pyl, segments_13, _k_Direct_0.pos, _k_MockFixedIntBlock_0.tii, _k_Direct_0.doc, _n_Pulsing41_0.tim]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_14,generation=40,filenames=[_o.fdt, _o_MockFixedIntBlock_0.tii, _o.fnm, _o.fdx, _o_NestedPulsing_0.doc, _o_MockFixedIntBlock_0.skp, _o_MockFixedIntBlock_0.pyl, _o.nvd, _o_Direct_0.doc, _o.nvm, _o_NestedPulsing_0.tim, _o_NestedPulsing_0.pos, _o_NestedPulsing_0.tip, _o_MockFixedIntBlock_0.pos, _o_MockFixedIntBlock_0.tib, _o_Direct_0.tip, _o.si, _o_MockFixedIntBlock_0.doc, segments_14, _o_Direct_0.tim, _o_MockFixedIntBlock_0.frq, _o_Direct_0.pos]
[junit4:junit4]   2> 3838 T79 C4 oasc.SolrDeletionPolicy.updateCommits newest commit = 40[_o.fdt, _o_MockFixedIntBlock_0.tii, _o.fnm, _o.fdx, _o_NestedPulsing_0.doc, _o_MockFixedIntBlock_0.skp, _o_MockFixedIntBlock_0.pyl, _o.nvd, _o_Direct_0.doc, _o.nvm, _o_NestedPulsing_0.tim, _o_NestedPulsing_0.pos, _o_NestedPulsing_0.tip, _o_MockFixedIntBlock_0.pos, _o_MockFixedIntBlock_0.tib, _o_Direct_0.tip, _o.si, _o_MockFixedIntBlock_0.doc, segments_14, _o_Direct_0.tim, _o_MockFixedIntBlock_0.frq, _o_Direct_0.pos]
[junit4:junit4]   2> 3841 T79 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 3844 T79 C4 oass.SolrIndexSearcher.<init> Opening Searcher@64d6833a main
[junit4:junit4]   2> 3845 T79 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 3845 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@64d6833a main{StandardDirectoryReader(segments_14:95:nrt _o(5.0):C1)}
[junit4:junit4]   2> 3846 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 3845 T79 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3847 T83 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@64d6833a main{StandardDirectoryReader(segments_14:95:nrt _o(5.0):C1)}
[junit4:junit4]   2> 3898 T83 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 3898 T79 C4 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 66
[junit4:junit4]   2> 3903 T78 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 3904 T78 C4 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_14,generation=40,filenames=[_o.fdt, _o_MockFixedIntBlock_0.tii, _o.fnm, _o.fdx, _o_NestedPulsing_0.doc, _o_MockFixedIntBlock_0.skp, _o_MockFixedIntBlock_0.pyl, _o.nvd, _o_Direct_0.doc, _o.nvm, _o_NestedPulsing_0.tim, _o_NestedPulsing_0.pos, _o_NestedPulsing_0.tip, _o_MockFixedIntBlock_0.pos, _o_MockFixedIntBlock_0.tib, _o_Direct_0.tip, _o.si, _o_MockFixedIntBlock_0.doc, segments_14, _o_Direct_0.tim, _o_MockFixedIntBlock_0.frq, _o_Direct_0.pos]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_15,generation=41,filenames=[_o.fdt, _o_MockFixedIntBlock_0.tii, _o.fnm, _o.fdx, _o_NestedPulsing_0.doc, _o_MockFixedIntBlock_0.skp, _o_MockFixedIntBlock_0.pyl, _o.nvd, _o_Direct_0.doc, _o.nvm, _o_NestedPulsing_0.tim, _o_NestedPulsing_0.pos, _o_NestedPulsing_0.tip, _o_MockFixedIntBlock_0.pos, _o_MockFixedIntBlock_0.tib, _o_Direct_0.tip, _o.si, _o_MockFixedIntBlock_0.doc, segments_15, _o_Direct_0.tim, _o_MockFixedIntBlock_0.frq, _o_Direct_0.pos]
[junit4:junit4]   2> 3904 T78 C4 oasc.SolrDeletionPolicy.updateCommits newest commit = 41[_o.fdt, _o_MockFixedIntBlock_0.tii, _o.fnm, _o.fdx, _o_NestedPulsing_0.doc, _o_MockFixedIntBlock_0.skp, _o_MockFixedIntBlock_0.pyl, _o.nvd, _o_Direct_0.doc, _o.nvm, _o_NestedPulsing_0.tim, _o_NestedPulsing_0.pos, _o_NestedPulsing_0.tip, _o_MockFixedIntBlock_0.pos, _o_MockFixedIntBlock_0.tib, _o_Direct_0.tip, _o.si, _o_MockFixedIntBlock_0.doc, segments_15, _o_Direct_0.tim, _o_MockFixedIntBlock_0.frq, _o_Direct_0.pos]
[junit4:junit4]   2> 3905 T78 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 3905 T78 C4 oass.SolrIndexSearcher.<init> Opening Searcher@339a13e5 main
[junit4:junit4]   2> 3906 T78 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3906 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@339a13e5 main{StandardDirectoryReader(segments_14:95:nrt _o(5.0):C1)}
[junit4:junit4]   2> 3907 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 3908 T83 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@339a13e5 main{StandardDirectoryReader(segments_14:95:nrt _o(5.0):C1)}
[junit4:junit4]   2> 3908 T83 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 3909 T78 C4 UPDATE [collection1] webapp=/solr path=/update params={optimize=true&waitSearcher=true&maxSegments=1&wt=xml&version=2.2} {optimize=} 0 6
[junit4:junit4]   2> 3913 T81 C4 REQ [collection1] webapp=/solr path=/select params={q=id:1112211111&wt=xml&version=2.2} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 3922 T80 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[2 (1427897687242440704), 3 (1427897687243489280), 4 (1427897687244537856), 5 (1427897687244537857)]} 0 3
[junit4:junit4]   2> 3926 T79 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 3931 T79 C4 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_15,generation=41,filenames=[_o.fdt, _o_MockFixedIntBlock_0.tii, _o.fnm, _o.fdx, _o_NestedPulsing_0.doc, _o_MockFixedIntBlock_0.skp, _o_MockFixedIntBlock_0.pyl, _o.nvd, _o_Direct_0.doc, _o.nvm, _o_NestedPulsing_0.tim, _o_NestedPulsing_0.pos, _o_NestedPulsing_0.tip, _o_MockFixedIntBlock_0.pos, _o_MockFixedIntBlock_0.tib, _o_Direct_0.tip, _o.si, _o_MockFixedIntBlock_0.doc, segments_15, _o_Direct_0.tim, _o_MockFixedIntBlock_0.frq, _o_Direct_0.pos]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_16,generation=42,filenames=[_o_MockFixedIntBlock_0.tii, _p.fdx, _p_Direct_0.doc, _p.fnm, _o_MockFixedIntBlock_0.skp, _p.fdt, _p_MockFixedIntBlock_0.tib, _p_Pulsing41_0.doc, _p_Pulsing41_0.pos, _p_NestedPulsing_0.doc, _o_Direct_0.doc, _o.nvd, _p_MockFixedIntBlock_0.doc, _p.nvm, _p_Direct_0.tim, _o_NestedPulsing_0.tim, _p_Direct_0.tip, _o_NestedPulsing_0.pos, _p_MockFixedIntBlock_0.frq, _o_NestedPulsing_0.tip, _p_MockFixedIntBlock_0.skp, _p_MockFixedIntBlock_0.pyl, _o_MockFixedIntBlock_0.tib, _o.si, _p_MockFixedIntBlock_0.tii, _p_MockFixedIntBlock_0.pos, _o_Direct_0.pos, _o.fdt, _o.fnm, _p.nvd, _o_NestedPulsing_0.doc, _o.fdx, _o_MockFixedIntBlock_0.pyl, _p.si, _p_Pulsing41_0.tip, _p_Pulsing41_0.tim, _p_NestedPulsing_0.pos, _o.nvm, _p_NestedPulsing_0.tim, _p_NestedPulsing_0.tip, _o_MockFixedIntBlock_0.pos, _o_Direct_0.tip, _o_MockFixedIntBlock_0.doc, _o_Direct_0.tim, segments_16, _p_Direct_0.pos, _o_MockFixedIntBlock_0.frq]
[junit4:junit4]   2> 3932 T79 C4 oasc.SolrDeletionPolicy.updateCommits newest commit = 42[_o_MockFixedIntBlock_0.tii, _p.fdx, _p_Direct_0.doc, _p.fnm, _o_MockFixedIntBlock_0.skp, _p.fdt, _p_MockFixedIntBlock_0.tib, _p_Pulsing41_0.doc, _p_Pulsing41_0.pos, _p_NestedPulsing_0.doc, _o_Direct_0.doc, _o.nvd, _p_MockFixedIntBlock_0.doc, _p.nvm, _p_Direct_0.tim, _o_NestedPulsing_0.tim, _p_Direct_0.tip, _o_NestedPulsing_0.pos, _p_MockFixedIntBlock_0.frq, _o_NestedPulsing_0.tip, _p_MockFixedIntBlock_0.skp, _p_MockFixedIntBlock_0.pyl, _o_MockFixedIntBlock_0.tib, _o.si, _p_MockFixedIntBlock_0.tii, _p_MockFixedIntBlock_0.pos, _o_Direct_0.pos, _o.fdt, _o.fnm, _p.nvd, _o_NestedPulsing_0.doc, _o.fdx, _o_MockFixedIntBlock_0.pyl, _p.si, _p_Pulsing41_0.tip, _p_Pulsing41_0.tim, _p_NestedPulsing_0.pos, _o.nvm, _p_NestedPulsing_0.tim, _p_NestedPulsing_0.tip, _o_MockFixedIntBlock_0.pos, _o_Direct_0.tip, _o_MockFixedIntBlock_0.doc, _o_Direct_0.tim, segments_16, _p_Direct_0.pos, _o_MockFixedIntBlock_0.frq]
[junit4:junit4]   2> 3932 T79 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 3934 T79 C4 oass.SolrIndexSearcher.<init> Opening Searcher@52a02597 main
[junit4:junit4]   2> 3935 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@52a02597 main{StandardDirectoryReader(segments_16:97:nrt _o(5.0):C1 _p(5.0):C4)}
[junit4:junit4]   2> 3935 T79 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3935 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 3937 T83 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@52a02597 main{StandardDirectoryReader(segments_16:97:nrt _o(5.0):C1 _p(5.0):C4)}
[junit4:junit4]   2> 3938 T83 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 3938 T79 C4 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 12
[junit4:junit4]   2> 3942 T78 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 3950 T78 C4 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_16,generation=42,filenames=[_o_MockFixedIntBlock_0.tii, _p.fdx, _p_Direct_0.doc, _p.fnm, _o_MockFixedIntBlock_0.skp, _p.fdt, _p_MockFixedIntBlock_0.tib, _p_Pulsing41_0.doc, _p_Pulsing41_0.pos, _p_NestedPulsing_0.doc, _o_Direct_0.doc, _o.nvd, _p_MockFixedIntBlock_0.doc, _p.nvm, _p_Direct_0.tim, _o_NestedPulsing_0.tim, _p_Direct_0.tip, _o_NestedPulsing_0.pos, _p_MockFixedIntBlock_0.frq, _o_NestedPulsing_0.tip, _p_MockFixedIntBlock_0.skp, _p_MockFixedIntBlock_0.pyl, _o_MockFixedIntBlock_0.tib, _o.si, _p_MockFixedIntBlock_0.tii, _p_MockFixedIntBlock_0.pos, _o_Direct_0.pos, _o.fdt, _o.fnm, _p.nvd, _o_NestedPulsing_0.doc, _o.fdx, _o_MockFixedIntBlock_0.pyl, _p.si, _p_Pulsing41_0.tip, _p_Pulsing41_0.tim, _p_NestedPulsing_0.pos, _o.nvm, _p_NestedPulsing_0.tim, _p_NestedPulsing_0.tip, _o_MockFixedIntBlock_0.pos, _o_Direct_0.tip, _o_MockFixedIntBlock_0.doc, _o_Direct_0.tim, segments_16, _p_Direct_0.pos, _o_MockFixedIntBlock_0.frq]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_17,generation=43,filenames=[_q_NestedPulsing_0.doc, _q_NestedPulsing_0.pos, _q_Direct_0.tip, _q_MockFixedIntBlock_0.tib, _q_Direct_0.tim, _q.fdx, _q_MockFixedIntBlock_0.skp, _q_Pulsing41_0.doc, _q.fdt, _q_MockFixedIntBlock_0.doc, _q.si, _q.nvm, _q_Direct_0.pos, _q.nvd, _q_MockFixedIntBlock_0.pos, _q_MockFixedIntBlock_0.tii, _q_Direct_0.doc, _q_Pulsing41_0.tim, _q_NestedPulsing_0.tip, _q_Pulsing41_0.tip, _q_MockFixedIntBlock_0.frq, _q_MockFixedIntBlock_0.pyl, _q_Pulsing41_0.pos, _q.fnm, segments_17, _q_NestedPulsing_0.tim]
[junit4:junit4]   2> 3950 T78 C4 oasc.SolrDeletionPolicy.updateCommits newest commit = 43[_q_NestedPulsing_0.doc, _q_NestedPulsing_0.pos, _q_Direct_0.tip, _q_MockFixedIntBlock_0.tib, _q_Direct_0.tim, _q.fdx, _q_MockFixedIntBlock_0.skp, _q_Pulsing41_0.doc, _q.fdt, _q_MockFixedIntBlock_0.doc, _q.si, _q.nvm, _q_Direct_0.pos, _q.nvd, _q_MockFixedIntBlock_0.pos, _q_MockFixedIntBlock_0.tii, _q_Direct_0.doc, _q_Pulsing41_0.tim, _q_NestedPulsing_0.tip, _q_Pulsing41_0.tip, _q_MockFixedIntBlock_0.frq, _q_MockFixedIntBlock_0.pyl, _q_Pulsing41_0.pos, _q.fnm, segments_17, _q_NestedPulsing_0.tim]
[junit4:junit4]   2> 3951 T78 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 3954 T78 C4 oass.SolrIndexSearcher.<init> Opening Searcher@253217dd main
[junit4:junit4]   2> 3954 T78 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3954 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@253217dd main{StandardDirectoryReader(segments_17:99:nrt _q(5.0):C5)}
[junit4:junit4]   2> 3955 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 3956 T83 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@253217dd main{StandardDirectoryReader(segments_17:99:nrt _q(5.0):C5)}
[junit4:junit4]   2> 3957 T83 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 3958 T78 C4 UPDATE [collection1] webapp=/solr path=/update params={optimize=true&waitSearcher=true&maxSegments=1&wt=xml&version=2.2} {optimize=} 0 16
[junit4:junit4]   2> 3963 T81 C4 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=xml&version=2.2} hits=5 status=0 QTime=1 
[junit4:junit4]   2> 3971 T80 C4 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=xml&fq=inStock:true&version=2.2} hits=2 status=0 QTime=1 
[junit4:junit4]   2> 3977 T79 C4 REQ [collection1] webapp=/solr path=/select params={facet=true&facet.query=price:[*+TO+2]&facet.query=price:[2+TO+4]&q=foo&wt=xml&fq={!field+f%3DinStock}true&fq={!term+f%3Dname}hoss&version=2.2} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 3978 T72 oas.SolrTestCaseJ4.tearDown ###Ending testExampleConfig
[junit4:junit4]   2> 3986 T72 oas.SolrTestCaseJ4.setUp ###Starting testUpdateRequestWithParameters
[junit4:junit4]   2> 3987 T72 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 4055 T78 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 4057 T78 C4 oass.SolrIndexSearcher.<init> Opening Searcher@18ac6680 realtime
[junit4:junit4]   2> 4057 T78 C4 UPDATE [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1427897687385047040)} 0 2
[junit4:junit4]   2> 4061 T81 C4 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 4062 T81 C4 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_17,generation=43,filenames=[_q_NestedPulsing_0.doc, _q_NestedPulsing_0.pos, _q_Direct_0.tip, _q_MockFixedIntBlock_0.tib, _q_Direct_0.tim, _q.fdx, _q_MockFixedIntBlock_0.skp, _q_Pulsing41_0.doc, _q.fdt, _q_MockFixedIntBlock_0.doc, _q.si, _q.nvm, _q_Direct_0.pos, _q.nvd, _q_MockFixedIntBlock_0.pos, _q_MockFixedIntBlock_0.tii, _q_Direct_0.doc, _q_Pulsing41_0.tim, _q_NestedPulsing_0.tip, _q_Pulsing41_0.tip, _q_MockFixedIntBlock_0.frq, _q_MockFixedIntBlock_0.pyl, _q_Pulsing41_0.pos, _q.fnm, segments_17, _q_NestedPulsing_0.tim]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_18,generation=44,filenames=[segments_18]
[junit4:junit4]   2> 4062 T81 C4 oasc.SolrDeletionPolicy.updateCommits newest commit = 44[segments_18]
[junit4:junit4]   2> 4063 T81 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 4063 T81 C4 oass.SolrIndexSearcher.<init> Opening Searcher@2601cad main
[junit4:junit4]   2> 4063 T81 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 4064 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@2601cad main{StandardDirectoryReader(segments_17:101:nrt)}
[junit4:junit4]   2> 4064 T81 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 4064 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 4066 T83 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2601cad main{StandardDirectoryReader(segments_17:101:nrt)}
[junit4:junit4]   2> 4066 T83 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 4066 T81 C4 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 5
[junit4:junit4]   2> 4071 T80 C4 UPDATE [collection1] webapp=/solr path=/update params={overwrite=false&wt=xml&version=2.2} {add=[id1 (1427897687400775680)]} 0 1
[junit4:junit4]   2> 4075 T79 C4 UPDATE [collection1] webapp=/solr path=/update params={overwrite=false&wt=xml&version=2.2} {add=[id1 (1427897687404969984)]} 0 1
[junit4:junit4]   2> 4078 T78 C4 oasu.DirectUpdate

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

lrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_1p,generation=61,filenames=[segments_1p]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5b598071 lockFactory=org.apache.lucene.store.NativeFSLockFactory@40d59822),segFN=segments_1q,generation=62,filenames=[segments_1q, _15_MockFixedIntBlock_0.doc, _15_NestedPulsing_0.tim, _15_MockFixedIntBlock_0.frq, _15_MockFixedIntBlock_0.tib, _15_NestedPulsing_0.pay, _15_Direct_0.pay, _15_Direct_0.doc, _15_NestedPulsing_0.pos, _15.fdt, _15_Pulsing41_0.pos, _15.fnm, _15.fdx, _15_Direct_0.tim, _15.nvd, _15_Direct_0.pos, _15_Direct_0.tip, _15.si, _15_NestedPulsing_0.doc, _15.nvm, _15_Pulsing41_0.pay, _15_Pulsing41_0.doc, _15_MockFixedIntBlock_0.pyl, _15_MockFixedIntBlock_0.pos, _15_MockFixedIntBlock_0.tii, _15_MockFixedIntBlock_0.skp, _15_Pulsing41_0.tim, _15_Pulsing41_0.tip, _15_NestedPulsing_0.tip]
[junit4:junit4]   2> 207018 T78 C4 oasc.SolrDeletionPolicy.updateCommits newest commit = 62[segments_1q, _15_MockFixedIntBlock_0.doc, _15_NestedPulsing_0.tim, _15_MockFixedIntBlock_0.frq, _15_MockFixedIntBlock_0.tib, _15_NestedPulsing_0.pay, _15_Direct_0.pay, _15_Direct_0.doc, _15_NestedPulsing_0.pos, _15.fdt, _15_Pulsing41_0.pos, _15.fnm, _15.fdx, _15_Direct_0.tim, _15.nvd, _15_Direct_0.pos, _15_Direct_0.tip, _15.si, _15_NestedPulsing_0.doc, _15.nvm, _15_Pulsing41_0.pay, _15_Pulsing41_0.doc, _15_MockFixedIntBlock_0.pyl, _15_MockFixedIntBlock_0.pos, _15_MockFixedIntBlock_0.tii, _15_MockFixedIntBlock_0.skp, _15_Pulsing41_0.tim, _15_Pulsing41_0.tip, _15_NestedPulsing_0.tip]
[junit4:junit4]   2> 207019 T78 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 207027 T78 C4 oass.SolrIndexSearcher.<init> Opening Searcher@8741946 main
[junit4:junit4]   2> 207028 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@8741946 main{StandardDirectoryReader(segments_1q:157:nrt _15(5.0):C5)}
[junit4:junit4]   2> 207028 T83 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4:junit4]   2> 207028 T78 C4 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 207029 T83 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@8741946 main{StandardDirectoryReader(segments_1q:157:nrt _15(5.0):C5)}
[junit4:junit4]   2> 207030 T83 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 207030 T78 C4 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&echoParams=ALL&commit=true&a=ሴ&wt=xml&version=2.2&softCommit=false} {add=[SP2514N (1427897900188303360), 6H500F0 (1427897900191449088), TWINX2048-3200PRO (1427897900192497664), VS1GB400C3 (1427897900193546240), VDBDB1A16 (1427897900194594816)],commit=} 0 30
[junit4:junit4]   2> 207034 T80 C4 REQ [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=5 status=0 QTime=0 
[junit4:junit4]   2> 207038 T72 oas.SolrTestCaseJ4.tearDown ###Ending testMultiContentStreamRequest
[junit4:junit4]   2> 207086 T72 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1208204611
[junit4:junit4]   2> 207086 T72 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@28f4f2d
[junit4:junit4]   2> 207091 T72 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 207092 T72 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 207098 T72 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 207099 T72 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 207105 T72 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=62,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=257,cumulative_deletesById=5,cumulative_deletesByQuery=31,cumulative_errors=1}
[junit4:junit4]   2> 207106 T72 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4:junit4]   2> 207106 T72 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 207106 T72 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4:junit4]   2> 207108 T72 C4 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 207108 T72 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 207109 T72 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 207109 T72 oasc.CachingDirectoryFactory.close Closing directory when closing factory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 207110 T72 oasc.CachingDirectoryFactory.closeDirectory Closing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861
[junit4:junit4]   2> 207110 T72 oasc.CachingDirectoryFactory.close Closing directory when closing factory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 207111 T72 oasc.CachingDirectoryFactory.closeDirectory Closing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleXMLTest-1361749346861/index
[junit4:junit4]   2> 207111 T72 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 207163 T72 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> NOTE: test params are: codec=Lucene42: {store_0_coordinate=PostingsFormat(name=Direct), cat=Pulsing41(freqCutoff=10 minBlockSize=79 maxBlockSize=224), manufacturedate_dt=PostingsFormat(name=NestedPulsing), id=MockFixedIntBlock(blockSize=907), sequence_i=MockFixedIntBlock(blockSize=907), name=MockFixedIntBlock(blockSize=907), features=PostingsFormat(name=Direct), unicode_s=PostingsFormat(name=Direct), manu_exact=MockFixedIntBlock(blockSize=907), payloads=PostingsFormat(name=Direct), manu_id_s=MockFixedIntBlock(blockSize=907), price_c____currency=Pulsing41(freqCutoff=10 minBlockSize=79 maxBlockSize=224), popularity=PostingsFormat(name=Direct), genre_s=MockFixedIntBlock(blockSize=907), author_t=PostingsFormat(name=NestedPulsing), text=PostingsFormat(name=Direct), _version_=PostingsFormat(name=NestedPulsing), store_1_coordinate=PostingsFormat(name=NestedPulsing), val_pi=MockFixedIntBlock(blockSize=907), series_t=PostingsFormat(name=NestedPulsing), timestamp_dt=PostingsFormat(name=Direct), price=PostingsFormat(name=Direct), manu=MockFixedIntBlock(blockSize=907), price_f=Pulsing41(freqCutoff=10 minBlockSize=79 maxBlockSize=224), multi_ss=Pulsing41(freqCutoff=10 minBlockSize=79 maxBlockSize=224), single_s=PostingsFormat(name=NestedPulsing), inStock=PostingsFormat(name=Direct), price_c____amount_raw=Pulsing41(freqCutoff=10 minBlockSize=79 maxBlockSize=224)}, docValues:{}, sim=DefaultSimilarity, locale=ga_IE, timezone=Mexico/BajaNorte
[junit4:junit4]   2> NOTE: Linux 3.2.0-38-generic amd64/Oracle Corporation 1.7.0_15 (64-bit)/cpus=8,threads=1,free=68442952,total=126484480
[junit4:junit4]   2> NOTE: All tests run in this JVM: [SolrExceptionTest, TestNamedListCodec, BasicHttpSolrServerTest, ModifiableSolrParamsTest, SolrExampleJettyTest, IteratorChainTest, TestBatchUpdate, LargeVolumeEmbeddedTest, SolrExampleXMLTest]
[junit4:junit4] Completed on J1 in 207.21s, 25 tests, 1 error <<< FAILURES!

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

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



Mime
View raw message