lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@sd-datasolutions.de>
Subject [JENKINS] Lucene-Solr-4.x-Windows (32bit/jdk1.7.0_07) - Build # 879 - Failure!
Date Sat, 22 Sep 2012 18:40:25 GMT
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(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:601)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:722)




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=segments_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=segments_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=true,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=segments_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=segments_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=true,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=segments_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=segments_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=true,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=segments_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=segments_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=true,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=segments_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=segments_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,adds=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=segments_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=true,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=segments_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=segments_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,adds=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=segments_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=true,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=segments_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=segments_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,adds=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=segments_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=true,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=segments_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=segments_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,adds=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=segments_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=true,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=segments_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=segments_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=true,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=segments_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=segments_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=true,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=segments_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=segments_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=true,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=segments_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=segments_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=true,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=segments_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=segments_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=true,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=segments_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=segments_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=true,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=segments_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=segments_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=true,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=segments_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=segments_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,adds=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=segments_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=true,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=segments_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=segments_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(DelegatingMethodAccessorImpl.java:43)
[junit4:junit4]    > 	at java.lang.reflect.Method.invoke(Method.java:601)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]   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=true,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=segments_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=segments_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,adds=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=segments_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=segments_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.SimpleFSDirectory@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=segments_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=true,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.SimpleFSDirectory@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=segments_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.SimpleFSDirectory@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=segments_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,adds=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.SimpleFSDirectory@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=segments_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=true,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.SimpleFSDirectory@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=segments_k,generation=20,filenames=[segments_k]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirectory@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=segments_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=true,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.SimpleFSDirectory@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=segments_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.SimpleFSDirectory@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=segments_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=true,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.SimpleFSDirectory@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=segments_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.SimpleFSDirectory@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=segments_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,adds=1,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=126,cumulative_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=segments_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=true,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=segments_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=segments_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=true,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=segments_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=segments_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,adds=1,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=107,cumulative_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=segments_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=true,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=segments_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=segments_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,adds=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.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@14cc123),segFN=segments_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=true,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.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@14cc123),segFN=segments_w,generation=32,filenames=[segments_w]
[junit4:junit4]   2> 		commit{dir=BaseDirectoryWrapper(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@14cc123),segFN=segments_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,adds=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.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@14cc123),segFN=segments_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.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@14cc123),segFN=segments_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



Mime
View raw message