lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "Uwe Schindler" <...@thetaphi.de>
Subject RE: [JENKINS] Lucene-Solr-4.x-Windows (32bit/jdk1.7.0_07) - Build # 879 - Failure!
Date Sat, 22 Sep 2012 20:11:46 GMT
I have never seen that one before! Anybody have an explanation?

-----
Uwe Schindler
H.-H.-Meier-Allee 63, D-28213 Bremen
http://www.thetaphi.de
eMail: uwe@thetaphi.de


> -----Original Message-----
> From: Policeman Jenkins Server [mailto:jenkins@sd-datasolutions.de]
> Sent: Saturday, September 22, 2012 8:40 PM
> To: dev@lucene.apache.org; yonik@apache.org; mikemccand@apache.org
> Subject: [JENKINS] Lucene-Solr-4.x-Windows (32bit/jdk1.7.0_07) - Build # 879 -
> Failure!
> 
> Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-4.x-Windows/879/
> Java: 32bit/jdk1.7.0_07 -server -XX:+UseConcMarkSweepGC
> 
> 1 tests failed.
> REGRESSION:  org.apache.solr.search.TestRecovery.testVersionsOnRestart
> 
> Error Message:
> 
> 
> Stack Trace:
> java.lang.AssertionError
> 	at
> __randomizedtesting.SeedInfo.seed([810F1DF62273E988:E1A13D885D2D3907]:
> 0)
> 	at
> org.apache.solr.search.TestRecovery.testVersionsOnRestart(TestRecovery.java:
> 611)
> 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> 	at
> sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:
> 57)
> 	at
> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorI
> mpl.java:43)
> 	at java.lang.reflect.Method.invoke(Method.java:601)
> 	at
> com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRu
> nner.java:1559)
> 	at
> com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(Randomiz
> edRunner.java:79)
> 	at
> com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Randomiz
> edRunner.java:737)
> 	at
> com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Randomiz
> edRunner.java:773)
> 	at
> com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Randomiz
> edRunner.java:787)
> 	at
> com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.eval
> uate(SystemPropertiesRestoreRule.java:53)
> 	at
> org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSet
> upTeardownChained.java:50)
> 	at
> org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCach
> eSanity.java:51)
> 	at
> org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfte
> rRule.java:45)
> 	at
> com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.ev
> aluate(SystemPropertiesInvariantRule.java:55)
> 	at
> org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThrea
> dAndTestName.java:48)
> 	at
> org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgn
> oreAfterMaxFailures.java:70)
> 	at
> org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.ja
> va:48)
> 	at
> com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(Stateme
> ntAdapter.java:36)
> 	at
> com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run
> (ThreadLeakControl.java:358)
> 	at
> com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(T
> hreadLeakControl.java:782)
> 	at
> com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLea
> kControl.java:442)
> 	at
> com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(Rando
> mizedRunner.java:746)
> 	at
> com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(Randomiz
> edRunner.java:648)
> 	at
> com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(Randomiz
> edRunner.java:682)
> 	at
> com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(Randomiz
> edRunner.java:693)
> 	at
> com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(Stateme
> ntAdapter.java:36)
> 	at
> com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.eval
> uate(SystemPropertiesRestoreRule.java:53)
> 	at
> org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfte
> rRule.java:45)
> 	at
> org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClass
> Name.java:42)
> 	at
> com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.ev
> aluate(SystemPropertiesInvariantRule.java:55)
> 	at
> com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethod
> sRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
> 	at
> com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethod
> sRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
> 	at
> com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(Stateme
> ntAdapter.java:36)
> 	at
> org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertio
> nsRequired.java:43)
> 	at
> org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.ja
> va:48)
> 	at
> org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgn
> oreAfterMaxFailures.java:70)
> 	at
> org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTest
> Suites.java:55)
> 	at
> com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(Stateme
> ntAdapter.java:36)
> 	at
> com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run
> (ThreadLeakControl.java:358)
> 	at java.lang.Thread.run(Thread.java:722)
> 
> 
> 
> 
> Build Log:
> [...truncated 7674 lines...]
> [junit4:junit4] Suite: org.apache.solr.search.TestRecovery
> [junit4:junit4]   2> 2 T167 oas.SolrTestCaseJ4.initCore ####initCore
> [junit4:junit4]   2> Creating dataDir: C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1348338241894
> [junit4:junit4]   2> 3 T167 oasc.SolrResourceLoader.<init> new
> SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test-files\solr\collection1\'
> [junit4:junit4]   2> 6 T167 oasc.SolrResourceLoader.replaceClassLoader Adding
> 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-
> files/solr/collection1/lib/classes/' to classloader
> [junit4:junit4]   2> 7 T167 oasc.SolrResourceLoader.replaceClassLoader Adding
> 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-
> files/solr/collection1/lib/README' to classloader
> [junit4:junit4]   2> 76 T167 oasc.SolrConfig.<init> Using Lucene MatchVersion:
> LUCENE_41
> [junit4:junit4]   2> 138 T167 oasc.SolrConfig.<init> Loaded SolrConfig:
> solrconfig-tlog.xml
> [junit4:junit4]   2> 138 T167 oass.IndexSchema.readSchema Reading Solr
> Schema
> [junit4:junit4]   2> 147 T167 oass.IndexSchema.readSchema Schema name=test
> [junit4:junit4]   2> 795 T167 oass.IndexSchema.readSchema default search field
> in schema is text
> [junit4:junit4]   2> 798 T167 oass.IndexSchema.readSchema unique key field: id
> [junit4:junit4]   2> 816 T167 oasc.SolrResourceLoader.locateSolrHome JNDI not
> configured for solr (NoInitialContextEx)
> [junit4:junit4]   2> 816 T167 oasc.SolrResourceLoader.locateSolrHome using
> system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test-files\solr
> [junit4:junit4]   2> 816 T167 oasc.SolrResourceLoader.<init> new
> SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test-files\solr\'
> [junit4:junit4]   2> 829 T167 oasc.CoreContainer.<init> New CoreContainer
> 28045267
> [junit4:junit4]   2> 831 T167 oasc.SolrCore.<init> [collection1] Opening new
> SolrCore at C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-
> 4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-
> 1348338241894\
> [junit4:junit4]   2> 832 T167 oasc.SolrCore.<init> JMX monitoring not detected
> for core: collection1
> [junit4:junit4]   2> 834 T167 oasc.SolrCore.getNewIndexDir WARNING New
> index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-
> 1348338241894\index/
> [junit4:junit4]   2> 837 T167 oasc.SolrCore.initIndex WARNING [collection1] Solr
> index directory 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\.\solrtest-TestRecovery-1348338241894\index' doesn't exist.
> Creating new index...
> [junit4:junit4]   2> 856 T167 oasc.CachingDirectoryFactory.get return new
> directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\.\solrtest-TestRecovery-1348338241894\index forceNew:false
> [junit4:junit4]   2> 861 T167 oasc.SolrDeletionPolicy.onCommit
> SolrDeletionPolicy.onCommit: commits:num=1
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@1044435),segFN=se
> gments_1,generation=1,filenames=[segments_1]
> [junit4:junit4]   2> 864 T167 oasc.SolrDeletionPolicy.updateCommits newest
> commit = 1
> [junit4:junit4]   2> 866 T167 oasc.RequestHandlers.initHandlersFromConfig
> created standard: solr.StandardRequestHandler
> [junit4:junit4]   2> 866 T167 oasc.RequestHandlers.initHandlersFromConfig
> created /get: solr.RealTimeGetHandler
> [junit4:junit4]   2> 866 T167 oasc.RequestHandlers.initHandlersFromConfig
> created /update: solr.UpdateRequestHandler
> [junit4:junit4]   2> 867 T167 oasc.RequestHandlers.initHandlersFromConfig
> created /admin/: org.apache.solr.handler.admin.AdminHandlers
> [junit4:junit4]   2> 872 T167 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
> [junit4:junit4]   2> 894 T167 oass.SolrIndexSearcher.<init> Opening
> Searcher@ae1c23 main
> [junit4:junit4]   2> 894 T167 oass.SolrIndexSearcher.getIndexDir WARNING
> WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 896 T167 oasu.CommitTracker.<init> Hard AutoCommit:
> disabled
> [junit4:junit4]   2> 897 T167 oasu.CommitTracker.<init> Soft AutoCommit:
> disabled
> [junit4:junit4]   2> 897 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting socketTimeout to: 0
> [junit4:junit4]   2> 897 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting urlScheme to: http://
> [junit4:junit4]   2> 898 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting connTimeout to: 0
> [junit4:junit4]   2> 898 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting maxConnectionsPerHost to: 20
> [junit4:junit4]   2> 898 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting corePoolSize to: 0
> [junit4:junit4]   2> 898 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting maximumPoolSize to: 2147483647
> [junit4:junit4]   2> 899 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting maxThreadIdleTime to: 5
> [junit4:junit4]   2> 899 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting sizeOfQueue to: -1
> [junit4:junit4]   2> 899 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting fairnessPolicy to: false
> [junit4:junit4]   2> 900 T167 oascsi.HttpClientUtil.createClient Creating new
> http client,
> config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0
> &connTimeout=0&retry=false
> [junit4:junit4]   2> 912 T168 oasc.SolrCore.registerSearcher [collection1]
> Registered new searcher Searcher@ae1c23
> main{StandardDirectoryReader(segments_1:1)}
> [junit4:junit4]   2> 912 T167 oasc.CoreContainer.register registering core:
> collection1
> [junit4:junit4]   2> 914 T167 oas.SolrTestCaseJ4.initCore ####initCore end
> [junit4:junit4]   2> 917 T167 oas.SolrTestCaseJ4.setUp ###Starting testBuffering
> [junit4:junit4]   2> ASYNC  NEW_CORE C15 name=collection1
> org.apache.solr.core.SolrCore@48837e
> [junit4:junit4]   2> 943 T167 C15 oasu.DirectUpdateHandler2.deleteAll
> [collection1] REMOVING ALL DOCUMENTS FROM INDEX
> [junit4:junit4]   2> 947 T167 C15 oasc.SolrDeletionPolicy.onInit
> SolrDeletionPolicy.onInit: commits:num=1
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@1044435),segFN=se
> gments_1,generation=1,filenames=[segments_1]
> [junit4:junit4]   2> 947 T167 C15 oasc.SolrDeletionPolicy.updateCommits
> newest commit = 1
> [junit4:junit4]   2> 948 T167 C15 oass.SolrIndexSearcher.<init> Opening
> Searcher@1401ca0 realtime
> [junit4:junit4]   2> 949 T167 C15 oass.SolrIndexSearcher.getIndexDir WARNING
> WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 949 T167 C15 REQ [collection1] webapp=null path=null
> params={_version_=-
> 9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json
> &indent=true} status=0 QTime=15
> [junit4:junit4]   2> 955 T167 C15 oasu.DirectUpdateHandler2.commit start
> commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=t
> rue,expungeDeletes=false,softCommit=false}
> [junit4:junit4]   2> 958 T167 C15 oasc.SolrDeletionPolicy.onCommit
> SolrDeletionPolicy.onCommit: commits:num=2
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@1044435),segFN=se
> gments_1,generation=1,filenames=[segments_1]
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@1044435),segFN=se
> gments_2,generation=2,filenames=[segments_2]
> [junit4:junit4]   2> 958 T167 C15 oasc.SolrDeletionPolicy.updateCommits
> newest commit = 2
> [junit4:junit4]   2> 961 T167 C15 oass.SolrIndexSearcher.<init> Opening
> Searcher@bf3275 main
> [junit4:junit4]   2> 961 T167 C15 oass.SolrIndexSearcher.getIndexDir WARNING
> WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 962 T167 C15 oasu.DirectUpdateHandler2.commit
> end_commit_flush
> [junit4:junit4]   2> 962 T168 oasc.SolrCore.registerSearcher [collection1]
> Registered new searcher Searcher@bf3275
> main{StandardDirectoryReader(segments_1:2:nrt)}
> [junit4:junit4]   2> 963 T167 C15 UPDATE [collection1] webapp=null path=null
> params={} {commit=} 0 8
> [junit4:junit4]   2> 980 T167 oasu.UpdateLog.bufferUpdates Starting to buffer
> updates. FSUpdateLog{state=ACTIVE, tlog=null}
> [junit4:junit4]   2> 980 T167 oasu.UpdateLog.bufferUpdates Starting to buffer
> updates. FSUpdateLog{state=ACTIVE, tlog=null}
> [junit4:junit4]   2> 1021 T167 C15 REQ [collection1] webapp=null path=null
> params={update.distrib=FROMLEADER&wt=json&indent=true} status=0
> QTime=33
> [junit4:junit4]   2> 1031 T167 C15 REQ [collection1] webapp=null path=null
> params={update.distrib=FROMLEADER&wt=json&indent=true} status=0
> QTime=0
> [junit4:junit4]   2> 1036 T167 C15 REQ [collection1] webapp=null path=null
> params={update.distrib=FROMLEADER&_version_=-
> 1017&wt=json&indent=true} status=0 QTime=0
> [junit4:junit4]   2> 1041 T167 C15 REQ [collection1] webapp=null path=null
> params={update.distrib=FROMLEADER&wt=json&indent=true} status=0
> QTime=0
> [junit4:junit4]   2> 1046 T167 C15 REQ [collection1] webapp=null path=null
> params={update.distrib=FROMLEADER&wt=json&indent=true} status=0
> QTime=0
> [junit4:junit4]   2> 1052 T167 C15 REQ [collection1] webapp=null path=null
> params={update.distrib=FROMLEADER&_version_=-
> 2010&versions=true&wt=json&indent=true} status=0 QTime=0
> [junit4:junit4]   2> 1067 T167 C15 REQ [collection1] webapp=null path=null
> params={getVersions=6&qt=/get} status=0 QTime=14
> [junit4:junit4]   2> 1073 T167 C15
> oasup.DistributedUpdateProcessor.processCommit Ignoring commit while not
> ACTIVE - state: BUFFERING replay:0
> [junit4:junit4]   2> 1073 T167 C15 UPDATE [collection1] webapp=null path=null
> params={} {commit=} 0 0
> [junit4:junit4]   2> 1076 T167 C15 REQ [collection1] webapp=null path=null
> params={getVersions=6&qt=/get} status=0 QTime=1
> [junit4:junit4]   2> 1076 T167 C15 REQ [collection1] webapp=null path=null
> params={q=*:*} hits=0 status=0 QTime=0
> [junit4:junit4]   2> 1077 T167 C15 REQ [collection1] webapp=null path=null
> params={id=B3&qt=/get} status=0 QTime=0
> [junit4:junit4]   2> 1082 T169 C15 oasu.UpdateLog$LogReplayer.doReplay
> WARNING Starting log replay tlog{file=C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-
> 1348338241894\tlog\tlog.0000000000000000001 refcount=2} active=true
> starting pos=0
> [junit4:junit4]   2> 1151 T169 C15 oass.SolrIndexSearcher.<init> Opening
> Searcher@24b54c realtime
> [junit4:junit4]   2> 1152 T169 C15 oass.SolrIndexSearcher.getIndexDir
> WARNING WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 1155 T169 C15 oasu.DirectUpdateHandler2.commit start
> commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=t
> rue,expungeDeletes=false,softCommit=false}
> [junit4:junit4]   2> 1165 T169 C15 oasc.SolrDeletionPolicy.onCommit
> SolrDeletionPolicy.onCommit: commits:num=2
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@1044435),segFN=se
> gments_2,generation=2,filenames=[segments_2]
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@1044435),segFN=se
> gments_3,generation=3,filenames=[_1.fdx, _1.frq, _1.fdt, _1.si, _1.tip, _1.fnm,
> _1.tim, segments_3]
> [junit4:junit4]   2> 1166 T169 C15 oasc.SolrDeletionPolicy.updateCommits
> newest commit = 3
> [junit4:junit4]   2> 1175 T169 C15 oass.SolrIndexSearcher.<init> Opening
> Searcher@1e2fbe8 main
> [junit4:junit4]   2> 1175 T169 C15 oass.SolrIndexSearcher.getIndexDir
> WARNING WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 1177 T169 C15 oasu.DirectUpdateHandler2.commit
> end_commit_flush
> [junit4:junit4]   2> 1177 T168 oasc.SolrCore.registerSearcher [collection1]
> Registered new searcher Searcher@1e2fbe8
> main{StandardDirectoryReader(segments_3:8:nrt _1(4.1):C2)}
> [junit4:junit4]   2> 1178 T169 C15 oasu.UpdateLog$LogReplayer.run WARNING
> Log replay finished. recoveryInfo=RecoveryInfo{adds=4 deletes=1
> deleteByQuery=1 errors=0 positionOfStart=0}
> [junit4:junit4]   2> 1178 T167 C15 REQ [collection1] webapp=null path=null
> params={getVersions=6&qt=/get} status=0 QTime=0
> [junit4:junit4]   2> 1179 T167 C15 REQ [collection1] webapp=null path=null
> params={q=*:*} hits=2 status=0 QTime=0
> [junit4:junit4]   2> 1180 T167 oasu.UpdateLog.bufferUpdates Starting to buffer
> updates. FSUpdateLog{state=ACTIVE, tlog=null}
> [junit4:junit4]   2> 1181 T167 C15 REQ [collection1] webapp=null path=null
> params={id=B3&qt=/get} status=0 QTime=0
> [junit4:junit4]   2> 1199 T167 C15 REQ [collection1] webapp=null path=null
> params={update.distrib=FROMLEADER&wt=json&indent=true} status=0
> QTime=1
> [junit4:junit4]   2> 1205 T167 C15 REQ [collection1] webapp=null path=null
> params={update.distrib=FROMLEADER&wt=json&indent=true} status=0
> QTime=0
> [junit4:junit4]   2> 1211 T167 C15 REQ [collection1] webapp=null path=null
> params={update.distrib=FROMLEADER&_version_=-
> 940&versions=true&wt=json&indent=true} status=0 QTime=0
> [junit4:junit4]   2> 1216 T167 C15 REQ [collection1] webapp=null path=null
> params={update.distrib=FROMLEADER&wt=json&indent=true} status=0
> QTime=0
> [junit4:junit4]   2> 1221 T167 C15 REQ [collection1] webapp=null path=null
> params={update.distrib=FROMLEADER&wt=json&indent=true} status=0
> QTime=0
> [junit4:junit4]   2> 1228 T167 C15 REQ [collection1] webapp=null path=null
> params={update.distrib=FROMLEADER&wt=json&indent=true} status=0
> QTime=0
> [junit4:junit4]   2> 1234 T167 C15 REQ [collection1] webapp=null path=null
> params={update.distrib=FROMLEADER&_version_=-
> 3000&wt=json&indent=true} status=0 QTime=0
> [junit4:junit4]   2> 1235 T167 C15 REQ [collection1] webapp=null path=null
> params={getVersions=13&qt=/get} status=0 QTime=0
> [junit4:junit4]   2> 1236 T169 C15 oasu.UpdateLog$LogReplayer.doReplay
> WARNING Starting log replay tlog{file=C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-
> 1348338241894\tlog\tlog.0000000000000000002 refcount=2} active=true
> starting pos=0
> [junit4:junit4]   2> 1242 T167 C15 REQ [collection1] webapp=null path=null
> params={update.distrib=FROMLEADER&wt=json&indent=true} status=0
> QTime=0
> [junit4:junit4]   2> 1251 T167 C15 REQ [collection1] webapp=null path=null
> params={update.distrib=FROMLEADER&_version_=-
> 950&versions=true&wt=json&indent=true} status=0 QTime=0
> [junit4:junit4]   2> 1258 T167 C15 REQ [collection1] webapp=null path=null
> params={update.distrib=FROMLEADER&_version_=-
> 2060&versions=true&wt=json&indent=true} status=0 QTime=0
> [junit4:junit4]   2> 1270 T169 C15 oass.SolrIndexSearcher.<init> Opening
> Searcher@3cd21a realtime
> [junit4:junit4]   2> 1271 T169 C15 oass.SolrIndexSearcher.getIndexDir
> WARNING WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 1272 T169 C15 oasu.DirectUpdateHandler2.addDoc
> Reordered DBQs detected.  Update=add{flags=10,_version_=1070,id=B7}
> DBQs=[DBQ{version=3000,q=id:B2 OR id:B8}]
> [junit4:junit4]   2> 1282 T169 C15 oass.SolrIndexSearcher.<init> Opening
> Searcher@14cf85f realtime
> [junit4:junit4]   2> 1282 T169 C15 oass.SolrIndexSearcher.getIndexDir
> WARNING WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 1287 T169 C15 oasu.DirectUpdateHandler2.commit start
> commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=t
> rue,expungeDeletes=false,softCommit=false}
> [junit4:junit4]   2> 1298 T169 C15 oasc.SolrDeletionPolicy.onCommit
> SolrDeletionPolicy.onCommit: commits:num=2
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@1044435),segFN=se
> gments_3,generation=3,filenames=[_1.fdx, _1.frq, _1.fdt, _1.si, _1.tip, _1.fnm,
> _1.tim, segments_3]
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@1044435),segFN=se
> gments_4,generation=4,filenames=[_2.si, _3.frq, _1.frq, _3.tim, _2.fdt, _2.frq,
> _3.fdt, _3.fnm, _1_1.del, _2.fdx, _1.fnm, _2.tim, _2.fnm, _3.fdx, _1.fdx, _2.tip,
> _1.fdt, _1.si, _3.si, _3.tip, _2_1.del, _1.tip, _1.tim, segments_4]
> [junit4:junit4]   2> 1298 T169 C15 oasc.SolrDeletionPolicy.updateCommits
> newest commit = 4
> [junit4:junit4]   2> 1301 T169 C15 oass.SolrIndexSearcher.<init> Opening
> Searcher@f91e02 main
> [junit4:junit4]   2> 1301 T169 C15 oass.SolrIndexSearcher.getIndexDir
> WARNING WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 1302 T169 C15 oasu.DirectUpdateHandler2.commit
> end_commit_flush
> [junit4:junit4]   2> 1302 T168 oasc.SolrCore.registerSearcher [collection1]
> Registered new searcher Searcher@f91e02
> main{StandardDirectoryReader(segments_4:14:nrt _1(4.1):C2/1 _2(4.1):C4/2
> _3(4.1):C1)}
> [junit4:junit4]   2> 1303 T169 C15 oasu.UpdateLog$LogReplayer.run WARNING
> Log replay finished. recoveryInfo=RecoveryInfo{adds=6 deletes=3
> deleteByQuery=1 errors=0 positionOfStart=0}
> [junit4:junit4]   2> 1333 T167 C15 REQ [collection1] webapp=null path=null
> params={fl=id,_version_&sort=id+asc&q=*:*} hits=4 status=0 QTime=30
> [junit4:junit4]   2> 1333 T167 oas.SolrTestCaseJ4.tearDown ###Ending
> testBuffering
> [junit4:junit4]   2> 1342 T167 oas.SolrTestCaseJ4.setUp ###Starting
> testLogReplay
> [junit4:junit4]   2> 1356 T167 C15 oasu.DirectUpdateHandler2.deleteAll
> [collection1] REMOVING ALL DOCUMENTS FROM INDEX
> [junit4:junit4]   2> 1360 T167 C15 oass.SolrIndexSearcher.<init> Opening
> Searcher@160df2c realtime
> [junit4:junit4]   2> 1361 T167 C15 oass.SolrIndexSearcher.getIndexDir
> WARNING WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 1362 T167 C15 REQ [collection1] webapp=null path=null
> params={_version_=-
> 9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json
> &indent=true} status=0 QTime=7
> [junit4:junit4]   2> 1367 T167 C15 oasu.DirectUpdateHandler2.commit start
> commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=t
> rue,expungeDeletes=false,softCommit=false}
> [junit4:junit4]   2> 1371 T167 C15 oasc.SolrDeletionPolicy.onCommit
> SolrDeletionPolicy.onCommit: commits:num=2
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@1044435),segFN=se
> gments_4,generation=4,filenames=[_2.si, _3.frq, _1.frq, _3.tim, _2.fdt, _2.frq,
> _3.fdt, _3.fnm, _1_1.del, _2.fdx, _1.fnm, _2.tim, _2.fnm, _3.fdx, _1.fdx, _2.tip,
> _1.fdt, _1.si, _3.si, _3.tip, _2_1.del, _1.tip, _1.tim, segments_4]
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@1044435),segFN=se
> gments_5,generation=5,filenames=[segments_5]
> [junit4:junit4]   2> 1371 T167 C15 oasc.SolrDeletionPolicy.updateCommits
> newest commit = 5
> [junit4:junit4]   2> 1384 T167 C15 oass.SolrIndexSearcher.<init> Opening
> Searcher@11c3174 main
> [junit4:junit4]   2> 1384 T167 C15 oass.SolrIndexSearcher.getIndexDir
> WARNING WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 1385 T167 C15 oasu.DirectUpdateHandler2.commit
> end_commit_flush
> [junit4:junit4]   2> 1385 T168 oasc.SolrCore.registerSearcher [collection1]
> Registered new searcher Searcher@11c3174
> main{StandardDirectoryReader(segments_4:15:nrt)}
> [junit4:junit4]   2> 1399 T167 C15 UPDATE [collection1] webapp=null path=null
> params={} {commit=} 0 32
> [junit4:junit4]   2> 1418 T167 C15 UPDATE [collection1] webapp=null path=null
> params={versions=true&wt=json&indent=true} {add=[A1
> (1413835121811783680)]} 0 5
> [junit4:junit4]   2> 1424 T167 C15 UPDATE [collection1] webapp=null path=null
> params={versions=true&wt=json&indent=true} {add=[A11
> (1413835121822269440)]} 0 1
> [junit4:junit4]   2> 1430 T167 C15 UPDATE [collection1] webapp=null path=null
> params={versions=true&wt=json&indent=true} {add=[A12
> (1413835121829609472)]} 0 0
> [junit4:junit4]   2> 1449 T167 C15 oass.SolrIndexSearcher.<init> Opening
> Searcher@14b5b6e realtime
> [junit4:junit4]   2> 1449 T167 C15 oass.SolrIndexSearcher.getIndexDir
> WARNING WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 1450 T167 C15 UPDATE [collection1] webapp=null path=null
> params={versions=true&wt=json&indent=true} {deleteByQuery=id:A11 (-
> 1413835121836949504)} 0 13
> [junit4:junit4]   2> 1457 T167 C15 UPDATE [collection1] webapp=null path=null
> params={versions=true&wt=json&indent=true} {add=[A13
> (1413835121855823872)]} 0 2
> [junit4:junit4]   2> 1458 T167 C15 REQ [collection1] webapp=null path=null
> params={q=*:*} hits=0 status=0 QTime=0
> [junit4:junit4]   2> 1458 T167 C15 REQ [collection1] webapp=null path=null
> params={getVersions=5&qt=/get} status=0 QTime=0
> [junit4:junit4]   2> 1459 T167 oasc.CoreContainer.shutdown Shutting down
> CoreContainer instance=28045267
> [junit4:junit4]   2> 1459 T167 oasc.SolrCore.close [collection1]  CLOSING
> SolrCore org.apache.solr.core.SolrCore@48837e
> [junit4:junit4]   2> 1460 T167 oasc.SolrCore.closeSearcher [collection1] Closing
> main searcher on request.
> [junit4:junit4]   2> 1460 T167 oasu.DirectUpdateHandler2.close closing
> DirectUpdateHandler2{commits=4,autocommits=0,soft
> autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=4,add
> s=4,deletesById=0,deletesByQuery=1,errors=2,cumulative_adds=13,cumulative
> _deletesById=2,cumulative_deletesByQuery=5,cumulative_errors=2}
> [junit4:junit4]   2> 1461 T167 oasu.DefaultSolrCoreState.decref SolrCoreState
> ref count has reached 0 - closing IndexWriter
> [junit4:junit4]   2> 1475 T167 oasu.DefaultSolrCoreState.decref Closing
> SolrCoreState - canceling any ongoing recovery
> [junit4:junit4]   2> 1476 T167 oasc.SolrResourceLoader.<init> new
> SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test-files\solr\collection1\'
> [junit4:junit4]   2> 1478 T167 oasc.SolrResourceLoader.replaceClassLoader
> Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-
> core/test-files/solr/collection1/lib/classes/' to classloader
> [junit4:junit4]   2> 1480 T167 oasc.SolrResourceLoader.replaceClassLoader
> Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-
> core/test-files/solr/collection1/lib/README' to classloader
> [junit4:junit4]   2> 1547 T167 oasc.SolrConfig.<init> Using Lucene
> MatchVersion: LUCENE_41
> [junit4:junit4]   2> 1611 T167 oasc.SolrConfig.<init> Loaded SolrConfig:
> solrconfig-tlog.xml
> [junit4:junit4]   2> 1612 T167 oass.IndexSchema.readSchema Reading Solr
> Schema
> [junit4:junit4]   2> 1618 T167 oass.IndexSchema.readSchema Schema
> name=test
> [junit4:junit4]   2> 2319 T167 oass.IndexSchema.readSchema default search
> field in schema is text
> [junit4:junit4]   2> 2323 T167 oass.IndexSchema.readSchema unique key field:
> id
> [junit4:junit4]   2> 2339 T167 oasc.SolrResourceLoader.locateSolrHome JNDI
> not configured for solr (NoInitialContextEx)
> [junit4:junit4]   2> 2340 T167 oasc.SolrResourceLoader.locateSolrHome using
> system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test-files\solr
> [junit4:junit4]   2> 2340 T167 oasc.SolrResourceLoader.<init> new
> SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test-files\solr\'
> [junit4:junit4]   2> 2354 T167 oasc.CoreContainer.<init> New CoreContainer
> 12879521
> [junit4:junit4]   2> 2355 T167 oasc.SolrCore.<init> [collection1] Opening new
> SolrCore at C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-
> 4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-
> 1348338241894\
> [junit4:junit4]   2> 2355 T167 oasc.SolrCore.<init> JMX monitoring not detected
> for core: collection1
> [junit4:junit4]   2> 2356 T167 oasc.SolrCore.getNewIndexDir WARNING New
> index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-
> 1348338241894\index/
> [junit4:junit4]   2> 2358 T167 oasc.RequestHandlers.initHandlersFromConfig
> created standard: solr.StandardRequestHandler
> [junit4:junit4]   2> 2359 T167 oasc.RequestHandlers.initHandlersFromConfig
> created /get: solr.RealTimeGetHandler
> [junit4:junit4]   2> 2359 T167 oasc.RequestHandlers.initHandlersFromConfig
> created /update: solr.UpdateRequestHandler
> [junit4:junit4]   2> 2360 T167 oasc.RequestHandlers.initHandlersFromConfig
> created /admin/: org.apache.solr.handler.admin.AdminHandlers
> [junit4:junit4]   2> 2363 T167 oashl.XMLLoader.init
> xsltCacheLifetimeSeconds=60
> [junit4:junit4]   2> 2377 T167 oasc.CachingDirectoryFactory.get return new
> directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\.\solrtest-TestRecovery-1348338241894\index forceNew:false
> [junit4:junit4]   2> 2378 T167 oass.SolrIndexSearcher.<init> Opening
> Searcher@12d739d main
> [junit4:junit4]   2> 2379 T167 oass.SolrIndexSearcher.getIndexDir WARNING
> WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 2383 T167 oasu.CommitTracker.<init> Hard AutoCommit:
> disabled
> [junit4:junit4]   2> 2383 T167 oasu.CommitTracker.<init> Soft AutoCommit:
> disabled
> [junit4:junit4]   2> 2384 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting socketTimeout to: 0
> [junit4:junit4]   2> 2384 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting urlScheme to: http://
> [junit4:junit4]   2> 2384 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting connTimeout to: 0
> [junit4:junit4]   2> 2384 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting maxConnectionsPerHost to: 20
> [junit4:junit4]   2> 2384 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting corePoolSize to: 0
> [junit4:junit4]   2> 2386 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting maximumPoolSize to: 2147483647
> [junit4:junit4]   2> 2386 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting maxThreadIdleTime to: 5
> [junit4:junit4]   2> 2387 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting sizeOfQueue to: -1
> [junit4:junit4]   2> 2387 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting fairnessPolicy to: false
> [junit4:junit4]   2> 2387 T167 oascsi.HttpClientUtil.createClient Creating new
> http client,
> config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0
> &connTimeout=0&retry=false
> [junit4:junit4]   2> 2398 T170 oasc.SolrCore.registerSearcher [collection1]
> Registered new searcher Searcher@12d739d
> main{StandardDirectoryReader(segments_5:15)}
> [junit4:junit4]   2> 2398 T167 oasc.CoreContainer.register registering core:
> collection1
> [junit4:junit4]   2> ASYNC  NEW_CORE C16 name=collection1
> org.apache.solr.core.SolrCore@f02f06
> [junit4:junit4]   2> 2399 T171 C16 oasu.UpdateLog$LogReplayer.doReplay
> WARNING Starting log replay tlog{file=C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-
> 1348338241894\tlog\tlog.0000000000000000004 refcount=2} active=false
> starting pos=0
> [junit4:junit4]   2> 2400 T167 C16 REQ [collection1] webapp=null path=null
> params={q=*:*} hits=0 status=0 QTime=0
> [junit4:junit4]   2> 2401 T167 C16 REQ [collection1] webapp=null path=null
> params={getVersions=5&qt=/get} status=0 QTime=0
> [junit4:junit4]   2> 2402 T167 C16 REQ [collection1] webapp=null path=null
> params={getVersions=5&qt=/get} status=0 QTime=1
> [junit4:junit4]   2> 2408 T171 C16 oasc.SolrDeletionPolicy.onInit
> SolrDeletionPolicy.onInit: commits:num=1
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@1cbd9a8),segFN=se
> gments_5,generation=5,filenames=[segments_5]
> [junit4:junit4]   2> 2408 T171 C16 oasc.SolrDeletionPolicy.updateCommits
> newest commit = 5
> [junit4:junit4]   2> 2408 T171 C16 oasu.DirectUpdateHandler2.addDoc
> Reordered DBQs detected.
> Update=add{flags=10,_version_=1413835121811783680,id=A1}
> DBQs=[DBQ{version=1413835121836949504,q=id:A11}]
> [junit4:junit4]   2> 2416 T171 C16 oass.SolrIndexSearcher.<init> Opening
> Searcher@dabf4b realtime
> [junit4:junit4]   2> 2416 T171 C16 oass.SolrIndexSearcher.getIndexDir
> WARNING WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 2416 T171 C16 oasu.DirectUpdateHandler2.addDoc
> Reordered DBQs detected.
> Update=add{flags=10,_version_=1413835121822269440,id=A11}
> DBQs=[DBQ{version=1413835121836949504,q=id:A11}]
> [junit4:junit4]   2> 2431 T171 C16 oass.SolrIndexSearcher.<init> Opening
> Searcher@1352dda realtime
> [junit4:junit4]   2> 2431 T171 C16 oass.SolrIndexSearcher.getIndexDir
> WARNING WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 2431 T171 C16 oasu.DirectUpdateHandler2.addDoc
> Reordered DBQs detected.
> Update=add{flags=10,_version_=1413835121829609472,id=A12}
> DBQs=[DBQ{version=1413835121836949504,q=id:A11}]
> [junit4:junit4]   2> 2443 T171 C16 oass.SolrIndexSearcher.<init> Opening
> Searcher@179227a realtime
> [junit4:junit4]   2> 2444 T171 C16 oass.SolrIndexSearcher.getIndexDir
> WARNING WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 2452 T171 C16 oasu.DirectUpdateHandler2.commit start
> commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=t
> rue,expungeDeletes=false,softCommit=false}
> [junit4:junit4]   2> 2464 T171 C16 oasc.SolrDeletionPolicy.onCommit
> SolrDeletionPolicy.onCommit: commits:num=2
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@1cbd9a8),segFN=se
> gments_5,generation=5,filenames=[segments_5]
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@1cbd9a8),segFN=se
> gments_6,generation=6,filenames=[_4.tim, _6.frq, _6.fdx, _7.si, _4.fnm, _7.fdx,
> _4.fdt, _4.si, _7.fdt, _4.fdx, _7.fnm, _4.frq, _6.tip, _6.tim, _7.frq, _7.tim, _6.si,
> _6.fnm, _4.tip, _6.fdt, segments_6, _7.tip]
> [junit4:junit4]   2> 2464 T171 C16 oasc.SolrDeletionPolicy.updateCommits
> newest commit = 6
> [junit4:junit4]   2> 2470 T171 C16 oass.SolrIndexSearcher.<init> Opening
> Searcher@144e298 main
> [junit4:junit4]   2> 2471 T171 C16 oass.SolrIndexSearcher.getIndexDir
> WARNING WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 2472 T171 C16 oasu.DirectUpdateHandler2.commit
> end_commit_flush
> [junit4:junit4]   2> 2472 T170 oasc.SolrCore.registerSearcher [collection1]
> Registered new searcher Searcher@144e298
> main{StandardDirectoryReader(segments_6:25:nrt _4(4.1):C1 _6(4.1):C1
> _7(4.1):C1)}
> [junit4:junit4]   2> 2473 T171 C16 oasu.UpdateLog$LogReplayer.run WARNING
> Log replay finished. recoveryInfo=RecoveryInfo{adds=4 deletes=0
> deleteByQuery=1 errors=0 positionOfStart=0}
> [junit4:junit4]   2> 2473 T167 C16 REQ [collection1] webapp=null path=null
> params={q=*:*} hits=3 status=0 QTime=0
> [junit4:junit4]   2> 2475 T167 C16 REQ [collection1] webapp=null path=null
> params={getVersions=5&qt=/get} status=0 QTime=0
> [junit4:junit4]   2> 2487 T167 C16 UPDATE [collection1] webapp=null path=null
> params={} {add=[A2 (1413835122933760000)]} 0 4
> [junit4:junit4]   2> 2506 T167 C16 UPDATE [collection1] webapp=null path=null
> params={} {add=[A3 (1413835122955780096)]} 0 2
> [junit4:junit4]   2> 2513 T167 C16 UPDATE [collection1] webapp=null path=null
> params={} {delete=[A2 (-1413835122964168704)]} 0 1
> [junit4:junit4]   2> 2523 T167 C16 UPDATE [collection1] webapp=null path=null
> params={} {add=[A4 (1413835122974654464)]} 0 1
> [junit4:junit4]   2> 2528 T167 C16 REQ [collection1] webapp=null path=null
> params={q=*:*} hits=3 status=0 QTime=1
> [junit4:junit4]   2> 2528 T167 oasc.CoreContainer.shutdown Shutting down
> CoreContainer instance=12879521
> [junit4:junit4]   2> 2529 T167 oasc.SolrCore.close [collection1]  CLOSING
> SolrCore org.apache.solr.core.SolrCore@f02f06
> [junit4:junit4]   2> 2530 T167 oasc.SolrCore.closeSearcher [collection1] Closing
> main searcher on request.
> [junit4:junit4]   2> 2530 T167 oasu.DirectUpdateHandler2.close closing
> DirectUpdateHandler2{commits=1,autocommits=0,soft
> autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=3,add
> s=3,deletesById=1,deletesByQuery=0,errors=0,cumulative_adds=7,cumulative_
> deletesById=1,cumulative_deletesByQuery=1,cumulative_errors=0}
> [junit4:junit4]   2> 2531 T167 oasu.DefaultSolrCoreState.decref SolrCoreState
> ref count has reached 0 - closing IndexWriter
> [junit4:junit4]   2> 2560 T167 oasu.DefaultSolrCoreState.decref Closing
> SolrCoreState - canceling any ongoing recovery
> [junit4:junit4]   2> 2561 T167 oasc.SolrResourceLoader.<init> new
> SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test-files\solr\collection1\'
> [junit4:junit4]   2> 2563 T167 oasc.SolrResourceLoader.replaceClassLoader
> Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-
> core/test-files/solr/collection1/lib/classes/' to classloader
> [junit4:junit4]   2> 2566 T167 oasc.SolrResourceLoader.replaceClassLoader
> Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-
> core/test-files/solr/collection1/lib/README' to classloader
> [junit4:junit4]   2> 2646 T167 oasc.SolrConfig.<init> Using Lucene
> MatchVersion: LUCENE_41
> [junit4:junit4]   2> 2710 T167 oasc.SolrConfig.<init> Loaded SolrConfig:
> solrconfig-tlog.xml
> [junit4:junit4]   2> 2711 T167 oass.IndexSchema.readSchema Reading Solr
> Schema
> [junit4:junit4]   2> 2716 T167 oass.IndexSchema.readSchema Schema
> name=test
> [junit4:junit4]   2> 3414 T167 oass.IndexSchema.readSchema default search
> field in schema is text
> [junit4:junit4]   2> 3417 T167 oass.IndexSchema.readSchema unique key field:
> id
> [junit4:junit4]   2> 3444 T167 oasc.SolrResourceLoader.locateSolrHome JNDI
> not configured for solr (NoInitialContextEx)
> [junit4:junit4]   2> 3444 T167 oasc.SolrResourceLoader.locateSolrHome using
> system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test-files\solr
> [junit4:junit4]   2> 3445 T167 oasc.SolrResourceLoader.<init> new
> SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test-files\solr\'
> [junit4:junit4]   2> 3461 T167 oasc.CoreContainer.<init> New CoreContainer
> 8359262
> [junit4:junit4]   2> 3461 T167 oasc.SolrCore.<init> [collection1] Opening new
> SolrCore at C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-
> 4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-
> 1348338241894\
> [junit4:junit4]   2> 3463 T167 oasc.SolrCore.<init> JMX monitoring not detected
> for core: collection1
> [junit4:junit4]   2> 3465 T167 oasc.SolrCore.getNewIndexDir WARNING New
> index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-
> 1348338241894\index/
> [junit4:junit4]   2> 3467 T167 oasc.RequestHandlers.initHandlersFromConfig
> created standard: solr.StandardRequestHandler
> [junit4:junit4]   2> 3468 T167 oasc.RequestHandlers.initHandlersFromConfig
> created /get: solr.RealTimeGetHandler
> [junit4:junit4]   2> 3468 T167 oasc.RequestHandlers.initHandlersFromConfig
> created /update: solr.UpdateRequestHandler
> [junit4:junit4]   2> 3469 T167 oasc.RequestHandlers.initHandlersFromConfig
> created /admin/: org.apache.solr.handler.admin.AdminHandlers
> [junit4:junit4]   2> 3474 T167 oashl.XMLLoader.init
> xsltCacheLifetimeSeconds=60
> [junit4:junit4]   2> 3480 T167 oasc.CachingDirectoryFactory.get return new
> directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\.\solrtest-TestRecovery-1348338241894\index forceNew:false
> [junit4:junit4]   2> 3495 T167 oass.SolrIndexSearcher.<init> Opening
> Searcher@18f54f6 main
> [junit4:junit4]   2> 3496 T167 oass.SolrIndexSearcher.getIndexDir WARNING
> WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 3503 T167 oasu.CommitTracker.<init> Hard AutoCommit:
> disabled
> [junit4:junit4]   2> 3503 T167 oasu.CommitTracker.<init> Soft AutoCommit:
> disabled
> [junit4:junit4]   2> 3504 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting socketTimeout to: 0
> [junit4:junit4]   2> 3504 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting urlScheme to: http://
> [junit4:junit4]   2> 3505 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting connTimeout to: 0
> [junit4:junit4]   2> 3506 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting maxConnectionsPerHost to: 20
> [junit4:junit4]   2> 3506 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting corePoolSize to: 0
> [junit4:junit4]   2> 3506 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting maximumPoolSize to: 2147483647
> [junit4:junit4]   2> 3507 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting maxThreadIdleTime to: 5
> [junit4:junit4]   2> 3508 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting sizeOfQueue to: -1
> [junit4:junit4]   2> 3508 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting fairnessPolicy to: false
> [junit4:junit4]   2> 3509 T167 oascsi.HttpClientUtil.createClient Creating new
> http client,
> config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0
> &connTimeout=0&retry=false
> [junit4:junit4]   2> 3527 T167 oasc.CoreContainer.register registering core:
> collection1
> [junit4:junit4]   2> 3528 T172 oasc.SolrCore.registerSearcher [collection1]
> Registered new searcher Searcher@18f54f6
> main{StandardDirectoryReader(segments_6:25 _4(4.1):C1 _6(4.1):C1
> _7(4.1):C1)}
> [junit4:junit4]   2> ASYNC  NEW_CORE C17 name=collection1
> org.apache.solr.core.SolrCore@1b93583
> [junit4:junit4]   2> 3529 T173 C17 oasu.UpdateLog$LogReplayer.doReplay
> WARNING Starting log replay tlog{file=C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-
> 1348338241894\tlog\tlog.0000000000000000006 refcount=2} active=false
> starting pos=0
> [junit4:junit4]   2> 3540 T173 C17 oasc.SolrDeletionPolicy.onInit
> SolrDeletionPolicy.onInit: commits:num=1
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@10c253e),segFN=se
> gments_6,generation=6,filenames=[_4.tim, _6.frq, _6.fdx, _7.si, _4.fnm, _7.fdx,
> _4.si, _4.fdt, _7.fdt, _4.fdx, _7.fnm, _4.frq, _6.tip, _6.tim, _7.tim, _7.frq,
> _6.fnm, _6.si, _4.tip, _6.fdt, segments_6, _7.tip]
> [junit4:junit4]   2> 3540 T173 C17 oasc.SolrDeletionPolicy.updateCommits
> newest commit = 6
> [junit4:junit4]   2> 3541 T173 C17 oasu.DirectUpdateHandler2.commit start
> commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=t
> rue,expungeDeletes=false,softCommit=false}
> [junit4:junit4]   2> 3559 T173 C17 oasc.SolrDeletionPolicy.onCommit
> SolrDeletionPolicy.onCommit: commits:num=2
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@10c253e),segFN=se
> gments_6,generation=6,filenames=[_4.tim, _6.frq, _6.fdx, _7.si, _4.fnm, _7.fdx,
> _4.si, _4.fdt, _7.fdt, _4.fdx, _7.fnm, _4.frq, _6.tip, _6.tim, _7.tim, _7.frq,
> _6.fnm, _6.si, _4.tip, _6.fdt, segments_6, _7.tip]
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@10c253e),segFN=se
> gments_7,generation=7,filenames=[_6.frq, _6.fdx, _4.fnm, _7.si, _8.tim, _8.tip,
> _4.fdx, _8.si, _7.fnm, _4.frq, _6.tip, _6.tim, _7.frq, _7.tim, _4.tip, _7.tip, _4.tim,
> _8.frq, _7.fdx, _4.fdt, _4.si, _7.fdt, segments_7, _8.fnm, _6.si, _6.fnm, _8.fdx,
> _6.fdt, _8.fdt]
> [junit4:junit4]   2> 3559 T173 C17 oasc.SolrDeletionPolicy.updateCommits
> newest commit = 7
> [junit4:junit4]   2> 3575 T173 C17 oass.SolrIndexSearcher.<init> Opening
> Searcher@69f889 main
> [junit4:junit4]   2> 3576 T173 C17 oass.SolrIndexSearcher.getIndexDir
> WARNING WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 3576 T173 C17 oasu.DirectUpdateHandler2.commit
> end_commit_flush
> [junit4:junit4]   2> 3576 T172 oasc.SolrCore.registerSearcher [collection1]
> Registered new searcher Searcher@69f889
> main{StandardDirectoryReader(segments_7:27 _4(4.1):C1 _6(4.1):C1
> _7(4.1):C1 _8(4.1):C2)}
> [junit4:junit4]   2> 3577 T173 C17 oasu.UpdateLog$LogReplayer.run WARNING
> Log replay finished. recoveryInfo=RecoveryInfo{adds=3 deletes=1
> deleteByQuery=0 errors=0 positionOfStart=0}
> [junit4:junit4]   2> 3578 T167 C17 REQ [collection1] webapp=null path=null
> params={q=*:*} hits=5 status=0 QTime=0
> [junit4:junit4]   2> 3580 T167 C17 REQ [collection1] webapp=null path=null
> params={q=id:A2} hits=0 status=0 QTime=1
> [junit4:junit4]   2> 3580 T167 oasc.CoreContainer.shutdown Shutting down
> CoreContainer instance=8359262
> [junit4:junit4]   2> 3580 T167 oasc.SolrCore.close [collection1]  CLOSING
> SolrCore org.apache.solr.core.SolrCore@1b93583
> [junit4:junit4]   2> 3582 T167 oasc.SolrCore.closeSearcher [collection1] Closing
> main searcher on request.
> [junit4:junit4]   2> 3585 T167 oasu.DirectUpdateHandler2.close closing
> DirectUpdateHandler2{commits=1,autocommits=0,soft
> autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,add
> s=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=2,cumulative_
> deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
> [junit4:junit4]   2> 3586 T167 oasu.DefaultSolrCoreState.decref SolrCoreState
> ref count has reached 0 - closing IndexWriter
> [junit4:junit4]   2> 3616 T167 oasu.DefaultSolrCoreState.decref Closing
> SolrCoreState - canceling any ongoing recovery
> [junit4:junit4]   2> 3617 T167 oasc.SolrResourceLoader.<init> new
> SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test-files\solr\collection1\'
> [junit4:junit4]   2> 3620 T167 oasc.SolrResourceLoader.replaceClassLoader
> Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-
> core/test-files/solr/collection1/lib/classes/' to classloader
> [junit4:junit4]   2> 3621 T167 oasc.SolrResourceLoader.replaceClassLoader
> Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-
> core/test-files/solr/collection1/lib/README' to classloader
> [junit4:junit4]   2> 3685 T167 oasc.SolrConfig.<init> Using Lucene
> MatchVersion: LUCENE_41
> [junit4:junit4]   2> 3744 T167 oasc.SolrConfig.<init> Loaded SolrConfig:
> solrconfig-tlog.xml
> [junit4:junit4]   2> 3745 T167 oass.IndexSchema.readSchema Reading Solr
> Schema
> [junit4:junit4]   2> 3750 T167 oass.IndexSchema.readSchema Schema
> name=test
> [junit4:junit4]   2> 4401 T167 oass.IndexSchema.readSchema default search
> field in schema is text
> [junit4:junit4]   2> 4401 T167 oass.IndexSchema.readSchema unique key field:
> id
> [junit4:junit4]   2> 4417 T167 oasc.SolrResourceLoader.locateSolrHome JNDI
> not configured for solr (NoInitialContextEx)
> [junit4:junit4]   2> 4418 T167 oasc.SolrResourceLoader.locateSolrHome using
> system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test-files\solr
> [junit4:junit4]   2> 4418 T167 oasc.SolrResourceLoader.<init> new
> SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test-files\solr\'
> [junit4:junit4]   2> 4431 T167 oasc.CoreContainer.<init> New CoreContainer
> 17616431
> [junit4:junit4]   2> 4431 T167 oasc.SolrCore.<init> [collection1] Opening new
> SolrCore at C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-
> 4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-
> 1348338241894\
> [junit4:junit4]   2> 4431 T167 oasc.SolrCore.<init> JMX monitoring not detected
> for core: collection1
> [junit4:junit4]   2> 4432 T167 oasc.SolrCore.getNewIndexDir WARNING New
> index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-
> 1348338241894\index/
> [junit4:junit4]   2> 4434 T167 oasc.RequestHandlers.initHandlersFromConfig
> created standard: solr.StandardRequestHandler
> [junit4:junit4]   2> 4435 T167 oasc.RequestHandlers.initHandlersFromConfig
> created /get: solr.RealTimeGetHandler
> [junit4:junit4]   2> 4435 T167 oasc.RequestHandlers.initHandlersFromConfig
> created /update: solr.UpdateRequestHandler
> [junit4:junit4]   2> 4435 T167 oasc.RequestHandlers.initHandlersFromConfig
> created /admin/: org.apache.solr.handler.admin.AdminHandlers
> [junit4:junit4]   2> 4438 T167 oashl.XMLLoader.init
> xsltCacheLifetimeSeconds=60
> [junit4:junit4]   2> 4445 T167 oasc.CachingDirectoryFactory.get return new
> directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\.\solrtest-TestRecovery-1348338241894\index forceNew:false
> [junit4:junit4]   2> 4457 T167 oass.SolrIndexSearcher.<init> Opening
> Searcher@7e253c main
> [junit4:junit4]   2> 4457 T167 oass.SolrIndexSearcher.getIndexDir WARNING
> WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 4461 T167 oasu.CommitTracker.<init> Hard AutoCommit:
> disabled
> [junit4:junit4]   2> 4462 T167 oasu.CommitTracker.<init> Soft AutoCommit:
> disabled
> [junit4:junit4]   2> 4462 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting socketTimeout to: 0
> [junit4:junit4]   2> 4462 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting urlScheme to: http://
> [junit4:junit4]   2> 4463 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting connTimeout to: 0
> [junit4:junit4]   2> 4463 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting maxConnectionsPerHost to: 20
> [junit4:junit4]   2> 4463 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting corePoolSize to: 0
> [junit4:junit4]   2> 4464 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting maximumPoolSize to: 2147483647
> [junit4:junit4]   2> 4464 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting maxThreadIdleTime to: 5
> [junit4:junit4]   2> 4464 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting sizeOfQueue to: -1
> [junit4:junit4]   2> 4465 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting fairnessPolicy to: false
> [junit4:junit4]   2> 4465 T167 oascsi.HttpClientUtil.createClient Creating new
> http client,
> config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0
> &connTimeout=0&retry=false
> [junit4:junit4]   2> 4479 T174 oasc.SolrCore.registerSearcher [collection1]
> Registered new searcher Searcher@7e253c
> main{StandardDirectoryReader(segments_7:27 _4(4.1):C1 _6(4.1):C1
> _7(4.1):C1 _8(4.1):C2)}
> [junit4:junit4]   2> 4479 T167 oasc.CoreContainer.register registering core:
> collection1
> [junit4:junit4]   2> ASYNC  NEW_CORE C18 name=collection1
> org.apache.solr.core.SolrCore@d6bba9
> [junit4:junit4]   2> 4480 T167 C18 REQ [collection1] webapp=null path=null
> params={q=*:*} hits=5 status=0 QTime=0
> [junit4:junit4]   2> 4582 T167 oas.SolrTestCaseJ4.tearDown ###Ending
> testLogReplay
> [junit4:junit4]   2> 4587 T167 oas.SolrTestCaseJ4.setUp ###Starting
> testRecoveryMultipleLogs
> [junit4:junit4]   2> 4594 T167 C18 oasu.DirectUpdateHandler2.deleteAll
> [collection1] REMOVING ALL DOCUMENTS FROM INDEX
> [junit4:junit4]   2> 4605 T167 C18 oasc.SolrDeletionPolicy.onInit
> SolrDeletionPolicy.onInit: commits:num=1
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@1063944),segFN=se
> gments_7,generation=7,filenames=[_6.frq, _6.fdx, _4.fnm, _7.si, _8.tim, _8.tip,
> _4.fdx, _8.si, _7.fnm, _4.frq, _6.tip, _6.tim, _7.frq, _7.tim, _4.tip, _7.tip, _4.tim,
> _8.frq, _7.fdx, _4.fdt, _4.si, _7.fdt, segments_7, _8.fnm, _6.si, _6.fnm, _8.fdx,
> _6.fdt, _8.fdt]
> [junit4:junit4]   2> 4605 T167 C18 oasc.SolrDeletionPolicy.updateCommits
> newest commit = 7
> [junit4:junit4]   2> 4608 T167 C18 oass.SolrIndexSearcher.<init> Opening
> Searcher@17e4571 realtime
> [junit4:junit4]   2> 4609 T167 C18 oass.SolrIndexSearcher.getIndexDir
> WARNING WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 4609 T167 C18 REQ [collection1] webapp=null path=null
> params={_version_=-
> 9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json
> &indent=true} status=0 QTime=15
> [junit4:junit4]   2> 4614 T167 C18 oasu.DirectUpdateHandler2.commit start
> commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=t
> rue,expungeDeletes=false,softCommit=false}
> [junit4:junit4]   2> 4616 T167 C18 oasc.SolrDeletionPolicy.onCommit
> SolrDeletionPolicy.onCommit: commits:num=2
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@1063944),segFN=se
> gments_7,generation=7,filenames=[_6.frq, _6.fdx, _4.fnm, _7.si, _8.tim, _8.tip,
> _4.fdx, _8.si, _7.fnm, _4.frq, _6.tip, _6.tim, _7.frq, _7.tim, _4.tip, _7.tip, _4.tim,
> _8.frq, _7.fdx, _4.fdt, _4.si, _7.fdt, segments_7, _8.fnm, _6.si, _6.fnm, _8.fdx,
> _6.fdt, _8.fdt]
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@1063944),segFN=se
> gments_8,generation=8,filenames=[segments_8]
> [junit4:junit4]   2> 4617 T167 C18 oasc.SolrDeletionPolicy.updateCommits
> newest commit = 8
> [junit4:junit4]   2> 4631 T167 C18 oass.SolrIndexSearcher.<init> Opening
> Searcher@1e59880 main
> [junit4:junit4]   2> 4632 T167 C18 oass.SolrIndexSearcher.getIndexDir
> WARNING WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 4632 T174 oasc.SolrCore.registerSearcher [collection1]
> Registered new searcher Searcher@1e59880
> main{StandardDirectoryReader(segments_7:28:nrt)}
> [junit4:junit4]   2> 4632 T167 C18 oasu.DirectUpdateHandler2.commit
> end_commit_flush
> [junit4:junit4]   2> 4635 T167 C18 UPDATE [collection1] webapp=null path=null
> params={} {commit=} 0 22
> [junit4:junit4]   2> 4658 T167 C18 UPDATE [collection1] webapp=null path=null
> params={} {add=[AAAAAA (1413835125211267072)]} 0 4
> [junit4:junit4]   2> 4667 T167 C18 UPDATE [collection1] webapp=null path=null
> params={} {add=[BBBBBB (1413835125223849984)]} 0 0
> [junit4:junit4]   2> 4676 T167 C18 UPDATE [collection1] webapp=null path=null
> params={} {add=[CCCCCC (1413835125232238592)]} 0 1
> [junit4:junit4]   2> 4679 T167 oasc.CoreContainer.shutdown Shutting down
> CoreContainer instance=17616431
> [junit4:junit4]   2> 4680 T167 oasc.SolrCore.close [collection1]  CLOSING
> SolrCore org.apache.solr.core.SolrCore@d6bba9
> [junit4:junit4]   2> 4680 T167 oasc.SolrCore.closeSearcher [collection1] Closing
> main searcher on request.
> [junit4:junit4]   2> 4694 T167 oasu.DirectUpdateHandler2.close closing
> DirectUpdateHandler2{commits=1,autocommits=0,soft
> autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=3,add
> s=3,deletesById=0,deletesByQuery=0,errors=1,cumulative_adds=3,cumulative_
> deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=1}
> [junit4:junit4]   2> 4694 T167 oasu.DefaultSolrCoreState.decref SolrCoreState
> ref count has reached 0 - closing IndexWriter
> [junit4:junit4]   2> 4703 T167 oasu.DefaultSolrCoreState.decref Closing
> SolrCoreState - canceling any ongoing recovery
> [junit4:junit4]   2> 4707 T167 oasc.SolrResourceLoader.<init> new
> SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test-files\solr\collection1\'
> [junit4:junit4]   2> 4709 T167 oasc.SolrResourceLoader.replaceClassLoader
> Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-
> core/test-files/solr/collection1/lib/classes/' to classloader
> [junit4:junit4]   2> 4709 T167 oasc.SolrResourceLoader.replaceClassLoader
> Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-
> core/test-files/solr/collection1/lib/README' to classloader
> [junit4:junit4]   2> 4779 T167 oasc.SolrConfig.<init> Using Lucene
> MatchVersion: LUCENE_41
> [junit4:junit4]   2> 4841 T167 oasc.SolrConfig.<init> Loaded SolrConfig:
> solrconfig-tlog.xml
> [junit4:junit4]   2> 4841 T167 oass.IndexSchema.readSchema Reading Solr
> Schema
> [junit4:junit4]   2> 4849 T167 oass.IndexSchema.readSchema Schema
> name=test
> [junit4:junit4]   2> 5495 T167 oass.IndexSchema.readSchema default search
> field in schema is text
> [junit4:junit4]   2> 5499 T167 oass.IndexSchema.readSchema unique key field:
> id
> [junit4:junit4]   2> 5561 T167 oasc.SolrResourceLoader.locateSolrHome JNDI
> not configured for solr (NoInitialContextEx)
> [junit4:junit4]   2> 5561 T167 oasc.SolrResourceLoader.locateSolrHome using
> system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test-files\solr
> [junit4:junit4]   2> 5562 T167 oasc.SolrResourceLoader.<init> new
> SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test-files\solr\'
> [junit4:junit4]   2> 5575 T167 oasc.CoreContainer.<init> New CoreContainer
> 1601324
> [junit4:junit4]   2> 5576 T167 oasc.SolrCore.<init> [collection1] Opening new
> SolrCore at C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-
> 4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-
> 1348338241894\
> [junit4:junit4]   2> 5576 T167 oasc.SolrCore.<init> JMX monitoring not detected
> for core: collection1
> [junit4:junit4]   2> 5577 T167 oasc.SolrCore.getNewIndexDir WARNING New
> index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-
> 1348338241894\index/
> [junit4:junit4]   2> 5580 T167 oasc.RequestHandlers.initHandlersFromConfig
> created standard: solr.StandardRequestHandler
> [junit4:junit4]   2> 5581 T167 oasc.RequestHandlers.initHandlersFromConfig
> created /get: solr.RealTimeGetHandler
> [junit4:junit4]   2> 5581 T167 oasc.RequestHandlers.initHandlersFromConfig
> created /update: solr.UpdateRequestHandler
> [junit4:junit4]   2> 5582 T167 oasc.RequestHandlers.initHandlersFromConfig
> created /admin/: org.apache.solr.handler.admin.AdminHandlers
> [junit4:junit4]   2> 5584 T167 oashl.XMLLoader.init
> xsltCacheLifetimeSeconds=60
> [junit4:junit4]   2> 5588 T167 oasc.CachingDirectoryFactory.get return new
> directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\.\solrtest-TestRecovery-1348338241894\index forceNew:false
> [junit4:junit4]   2> 5591 T167 oass.SolrIndexSearcher.<init> Opening
> Searcher@1846ed7 main
> [junit4:junit4]   2> 5591 T167 oass.SolrIndexSearcher.getIndexDir WARNING
> WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 5594 T167 oasu.CommitTracker.<init> Hard AutoCommit:
> disabled
> [junit4:junit4]   2> 5595 T167 oasu.CommitTracker.<init> Soft AutoCommit:
> disabled
> [junit4:junit4]   2> 5595 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting socketTimeout to: 0
> [junit4:junit4]   2> 5596 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting urlScheme to: http://
> [junit4:junit4]   2> 5596 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting connTimeout to: 0
> [junit4:junit4]   2> 5597 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting maxConnectionsPerHost to: 20
> [junit4:junit4]   2> 5597 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting corePoolSize to: 0
> [junit4:junit4]   2> 5597 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting maximumPoolSize to: 2147483647
> [junit4:junit4]   2> 5598 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting maxThreadIdleTime to: 5
> [junit4:junit4]   2> 5598 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting sizeOfQueue to: -1
> [junit4:junit4]   2> 5599 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting fairnessPolicy to: false
> [junit4:junit4]   2> 5599 T167 oascsi.HttpClientUtil.createClient Creating new
> http client,
> config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0
> &connTimeout=0&retry=false
> [junit4:junit4]   2> 5609 T167 oasc.CoreContainer.register registering core:
> collection1
> [junit4:junit4]   2> 5610 T175 oasc.SolrCore.registerSearcher [collection1]
> Registered new searcher Searcher@1846ed7
> main{StandardDirectoryReader(segments_8:28)}
> [junit4:junit4]   2> ASYNC  NEW_CORE C19 name=collection1
> org.apache.solr.core.SolrCore@1d63abc
> [junit4:junit4]   2> 5614 T176 C19 oasu.UpdateLog$LogReplayer.doReplay
> WARNING Starting log replay tlog{file=C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-
> 1348338241894\tlog\tlog.0000000000000000008 refcount=2} active=false
> starting pos=0
> [junit4:junit4]   2> 5625 T176 C19 oasc.SolrDeletionPolicy.onInit
> SolrDeletionPolicy.onInit: commits:num=1
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@15cce4b),segFN=se
> gments_8,generation=8,filenames=[segments_8]
> [junit4:junit4]   2> 5625 T176 C19 oasc.SolrDeletionPolicy.updateCommits
> newest commit = 8
> [junit4:junit4]   2> 5628 T176 C19 oasc.SolrException.log Ignoring exception
> matching OutOfBoundsException
> [junit4:junit4]   2> 5629 T176 C19 oasu.DirectUpdateHandler2.commit start
> commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=t
> rue,expungeDeletes=false,softCommit=false}
> [junit4:junit4]   2> 5638 T176 C19 oasc.SolrDeletionPolicy.onCommit
> SolrDeletionPolicy.onCommit: commits:num=2
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@15cce4b),segFN=se
> gments_8,generation=8,filenames=[segments_8]
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@15cce4b),segFN=se
> gments_9,generation=9,filenames=[segments_9, _9.fnm, _9.fdt, _9.si, _9.fdx,
> _9.tip, _9.frq, _9.tim]
> [junit4:junit4]   2> 5639 T176 C19 oasc.SolrDeletionPolicy.updateCommits
> newest commit = 9
> [junit4:junit4]   2> 5651 T176 C19 oass.SolrIndexSearcher.<init> Opening
> Searcher@1e5e3d6 main
> [junit4:junit4]   2> 5651 T176 C19 oass.SolrIndexSearcher.getIndexDir
> WARNING WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 5652 T175 oasc.SolrCore.registerSearcher [collection1]
> Registered new searcher Searcher@1e5e3d6
> main{StandardDirectoryReader(segments_9:30 _9(4.1):C3)}
> [junit4:junit4]   2> 5652 T176 C19 oasu.DirectUpdateHandler2.commit
> end_commit_flush
> [junit4:junit4]   2> 5653 T176 C19 oasu.UpdateLog$LogReplayer.doReplay
> WARNING Starting log replay tlog{file=C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-
> 1348338241894\tlog\tlog.0000000000000000009 refcount=2} active=false
> starting pos=0
> [junit4:junit4]   2> 5656 T176 C19 oasc.SolrException.log Ignoring exception
> matching OutOfBoundsException
> [junit4:junit4]   2> 5656 T176 C19 oasu.DirectUpdateHandler2.commit start
> commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=t
> rue,expungeDeletes=false,softCommit=false}
> [junit4:junit4]   2> 5671 T176 C19 oasc.SolrDeletionPolicy.onCommit
> SolrDeletionPolicy.onCommit: commits:num=2
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@15cce4b),segFN=se
> gments_9,generation=9,filenames=[segments_9, _9.fnm, _9.fdt, _9.si, _9.fdx,
> _9.tip, _9.frq, _9.tim]
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@15cce4b),segFN=se
> gments_a,generation=10,filenames=[_a.tim, _a.tip, segments_a, _a.frq,
> _9.fnm, _9.fdt, _a.fdt, _a.si, _9.tip, _9.fdx, _a.fdx, _9.tim, _9.frq, _9.si, _a.fnm]
> [junit4:junit4]   2> 5672 T176 C19 oasc.SolrDeletionPolicy.updateCommits
> newest commit = 10
> [junit4:junit4]   2> 5684 T176 C19 oass.SolrIndexSearcher.<init> Opening
> Searcher@1dcea79 main
> [junit4:junit4]   2> 5684 T176 C19 oass.SolrIndexSearcher.getIndexDir
> WARNING WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 5685 T176 C19 oasu.DirectUpdateHandler2.commit
> end_commit_flush
> [junit4:junit4]   2> 5685 T175 oasc.SolrCore.registerSearcher [collection1]
> Registered new searcher Searcher@1dcea79
> main{StandardDirectoryReader(segments_a:32 _9(4.1):C3 _a(4.1):C3)}
> [junit4:junit4]   2> 5686 T176 C19 oasu.UpdateLog$LogReplayer.run WARNING
> Log replay finished. recoveryInfo=RecoveryInfo{adds=6 deletes=0
> deleteByQuery=0 errors=0 positionOfStart=0}
> [junit4:junit4]   2> 5687 T167 C19 REQ [collection1] webapp=null path=null
> params={q=*:*} hits=6 status=0 QTime=1
> [junit4:junit4]   2> 5687 T167 oas.SolrTestCaseJ4.tearDown ###Ending
> testRecoveryMultipleLogs
> [junit4:junit4]   2> 5693 T167 oas.SolrTestCaseJ4.setUp ###Starting
> testDropBuffered
> [junit4:junit4]   2> 5711 T167 C19 oasu.DirectUpdateHandler2.deleteAll
> [collection1] REMOVING ALL DOCUMENTS FROM INDEX
> [junit4:junit4]   2> 5715 T167 C19 oass.SolrIndexSearcher.<init> Opening
> Searcher@f1277f realtime
> [junit4:junit4]   2> 5715 T167 C19 oass.SolrIndexSearcher.getIndexDir
> WARNING WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 5717 T167 C19 REQ [collection1] webapp=null path=null
> params={_version_=-
> 9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json
> &indent=true} status=0 QTime=6
> [junit4:junit4]   2> 5721 T167 C19 oasu.DirectUpdateHandler2.commit start
> commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=t
> rue,expungeDeletes=false,softCommit=false}
> [junit4:junit4]   2> 5724 T167 C19 oasc.SolrDeletionPolicy.onCommit
> SolrDeletionPolicy.onCommit: commits:num=2
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@15cce4b),segFN=se
> gments_a,generation=10,filenames=[_a.tim, _a.tip, segments_a, _a.frq,
> _9.fnm, _9.fdt, _a.fdt, _a.si, _9.tip, _9.fdx, _a.fdx, _9.tim, _9.frq, _9.si, _a.fnm]
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@15cce4b),segFN=se
> gments_b,generation=11,filenames=[segments_b]
> [junit4:junit4]   2> 5725 T167 C19 oasc.SolrDeletionPolicy.updateCommits
> newest commit = 11
> [junit4:junit4]   2> 5735 T167 C19 oass.SolrIndexSearcher.<init> Opening
> Searcher@29b92a main
> [junit4:junit4]   2> 5736 T167 C19 oass.SolrIndexSearcher.getIndexDir
> WARNING WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 5736 T167 C19 oasu.DirectUpdateHandler2.commit
> end_commit_flush
> [junit4:junit4]   2> 5736 T175 oasc.SolrCore.registerSearcher [collection1]
> Registered new searcher Searcher@29b92a
> main{StandardDirectoryReader(segments_a:33:nrt)}
> [junit4:junit4]   2> 5739 T167 C19 UPDATE [collection1] webapp=null path=null
> params={} {commit=} 0 18
> [junit4:junit4]   2> 5756 T167 oasu.UpdateLog.bufferUpdates Starting to buffer
> updates. FSUpdateLog{state=ACTIVE, tlog=null}
> [junit4:junit4]   2> 5757 T167 oasu.UpdateLog.bufferUpdates Starting to buffer
> updates. FSUpdateLog{state=ACTIVE, tlog=null}
> [junit4:junit4]   2> 5768 T167 C19 REQ [collection1] webapp=null path=null
> params={update.distrib=FROMLEADER&wt=json&indent=true} status=0
> QTime=2
> [junit4:junit4]   2> 5773 T167 C19 REQ [collection1] webapp=null path=null
> params={update.distrib=FROMLEADER&wt=json&indent=true} status=0
> QTime=0
> [junit4:junit4]   2> 5779 T167 C19 REQ [collection1] webapp=null path=null
> params={update.distrib=FROMLEADER&wt=json&indent=true} status=0
> QTime=0
> [junit4:junit4]   2> 5780 T167 oasu.UpdateLog.dropBufferedUpdates Dropping
> buffered updates FSUpdateLog{state=BUFFERING,
> tlog=tlog{file=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\.\solrtest-TestRecovery-
> 1348338241894\tlog\tlog.0000000000000000013 refcount=1}}
> [junit4:junit4]   2> 5780 T167 oasu.UpdateLog.bufferUpdates Starting to buffer
> updates. FSUpdateLog{state=ACTIVE,
> tlog=tlog{file=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\.\solrtest-TestRecovery-
> 1348338241894\tlog\tlog.0000000000000000013 refcount=1}}
> [junit4:junit4]   2> 5790 T167 C19 REQ [collection1] webapp=null path=null
> params={update.distrib=FROMLEADER&wt=json&indent=true} status=0
> QTime=1
> [junit4:junit4]   2> 5794 T167 C19 REQ [collection1] webapp=null path=null
> params={update.distrib=FROMLEADER&wt=json&indent=true} status=0
> QTime=0
> [junit4:junit4]   2> 5795 T176 C19 oasu.UpdateLog$LogReplayer.doReplay
> WARNING Starting log replay tlog{file=C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-
> 1348338241894\tlog\tlog.0000000000000000013 refcount=2} active=true
> starting pos=0
> [junit4:junit4]   2> 5799 T176 C19 oasu.DirectUpdateHandler2.commit start
> commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=t
> rue,expungeDeletes=false,softCommit=false}
> [junit4:junit4]   2> 5815 T176 C19 oasc.SolrDeletionPolicy.onCommit
> SolrDeletionPolicy.onCommit: commits:num=2
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@15cce4b),segFN=se
> gments_b,generation=11,filenames=[segments_b]
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@15cce4b),segFN=se
> gments_c,generation=12,filenames=[_b.frq, segments_c, _b.si, _b.tim, _b.tip,
> _b.fnm, _b.fdt, _b.fdx]
> [junit4:junit4]   2> 5815 T176 C19 oasc.SolrDeletionPolicy.updateCommits
> newest commit = 12
> [junit4:junit4]   2> 5821 T176 C19 oass.SolrIndexSearcher.<init> Opening
> Searcher@1a57585 main
> [junit4:junit4]   2> 5821 T176 C19 oass.SolrIndexSearcher.getIndexDir
> WARNING WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 5822 T175 oasc.SolrCore.registerSearcher [collection1]
> Registered new searcher Searcher@1a57585
> main{StandardDirectoryReader(segments_c:35:nrt _b(4.1):C2)}
> [junit4:junit4]   2> 5823 T176 C19 oasu.DirectUpdateHandler2.commit
> end_commit_flush
> [junit4:junit4]   2> 5823 T176 C19 oasu.UpdateLog$LogReplayer.run WARNING
> Log replay finished. recoveryInfo=RecoveryInfo{adds=2 deletes=0
> deleteByQuery=0 errors=0 positionOfStart=0}
> [junit4:junit4]   2> 5824 T167 C19 REQ [collection1] webapp=null path=null
> params={getVersions=2&qt=/get} status=0 QTime=1
> [junit4:junit4]   2> 5837 T167 C19 REQ [collection1] webapp=null path=null
> params={update.distrib=FROMLEADER&wt=json&indent=true} status=0
> QTime=3
> [junit4:junit4]   2> 5843 T167 C19 REQ [collection1] webapp=null path=null
> params={update.distrib=FROMLEADER&wt=json&indent=true} status=0
> QTime=1
> [junit4:junit4]   2> 5843 T167 oasu.UpdateLog.bufferUpdates Starting to buffer
> updates. FSUpdateLog{state=ACTIVE,
> tlog=tlog{file=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\.\solrtest-TestRecovery-
> 1348338241894\tlog\tlog.0000000000000000014 refcount=1}}
> [junit4:junit4]   2> 5847 T167 C19 REQ [collection1] webapp=null path=null
> params={update.distrib=FROMLEADER&wt=json&indent=true} status=0
> QTime=0
> [junit4:junit4]   2> 5855 T167 C19 REQ [collection1] webapp=null path=null
> params={update.distrib=FROMLEADER&wt=json&indent=true} status=0
> QTime=0
> [junit4:junit4]   2> 5856 T167 oasu.UpdateLog.dropBufferedUpdates Dropping
> buffered updates FSUpdateLog{state=BUFFERING,
> tlog=tlog{file=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\.\solrtest-TestRecovery-
> 1348338241894\tlog\tlog.0000000000000000014 refcount=1}}
> [junit4:junit4]   2> 5857 T167 oasu.UpdateLog.bufferUpdates Starting to buffer
> updates. FSUpdateLog{state=ACTIVE,
> tlog=tlog{file=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\.\solrtest-TestRecovery-
> 1348338241894\tlog\tlog.0000000000000000014 refcount=1}}
> [junit4:junit4]   2> 5862 T167 C19 REQ [collection1] webapp=null path=null
> params={update.distrib=FROMLEADER&wt=json&indent=true} status=0
> QTime=0
> [junit4:junit4]   2> 5868 T167 C19 REQ [collection1] webapp=null path=null
> params={update.distrib=FROMLEADER&wt=json&indent=true} status=0
> QTime=0
> [junit4:junit4]   2> 5868 T176 C19 oasu.UpdateLog$LogReplayer.doReplay
> WARNING Starting log replay tlog{file=C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-
> 1348338241894\tlog\tlog.0000000000000000014 refcount=2} active=true
> starting pos=92
> [junit4:junit4]   2> 5870 T176 C19 oasu.DirectUpdateHandler2.commit start
> commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=t
> rue,expungeDeletes=false,softCommit=false}
> [junit4:junit4]   2> 5896 T176 C19 oasc.SolrDeletionPolicy.onCommit
> SolrDeletionPolicy.onCommit: commits:num=2
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@15cce4b),segFN=se
> gments_c,generation=12,filenames=[_b.frq, segments_c, _b.si, _b.tim, _b.tip,
> _b.fnm, _b.fdt, _b.fdx]
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@15cce4b),segFN=se
> gments_d,generation=13,filenames=[segments_d, _b.tip, _c.tim, _c.fdx, _b.frq,
> _c.fdt, _c.si, _b.si, _b.tim, _c.tip, _b.fnm, _b.fdt, _c.fnm, _c.frq, _b.fdx]
> [junit4:junit4]   2> 5897 T176 C19 oasc.SolrDeletionPolicy.updateCommits
> newest commit = 13
> [junit4:junit4]   2> 5903 T176 C19 oass.SolrIndexSearcher.<init> Opening
> Searcher@d7310d main
> [junit4:junit4]   2> 5903 T176 C19 oass.SolrIndexSearcher.getIndexDir
> WARNING WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 5904 T176 C19 oasu.DirectUpdateHandler2.commit
> end_commit_flush
> [junit4:junit4]   2> 5904 T175 oasc.SolrCore.registerSearcher [collection1]
> Registered new searcher Searcher@d7310d
> main{StandardDirectoryReader(segments_d:37:nrt _b(4.1):C2 _c(4.1):C4)}
> [junit4:junit4]   2> 5905 T176 C19 oasu.UpdateLog$LogReplayer.run WARNING
> Log replay finished. recoveryInfo=RecoveryInfo{adds=2 deletes=0
> deleteByQuery=0 errors=0 positionOfStart=92}
> [junit4:junit4]   2> 5906 T167 C19 REQ [collection1] webapp=null path=null
> params={fl=id,_version_&sort=_version_+asc&q=*:*} hits=6 status=0 QTime=1
> [junit4:junit4]   2> 5909 T167 C19 REQ [collection1] webapp=null path=null
> params={getVersions=6&qt=/get} status=0 QTime=1
> [junit4:junit4]   2> 5909 T167 oasu.UpdateLog.bufferUpdates Starting to buffer
> updates. FSUpdateLog{state=ACTIVE, tlog=null}
> [junit4:junit4]   2> 5922 T167 C19 REQ [collection1] webapp=null path=null
> params={update.distrib=FROMLEADER&wt=json&indent=true} status=0
> QTime=2
> [junit4:junit4]   2> 5926 T167 C19 REQ [collection1] webapp=null path=null
> params={update.distrib=FROMLEADER&wt=json&indent=true} status=0
> QTime=0
> [junit4:junit4]   2> 5927 T167 oasu.UpdateLog.dropBufferedUpdates Dropping
> buffered updates FSUpdateLog{state=BUFFERING,
> tlog=tlog{file=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\.\solrtest-TestRecovery-
> 1348338241894\tlog\tlog.0000000000000000015 refcount=1}}
> [junit4:junit4]   2> 5935 T167 C19 REQ [collection1] webapp=null path=null
> params={update.distrib=FROMLEADER&wt=json&indent=true} status=0
> QTime=2
> [junit4:junit4]   2> 5944 T167 C19 REQ [collection1] webapp=null path=null
> params={update.distrib=FROMLEADER&wt=json&indent=true} status=0
> QTime=1
> [junit4:junit4]   2> 5948 T167 C19 oasu.DirectUpdateHandler2.commit start
> commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=t
> rue,expungeDeletes=false,softCommit=false}
> [junit4:junit4]   2> 5958 T167 C19 oasc.SolrDeletionPolicy.onCommit
> SolrDeletionPolicy.onCommit: commits:num=2
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@15cce4b),segFN=se
> gments_d,generation=13,filenames=[segments_d, _b.tip, _c.tim, _c.fdx, _b.frq,
> _c.fdt, _c.si, _b.si, _b.tim, _c.tip, _b.fnm, _b.fdt, _c.fnm, _c.frq, _b.fdx]
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@15cce4b),segFN=se
> gments_e,generation=14,filenames=[_d.frq, _d.tim, segments_e, _b.tip, _c.tim,
> _d.fnm, _c.fdx, _d.fdx, _b.frq, _c.fdt, _d.fdt, _c.si, _b.si, _b.tim, _c.tip, _d.si,
> _b.fnm, _b.fdt, _c.fnm, _d.tip, _c.frq, _b.fdx]
> [junit4:junit4]   2> 5959 T167 C19 oasc.SolrDeletionPolicy.updateCommits
> newest commit = 14
> [junit4:junit4]   2> 5968 T167 C19 oass.SolrIndexSearcher.<init> Opening
> Searcher@322eb3 main
> [junit4:junit4]   2> 5968 T167 C19 oass.SolrIndexSearcher.getIndexDir
> WARNING WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 5969 T175 oasc.SolrCore.registerSearcher [collection1]
> Registered new searcher Searcher@322eb3
> main{StandardDirectoryReader(segments_e:39:nrt _b(4.1):C2 _c(4.1):C4
> _d(4.1):C2)}
> [junit4:junit4]   2> 5969 T167 C19 oasu.DirectUpdateHandler2.commit
> end_commit_flush
> [junit4:junit4]   2> 5970 T167 C19 UPDATE [collection1] webapp=null path=null
> params={} {commit=} 0 22
> [junit4:junit4]   2> 5977 T167 C19 REQ [collection1] webapp=null path=null
> params={getVersions=2&qt=/get} status=0 QTime=1
> [junit4:junit4]   2> 5978 T167 C19 REQ [collection1] webapp=null path=null
> params={fl=id,_version_&sort=_version_+desc&q=*:*&rows=2} hits=8 status=0
> QTime=0
> [junit4:junit4]   2> 5991 T167 C19 REQ [collection1] webapp=null path=null
> params={update.distrib=FROMLEADER&wt=json&indent=true} status=0
> QTime=5
> [junit4:junit4]   2> 5991 T167 oas.SolrTestCaseJ4.tearDown ###Ending
> testDropBuffered
> [junit4:junit4]   2> 5997 T167 oas.SolrTestCaseJ4.setUp ###Starting
> testVersionsOnRestart
> [junit4:junit4]   2> 6001 T167 C19 oasu.DirectUpdateHandler2.deleteAll
> [collection1] REMOVING ALL DOCUMENTS FROM INDEX
> [junit4:junit4]   2> 6009 T167 C19 oass.SolrIndexSearcher.<init> Opening
> Searcher@f8fff2 realtime
> [junit4:junit4]   2> 6010 T167 C19 oass.SolrIndexSearcher.getIndexDir
> WARNING WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 6010 T167 C19 REQ [collection1] webapp=null path=null
> params={_version_=-
> 9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json
> &indent=true} status=0 QTime=9
> [junit4:junit4]   2> 6018 T167 C19 oasu.DirectUpdateHandler2.commit start
> commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=t
> rue,expungeDeletes=false,softCommit=false}
> [junit4:junit4]   2> 6022 T167 C19 oasc.SolrDeletionPolicy.onCommit
> SolrDeletionPolicy.onCommit: commits:num=2
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@15cce4b),segFN=se
> gments_e,generation=14,filenames=[_d.frq, _d.tim, segments_e, _b.tip, _c.tim,
> _d.fnm, _c.fdx, _d.fdx, _b.frq, _c.fdt, _d.fdt, _c.si, _b.si, _b.tim, _c.tip, _d.si,
> _b.fnm, _b.fdt, _c.fnm, _d.tip, _c.frq, _b.fdx]
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@15cce4b),segFN=se
> gments_f,generation=15,filenames=[segments_f]
> [junit4:junit4]   2> 6022 T167 C19 oasc.SolrDeletionPolicy.updateCommits
> newest commit = 15
> [junit4:junit4]   2> 6038 T167 C19 oass.SolrIndexSearcher.<init> Opening
> Searcher@1d92053 main
> [junit4:junit4]   2> 6038 T167 C19 oass.SolrIndexSearcher.getIndexDir
> WARNING WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 6040 T175 oasc.SolrCore.registerSearcher [collection1]
> Registered new searcher Searcher@1d92053
> main{StandardDirectoryReader(segments_e:41:nrt)}
> [junit4:junit4]   2> 6040 T167 C19 oasu.DirectUpdateHandler2.commit
> end_commit_flush
> [junit4:junit4]   2> 6050 T167 C19 UPDATE [collection1] webapp=null path=null
> params={} {commit=} 0 32
> [junit4:junit4]   2> 6066 T167 C19 UPDATE [collection1] webapp=null path=null
> params={} {add=[D1 (1413835126685564928)]} 0 5
> [junit4:junit4]   2> 6072 T167 C19 UPDATE [collection1] webapp=null path=null
> params={} {add=[D2 (1413835126696050688)]} 0 1
> [junit4:junit4]   2> 6076 T167 C19 oasu.DirectUpdateHandler2.commit start
> commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=t
> rue,expungeDeletes=false,softCommit=false}
> [junit4:junit4]   2> 6093 T167 C19 oasc.SolrDeletionPolicy.onCommit
> SolrDeletionPolicy.onCommit: commits:num=2
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@15cce4b),segFN=se
> gments_f,generation=15,filenames=[segments_f]
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@15cce4b),segFN=se
> gments_g,generation=16,filenames=[_f.fdx, _f.tip, _f.fnm, _f.tim, _f.frq,
> segments_g, _f.si, _f.fdt]
> [junit4:junit4]   2> 6094 T167 C19 oasc.SolrDeletionPolicy.updateCommits
> newest commit = 16
> [junit4:junit4]   2> 6101 T167 C19 oass.SolrIndexSearcher.<init> Opening
> Searcher@bb68b4 main
> [junit4:junit4]   2> 6102 T167 C19 oass.SolrIndexSearcher.getIndexDir
> WARNING WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 6102 T175 oasc.SolrCore.registerSearcher [collection1]
> Registered new searcher Searcher@bb68b4
> main{StandardDirectoryReader(segments_g:43:nrt _f(4.1):C2)}
> [junit4:junit4]   2> 6104 T167 C19 oasu.DirectUpdateHandler2.commit
> end_commit_flush
> [junit4:junit4]   2> 6105 T167 C19 UPDATE [collection1] webapp=null path=null
> params={} {commit=} 0 29
> [junit4:junit4]   2> 6112 T167 C19 REQ [collection1] webapp=null path=null
> params={q=id:D1} hits=1 status=0 QTime=1
> [junit4:junit4]   2> 6113 T167 C19 REQ [collection1] webapp=null path=null
> params={q=id:D2} hits=1 status=0 QTime=1
> [junit4:junit4]   2> 6113 T167 oasc.CoreContainer.shutdown Shutting down
> CoreContainer instance=1601324
> [junit4:junit4]   2> 6113 T167 oasc.SolrCore.close [collection1]  CLOSING
> SolrCore org.apache.solr.core.SolrCore@1d63abc
> [junit4:junit4]   2> 6114 T167 oasc.SolrCore.closeSearcher [collection1] Closing
> main searcher on request.
> [junit4:junit4]   2> 6114 T167 oasu.DirectUpdateHandler2.close closing
> DirectUpdateHandler2{commits=8,autocommits=0,soft
> autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,add
> s=0,deletesById=0,deletesByQuery=0,errors=2,cumulative_adds=17,cumulative
> _deletesById=0,cumulative_deletesByQuery=2,cumulative_errors=2}
> [junit4:junit4]   2> 6115 T167 oasu.DefaultSolrCoreState.decref SolrCoreState
> ref count has reached 0 - closing IndexWriter
> [junit4:junit4]   2> 6126 T167 oasu.DefaultSolrCoreState.decref Closing
> SolrCoreState - canceling any ongoing recovery
> [junit4:junit4]   2> 6126 T167 oasc.SolrResourceLoader.<init> new
> SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test-files\solr\collection1\'
> [junit4:junit4]   2> 6129 T167 oasc.SolrResourceLoader.replaceClassLoader
> Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-
> core/test-files/solr/collection1/lib/classes/' to classloader
> [junit4:junit4]   2> 6130 T167 oasc.SolrResourceLoader.replaceClassLoader
> Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-
> core/test-files/solr/collection1/lib/README' to classloader
> [junit4:junit4]   2> 6207 T167 oasc.SolrConfig.<init> Using Lucene
> MatchVersion: LUCENE_41
> [junit4:junit4]   2> 6282 T167 oasc.SolrConfig.<init> Loaded SolrConfig:
> solrconfig-tlog.xml
> [junit4:junit4]   2> 6283 T167 oass.IndexSchema.readSchema Reading Solr
> Schema
> [junit4:junit4]   2> 6289 T167 oass.IndexSchema.readSchema Schema
> name=test
> [junit4:junit4]   2> -60908 T167 oass.IndexSchema.readSchema default search
> field in schema is text
> [junit4:junit4]   2> -60902 T167 oass.IndexSchema.readSchema unique key field:
> id
> [junit4:junit4]   2> -60886 T167 oasc.SolrResourceLoader.locateSolrHome JNDI
> not configured for solr (NoInitialContextEx)
> [junit4:junit4]   2> -60886 T167 oasc.SolrResourceLoader.locateSolrHome using
> system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test-files\solr
> [junit4:junit4]   2> -60886 T167 oasc.SolrResourceLoader.<init> new
> SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test-files\solr\'
> [junit4:junit4]   2> -60856 T167 oasc.CoreContainer.<init> New CoreContainer
> 3655432
> [junit4:junit4]   2> -60856 T167 oasc.SolrCore.<init> [collection1] Opening new
> SolrCore at C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-
> 4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-
> 1348338241894\
> [junit4:junit4]   2> -60855 T167 oasc.SolrCore.<init> JMX monitoring not
> detected for core: collection1
> [junit4:junit4]   2> -60853 T167 oasc.SolrCore.getNewIndexDir WARNING New
> index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-
> 1348338241894\index/
> [junit4:junit4]   2> -60851 T167 oasc.RequestHandlers.initHandlersFromConfig
> created standard: solr.StandardRequestHandler
> [junit4:junit4]   2> -60851 T167 oasc.RequestHandlers.initHandlersFromConfig
> created /get: solr.RealTimeGetHandler
> [junit4:junit4]   2> -60850 T167 oasc.RequestHandlers.initHandlersFromConfig
> created /update: solr.UpdateRequestHandler
> [junit4:junit4]   2> -60850 T167 oasc.RequestHandlers.initHandlersFromConfig
> created /admin/: org.apache.solr.handler.admin.AdminHandlers
> [junit4:junit4]   2> -60845 T167 oashl.XMLLoader.init
> xsltCacheLifetimeSeconds=60
> [junit4:junit4]   2> -60837 T167 oasc.CachingDirectoryFactory.get return new
> directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\.\solrtest-TestRecovery-1348338241894\index forceNew:false
> [junit4:junit4]   2> -60831 T167 oass.SolrIndexSearcher.<init> Opening
> Searcher@152e336 main
> [junit4:junit4]   2> -60831 T167 oass.SolrIndexSearcher.getIndexDir WARNING
> WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> -60822 T167 oasu.CommitTracker.<init> Hard AutoCommit:
> disabled
> [junit4:junit4]   2> -60821 T167 oasu.CommitTracker.<init> Soft AutoCommit:
> disabled
> [junit4:junit4]   2> -60820 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting socketTimeout to: 0
> [junit4:junit4]   2> -60820 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting urlScheme to: http://
> [junit4:junit4]   2> -60819 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting connTimeout to: 0
> [junit4:junit4]   2> -60819 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting maxConnectionsPerHost to: 20
> [junit4:junit4]   2> -60819 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting corePoolSize to: 0
> [junit4:junit4]   2> -60818 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting maximumPoolSize to: 2147483647
> [junit4:junit4]   2> -60818 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting maxThreadIdleTime to: 5
> [junit4:junit4]   2> -60818 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting sizeOfQueue to: -1
> [junit4:junit4]   2> -60817 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting fairnessPolicy to: false
> [junit4:junit4]   2> -60817 T167 oascsi.HttpClientUtil.createClient Creating new
> http client,
> config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0
> &connTimeout=0&retry=false
> [junit4:junit4]   2> -60808 T177 oasc.SolrCore.registerSearcher [collection1]
> Registered new searcher Searcher@152e336
> main{StandardDirectoryReader(segments_g:43 _f(4.1):C2)}
> [junit4:junit4]   2> -60808 T167 oasc.CoreContainer.register registering core:
> collection1
> [junit4:junit4]   2> ASYNC  NEW_CORE C20 name=collection1
> org.apache.solr.core.SolrCore@1ce4acd
> [junit4:junit4]   2> -60795 T167 C20 oasc.SolrDeletionPolicy.onInit
> SolrDeletionPolicy.onInit: commits:num=1
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@d8fd10),segFN=seg
> ments_g,generation=16,filenames=[_f.fdx, _f.tip, _f.fnm, _f.tim, _f.frq,
> segments_g, _f.fdt, _f.si]
> [junit4:junit4]   2> -60795 T167 C20 oasc.SolrDeletionPolicy.updateCommits
> newest commit = 16
> [junit4:junit4]   2> -60795 T167 C20 oasu.DirectUpdateHandler2.addDoc
> Reordered DBQs detected.
> Update=add{flags=0,_version_=1413835056576724992,id=D1}
> DBQs=[DBQ{version=1413835121836949504,q=id:A11}]
> [junit4:junit4]   2> -60783 T167 C20 oass.SolrIndexSearcher.<init> Opening
> Searcher@26d81c realtime
> [junit4:junit4]   2> -60783 T167 C20 oass.SolrIndexSearcher.getIndexDir
> WARNING WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> -60782 T167 C20 UPDATE [collection1] webapp=null
> path=null params={} {add=[D1 (1413835056576724992)]} 0 18
> [junit4:junit4]   2> -60768 T167 C20 oasu.DirectUpdateHandler2.commit start
> commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=t
> rue,expungeDeletes=false,softCommit=false}
> [junit4:junit4]   2> -60762 T167 C20 oasc.SolrDeletionPolicy.onCommit
> SolrDeletionPolicy.onCommit: commits:num=2
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@d8fd10),segFN=seg
> ments_g,generation=16,filenames=[_f.fdx, _f.tip, _f.fnm, _f.tim, _f.frq,
> segments_g, _f.fdt, _f.si]
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@d8fd10),segFN=seg
> ments_h,generation=17,filenames=[_g.fdt, _g.si, _f.tip, _g.fdx, _f.tim, _g.tim,
> _g.tip, _g.frq, segments_h, _f.si, _f.fdt, _f.fdx, _g.fnm, _f_1.del, _f.fnm, _f.frq]
> [junit4:junit4]   2> -60761 T167 C20 oasc.SolrDeletionPolicy.updateCommits
> newest commit = 17
> [junit4:junit4]   2> -60758 T167 C20 oass.SolrIndexSearcher.<init> Opening
> Searcher@f1a478 main
> [junit4:junit4]   2> -60758 T167 C20 oass.SolrIndexSearcher.getIndexDir
> WARNING WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> -60757 T177 oasc.SolrCore.registerSearcher [collection1]
> Registered new searcher Searcher@f1a478
> main{StandardDirectoryReader(segments_g:46:nrt _f(4.1):C2/1 _g(4.1):C1)}
> [junit4:junit4]   2> -60754 T167 C20 oasu.DirectUpdateHandler2.commit
> end_commit_flush
> [junit4:junit4]   2> -60754 T167 C20 UPDATE [collection1] webapp=null
> path=null params={} {commit=} 0 14
> [junit4:junit4]   2> -60745 T167 C20 REQ [collection1] webapp=null path=null
> params={q=id:D1} hits=1 status=0 QTime=1
> [junit4:junit4]   2> -60744 T167 oas.SolrTestCaseJ4.tearDown ###Ending
> testVersionsOnRestart
> [junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestRecovery -
> Dtests.method=testVersionsOnRestart -Dtests.seed=810F1DF62273E988 -
> Dtests.slow=true -Dtests.locale=hi_IN -Dtests.timezone=America/Cordoba -
> Dtests.file.encoding=UTF-8
> [junit4:junit4] FAILURE -66.64s | TestRecovery.testVersionsOnRestart <<<
> [junit4:junit4]    > Throwable #1: java.lang.AssertionError
> [junit4:junit4]    > 	at
> __randomizedtesting.SeedInfo.seed([810F1DF62273E988:E1A13D885D2D3907]:
> 0)
> [junit4:junit4]    > 	at
> org.apache.solr.search.TestRecovery.testVersionsOnRestart(TestRecovery.java:
> 611)
> [junit4:junit4]    > 	at
> sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> [junit4:junit4]    > 	at
> sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:
> 57)
> [junit4:junit4]    > 	at
> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorI
> mpl.java:43)
> [junit4:junit4]    > 	at java.lang.reflect.Method.invoke(Method.java:601)
> [junit4:junit4]    > 	at
> com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRu
> nner.java:1559)
> [junit4:junit4]    > 	at
> com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(Randomiz
> edRunner.java:79)
> [junit4:junit4]    > 	at
> com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Randomiz
> edRunner.java:737)
> [junit4:junit4]    > 	at
> com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Randomiz
> edRunner.java:773)
> [junit4:junit4]    > 	at
> com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Randomiz
> edRunner.java:787)
> [junit4:junit4]    > 	at
> com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.eval
> uate(SystemPropertiesRestoreRule.java:53)
> [junit4:junit4]    > 	at
> org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSet
> upTeardownChained.java:50)
> [junit4:junit4]    > 	at
> org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCach
> eSanity.java:51)
> [junit4:junit4]    > 	at
> org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfte
> rRule.java:45)
> [junit4:junit4]    > 	at
> com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.ev
> aluate(SystemPropertiesInvariantRule.java:55)
> [junit4:junit4]    > 	at
> org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThrea
> dAndTestName.java:48)
> [junit4:junit4]    > 	at
> org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgn
> oreAfterMaxFailures.java:70)
> [junit4:junit4]    > 	at
> org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.ja
> va:48)
> [junit4:junit4]    > 	at
> com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(Stateme
> ntAdapter.java:36)
> [junit4:junit4]    > 	at
> com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run
> (ThreadLeakControl.java:358)
> [junit4:junit4]    > 	at
> com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(T
> hreadLeakControl.java:782)
> [junit4:junit4]    > 	at
> com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLea
> kControl.java:442)
> [junit4:junit4]    > 	at
> com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(Rando
> mizedRunner.java:746)
> [junit4:junit4]    > 	at
> com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(Randomiz
> edRunner.java:648)
> [junit4:junit4]    > 	at
> com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(Randomiz
> edRunner.java:682)
> [junit4:junit4]    > 	at
> com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(Randomiz
> edRunner.java:693)
> [junit4:junit4]    > 	at
> com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(Stateme
> ntAdapter.java:36)
> [junit4:junit4]    > 	at
> com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.eval
> uate(SystemPropertiesRestoreRule.java:53)
> [junit4:junit4]    > 	at
> org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfte
> rRule.java:45)
> [junit4:junit4]    > 	at
> org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClass
> Name.java:42)
> [junit4:junit4]    > 	at
> com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.ev
> aluate(SystemPropertiesInvariantRule.java:55)
> [junit4:junit4]    > 	at
> com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethod
> sRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
> [junit4:junit4]    > 	at
> com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethod
> sRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
> [junit4:junit4]    > 	at
> com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(Stateme
> ntAdapter.java:36)
> [junit4:junit4]    > 	at
> org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertio
> nsRequired.java:43)
> [junit4:junit4]    > 	at
> org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.ja
> va:48)
> [junit4:junit4]    > 	at
> org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgn
> oreAfterMaxFailures.java:70)
> [junit4:junit4]    > 	at
> org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTest
> Suites.java:55)
> [junit4:junit4]    > 	at
> com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(Stateme
> ntAdapter.java:36)
> [junit4:junit4]    > 	at
> com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run
> (ThreadLeakControl.java:358)
> [junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:722)
> [junit4:junit4]   2> -60642 T167 oas.SolrTestCaseJ4.setUp ###Starting
> testCleanShutdown
> [junit4:junit4]   2> -60632 T167 C20 oasu.DirectUpdateHandler2.deleteAll
> [collection1] REMOVING ALL DOCUMENTS FROM INDEX
> [junit4:junit4]   2> -60628 T167 C20 oass.SolrIndexSearcher.<init> Opening
> Searcher@2c500d realtime
> [junit4:junit4]   2> -60627 T167 C20 oass.SolrIndexSearcher.getIndexDir
> WARNING WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> -60627 T167 C20 REQ [collection1] webapp=null path=null
> params={_version_=-
> 9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json
> &indent=true} status=0 QTime=6
> [junit4:junit4]   2> -60621 T167 C20 oasu.DirectUpdateHandler2.commit start
> commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=t
> rue,expungeDeletes=false,softCommit=false}
> [junit4:junit4]   2> -60618 T167 C20 oasc.SolrDeletionPolicy.onCommit
> SolrDeletionPolicy.onCommit: commits:num=2
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@d8fd10),segFN=seg
> ments_h,generation=17,filenames=[_g.fdt, _g.si, _f.tip, _g.fdx, _f.tim, _g.tim,
> _g.tip, _g.frq, segments_h, _f.si, _f.fdt, _f.fdx, _g.fnm, _f_1.del, _f.fnm, _f.frq]
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@d8fd10),segFN=seg
> ments_i,generation=18,filenames=[segments_i]
> [junit4:junit4]   2> -60617 T167 C20 oasc.SolrDeletionPolicy.updateCommits
> newest commit = 18
> [junit4:junit4]   2> -60601 T167 C20 oass.SolrIndexSearcher.<init> Opening
> Searcher@fc38af main
> [junit4:junit4]   2> -60601 T167 C20 oass.SolrIndexSearcher.getIndexDir
> WARNING WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> -60601 T167 C20 oasu.DirectUpdateHandler2.commit
> end_commit_flush
> [junit4:junit4]   2> -60601 T177 oasc.SolrCore.registerSearcher [collection1]
> Registered new searcher Searcher@fc38af
> main{StandardDirectoryReader(segments_h:47:nrt)}
> [junit4:junit4]   2> -60592 T167 C20 UPDATE [collection1] webapp=null
> path=null params={} {commit=} 0 29
> [junit4:junit4]   2> -60573 T167 C20 UPDATE [collection1] webapp=null
> path=null params={} {add=[E1 (1413835056808460288)]} 0 6
> [junit4:junit4]   2> -60564 T167 C20 UPDATE [collection1] webapp=null
> path=null params={} {add=[E2 (1413835056823140352)]} 0 1
> [junit4:junit4]   2> -60562 T167 oasc.CoreContainer.shutdown Shutting down
> CoreContainer instance=3655432
> [junit4:junit4]   2> -60561 T167 oasc.SolrCore.close [collection1]  CLOSING
> SolrCore org.apache.solr.core.SolrCore@1ce4acd
> [junit4:junit4]   2> -60560 T167 oasc.SolrCore.closeSearcher [collection1]
> Closing main searcher on request.
> [junit4:junit4]   2> -60560 T167 oasu.DirectUpdateHandler2.close closing
> DirectUpdateHandler2{commits=2,autocommits=0,soft
> autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=2,add
> s=2,deletesById=0,deletesByQuery=0,errors=1,cumulative_adds=3,cumulative_
> deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=1}
> [junit4:junit4]   2> -60559 T167 oasu.DefaultSolrCoreState.decref SolrCoreState
> ref count has reached 0 - closing IndexWriter
> [junit4:junit4]   2> -60548 T167 C20 oasc.SolrDeletionPolicy.onCommit
> SolrDeletionPolicy.onCommit: commits:num=2
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@d8fd10),segFN=seg
> ments_i,generation=18,filenames=[segments_i]
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@d8fd10),segFN=seg
> ments_j,generation=19,filenames=[_h.tip, _h.fnm, _h.fdt, _h.tim, _h.si, _h.fdx,
> _h.frq, segments_j]
> [junit4:junit4]   2> -60548 T167 C20 oasc.SolrDeletionPolicy.updateCommits
> newest commit = 19
> [junit4:junit4]   2> -60533 T167 oasu.DefaultSolrCoreState.decref Closing
> SolrCoreState - canceling any ongoing recovery
> [junit4:junit4]   2> -60532 T167 oasc.SolrResourceLoader.<init> new
> SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test-files\solr\collection1\'
> [junit4:junit4]   2> -60528 T167 oasc.SolrResourceLoader.replaceClassLoader
> Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-
> core/test-files/solr/collection1/lib/classes/' to classloader
> [junit4:junit4]   2> -60526 T167 oasc.SolrResourceLoader.replaceClassLoader
> Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-
> core/test-files/solr/collection1/lib/README' to classloader
> [junit4:junit4]   2> -60430 T167 oasc.SolrConfig.<init> Using Lucene
> MatchVersion: LUCENE_41
> [junit4:junit4]   2> -60361 T167 oasc.SolrConfig.<init> Loaded SolrConfig:
> solrconfig-tlog.xml
> [junit4:junit4]   2> -60361 T167 oass.IndexSchema.readSchema Reading Solr
> Schema
> [junit4:junit4]   2> -60351 T167 oass.IndexSchema.readSchema Schema
> name=test
> [junit4:junit4]   2> -59687 T167 oass.IndexSchema.readSchema default search
> field in schema is text
> [junit4:junit4]   2> -59683 T167 oass.IndexSchema.readSchema unique key field:
> id
> [junit4:junit4]   2> -59663 T167 oasc.SolrResourceLoader.locateSolrHome JNDI
> not configured for solr (NoInitialContextEx)
> [junit4:junit4]   2> -59663 T167 oasc.SolrResourceLoader.locateSolrHome using
> system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test-files\solr
> [junit4:junit4]   2> -59663 T167 oasc.SolrResourceLoader.<init> new
> SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test-files\solr\'
> [junit4:junit4]   2> -59644 T167 oasc.CoreContainer.<init> New CoreContainer
> 27080284
> [junit4:junit4]   2> -59643 T167 oasc.SolrCore.<init> [collection1] Opening new
> SolrCore at C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-
> 4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-
> 1348338241894\
> [junit4:junit4]   2> -59643 T167 oasc.SolrCore.<init> JMX monitoring not
> detected for core: collection1
> [junit4:junit4]   2> -59642 T167 oasc.SolrCore.getNewIndexDir WARNING New
> index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-
> 1348338241894\index/
> [junit4:junit4]   2> -59641 T167 oasc.RequestHandlers.initHandlersFromConfig
> created standard: solr.StandardRequestHandler
> [junit4:junit4]   2> -59640 T167 oasc.RequestHandlers.initHandlersFromConfig
> created /get: solr.RealTimeGetHandler
> [junit4:junit4]   2> -59639 T167 oasc.RequestHandlers.initHandlersFromConfig
> created /update: solr.UpdateRequestHandler
> [junit4:junit4]   2> -59639 T167 oasc.RequestHandlers.initHandlersFromConfig
> created /admin/: org.apache.solr.handler.admin.AdminHandlers
> [junit4:junit4]   2> -59636 T167 oashl.XMLLoader.init
> xsltCacheLifetimeSeconds=60
> [junit4:junit4]   2> -59632 T167 oasc.CachingDirectoryFactory.get return new
> directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\.\solrtest-TestRecovery-1348338241894\index forceNew:false
> [junit4:junit4]   2> -59629 T167 oass.SolrIndexSearcher.<init> Opening
> Searcher@c724a9 main
> [junit4:junit4]   2> -59629 T167 oass.SolrIndexSearcher.getIndexDir WARNING
> WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> -59622 T167 oasu.CommitTracker.<init> Hard AutoCommit:
> disabled
> [junit4:junit4]   2> -59621 T167 oasu.CommitTracker.<init> Soft AutoCommit:
> disabled
> [junit4:junit4]   2> -59621 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting socketTimeout to: 0
> [junit4:junit4]   2> -59621 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting urlScheme to: http://
> [junit4:junit4]   2> -59620 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting connTimeout to: 0
> [junit4:junit4]   2> -59620 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting maxConnectionsPerHost to: 20
> [junit4:junit4]   2> -59619 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting corePoolSize to: 0
> [junit4:junit4]   2> -59618 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting maximumPoolSize to: 2147483647
> [junit4:junit4]   2> -59618 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting maxThreadIdleTime to: 5
> [junit4:junit4]   2> -59617 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting sizeOfQueue to: -1
> [junit4:junit4]   2> -59617 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting fairnessPolicy to: false
> [junit4:junit4]   2> -59616 T167 oascsi.HttpClientUtil.createClient Creating new
> http client,
> config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0
> &connTimeout=0&retry=false
> [junit4:junit4]   2> -59601 T167 oasc.CoreContainer.register registering core:
> collection1
> [junit4:junit4]   2> -59599 T178 oasc.SolrCore.registerSearcher [collection1]
> Registered new searcher Searcher@c724a9
> main{StandardDirectoryReader(segments_j:49 _h(4.1):C2)}
> [junit4:junit4]   2> ASYNC  NEW_CORE C21 name=collection1
> org.apache.solr.core.SolrCore@1c4a072
> [junit4:junit4]   2> -59595 T167 C21 REQ [collection1] webapp=null path=null
> params={q=*:*} hits=2 status=0 QTime=5
> [junit4:junit4]   2> -59595 T167 oas.SolrTestCaseJ4.tearDown ###Ending
> testCleanShutdown
> [junit4:junit4]   2> -59587 T167 oas.SolrTestCaseJ4.setUp ###Starting
> testRemoveOldLogs
> [junit4:junit4]   2> -59579 T167 C21 oasu.DirectUpdateHandler2.deleteAll
> [collection1] REMOVING ALL DOCUMENTS FROM INDEX
> [junit4:junit4]   2> -59573 T167 C21 oasc.SolrDeletionPolicy.onInit
> SolrDeletionPolicy.onInit: commits:num=1
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirecto
> ry@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@13d82c),segFN=seg
> ments_j,generation=19,filenames=[_h.tip, _h.fnm, _h.fdt, _h.tim, _h.fdx, _h.si,
> _h.frq, segments_j]
> [junit4:junit4]   2> -59572 T167 C21 oasc.SolrDeletionPolicy.updateCommits
> newest commit = 19
> [junit4:junit4]   2> -59570 T167 C21 oass.SolrIndexSearcher.<init> Opening
> Searcher@1218c0c realtime
> [junit4:junit4]   2> -59570 T167 C21 oass.SolrIndexSearcher.getIndexDir
> WARNING WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> -59570 T167 C21 REQ [collection1] webapp=null path=null
> params={_version_=-
> 9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json
> &indent=true} status=0 QTime=9
> [junit4:junit4]   2> -59566 T167 C21 oasu.DirectUpdateHandler2.commit start
> commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=t
> rue,expungeDeletes=false,softCommit=false}
> [junit4:junit4]   2> -59563 T167 C21 oasc.SolrDeletionPolicy.onCommit
> SolrDeletionPolicy.onCommit: commits:num=2
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirecto
> ry@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@13d82c),segFN=seg
> ments_j,generation=19,filenames=[_h.tip, _h.fnm, _h.fdt, _h.tim, _h.fdx, _h.si,
> _h.frq, segments_j]
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirecto
> ry@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@13d82c),segFN=seg
> ments_k,generation=20,filenames=[segments_k]
> [junit4:junit4]   2> -59562 T167 C21 oasc.SolrDeletionPolicy.updateCommits
> newest commit = 20
> [junit4:junit4]   2> -59558 T167 C21 oass.SolrIndexSearcher.<init> Opening
> Searcher@1163b88 main
> [junit4:junit4]   2> -59558 T167 C21 oass.SolrIndexSearcher.getIndexDir
> WARNING WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> -59557 T167 C21 oasu.DirectUpdateHandler2.commit
> end_commit_flush
> [junit4:junit4]   2> -59557 T178 oasc.SolrCore.registerSearcher [collection1]
> Registered new searcher Searcher@1163b88
> main{StandardDirectoryReader(segments_j:50:nrt)}
> [junit4:junit4]   2> -59537 T167 C21 UPDATE [collection1] webapp=null
> path=null params={} {commit=} 0 29
> [junit4:junit4]   2> -59516 T167 oasc.CoreContainer.shutdown Shutting down
> CoreContainer instance=27080284
> [junit4:junit4]   2> -59515 T167 oasc.SolrCore.close [collection1]  CLOSING
> SolrCore org.apache.solr.core.SolrCore@1c4a072
> [junit4:junit4]   2> -59469 T167 oasc.SolrCore.closeSearcher [collection1]
> Closing main searcher on request.
> [junit4:junit4]   2> -59464 T167 oasu.DirectUpdateHandler2.close closing
> DirectUpdateHandler2{commits=1,autocommits=0,soft
> autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,add
> s=0,deletesById=0,deletesByQuery=0,errors=1,cumulative_adds=0,cumulative_
> deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=1}
> [junit4:junit4]   2> -59464 T167 oasu.DefaultSolrCoreState.decref SolrCoreState
> ref count has reached 0 - closing IndexWriter
> [junit4:junit4]   2> -59457 T167 oasu.DefaultSolrCoreState.decref Closing
> SolrCoreState - canceling any ongoing recovery
> [junit4:junit4]   2> -59438 T167 oasc.SolrResourceLoader.<init> new
> SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test-files\solr\collection1\'
> [junit4:junit4]   2> -59434 T167 oasc.SolrResourceLoader.replaceClassLoader
> Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-
> core/test-files/solr/collection1/lib/classes/' to classloader
> [junit4:junit4]   2> -59432 T167 oasc.SolrResourceLoader.replaceClassLoader
> Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-
> core/test-files/solr/collection1/lib/README' to classloader
> [junit4:junit4]   2> -59355 T167 oasc.SolrConfig.<init> Using Lucene
> MatchVersion: LUCENE_41
> [junit4:junit4]   2> -59295 T167 oasc.SolrConfig.<init> Loaded SolrConfig:
> solrconfig-tlog.xml
> [junit4:junit4]   2> -59295 T167 oass.IndexSchema.readSchema Reading Solr
> Schema
> [junit4:junit4]   2> -59289 T167 oass.IndexSchema.readSchema Schema
> name=test
> [junit4:junit4]   2> -58667 T167 oass.IndexSchema.readSchema default search
> field in schema is text
> [junit4:junit4]   2> -58663 T167 oass.IndexSchema.readSchema unique key field:
> id
> [junit4:junit4]   2> -58647 T167 oasc.SolrResourceLoader.locateSolrHome JNDI
> not configured for solr (NoInitialContextEx)
> [junit4:junit4]   2> -58647 T167 oasc.SolrResourceLoader.locateSolrHome using
> system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test-files\solr
> [junit4:junit4]   2> -58647 T167 oasc.SolrResourceLoader.<init> new
> SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test-files\solr\'
> [junit4:junit4]   2> -58632 T167 oasc.CoreContainer.<init> New CoreContainer
> 9609700
> [junit4:junit4]   2> -58631 T167 oasc.SolrCore.<init> [collection1] Opening new
> SolrCore at C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-
> 4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-
> 1348338241894\
> [junit4:junit4]   2> -58631 T167 oasc.SolrCore.<init> JMX monitoring not
> detected for core: collection1
> [junit4:junit4]   2> -58629 T167 oasc.SolrCore.getNewIndexDir WARNING New
> index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-
> 1348338241894\index/
> [junit4:junit4]   2> -58627 T167 oasc.RequestHandlers.initHandlersFromConfig
> created standard: solr.StandardRequestHandler
> [junit4:junit4]   2> -58627 T167 oasc.RequestHandlers.initHandlersFromConfig
> created /get: solr.RealTimeGetHandler
> [junit4:junit4]   2> -58627 T167 oasc.RequestHandlers.initHandlersFromConfig
> created /update: solr.UpdateRequestHandler
> [junit4:junit4]   2> -58626 T167 oasc.RequestHandlers.initHandlersFromConfig
> created /admin/: org.apache.solr.handler.admin.AdminHandlers
> [junit4:junit4]   2> -58623 T167 oashl.XMLLoader.init
> xsltCacheLifetimeSeconds=60
> [junit4:junit4]   2> -58616 T167 oasc.CachingDirectoryFactory.get return new
> directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\.\solrtest-TestRecovery-1348338241894\index forceNew:false
> [junit4:junit4]   2> -58614 T167 oass.SolrIndexSearcher.<init> Opening
> Searcher@39101d main
> [junit4:junit4]   2> -58613 T167 oass.SolrIndexSearcher.getIndexDir WARNING
> WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> -58613 T167 oasu.CommitTracker.<init> Hard AutoCommit:
> disabled
> [junit4:junit4]   2> -58612 T167 oasu.CommitTracker.<init> Soft AutoCommit:
> disabled
> [junit4:junit4]   2> -58612 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting socketTimeout to: 0
> [junit4:junit4]   2> -58612 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting urlScheme to: http://
> [junit4:junit4]   2> -58611 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting connTimeout to: 0
> [junit4:junit4]   2> -58611 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting maxConnectionsPerHost to: 20
> [junit4:junit4]   2> -58610 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting corePoolSize to: 0
> [junit4:junit4]   2> -58610 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting maximumPoolSize to: 2147483647
> [junit4:junit4]   2> -58610 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting maxThreadIdleTime to: 5
> [junit4:junit4]   2> -58609 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting sizeOfQueue to: -1
> [junit4:junit4]   2> -58609 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting fairnessPolicy to: false
> [junit4:junit4]   2> -58608 T167 oascsi.HttpClientUtil.createClient Creating new
> http client,
> config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0
> &connTimeout=0&retry=false
> [junit4:junit4]   2> -58595 T179 oasc.SolrCore.registerSearcher [collection1]
> Registered new searcher Searcher@39101d
> main{StandardDirectoryReader(segments_k:50)}
> [junit4:junit4]   2> -58595 T167 oasc.CoreContainer.register registering core:
> collection1
> [junit4:junit4]   2> ASYNC  NEW_CORE C22 name=collection1
> org.apache.solr.core.SolrCore@13617fc
> [junit4:junit4]   2> -58585 T167 C22 oasc.SolrDeletionPolicy.onInit
> SolrDeletionPolicy.onInit: commits:num=1
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirecto
> ry@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@13b6833),segFN=se
> gments_k,generation=20,filenames=[segments_k]
> [junit4:junit4]   2> -58584 T167 C22 oasc.SolrDeletionPolicy.updateCommits
> newest commit = 20
> [junit4:junit4]   2> -58582 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[10
> (1413835058896175104)]} 0 6
> [junit4:junit4]   2> -58576 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[10
> (1413835058908758016)]} 0 1
> [junit4:junit4]   2> -58571 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[10
> (1413835058914000896)]} 0 0
> [junit4:junit4]   2> -58563 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[10
> (1413835058922389504)]} 0 0
> [junit4:junit4]   2> -58559 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[10
> (1413835058926583808)]} 0 0
> [junit4:junit4]   2> -58553 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[10
> (1413835058931826688)]} 0 1
> [junit4:junit4]   2> -58548 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[10
> (1413835058938118144)]} 0 0
> [junit4:junit4]   2> -58542 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[10
> (1413835058943361024)]} 0 1
> [junit4:junit4]   2> -58535 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[10
> (1413835058951749632)]} 0 0
> [junit4:junit4]   2> -58528 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[10
> (1413835058959089664)]} 0 0
> [junit4:junit4]   2> -58527 T167 C22 REQ [collection1] webapp=null path=null
> params={getVersions=50&qt=/get} status=0 QTime=0
> [junit4:junit4]   2> -58520 T167 C22 oasu.DirectUpdateHandler2.commit start
> commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=t
> rue,expungeDeletes=false,softCommit=false}
> [junit4:junit4]   2> -58512 T167 C22 oasc.SolrDeletionPolicy.onCommit
> SolrDeletionPolicy.onCommit: commits:num=2
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirecto
> ry@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@13b6833),segFN=se
> gments_k,generation=20,filenames=[segments_k]
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirecto
> ry@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@13b6833),segFN=se
> gments_l,generation=21,filenames=[_i.frq, _i_1.del, _i.fdx, segments_l, _i.fnm,
> _i.si, _i.fdt, _i.tip, _i.tim]
> [junit4:junit4]   2> -58512 T167 C22 oasc.SolrDeletionPolicy.updateCommits
> newest commit = 21
> [junit4:junit4]   2> -58502 T167 C22 oass.SolrIndexSearcher.<init> Opening
> Searcher@4d5ad7 main
> [junit4:junit4]   2> -58502 T167 C22 oass.SolrIndexSearcher.getIndexDir
> WARNING WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> -58501 T179 oasc.SolrCore.registerSearcher [collection1]
> Registered new searcher Searcher@4d5ad7
> main{StandardDirectoryReader(segments_l:52 _i(4.1):C10/9)}
> [junit4:junit4]   2> -58501 T167 C22 oasu.DirectUpdateHandler2.commit
> end_commit_flush
> [junit4:junit4]   2> -58500 T167 C22 UPDATE [collection1] webapp=null
> path=null params={} {commit=} 0 20
> [junit4:junit4]   2> -58483 T167 C22 REQ [collection1] webapp=null path=null
> params={getVersions=50&qt=/get} status=0 QTime=0
> [junit4:junit4]   2> -58474 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[20
> (1413835059013615616)]} 0 2
> [junit4:junit4]   2> -58468 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[20
> (1413835059020955648)]} 0 1
> [junit4:junit4]   2> -58462 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[20
> (1413835059027247104)]} 0 1
> [junit4:junit4]   2> -58457 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[20
> (1413835059033538560)]} 0 0
> [junit4:junit4]   2> -58449 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[20
> (1413835059041927168)]} 0 0
> [junit4:junit4]   2> -58443 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[20
> (1413835059047170048)]} 0 1
> [junit4:junit4]   2> -58438 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[20
> (1413835059053461504)]} 0 0
> [junit4:junit4]   2> -58434 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[20
> (1413835059057655808)]} 0 0
> [junit4:junit4]   2> -58428 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[20
> (1413835059063947264)]} 0 1
> [junit4:junit4]   2> -58422 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[20
> (1413835059070238720)]} 0 1
> [junit4:junit4]   2> -58421 T167 C22 REQ [collection1] webapp=null path=null
> params={getVersions=50&qt=/get} status=0 QTime=1
> [junit4:junit4]   2> -58414 T167 C22 oasu.DirectUpdateHandler2.commit start
> commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=t
> rue,expungeDeletes=false,softCommit=false}
> [junit4:junit4]   2> -58400 T167 C22 oasc.SolrDeletionPolicy.onCommit
> SolrDeletionPolicy.onCommit: commits:num=2
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirecto
> ry@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@13b6833),segFN=se
> gments_l,generation=21,filenames=[_i.frq, _i_1.del, _i.fdx, segments_l, _i.fnm,
> _i.si, _i.fdt, _i.tip, _i.tim]
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirecto
> ry@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@13b6833),segFN=se
> gments_m,generation=22,filenames=[_j.fdx, _j.si, _j_1.del, _j.fdt, _i.frq,
> segments_m, _i.si, _j.tip, _j.fnm, _i_1.del, _i.fdx, _j.frq, _j.tim, _i.fnm, _i.tip,
> _i.fdt, _i.tim]
> [junit4:junit4]   2> -58400 T167 C22 oasc.SolrDeletionPolicy.updateCommits
> newest commit = 22
> [junit4:junit4]   2> -58386 T167 C22 oass.SolrIndexSearcher.<init> Opening
> Searcher@1ed2cd1 main
> [junit4:junit4]   2> -58386 T167 C22 oass.SolrIndexSearcher.getIndexDir
> WARNING WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> -58384 T179 oasc.SolrCore.registerSearcher [collection1]
> Registered new searcher Searcher@1ed2cd1
> main{StandardDirectoryReader(segments_m:54 _i(4.1):C10/9 _j(4.1):C10/9)}
> [junit4:junit4]   2> -58384 T167 C22 oasu.DirectUpdateHandler2.commit
> end_commit_flush
> [junit4:junit4]   2> -58383 T167 C22 UPDATE [collection1] webapp=null
> path=null params={} {commit=} 0 31
> [junit4:junit4]   2> -58376 T167 C22 REQ [collection1] webapp=null path=null
> params={getVersions=50&qt=/get} status=0 QTime=0
> [junit4:junit4]   2> -58364 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059127910400)]} 0 3
> [junit4:junit4]   2> -58358 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059136299008)]} 0 1
> [junit4:junit4]   2> -58353 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059141541888)]} 0 1
> [junit4:junit4]   2> -58349 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059146784768)]} 0 0
> [junit4:junit4]   2> -58344 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059152027648)]} 0 0
> [junit4:junit4]   2> -58335 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059161464832)]} 0 0
> [junit4:junit4]   2> -58330 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059166707712)]} 0 0
> [junit4:junit4]   2> -58324 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059172999168)]} 0 1
> [junit4:junit4]   2> -58319 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059178242048)]} 0 0
> [junit4:junit4]   2> -58314 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059183484928)]} 0 0
> [junit4:junit4]   2> -58309 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059188727808)]} 0 0
> [junit4:junit4]   2> -58302 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059196067840)]} 0 0
> [junit4:junit4]   2> -58296 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059201310720)]} 0 1
> [junit4:junit4]   2> -58290 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059207602176)]} 0 1
> [junit4:junit4]   2> -58283 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059215990784)]} 0 0
> [junit4:junit4]   2> -58277 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059221233664)]} 0 1
> [junit4:junit4]   2> -58272 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059226476544)]} 0 1
> [junit4:junit4]   2> -58265 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059234865152)]} 0 0
> [junit4:junit4]   2> -58260 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059240108032)]} 0 0
> [junit4:junit4]   2> -58255 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059245350912)]} 0 0
> [junit4:junit4]   2> -58249 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059250593792)]} 0 1
> [junit4:junit4]   2> -58244 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059256885248)]} 0 1
> [junit4:junit4]   2> -58236 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059263176704)]} 0 2
> [junit4:junit4]   2> -58230 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059271565312)]} 0 0
> [junit4:junit4]   2> -58220 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059282051072)]} 0 0
> [junit4:junit4]   2> -58212 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059290439680)]} 0 1
> [junit4:junit4]   2> -58205 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059297779712)]} 0 0
> [junit4:junit4]   2> -58198 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059305119744)]} 0 0
> [junit4:junit4]   2> -58190 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059313508352)]} 0 0
> [junit4:junit4]   2> -58184 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059319799808)]} 0 0
> [junit4:junit4]   2> -58175 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059329236992)]} 0 0
> [junit4:junit4]   2> -58169 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059334479872)]} 0 1
> [junit4:junit4]   2> -58164 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059340771328)]} 0 0
> [junit4:junit4]   2> -58159 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059346014208)]} 0 0
> [junit4:junit4]   2> -58154 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059351257088)]} 0 0
> [junit4:junit4]   2> -58148 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059357548544)]} 0 0
> [junit4:junit4]   2> -58142 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059362791424)]} 0 1
> [junit4:junit4]   2> -58136 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059370131456)]} 0 0
> [junit4:junit4]   2> -58130 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059375374336)]} 0 1
> [junit4:junit4]   2> -58125 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059380617216)]} 0 1
> [junit4:junit4]   2> -58120 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059386908672)]} 0 0
> [junit4:junit4]   2> -58115 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059392151552)]} 0 0
> [junit4:junit4]   2> -58110 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059397394432)]} 0 0
> [junit4:junit4]   2> -58103 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059404734464)]} 0 0
> [junit4:junit4]   2> -58099 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059408928768)]} 0 0
> [junit4:junit4]   2> -58094 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059414171648)]} 0 0
> [junit4:junit4]   2> -58089 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059419414528)]} 0 0
> [junit4:junit4]   2> -58083 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059425705984)]} 0 0
> [junit4:junit4]   2> -58078 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059430948864)]} 0 0
> [junit4:junit4]   2> -58073 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059436191744)]} 0 0
> [junit4:junit4]   2> -58066 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059443531776)]} 0 0
> [junit4:junit4]   2> -58061 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059448774656)]} 0 0
> [junit4:junit4]   2> -58057 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059452968960)]} 0 0
> [junit4:junit4]   2> -58052 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059458211840)]} 0 0
> [junit4:junit4]   2> -58046 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059463454720)]} 0 1
> [junit4:junit4]   2> -58041 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059469746176)]} 0 1
> [junit4:junit4]   2> -58034 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059477086208)]} 0 0
> [junit4:junit4]   2> -58028 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059483377664)]} 0 0
> [junit4:junit4]   2> -58023 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059488620544)]} 0 0
> [junit4:junit4]   2> -58018 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059493863424)]} 0 0
> [junit4:junit4]   2> -58014 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059498057728)]} 0 0
> [junit4:junit4]   2> -58009 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059503300608)]} 0 0
> [junit4:junit4]   2> -58000 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059512737792)]} 0 1
> [junit4:junit4]   2> -57995 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059517980672)]} 0 0
> [junit4:junit4]   2> -57989 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059523223552)]} 0 1
> [junit4:junit4]   2> -57984 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059529515008)]} 0 0
> [junit4:junit4]   2> -57979 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059534757888)]} 0 0
> [junit4:junit4]   2> -57975 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059538952192)]} 0 0
> [junit4:junit4]   2> -57970 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059544195072)]} 0 1
> [junit4:junit4]   2> -57964 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059550486528)]} 0 0
> [junit4:junit4]   2> -57959 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059555729408)]} 0 0
> [junit4:junit4]   2> -57954 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059560972288)]} 0 0
> [junit4:junit4]   2> -57949 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059566215168)]} 0 0
> [junit4:junit4]   2> -57943 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059572506624)]} 0 0
> [junit4:junit4]   2> -57934 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059580895232)]} 0 1
> [junit4:junit4]   2> -57926 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059590332416)]} 0 0
> [junit4:junit4]   2> -57921 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059595575296)]} 0 0
> [junit4:junit4]   2> -57915 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059601866752)]} 0 0
> [junit4:junit4]   2> -57910 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059606061056)]} 0 1
> [junit4:junit4]   2> -57905 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059612352512)]} 0 0
> [junit4:junit4]   2> -57900 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059617595392)]} 0 0
> [junit4:junit4]   2> -57895 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059622838272)]} 0 0
> [junit4:junit4]   2> -57888 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059629129728)]} 0 1
> [junit4:junit4]   2> -57882 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059635421184)]} 0 1
> [junit4:junit4]   2> -57876 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059641712640)]} 0 1
> [junit4:junit4]   2> -57871 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059648004096)]} 0 0
> [junit4:junit4]   2> -57866 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059653246976)]} 0 0
> [junit4:junit4]   2> -57861 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059658489856)]} 0 0
> [junit4:junit4]   2> -57854 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059665829888)]} 0 0
> [junit4:junit4]   2> -57849 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059671072768)]} 0 0
> [junit4:junit4]   2> -57844 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059675267072)]} 0 1
> [junit4:junit4]   2> -57839 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059681558528)]} 0 0
> [junit4:junit4]   2> -57834 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059686801408)]} 0 0
> [junit4:junit4]   2> -57828 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059693092864)]} 0 1
> [junit4:junit4]   2> -57821 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059700432896)]} 0 1
> [junit4:junit4]   2> -57816 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059705675776)]} 0 0
> [junit4:junit4]   2> -57810 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059711967232)]} 0 1
> [junit4:junit4]   2> -57804 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059718258688)]} 0 0
> [junit4:junit4]   2> -57798 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059724550144)]} 0 0
> [junit4:junit4]   2> -57792 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059729793024)]} 0 1
> [junit4:junit4]   2> -57787 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059736084480)]} 0 1
> [junit4:junit4]   2> -57779 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059744473088)]} 0 0
> [junit4:junit4]   2> -57774 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059748667392)]} 0 1
> [junit4:junit4]   2> -57769 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059754958848)]} 0 0
> [junit4:junit4]   2> -57765 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[125
> (1413835059759153152)]} 0 0
> [junit4:junit4]   2> -57763 T167 C22 REQ [collection1] webapp=null path=null
> params={getVersions=50&qt=/get} status=0 QTime=1
> [junit4:junit4]   2> -57758 T167 C22 oasu.DirectUpdateHandler2.commit start
> commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=t
> rue,expungeDeletes=false,softCommit=false}
> [junit4:junit4]   2> -57739 T167 C22 oasc.SolrDeletionPolicy.onCommit
> SolrDeletionPolicy.onCommit: commits:num=2
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirecto
> ry@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@13b6833),segFN=se
> gments_m,generation=22,filenames=[_j.fdx, _j.si, _j_1.del, _j.fdt, _i.frq,
> segments_m, _i.si, _j.tip, _j.fnm, _i_1.del, _i.fdx, _j.frq, _j.tim, _i.fnm, _i.tip,
> _i.fdt, _i.tim]
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirecto
> ry@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@13b6833),segFN=se
> gments_n,generation=23,filenames=[_j.fdx, _j.fdt, _k.fdt, _k.fdx, _k.fnm, _i.si,
> _k.tim, _i_1.del, _i.fdx, _k_1.del, _k.tip, _k.si, _i.fdt, _i.tip, _i.tim, _j_1.del, _j.si,
> _i.frq, segments_n, _j.tip, _j.fnm, _k.frq, _i.fnm, _j.tim, _j.frq]
> [junit4:junit4]   2> -57738 T167 C22 oasc.SolrDeletionPolicy.updateCommits
> newest commit = 23
> [junit4:junit4]   2> -57726 T167 C22 oass.SolrIndexSearcher.<init> Opening
> Searcher@155509e main
> [junit4:junit4]   2> -57726 T167 C22 oass.SolrIndexSearcher.getIndexDir
> WARNING WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> -57726 T179 oasc.SolrCore.registerSearcher [collection1]
> Registered new searcher Searcher@155509e
> main{StandardDirectoryReader(segments_n:56 _i(4.1):C10/9 _j(4.1):C10/9
> _k(4.1):C105/104)}
> [junit4:junit4]   2> -57723 T167 C22 oasu.DirectUpdateHandler2.commit
> end_commit_flush
> [junit4:junit4]   2> -57723 T167 C22 UPDATE [collection1] webapp=null
> path=null params={} {commit=} 0 35
> [junit4:junit4]   2> -57716 T167 C22 REQ [collection1] webapp=null path=null
> params={getVersions=50&qt=/get} status=0 QTime=1
> [junit4:junit4]   2> -57698 T167 C22 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[126
> (1413835059825213440)]} 0 4
> [junit4:junit4]   2> -57698 T167 oasc.CoreContainer.shutdown Shutting down
> CoreContainer instance=9609700
> [junit4:junit4]   2> -57697 T167 oasc.SolrCore.close [collection1]  CLOSING
> SolrCore org.apache.solr.core.SolrCore@13617fc
> [junit4:junit4]   2> -57697 T167 oasc.SolrCore.closeSearcher [collection1]
> Closing main searcher on request.
> [junit4:junit4]   2> -57696 T167 oasu.DirectUpdateHandler2.close closing
> DirectUpdateHandler2{commits=3,autocommits=0,soft
> autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=1,add
> s=1,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=126,cumulativ
> e_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
> [junit4:junit4]   2> -57696 T167 oasu.DefaultSolrCoreState.decref SolrCoreState
> ref count has reached 0 - closing IndexWriter
> [junit4:junit4]   2> -57652 T167 oasu.DefaultSolrCoreState.decref Closing
> SolrCoreState - canceling any ongoing recovery
> [junit4:junit4]   2> -57652 T167 oasc.SolrResourceLoader.<init> new
> SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test-files\solr\collection1\'
> [junit4:junit4]   2> -57648 T167 oasc.SolrResourceLoader.replaceClassLoader
> Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-
> core/test-files/solr/collection1/lib/classes/' to classloader
> [junit4:junit4]   2> -57646 T167 oasc.SolrResourceLoader.replaceClassLoader
> Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-
> core/test-files/solr/collection1/lib/README' to classloader
> [junit4:junit4]   2> -57569 T167 oasc.SolrConfig.<init> Using Lucene
> MatchVersion: LUCENE_41
> [junit4:junit4]   2> -57501 T167 oasc.SolrConfig.<init> Loaded SolrConfig:
> solrconfig-tlog.xml
> [junit4:junit4]   2> -57500 T167 oass.IndexSchema.readSchema Reading Solr
> Schema
> [junit4:junit4]   2> -57494 T167 oass.IndexSchema.readSchema Schema
> name=test
> [junit4:junit4]   2> -56870 T167 oass.IndexSchema.readSchema default search
> field in schema is text
> [junit4:junit4]   2> -56867 T167 oass.IndexSchema.readSchema unique key field:
> id
> [junit4:junit4]   2> -56852 T167 oasc.SolrResourceLoader.locateSolrHome JNDI
> not configured for solr (NoInitialContextEx)
> [junit4:junit4]   2> -56852 T167 oasc.SolrResourceLoader.locateSolrHome using
> system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test-files\solr
> [junit4:junit4]   2> -56851 T167 oasc.SolrResourceLoader.<init> new
> SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test-files\solr\'
> [junit4:junit4]   2> -56837 T167 oasc.CoreContainer.<init> New CoreContainer
> 21877345
> [junit4:junit4]   2> -56836 T167 oasc.SolrCore.<init> [collection1] Opening new
> SolrCore at C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-
> 4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-
> 1348338241894\
> [junit4:junit4]   2> -56836 T167 oasc.SolrCore.<init> JMX monitoring not
> detected for core: collection1
> [junit4:junit4]   2> -56835 T167 oasc.SolrCore.getNewIndexDir WARNING New
> index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-
> 1348338241894\index/
> [junit4:junit4]   2> -56832 T167 oasc.RequestHandlers.initHandlersFromConfig
> created standard: solr.StandardRequestHandler
> [junit4:junit4]   2> -56832 T167 oasc.RequestHandlers.initHandlersFromConfig
> created /get: solr.RealTimeGetHandler
> [junit4:junit4]   2> -56831 T167 oasc.RequestHandlers.initHandlersFromConfig
> created /update: solr.UpdateRequestHandler
> [junit4:junit4]   2> -56831 T167 oasc.RequestHandlers.initHandlersFromConfig
> created /admin/: org.apache.solr.handler.admin.AdminHandlers
> [junit4:junit4]   2> -56829 T167 oashl.XMLLoader.init
> xsltCacheLifetimeSeconds=60
> [junit4:junit4]   2> -56825 T167 oasc.CachingDirectoryFactory.get return new
> directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\.\solrtest-TestRecovery-1348338241894\index forceNew:false
> [junit4:junit4]   2> -56815 T167 oass.SolrIndexSearcher.<init> Opening
> Searcher@af7908 main
> [junit4:junit4]   2> -56814 T167 oass.SolrIndexSearcher.getIndexDir WARNING
> WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> -56811 T167 oasu.CommitTracker.<init> Hard AutoCommit:
> disabled
> [junit4:junit4]   2> -56810 T167 oasu.CommitTracker.<init> Soft AutoCommit:
> disabled
> [junit4:junit4]   2> -56810 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting socketTimeout to: 0
> [junit4:junit4]   2> -56810 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting urlScheme to: http://
> [junit4:junit4]   2> -56810 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting connTimeout to: 0
> [junit4:junit4]   2> -56809 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting maxConnectionsPerHost to: 20
> [junit4:junit4]   2> -56809 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting corePoolSize to: 0
> [junit4:junit4]   2> -56809 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting maximumPoolSize to: 2147483647
> [junit4:junit4]   2> -56808 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting maxThreadIdleTime to: 5
> [junit4:junit4]   2> -56808 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting sizeOfQueue to: -1
> [junit4:junit4]   2> -56808 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting fairnessPolicy to: false
> [junit4:junit4]   2> -56807 T167 oascsi.HttpClientUtil.createClient Creating new
> http client,
> config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0
> &connTimeout=0&retry=false
> [junit4:junit4]   2> -56797 T180 oasc.SolrCore.registerSearcher [collection1]
> Registered new searcher Searcher@af7908
> main{StandardDirectoryReader(segments_n:56 _i(4.1):C10/9 _j(4.1):C10/9
> _k(4.1):C105/104)}
> [junit4:junit4]   2> -56797 T167 oasc.CoreContainer.register registering core:
> collection1
> [junit4:junit4]   2> ASYNC  NEW_CORE C23 name=collection1
> org.apache.solr.core.SolrCore@1828e92
> [junit4:junit4]   2> -56795 T181 C23 oasu.UpdateLog$LogReplayer.doReplay
> WARNING Starting log replay tlog{file=C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-
> 1348338241894\tlog\tlog.0000000000000000003 refcount=2} active=false
> starting pos=0
> [junit4:junit4]   2> -56793 T167 C23 REQ [collection1] webapp=null path=null
> params={getVersions=50&qt=/get} status=0 QTime=1
> [junit4:junit4]   2> -56781 T181 C23 oasc.SolrDeletionPolicy.onInit
> SolrDeletionPolicy.onInit: commits:num=1
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@492e9d),segFN=seg
> ments_n,generation=23,filenames=[_j.fdx, _j.fdt, _k.fdt, _k.fdx, _k.fnm, _i.si,
> _k.tim, _i_1.del, _i.fdx, _k_1.del, _k.tip, _k.si, _i.tip, _i.fdt, _i.tim, _j_1.del, _j.si,
> _i.frq, segments_n, _j.tip, _j.fnm, _k.frq, _i.fnm, _j.frq, _j.tim]
> [junit4:junit4]   2> -56780 T181 C23 oasc.SolrDeletionPolicy.updateCommits
> newest commit = 23
> [junit4:junit4]   2> -56778 T181 C23 oasu.DirectUpdateHandler2.commit start
> commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=t
> rue,expungeDeletes=false,softCommit=false}
> [junit4:junit4]   2> -56747 T181 C23 oasc.SolrDeletionPolicy.onCommit
> SolrDeletionPolicy.onCommit: commits:num=2
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@492e9d),segFN=seg
> ments_n,generation=23,filenames=[_j.fdx, _j.fdt, _k.fdt, _k.fdx, _k.fnm, _i.si,
> _k.tim, _i_1.del, _i.fdx, _k_1.del, _k.tip, _k.si, _i.tip, _i.fdt, _i.tim, _j_1.del, _j.si,
> _i.frq, segments_n, _j.tip, _j.fnm, _k.frq, _i.fnm, _j.frq, _j.tim]
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@492e9d),segFN=seg
> ments_o,generation=24,filenames=[_j.fdx, _l.fnm, _j.fdt, _l.si, _k.fdt, _k.fdx,
> _l.frq, _k.fnm, _i.si, _l.tip, _k.tim, _l.tim, _i_1.del, _i.fdx, _k_1.del, _k.tip, _k.si,
> _i.tip, _i.fdt, _i.tim, _j_1.del, _j.si, _i.frq, _j.tip, _j.fnm, _l.fdx, segments_o,
> _l.fdt, _k.frq, _i.fnm, _j.frq, _j.tim]
> [junit4:junit4]   2> -56746 T181 C23 oasc.SolrDeletionPolicy.updateCommits
> newest commit = 24
> [junit4:junit4]   2> -56734 T181 C23 oass.SolrIndexSearcher.<init> Opening
> Searcher@620819 main
> [junit4:junit4]   2> -56733 T181 C23 oass.SolrIndexSearcher.getIndexDir
> WARNING WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> -56733 T181 C23 oasu.DirectUpdateHandler2.commit
> end_commit_flush
> [junit4:junit4]   2> -56733 T180 oasc.SolrCore.registerSearcher [collection1]
> Registered new searcher Searcher@620819
> main{StandardDirectoryReader(segments_o:58 _i(4.1):C10/9 _j(4.1):C10/9
> _k(4.1):C105/104 _l(4.1):C1)}
> [junit4:junit4]   2> -56732 T181 C23 oasu.UpdateLog$LogReplayer.run
> WARNING Log replay finished. recoveryInfo=RecoveryInfo{adds=1 deletes=0
> deleteByQuery=0 errors=0 positionOfStart=0}
> [junit4:junit4]   2> -56731 T167 C23 REQ [collection1] webapp=null path=null
> params={getVersions=50&qt=/get} status=0 QTime=1
> [junit4:junit4]   2> -56715 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835060857012224)]} 0 3
> [junit4:junit4]   2> -56710 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835060865400832)]} 0 0
> [junit4:junit4]   2> -56704 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835060870643712)]} 0 1
> [junit4:junit4]   2> -56697 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835060877983744)]} 0 1
> [junit4:junit4]   2> -56692 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835060884275200)]} 0 0
> [junit4:junit4]   2> -56685 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835060890566656)]} 0 1
> [junit4:junit4]   2> -56676 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835060900003840)]} 0 1
> [junit4:junit4]   2> -56666 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835060910489600)]} 0 1
> [junit4:junit4]   2> -56657 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835060919926784)]} 0 1
> [junit4:junit4]   2> -56649 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835060929363968)]} 0 0
> [junit4:junit4]   2> -56644 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835060934606848)]} 0 0
> [junit4:junit4]   2> -56640 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835060938801152)]} 0 0
> [junit4:junit4]   2> -56633 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835060946141184)]} 0 0
> [junit4:junit4]   2> -56622 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835060956626944)]} 0 1
> [junit4:junit4]   2> -56614 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835060966064128)]} 0 0
> [junit4:junit4]   2> -56608 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835060971307008)]} 0 1
> [junit4:junit4]   2> -56603 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835060977598464)]} 0 0
> [junit4:junit4]   2> -56597 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835060982841344)]} 0 1
> [junit4:junit4]   2> -56589 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835060992278528)]} 0 0
> [junit4:junit4]   2> -56579 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061001715712)]} 0 1
> [junit4:junit4]   2> -56572 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061010104320)]} 0 0
> [junit4:junit4]   2> -56564 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061018492928)]} 0 0
> [junit4:junit4]   2> -56557 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061024784384)]} 0 1
> [junit4:junit4]   2> -56549 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061034221568)]} 0 0
> [junit4:junit4]   2> -56544 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061039464448)]} 0 0
> [junit4:junit4]   2> -56539 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061044707328)]} 0 0
> [junit4:junit4]   2> -56532 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061052047360)]} 0 0
> [junit4:junit4]   2> -56528 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061056241664)]} 0 0
> [junit4:junit4]   2> -56521 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061063581696)]} 0 0
> [junit4:junit4]   2> -56517 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061067776000)]} 0 0
> [junit4:junit4]   2> -56512 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061073018880)]} 0 1
> [junit4:junit4]   2> -56507 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061077213184)]} 0 1
> [junit4:junit4]   2> -56500 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061085601792)]} 0 0
> [junit4:junit4]   2> -56494 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061091893248)]} 0 0
> [junit4:junit4]   2> -56489 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061097136128)]} 0 0
> [junit4:junit4]   2> -56484 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061102379008)]} 0 0
> [junit4:junit4]   2> -56479 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061107621888)]} 0 0
> [junit4:junit4]   2> -56474 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061112864768)]} 0 0
> [junit4:junit4]   2> -56467 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061120204800)]} 0 1
> [junit4:junit4]   2> -56462 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061125447680)]} 0 0
> [junit4:junit4]   2> -56456 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061130690560)]} 0 1
> [junit4:junit4]   2> -56451 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061135933440)]} 0 0
> [junit4:junit4]   2> -56446 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061142224896)]} 0 0
> [junit4:junit4]   2> -56440 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061147467776)]} 0 1
> [junit4:junit4]   2> -56436 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061152710656)]} 0 0
> [junit4:junit4]   2> -56429 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061160050688)]} 0 0
> [junit4:junit4]   2> -56423 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061166342144)]} 0 0
> [junit4:junit4]   2> -56418 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061171585024)]} 0 0
> [junit4:junit4]   2> -56412 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061176827904)]} 0 1
> [junit4:junit4]   2> -56407 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061183119360)]} 0 0
> [junit4:junit4]   2> -56402 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061188362240)]} 0 0
> [junit4:junit4]   2> -56394 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061195702272)]} 0 1
> [junit4:junit4]   2> -56390 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061200945152)]} 0 1
> [junit4:junit4]   2> -56385 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061206188032)]} 0 0
> [junit4:junit4]   2> -56379 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061211430912)]} 0 1
> [junit4:junit4]   2> -56374 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061217722368)]} 0 0
> [junit4:junit4]   2> -56369 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061222965248)]} 0 0
> [junit4:junit4]   2> -56363 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061228208128)]} 0 1
> [junit4:junit4]   2> -56356 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061235548160)]} 0 1
> [junit4:junit4]   2> -56351 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061241839616)]} 0 0
> [junit4:junit4]   2> -56347 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061246033920)]} 0 0
> [junit4:junit4]   2> -56341 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061252325376)]} 0 0
> [junit4:junit4]   2> -56334 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061258616832)]} 0 1
> [junit4:junit4]   2> -56329 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061264908288)]} 0 0
> [junit4:junit4]   2> -56321 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061273296896)]} 0 0
> [junit4:junit4]   2> -56315 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061279588352)]} 0 0
> [junit4:junit4]   2> -56309 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061285879808)]} 0 0
> [junit4:junit4]   2> -56304 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061290074112)]} 0 1
> [junit4:junit4]   2> -56299 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061296365568)]} 0 0
> [junit4:junit4]   2> -56294 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061301608448)]} 0 0
> [junit4:junit4]   2> -56286 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061309997056)]} 0 1
> [junit4:junit4]   2> -56281 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061315239936)]} 0 0
> [junit4:junit4]   2> -56276 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061320482816)]} 0 0
> [junit4:junit4]   2> -56270 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061325725696)]} 0 1
> [junit4:junit4]   2> -56267 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061329920000)]} 0 0
> [junit4:junit4]   2> -56261 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061336211456)]} 0 0
> [junit4:junit4]   2> -56256 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061341454336)]} 0 0
> [junit4:junit4]   2> -56248 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061349842944)]} 0 2
> [junit4:junit4]   2> -56242 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061356134400)]} 0 0
> [junit4:junit4]   2> -56237 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061361377280)]} 0 0
> [junit4:junit4]   2> -56231 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061367668736)]} 0 0
> [junit4:junit4]   2> -56226 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061372911616)]} 0 0
> [junit4:junit4]   2> -56221 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061378154496)]} 0 0
> [junit4:junit4]   2> -56213 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061386543104)]} 0 0
> [junit4:junit4]   2> -56207 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061391785984)]} 0 1
> [junit4:junit4]   2> -56201 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061398077440)]} 0 1
> [junit4:junit4]   2> -56195 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061404368896)]} 0 1
> [junit4:junit4]   2> -56190 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061410660352)]} 0 1
> [junit4:junit4]   2> -56185 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061415903232)]} 0 0
> [junit4:junit4]   2> -56181 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061420097536)]} 0 0
> [junit4:junit4]   2> -56172 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061428486144)]} 0 1
> [junit4:junit4]   2> -56167 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061434777600)]} 0 0
> [junit4:junit4]   2> -56161 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061441069056)]} 0 0
> [junit4:junit4]   2> -56155 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061446311936)]} 0 1
> [junit4:junit4]   2> -56150 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061452603392)]} 0 0
> [junit4:junit4]   2> -56145 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061457846272)]} 0 0
> [junit4:junit4]   2> -56138 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061464137728)]} 0 1
> [junit4:junit4]   2> -56133 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061470429184)]} 0 0
> [junit4:junit4]   2> -56127 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061475672064)]} 0 1
> [junit4:junit4]   2> -56122 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061481963520)]} 0 1
> [junit4:junit4]   2> -56117 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061487206400)]} 0 0
> [junit4:junit4]   2> -56112 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061492449280)]} 0 0
> [junit4:junit4]   2> -56106 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061497692160)]} 0 1
> [junit4:junit4]   2> -56100 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061505032192)]} 0 0
> [junit4:junit4]   2> -56094 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[231
> (1413835061510275072)]} 0 1
> [junit4:junit4]   2> -56092 T167 C23 REQ [collection1] webapp=null path=null
> params={getVersions=50&qt=/get} status=0 QTime=1
> [junit4:junit4]   2> -56087 T167 C23 oasu.DirectUpdateHandler2.commit start
> commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=t
> rue,expungeDeletes=false,softCommit=false}
> [junit4:junit4]   2> -56066 T167 C23 oasc.SolrDeletionPolicy.onCommit
> SolrDeletionPolicy.onCommit: commits:num=2
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@492e9d),segFN=seg
> ments_o,generation=24,filenames=[_j.fdx, _l.fnm, _j.fdt, _l.si, _k.fdt, _k.fdx,
> _l.frq, _k.fnm, _i.si, _l.tip, _k.tim, _l.tim, _i_1.del, _i.fdx, _k_1.del, _k.tip, _k.si,
> _i.tip, _i.fdt, _i.tim, _j_1.del, _j.si, _i.frq, _j.tip, _j.fnm, _l.fdx, segments_o,
> _l.fdt, _k.frq, _i.fnm, _j.frq, _j.tim]
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@492e9d),segFN=seg
> ments_p,generation=25,filenames=[_j.fdx, _l.fnm, _j.fdt, _l.si, _k.fdt, _m.frq,
> _k.fdx, _m_1.del, _l.frq, _k.fnm, _i.si, _m.si, _m.tip, _l.tip, _k.tim, _m.tim,
> _l.tim, _i_1.del, _i.fdx, _k_1.del, _k.tip, _m.fnm, _k.si, _i.tip, _i.fdt, _i.tim,
> _j_1.del, _j.si, _i.frq, _j.tip, _m.fdt, _j.fnm, _l.fdx, segments_p, _m.fdx, _l.fdt,
> _k.frq, _i.fnm, _j.frq, _j.tim]
> [junit4:junit4]   2> -56064 T167 C23 oasc.SolrDeletionPolicy.updateCommits
> newest commit = 25
> [junit4:junit4]   2> -56049 T167 C23 oass.SolrIndexSearcher.<init> Opening
> Searcher@1020bd4 main
> [junit4:junit4]   2> -56048 T167 C23 oass.SolrIndexSearcher.getIndexDir
> WARNING WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> -56048 T180 oasc.SolrCore.registerSearcher [collection1]
> Registered new searcher Searcher@1020bd4
> main{StandardDirectoryReader(segments_p:60 _i(4.1):C10/9 _j(4.1):C10/9
> _k(4.1):C105/104 _l(4.1):C1 _m(4.1):C105/104)}
> [junit4:junit4]   2> -56045 T167 C23 oasu.DirectUpdateHandler2.commit
> end_commit_flush
> [junit4:junit4]   2> -56045 T167 C23 UPDATE [collection1] webapp=null
> path=null params={} {commit=} 0 42
> [junit4:junit4]   2> -56027 T167 C23 REQ [collection1] webapp=null path=null
> params={getVersions=50&qt=/get} status=0 QTime=1
> [junit4:junit4]   2> -56014 T167 C23 UPDATE [collection1] webapp=null
> path=null params={versions=true&wt=json&indent=true} {add=[232
> (1413835061591015424)]} 0 4
> [junit4:junit4]   2> -56014 T167 oasc.CoreContainer.shutdown Shutting down
> CoreContainer instance=21877345
> [junit4:junit4]   2> -56014 T167 oasc.SolrCore.close [collection1]  CLOSING
> SolrCore org.apache.solr.core.SolrCore@1828e92
> [junit4:junit4]   2> -56013 T167 oasc.SolrCore.closeSearcher [collection1]
> Closing main searcher on request.
> [junit4:junit4]   2> -56013 T167 oasu.DirectUpdateHandler2.close closing
> DirectUpdateHandler2{commits=2,autocommits=0,soft
> autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=1,add
> s=1,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=107,cumulativ
> e_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
> [junit4:junit4]   2> -56012 T167 oasu.DefaultSolrCoreState.decref SolrCoreState
> ref count has reached 0 - closing IndexWriter
> [junit4:junit4]   2> -55949 T167 oasu.DefaultSolrCoreState.decref Closing
> SolrCoreState - canceling any ongoing recovery
> [junit4:junit4]   2> -55945 T167 oasc.SolrResourceLoader.<init> new
> SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test-files\solr\collection1\'
> [junit4:junit4]   2> -55942 T167 oasc.SolrResourceLoader.replaceClassLoader
> Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-
> core/test-files/solr/collection1/lib/classes/' to classloader
> [junit4:junit4]   2> -55938 T167 oasc.SolrResourceLoader.replaceClassLoader
> Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-
> core/test-files/solr/collection1/lib/README' to classloader
> [junit4:junit4]   2> -55874 T167 oasc.SolrConfig.<init> Using Lucene
> MatchVersion: LUCENE_41
> [junit4:junit4]   2> -55819 T167 oasc.SolrConfig.<init> Loaded SolrConfig:
> solrconfig-tlog.xml
> [junit4:junit4]   2> -55818 T167 oass.IndexSchema.readSchema Reading Solr
> Schema
> [junit4:junit4]   2> -55812 T167 oass.IndexSchema.readSchema Schema
> name=test
> [junit4:junit4]   2> -55169 T167 oass.IndexSchema.readSchema default search
> field in schema is text
> [junit4:junit4]   2> -55164 T167 oass.IndexSchema.readSchema unique key field:
> id
> [junit4:junit4]   2> -55149 T167 oasc.SolrResourceLoader.locateSolrHome JNDI
> not configured for solr (NoInitialContextEx)
> [junit4:junit4]   2> -55149 T167 oasc.SolrResourceLoader.locateSolrHome using
> system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test-files\solr
> [junit4:junit4]   2> -55149 T167 oasc.SolrResourceLoader.<init> new
> SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test-files\solr\'
> [junit4:junit4]   2> -55137 T167 oasc.CoreContainer.<init> New CoreContainer
> 33469216
> [junit4:junit4]   2> -55137 T167 oasc.SolrCore.<init> [collection1] Opening new
> SolrCore at C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-
> 4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-
> 1348338241894\
> [junit4:junit4]   2> -55137 T167 oasc.SolrCore.<init> JMX monitoring not
> detected for core: collection1
> [junit4:junit4]   2> -55136 T167 oasc.SolrCore.getNewIndexDir WARNING New
> index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-
> 1348338241894\index/
> [junit4:junit4]   2> -55134 T167 oasc.RequestHandlers.initHandlersFromConfig
> created standard: solr.StandardRequestHandler
> [junit4:junit4]   2> -55134 T167 oasc.RequestHandlers.initHandlersFromConfig
> created /get: solr.RealTimeGetHandler
> [junit4:junit4]   2> -55133 T167 oasc.RequestHandlers.initHandlersFromConfig
> created /update: solr.UpdateRequestHandler
> [junit4:junit4]   2> -55133 T167 oasc.RequestHandlers.initHandlersFromConfig
> created /admin/: org.apache.solr.handler.admin.AdminHandlers
> [junit4:junit4]   2> -55130 T167 oashl.XMLLoader.init
> xsltCacheLifetimeSeconds=60
> [junit4:junit4]   2> -55126 T167 oasc.CachingDirectoryFactory.get return new
> directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\.\solrtest-TestRecovery-1348338241894\index forceNew:false
> [junit4:junit4]   2> -55113 T167 oass.SolrIndexSearcher.<init> Opening
> Searcher@4ccd82 main
> [junit4:junit4]   2> -55113 T167 oass.SolrIndexSearcher.getIndexDir WARNING
> WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> -55111 T167 oasc.SolrException.log Ignoring exception
> matching Failure to open existing
> [junit4:junit4]   2> -55110 T167 oasu.CommitTracker.<init> Hard AutoCommit:
> disabled
> [junit4:junit4]   2> -55109 T167 oasu.CommitTracker.<init> Soft AutoCommit:
> disabled
> [junit4:junit4]   2> -55109 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting socketTimeout to: 0
> [junit4:junit4]   2> -55109 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting urlScheme to: http://
> [junit4:junit4]   2> -55108 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting connTimeout to: 0
> [junit4:junit4]   2> -55108 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting maxConnectionsPerHost to: 20
> [junit4:junit4]   2> -55108 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting corePoolSize to: 0
> [junit4:junit4]   2> -55107 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting maximumPoolSize to: 2147483647
> [junit4:junit4]   2> -55107 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting maxThreadIdleTime to: 5
> [junit4:junit4]   2> -55107 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting sizeOfQueue to: -1
> [junit4:junit4]   2> -55106 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting fairnessPolicy to: false
> [junit4:junit4]   2> -55106 T167 oascsi.HttpClientUtil.createClient Creating new
> http client,
> config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0
> &connTimeout=0&retry=false
> [junit4:junit4]   2> -55097 T182 oasc.SolrCore.registerSearcher [collection1]
> Registered new searcher Searcher@4ccd82
> main{StandardDirectoryReader(segments_p:60 _i(4.1):C10/9 _j(4.1):C10/9
> _k(4.1):C105/104 _l(4.1):C1 _m(4.1):C105/104)}
> [junit4:junit4]   2> -55097 T167 oasc.CoreContainer.register registering core:
> collection1
> [junit4:junit4]   2> ASYNC  NEW_CORE C24 name=collection1
> org.apache.solr.core.SolrCore@166000f
> [junit4:junit4]   2> -55091 T167 C24 REQ [collection1] webapp=null path=null
> params={getVersions=50&qt=/get} status=0 QTime=1
> [junit4:junit4]   2> -55090 T167 oas.SolrTestCaseJ4.tearDown ###Ending
> testRemoveOldLogs
> [junit4:junit4]   2> -55085 T167 oas.SolrTestCaseJ4.setUp ###Starting
> testCorruptLog
> [junit4:junit4]   2> -55077 T167 C24 oasu.DirectUpdateHandler2.deleteAll
> [collection1] REMOVING ALL DOCUMENTS FROM INDEX
> [junit4:junit4]   2> -55061 T167 C24 oasc.SolrDeletionPolicy.onInit
> SolrDeletionPolicy.onInit: commits:num=1
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@1484766),segFN=se
> gments_p,generation=25,filenames=[_j.fdx, _l.fnm, _j.fdt, _l.si, _k.fdt, _m.frq,
> _k.fdx, _m_1.del, _l.frq, _k.fnm, _i.si, _m.si, _m.tip, _l.tip, _k.tim, _m.tim,
> _l.tim, _i_1.del, _i.fdx, _k_1.del, _k.tip, _m.fnm, _k.si, _i.tip, _i.fdt, _i.tim,
> _j_1.del, _j.si, _i.frq, _j.tip, _m.fdt, _j.fnm, _l.fdx, segments_p, _m.fdx, _l.fdt,
> _k.frq, _i.fnm, _j.frq, _j.tim]
> [junit4:junit4]   2> -55060 T167 C24 oasc.SolrDeletionPolicy.updateCommits
> newest commit = 25
> [junit4:junit4]   2> -55059 T167 C24 oass.SolrIndexSearcher.<init> Opening
> Searcher@1b4e510 realtime
> [junit4:junit4]   2> -55058 T167 C24 oass.SolrIndexSearcher.getIndexDir
> WARNING WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> -55057 T167 C24 REQ [collection1] webapp=null path=null
> params={_version_=-
> 9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json
> &indent=true} status=0 QTime=20
> [junit4:junit4]   2> -55049 T167 C24 oasu.DirectUpdateHandler2.commit start
> commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=t
> rue,expungeDeletes=false,softCommit=false}
> [junit4:junit4]   2> -55047 T167 C24 oasc.SolrDeletionPolicy.onCommit
> SolrDeletionPolicy.onCommit: commits:num=2
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@1484766),segFN=se
> gments_p,generation=25,filenames=[_j.fdx, _l.fnm, _j.fdt, _l.si, _k.fdt, _m.frq,
> _k.fdx, _m_1.del, _l.frq, _k.fnm, _i.si, _m.si, _m.tip, _l.tip, _k.tim, _m.tim,
> _l.tim, _i_1.del, _i.fdx, _k_1.del, _k.tip, _m.fnm, _k.si, _i.tip, _i.fdt, _i.tim,
> _j_1.del, _j.si, _i.frq, _j.tip, _m.fdt, _j.fnm, _l.fdx, segments_p, _m.fdx, _l.fdt,
> _k.frq, _i.fnm, _j.frq, _j.tim]
> [junit4:junit4]   2>
> 	commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory
> @C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@1484766),segFN=se
> gments_q,generation=26,filenames=[segments_q]
> [junit4:junit4]   2> -55046 T167 C24 oasc.SolrDeletionPolicy.updateCommits
> newest commit = 26
> [junit4:junit4]   2> -55027 T167 C24 oass.SolrIndexSearcher.<init> Opening
> Searcher@49c97 main
> [junit4:junit4]   2> -55026 T167 C24 oass.SolrIndexSearcher.getIndexDir
> WARNING WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> -55026 T167 C24 oasu.DirectUpdateHandler2.commit
> end_commit_flush
> [junit4:junit4]   2> -55026 T182 oasc.SolrCore.registerSearcher [collection1]
> Registered new searcher Searcher@49c97
> main{StandardDirectoryReader(segments_p:61:nrt)}
> [junit4:junit4]   2> -55024 T167 C24 UPDATE [collection1] webapp=null
> path=null params={} {commit=} 0 25
> [junit4:junit4]   2> -55002 T167 C24 UPDATE [collection1] webapp=null
> path=null params={} {add=[G1 (1413835062653222912)]} 0 3
> [junit4:junit4]   2> -54994 T167 C24 UPDATE [collection1] webapp=null
> path=null params={} {add=[G2 (1413835062663708672)]} 0 1
> [junit4:junit4]   2> -54987 T167 C24 UPDATE [collection1] webapp=null
> path=null params={} {add=[G3 (1413835062671048704)]} 0 1
> [junit4:junit4]   2> -54985 T167 oasc.CoreContainer.shutdown Shutting down
> CoreContainer instance=33469216
> [junit4:junit4]   2> -54985 T167 oasc.SolrCore.close [collection1]  CLOSING
> SolrCore org.apache.solr.core.SolrCore@166000f
> [junit4:junit4]   2> -54984 T167 oasc.SolrCore.closeSearcher [collection1]
> Closing main searcher on request.
> [junit4:junit4]   2> -54968 T167 oasu.DirectUpdateHandler2.close closing
> DirectUpdateHandler2{commits=1,autocommits=0,soft
> autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=3,add
> s=3,deletesById=0,deletesByQuery=0,errors=1,cumulative_adds=3,cumulative_
> deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=1}
> [junit4:junit4]   2> -54968 T167 oasu.DefaultSolrCoreState.decref SolrCoreState
> ref count has reached 0 - closing IndexWriter
> [junit4:junit4]   2> -54958 T167 oasu.DefaultSolrCoreState.decref Closing
> SolrCoreSta
> 
> [...truncated too long message...]
> 
> Handlers.initHandlersFromConfig created standard:
> solr.StandardRequestHandler
> [junit4:junit4]   2> -48163 T167 oasc.RequestHandlers.initHandlersFromConfig
> created /get: solr.RealTimeGetHandler
> [junit4:junit4]   2> -48163 T167 oasc.RequestHandlers.initHandlersFromConfig
> created /update: solr.UpdateRequestHandler
> [junit4:junit4]   2> -48163 T167 oasc.RequestHandlers.initHandlersFromConfig
> created /admin/: org.apache.solr.handler.admin.AdminHandlers
> [junit4:junit4]   2> -48159 T167 oashl.XMLLoader.init
> xsltCacheLifetimeSeconds=60
> [junit4:junit4]   2> -48153 T167 oasc.CachingDirectoryFactory.get return new
> directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\.\solrtest-TestRecovery-1348338241894\index forceNew:false
> [junit4:junit4]   2> -48152 T167 oass.SolrIndexSearcher.<init> Opening
> Searcher@15f11f8 main
> [junit4:junit4]   2> -48152 T167 oass.SolrIndexSearcher.getIndexDir WARNING
> WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.BaseDirectoryWrapper
> [junit4:junit4]   2> -48150 T167 oasu.CommitTracker.<init> Hard AutoCommit:
> disabled
> [junit4:junit4]   2> -48150 T167 oasu.CommitTracker.<init> Soft AutoCommit:
> disabled
> [junit4:junit4]   2> -48149 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting socketTimeout to: 0
> [junit4:junit4]   2> -48149 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting urlScheme to: http://
> [junit4:junit4]   2> -48149 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting connTimeout to: 0
> [junit4:junit4]   2> -48149 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting maxConnectionsPerHost to: 20
> [junit4:junit4]   2> -48148 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting corePoolSize to: 0
> [junit4:junit4]   2> -48148 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting maximumPoolSize to: 2147483647
> [junit4:junit4]   2> -48148 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting maxThreadIdleTime to: 5
> [junit4:junit4]   2> -48148 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting sizeOfQueue to: -1
> [junit4:junit4]   2> -48147 T167 oashc.HttpShardHandlerFactory.getParameter
> Setting fairnessPolicy to: false
> [junit4:junit4]   2> -48147 T167 oascsi.HttpClientUtil.createClient Creating new
> http client,
> config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0
> &connTimeout=0&retry=false
> [junit4:junit4]   2> -48136 T167 oasc.CoreContainer.register registering core:
> collection1
> [junit4:junit4]   2> ASYNC  NEW_CORE C31 name=collection1
> org.apache.solr.core.SolrCore@98c2f2
> [junit4:junit4]   2> -48134 T192 C31 oasu.UpdateLog$LogReplayer.doReplay
> WARNING Starting log replay tlog{file=C:\Jenkins\workspace\Lucene-Solr-4.x-
> Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-
> 1348338241894\tlog\tlog.0000000000000000006 refcount=2} active=false
> starting pos=0
> [junit4:junit4]   2> -48128 T192 C31 oasc.SolrDeletionPolicy.onInit
> SolrDeletionPolicy.onInit: commits:num=1
> [junit4:junit4]   2>
> 	commit{dir=BaseDirectoryWrapper(org.apache.lucene.store.NIOFSDire
> ctory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@14cc123),segFN=se
> gments_w,generation=32,filenames=[segments_w]
> [junit4:junit4]   2> -48126 T192 C31 oasc.SolrDeletionPolicy.updateCommits
> newest commit = 32
> [junit4:junit4]   2> -48125 T192 C31 oasc.SolrException.log Ignoring exception
> matching OutOfBoundsException
> [junit4:junit4]   2> -48124 T192 C31 oasu.DirectUpdateHandler2.commit start
> commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=t
> rue,expungeDeletes=false,softCommit=false}
> [junit4:junit4]   2> -48120 T191 oasc.SolrCore.registerSearcher [collection1]
> Registered new searcher Searcher@15f11f8
> main{StandardDirectoryReader(segments_w:71)}
> [junit4:junit4]   2> -48107 T192 C31 oasc.SolrDeletionPolicy.onCommit
> SolrDeletionPolicy.onCommit: commits:num=2
> [junit4:junit4]   2>
> 	commit{dir=BaseDirectoryWrapper(org.apache.lucene.store.NIOFSDire
> ctory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@14cc123),segFN=se
> gments_w,generation=32,filenames=[segments_w]
> [junit4:junit4]   2>
> 	commit{dir=BaseDirectoryWrapper(org.apache.lucene.store.NIOFSDire
> ctory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@14cc123),segFN=se
> gments_x,generation=33,filenames=[_r.tim, _r.fdt, _r.fnm, _r.fdx, _r.frq, _r.si,
> _r.tip, segments_x]
> [junit4:junit4]   2> -48107 T192 C31 oasc.SolrDeletionPolicy.updateCommits
> newest commit = 33
> [junit4:junit4]   2> -48101 T192 C31 oass.SolrIndexSearcher.<init> Opening
> Searcher@7202cf main
> [junit4:junit4]   2> -48101 T192 C31 oass.SolrIndexSearcher.getIndexDir
> WARNING WARNING: Directory impl does not support setting indexDir:
> org.apache.lucene.store.BaseDirectoryWrapper
> [junit4:junit4]   2> -48100 T192 C31 oasu.DirectUpdateHandler2.commit
> end_commit_flush
> [junit4:junit4]   2> -48100 T191 oasc.SolrCore.registerSearcher [collection1]
> Registered new searcher Searcher@7202cf
> main{StandardDirectoryReader(segments_x:73 _r(4.1):C3)}
> [junit4:junit4]   2> -48099 T192 C31 oasu.UpdateLog$LogReplayer.run
> WARNING Log replay finished. recoveryInfo=RecoveryInfo{adds=3 deletes=0
> deleteByQuery=0 errors=0 positionOfStart=0}
> [junit4:junit4]   2> -48099 T167 C31 REQ [collection1] webapp=null path=null
> params={q=*:*} hits=3 status=0 QTime=0
> [junit4:junit4]   2> -48082 T167 C31 REQ [collection1] webapp=null path=null
> params={update.distrib=FROMLEADER&wt=json&indent=true} status=0
> QTime=5
> [junit4:junit4]   2> -48078 T167 C31 REQ [collection1] webapp=null path=null
> params={update.distrib=FROMLEADER&wt=json&indent=true} status=0
> QTime=0
> [junit4:junit4]   2> -48072 T167 C31 REQ [collection1] webapp=null path=null
> params={update.distrib=FROMLEADER&wt=json&indent=true} status=0
> QTime=1
> [junit4:junit4]   2> -48071 T167 C31 REQ [collection1] webapp=null path=null
> params={getVersions=3&qt=/get} status=0 QTime=1
> [junit4:junit4]   2> -48071 T167 oas.SolrTestCaseJ4.tearDown ###Ending
> testTruncatedLog
> [junit4:junit4]   2> -48070 T167 oas.SolrTestCaseJ4.deleteCore ###deleteCore
> [junit4:junit4]   2> -48070 T167 oasc.CoreContainer.shutdown Shutting down
> CoreContainer instance=29036821
> [junit4:junit4]   2> -48068 T167 oasc.SolrCore.close [collection1]  CLOSING
> SolrCore org.apache.solr.core.SolrCore@98c2f2
> [junit4:junit4]   2> -48067 T167 oasc.SolrCore.closeSearcher [collection1]
> Closing main searcher on request.
> [junit4:junit4]   2> -48067 T167 oasu.DirectUpdateHandler2.close closing
> DirectUpdateHandler2{commits=1,autocommits=0,soft
> autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=3,add
> s=3,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=6,cumulative_
> deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
> [junit4:junit4]   2> -48066 T167 oasu.DefaultSolrCoreState.decref SolrCoreState
> ref count has reached 0 - closing IndexWriter
> [junit4:junit4]   2> -48049 T167 C31 oasc.SolrDeletionPolicy.onCommit
> SolrDeletionPolicy.onCommit: commits:num=2
> [junit4:junit4]   2>
> 	commit{dir=BaseDirectoryWrapper(org.apache.lucene.store.NIOFSDire
> ctory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@14cc123),segFN=se
> gments_x,generation=33,filenames=[_r.tim, _r.fdt, _r.fnm, _r.fdx, _r.frq, _r.si,
> _r.tip, segments_x]
> [junit4:junit4]   2>
> 	commit{dir=BaseDirectoryWrapper(org.apache.lucene.store.NIOFSDire
> ctory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-
> core\test\J0\solrtest-TestRecovery-1348338241894\index
> lockFactory=org.apache.lucene.store.NativeFSLockFactory@14cc123),segFN=se
> gments_y,generation=34,filenames=[_r.tim, _s.fdt, _s.si, _s.fnm, _s.tim, _s.frq,
> _r.fnm, _r.fdt, _s.fdx, _r.fdx, _s.tip, _r.frq, _r.si, segments_y, _r.tip]
> [junit4:junit4]   2> -48047 T167 C31 oasc.SolrDeletionPolicy.updateCommits
> newest commit = 34
> [junit4:junit4]   2> -48032 T167 oasu.DefaultSolrCoreState.decref Closing
> SolrCoreState - canceling any ongoing recovery
> [junit4:junit4]   2> NOTE: test params are: codec=Asserting,
> sim=DefaultSimilarity, locale=hi_IN, timezone=America/Cordoba
> [junit4:junit4]   2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.7.0_07 (32-
> bit)/cpus=2,threads=1,free=17098048,total=46268416
> [junit4:junit4]   2> NOTE: All tests run in this JVM:
> [OpenExchangeRatesOrgProviderTest, DistributedSpellCheckComponentTest,
> TestTrie, AlternateDirectoryTest, TestFiltering, SimplePostToolTest,
> TestSuggestSpellingConverter, ShowFileRequestHandlerTest, FileUtilsTest,
> TestElisionMultitermQuery, HighlighterTest, PingRequestHandlerTest,
> TestHashPartitioner, TestFastWriter, TestRecovery]
> [junit4:junit4] Completed in -47.97s, 10 tests, 1 failure <<< FAILURES!
> 
> [...truncated 675 lines...]
> BUILD FAILED
> C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\build.xml:32: The following
> error occurred while executing this line:
> C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build.xml:179: The
> following error occurred while executing this line:
> C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\lucene\module-build.xml:63:
> The following error occurred while executing this line:
> C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\lucene\common-
> build.xml:1141: The following error occurred while executing this line:
> C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:814:
> There were test failures: 231 suites, 954 tests, 1 failure, 13 ignored (8
> assumptions)
> 
> Total time: 37 minutes 6 seconds
> Build step 'Invoke Ant' marked build as failure
> Recording test results
> Description set: Java: 32bit/jdk1.7.0_07 -server -XX:+UseConcMarkSweepGC
> Email was triggered for: Failure
> Sending email for trigger: Failure
> 



---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org
For additional commands, e-mail: dev-help@lucene.apache.org


Mime
View raw message