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-trunk-Windows (32bit/jdk1.7.0_07) - Build # 755 - Failure!
Date Thu, 13 Sep 2012 16:38:43 GMT
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-trunk-Windows/755/
Java: 32bit/jdk1.7.0_07 -client -XX:+UseSerialGC

1 tests failed.
REGRESSION:  org.apache.solr.search.TestRecovery.testRemoveOldLogs

Error Message:
expected:<0> but was:<1>

Stack Trace:
java.lang.AssertionError: expected:<0> but was:<1>
	at __randomizedtesting.SeedInfo.seed([60190B42E904DE2B:4185CAE9213E57D3]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.junit.Assert.failNotEquals(Assert.java:647)
	at org.junit.Assert.assertEquals(Assert.java:128)
	at org.junit.Assert.assertEquals(Assert.java:472)
	at org.junit.Assert.assertEquals(Assert.java:456)
	at org.apache.solr.search.TestRecovery.testRemoveOldLogs(TestRecovery.java:721)
	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 7746 lines...]
[junit4:junit4] Suite: org.apache.solr.search.TestRecovery
[junit4:junit4]   2> 3 T334 oas.SolrTestCaseJ4.initCore ####initCore
[junit4:junit4]   2> Creating dataDir: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846
[junit4:junit4]   2> 6 T334 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4:junit4]   2> 11 T334 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 12 T334 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 104 T334 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 189 T334 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
[junit4:junit4]   2> 190 T334 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 199 T334 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 1206 T334 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 1214 T334 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 1229 T334 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 1230 T334 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr
[junit4:junit4]   2> 1230 T334 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 1256 T334 oasc.CoreContainer.<init> New CoreContainer 22059523
[junit4:junit4]   2> 1257 T334 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\
[junit4:junit4]   2> 1257 T334 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 1259 T334 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\index/
[junit4:junit4]   2> 1261 T334 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 1261 T334 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\index forceNew:false
[junit4:junit4]   2> 1269 T334 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@662740),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 1270 T334 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 1272 T334 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 1272 T334 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 1273 T334 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 1273 T334 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 1280 T334 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 1295 T334 oass.SolrIndexSearcher.<init> Opening Searcher@144d799 main
[junit4:junit4]   2> 1296 T334 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 1299 T334 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 1300 T334 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 1300 T334 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 1300 T334 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 1301 T334 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 1301 T334 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 1302 T334 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 1302 T334 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 1302 T334 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 1303 T334 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 1303 T334 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 1304 T334 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 1319 T335 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@144d799 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 1319 T334 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 1321 T334 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4:junit4]   2> 1327 T334 oas.SolrTestCaseJ4.setUp ###Starting testLogReplay
[junit4:junit4]   2> ASYNC  NEW_CORE C46 name=collection1 org.apache.solr.core.SolrCore@b278ab
[junit4:junit4]   2> 1340 T334 C46 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 1346 T334 C46 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@662740),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 1347 T334 C46 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 1348 T334 C46 oass.SolrIndexSearcher.<init> Opening Searcher@3c5bbd realtime
[junit4:junit4]   2> 1349 T334 C46 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 1349 T334 C46 REQ [collection1] webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true} status=0 QTime=10 
[junit4:junit4]   2> 1358 T334 C46 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 1362 T334 C46 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@662740),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@662740),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 1363 T334 C46 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 1369 T334 C46 oass.SolrIndexSearcher.<init> Opening Searcher@17767ee main
[junit4:junit4]   2> 1369 T334 C46 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 1370 T335 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@17767ee main{StandardDirectoryReader(segments_1:2:nrt)}
[junit4:junit4]   2> 1370 T334 C46 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 1371 T334 C46 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 13
[junit4:junit4]   2> 1418 T334 C46 UPDATE [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[A1 (1413012018961580032)]} 0 4
[junit4:junit4]   2> 1427 T334 C46 UPDATE [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[A11 (1413012018974162944)]} 0 1
[junit4:junit4]   2> 1434 T334 C46 UPDATE [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[A12 (1413012018982551552)]} 0 0
[junit4:junit4]   2> 1499 T334 C46 oass.SolrIndexSearcher.<init> Opening Searcher@1ef9572 realtime
[junit4:junit4]   2> 1500 T334 C46 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 1500 T334 C46 UPDATE [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {deleteByQuery=id:A11 (-1413012018990940160)} 0 58
[junit4:junit4]   2> 1509 T334 C46 UPDATE [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[A13 (1413012019060146176)]} 0 2
[junit4:junit4]   2> 1510 T334 C46 REQ [collection1] webapp=null path=null params={q=*:*} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 1510 T334 C46 REQ [collection1] webapp=null path=null params={getVersions=5&qt=/get} status=0 QTime=0 
[junit4:junit4]   2> 1512 T334 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=22059523
[junit4:junit4]   2> 1512 T334 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@b278ab
[junit4:junit4]   2> 1513 T334 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 1513 T334 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=4,adds=4,deletesById=0,deletesByQuery=1,errors=1,cumulative_adds=4,cumulative_deletesById=0,cumulative_deletesByQuery=2,cumulative_errors=1}
[junit4:junit4]   2> 1513 T334 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 1523 T334 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 1523 T334 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4:junit4]   2> 1527 T334 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 1529 T334 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 1635 T334 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 1741 T334 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
[junit4:junit4]   2> 1744 T334 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 1752 T334 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 2586 T334 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 2591 T334 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 2603 T334 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2604 T334 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr
[junit4:junit4]   2> 2604 T334 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 2618 T334 oasc.CoreContainer.<init> New CoreContainer 25857288
[junit4:junit4]   2> 2618 T334 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\
[junit4:junit4]   2> 2619 T334 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 2620 T334 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\index/
[junit4:junit4]   2> 2621 T334 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 2622 T334 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 2622 T334 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 2623 T334 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 2627 T334 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 2632 T334 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\index forceNew:false
[junit4:junit4]   2> 2633 T334 oass.SolrIndexSearcher.<init> Opening Searcher@14b6d51 main
[junit4:junit4]   2> 2634 T334 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 2637 T334 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 2638 T334 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 2638 T334 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 2639 T334 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 2639 T334 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 2639 T334 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 2641 T334 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 2642 T334 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 2642 T334 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 2643 T334 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 2643 T334 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 2643 T334 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 2660 T336 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@14b6d51 main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 2660 T334 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> ASYNC  NEW_CORE C47 name=collection1 org.apache.solr.core.SolrCore@156c280
[junit4:junit4]   2> 2668 T337 C47 oasu.UpdateLog$LogReplayer.doReplay WARNING Starting log replay tlog{file=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\tlog\tlog.0000000000000000001 refcount=2} active=false starting pos=0
[junit4:junit4]   2> 2672 T334 C47 REQ [collection1] webapp=null path=null params={q=*:*} hits=0 status=0 QTime=4 
[junit4:junit4]   2> 2673 T334 C47 REQ [collection1] webapp=null path=null params={getVersions=5&qt=/get} status=0 QTime=0 
[junit4:junit4]   2> 2674 T334 C47 REQ [collection1] webapp=null path=null params={getVersions=5&qt=/get} status=0 QTime=0 
[junit4:junit4]   2> 2685 T337 C47 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@2f7283),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 2685 T337 C47 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 2685 T337 C47 oasu.DirectUpdateHandler2.addDoc Reordered DBQs detected.  Update=add{flags=10,_version_=1413012018961580032,id=A1} DBQs=[DBQ{version=1413012018990940160,q=id:A11}]
[junit4:junit4]   2> 2692 T337 C47 oass.SolrIndexSearcher.<init> Opening Searcher@3cafba realtime
[junit4:junit4]   2> 2692 T337 C47 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 2692 T337 C47 oasu.DirectUpdateHandler2.addDoc Reordered DBQs detected.  Update=add{flags=10,_version_=1413012018974162944,id=A11} DBQs=[DBQ{version=1413012018990940160,q=id:A11}]
[junit4:junit4]   2> 2707 T337 C47 oass.SolrIndexSearcher.<init> Opening Searcher@13e4be9 realtime
[junit4:junit4]   2> 2707 T337 C47 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 2707 T337 C47 oasu.DirectUpdateHandler2.addDoc Reordered DBQs detected.  Update=add{flags=10,_version_=1413012018982551552,id=A12} DBQs=[DBQ{version=1413012018990940160,q=id:A11}]
[junit4:junit4]   2> 2719 T337 C47 oass.SolrIndexSearcher.<init> Opening Searcher@69bea3 realtime
[junit4:junit4]   2> 2719 T337 C47 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 2731 T337 C47 oasu.DirectUpdateHandler2.commit start commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 2739 T337 C47 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@2f7283),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@2f7283),segFN=segments_3,generation=3,filenames=[_3.fld, _2.si, _2.pst, _3.inf, _2.fld, _2.inf, _3.si, _0.inf, _0.si, _0.pst, _0.fld, segments_3, _3.pst]
[junit4:junit4]   2> 2739 T337 C47 oasc.SolrDeletionPolicy.updateCommits newest commit = 3
[junit4:junit4]   2> 2745 T337 C47 oass.SolrIndexSearcher.<init> Opening Searcher@10d3766 main
[junit4:junit4]   2> 2746 T337 C47 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 2746 T337 C47 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 2746 T336 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@10d3766 main{StandardDirectoryReader(segments_3:12:nrt _0(5.0):C1 _2(5.0):C1 _3(5.0):C1)}
[junit4:junit4]   2> 2747 T337 C47 oasu.UpdateLog$LogReplayer.run WARNING Log replay finished. recoveryInfo=RecoveryInfo{adds=4 deletes=0 deleteByQuery=1 errors=0 positionOfStart=0}
[junit4:junit4]   2> 2747 T334 C47 REQ [collection1] webapp=null path=null params={q=*:*} hits=3 status=0 QTime=0 
[junit4:junit4]   2> 2749 T334 C47 REQ [collection1] webapp=null path=null params={getVersions=5&qt=/get} status=0 QTime=1 
[junit4:junit4]   2> 2761 T334 C47 UPDATE [collection1] webapp=null path=null params={} {add=[A2 (1413012020370866176)]} 0 3
[junit4:junit4]   2> 2800 T334 C47 UPDATE [collection1] webapp=null path=null params={} {add=[A3 (1413012020412809216)]} 0 2
[junit4:junit4]   2> 2805 T334 C47 UPDATE [collection1] webapp=null path=null params={} {delete=[A2 (-1413012020420149248)]} 0 0
[junit4:junit4]   2> 2819 T334 C47 UPDATE [collection1] webapp=null path=null params={} {add=[A4 (1413012020434829312)]} 0 1
[junit4:junit4]   2> 2824 T334 C47 REQ [collection1] webapp=null path=null params={q=*:*} hits=3 status=0 QTime=0 
[junit4:junit4]   2> 2825 T334 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=25857288
[junit4:junit4]   2> 2826 T334 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@156c280
[junit4:junit4]   2> 2829 T334 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 2830 T334 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> 2832 T334 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 2850 T334 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 2851 T334 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4:junit4]   2> 2853 T334 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 2854 T334 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 2924 T334 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 3008 T334 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
[junit4:junit4]   2> 3008 T334 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 3019 T334 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 3836 T334 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 3841 T334 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 3859 T334 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3859 T334 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr
[junit4:junit4]   2> 3859 T334 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 3875 T334 oasc.CoreContainer.<init> New CoreContainer 20828423
[junit4:junit4]   2> 3876 T334 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\
[junit4:junit4]   2> 3876 T334 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 3877 T334 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\index/
[junit4:junit4]   2> 3880 T334 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 3880 T334 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 3881 T334 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 3881 T334 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 3884 T334 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 3891 T334 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\index forceNew:false
[junit4:junit4]   2> 3897 T334 oass.SolrIndexSearcher.<init> Opening Searcher@1dd6c64 main
[junit4:junit4]   2> 3898 T334 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 3909 T334 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 3909 T334 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 3910 T334 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 3911 T334 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 3911 T334 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 3911 T334 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 3912 T334 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 3912 T334 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 3913 T334 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 3913 T334 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 3913 T334 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 3915 T334 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 3931 T338 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1dd6c64 main{StandardDirectoryReader(segments_3:12 _0(5.0):C1 _2(5.0):C1 _3(5.0):C1)}
[junit4:junit4]   2> 3931 T334 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> ASYNC  NEW_CORE C48 name=collection1 org.apache.solr.core.SolrCore@2aa91a
[junit4:junit4]   2> 3933 T339 C48 oasu.UpdateLog$LogReplayer.doReplay WARNING Starting log replay tlog{file=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\tlog\tlog.0000000000000000003 refcount=2} active=false starting pos=0
[junit4:junit4]   2> 3946 T339 C48 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@3beb19),segFN=segments_3,generation=3,filenames=[_3.fld, _2.pst, _2.si, _3.inf, _2.fld, _2.inf, _3.si, _0.inf, _0.si, _0.pst, _0.fld, segments_3, _3.pst]
[junit4:junit4]   2> 3947 T339 C48 oasc.SolrDeletionPolicy.updateCommits newest commit = 3
[junit4:junit4]   2> 3949 T339 C48 oasu.DirectUpdateHandler2.commit start commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 3966 T339 C48 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@3beb19),segFN=segments_3,generation=3,filenames=[_3.fld, _2.pst, _2.si, _3.inf, _2.fld, _2.inf, _3.si, _0.inf, _0.si, _0.pst, _0.fld, segments_3, _3.pst]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@3beb19),segFN=segments_4,generation=4,filenames=[_2.si, _3.fld, _2.fld, _4.fld, _2.inf, _4.si, _4.inf, _0.pst, _0.fld, _3.pst, _4.pst, _2.pst, _3.inf, _0.inf, _3.si, _0.si, segments_4]
[junit4:junit4]   2> 3966 T339 C48 oasc.SolrDeletionPolicy.updateCommits newest commit = 4
[junit4:junit4]   2> 3983 T339 C48 oass.SolrIndexSearcher.<init> Opening Searcher@1c43375 main
[junit4:junit4]   2> 3984 T339 C48 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 3984 T339 C48 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3985 T338 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1c43375 main{StandardDirectoryReader(segments_4:14 _0(5.0):C1 _2(5.0):C1 _3(5.0):C1 _4(5.0):C2)}
[junit4:junit4]   2> 3987 T339 C48 oasu.UpdateLog$LogReplayer.run WARNING Log replay finished. recoveryInfo=RecoveryInfo{adds=3 deletes=1 deleteByQuery=0 errors=0 positionOfStart=0}
[junit4:junit4]   2> 3988 T334 C48 REQ [collection1] webapp=null path=null params={q=*:*} hits=5 status=0 QTime=1 
[junit4:junit4]   2> 3990 T334 C48 REQ [collection1] webapp=null path=null params={q=id:A2} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 3991 T334 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=20828423
[junit4:junit4]   2> 3991 T334 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@2aa91a
[junit4:junit4]   2> 3992 T334 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 3992 T334 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> 3994 T334 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 4019 T334 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 4020 T334 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4:junit4]   2> 4024 T334 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 4026 T334 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 4136 T334 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 4225 T334 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
[junit4:junit4]   2> 4226 T334 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 4231 T334 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 5061 T334 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 5075 T334 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 5091 T334 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 5091 T334 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr
[junit4:junit4]   2> 5091 T334 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 5112 T334 oasc.CoreContainer.<init> New CoreContainer 17354005
[junit4:junit4]   2> 5113 T334 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\
[junit4:junit4]   2> 5113 T334 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 5117 T334 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\index/
[junit4:junit4]   2> 5118 T334 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 5119 T334 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 5119 T334 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 5120 T334 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 5125 T334 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 5131 T334 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\index forceNew:false
[junit4:junit4]   2> 5141 T334 oass.SolrIndexSearcher.<init> Opening Searcher@1de47cf main
[junit4:junit4]   2> 5141 T334 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 5146 T334 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 5147 T334 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 5147 T334 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 5147 T334 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 5147 T334 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 5148 T334 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 5148 T334 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 5149 T334 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 5149 T334 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 5150 T334 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 5150 T334 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 5151 T334 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 5162 T340 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1de47cf main{StandardDirectoryReader(segments_4:14 _0(5.0):C1 _2(5.0):C1 _3(5.0):C1 _4(5.0):C2)}
[junit4:junit4]   2> 5162 T334 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> ASYNC  NEW_CORE C49 name=collection1 org.apache.solr.core.SolrCore@b828d5
[junit4:junit4]   2> 5163 T334 C49 REQ [collection1] webapp=null path=null params={q=*:*} hits=5 status=0 QTime=0 
[junit4:junit4]   2> 5264 T334 oas.SolrTestCaseJ4.tearDown ###Ending testLogReplay
[junit4:junit4]   2> 5269 T334 oas.SolrTestCaseJ4.setUp ###Starting testVersionsOnRestart
[junit4:junit4]   2> 5279 T334 C49 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 5289 T334 C49 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@f0c691),segFN=segments_4,generation=4,filenames=[_2.si, _3.fld, _2.fld, _4.fld, _2.inf, _4.inf, _4.si, _0.pst, _0.fld, _3.pst, _4.pst, _2.pst, _3.inf, _0.inf, _3.si, _0.si, segments_4]
[junit4:junit4]   2> 5290 T334 C49 oasc.SolrDeletionPolicy.updateCommits newest commit = 4
[junit4:junit4]   2> 5293 T334 C49 oass.SolrIndexSearcher.<init> Opening Searcher@b94e61 realtime
[junit4:junit4]   2> 5293 T334 C49 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 5293 T334 C49 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> 5297 T334 C49 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 5300 T334 C49 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@f0c691),segFN=segments_4,generation=4,filenames=[_2.si, _3.fld, _2.fld, _4.fld, _2.inf, _4.inf, _4.si, _0.pst, _0.fld, _3.pst, _4.pst, _2.pst, _3.inf, _0.inf, _3.si, _0.si, segments_4]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@f0c691),segFN=segments_5,generation=5,filenames=[segments_5]
[junit4:junit4]   2> 5301 T334 C49 oasc.SolrDeletionPolicy.updateCommits newest commit = 5
[junit4:junit4]   2> 5313 T334 C49 oass.SolrIndexSearcher.<init> Opening Searcher@1014a18 main
[junit4:junit4]   2> 5313 T334 C49 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 5314 T340 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1014a18 main{StandardDirectoryReader(segments_4:15:nrt)}
[junit4:junit4]   2> 5314 T334 C49 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 5318 T334 C49 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 21
[junit4:junit4]   2> 5354 T334 C49 UPDATE [collection1] webapp=null path=null params={} {add=[D1 (1413012023080386560)]} 0 12
[junit4:junit4]   2> 5362 T334 C49 UPDATE [collection1] webapp=null path=null params={} {add=[D2 (1413012023099260928)]} 0 2
[junit4:junit4]   2> 5368 T334 C49 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 5381 T334 C49 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@f0c691),segFN=segments_5,generation=5,filenames=[segments_5]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@f0c691),segFN=segments_6,generation=6,filenames=[_5.si, _5.inf, _5.pst, _5.fld, segments_6]
[junit4:junit4]   2> 5381 T334 C49 oasc.SolrDeletionPolicy.updateCommits newest commit = 6
[junit4:junit4]   2> 5386 T334 C49 oass.SolrIndexSearcher.<init> Opening Searcher@a88a21 main
[junit4:junit4]   2> 5386 T334 C49 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 5387 T340 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@a88a21 main{StandardDirectoryReader(segments_6:17:nrt _5(5.0):C2)}
[junit4:junit4]   2> 5387 T334 C49 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 5387 T334 C49 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 19
[junit4:junit4]   2> 5392 T334 C49 REQ [collection1] webapp=null path=null params={q=id:D1} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 5394 T334 C49 REQ [collection1] webapp=null path=null params={q=id:D2} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 5394 T334 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=17354005
[junit4:junit4]   2> 5395 T334 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@b828d5
[junit4:junit4]   2> 5395 T334 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 5395 T334 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=2,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=1,cumulative_adds=2,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=1}
[junit4:junit4]   2> 5396 T334 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 5402 T334 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 5403 T334 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4:junit4]   2> 5406 T334 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 5407 T334 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 5475 T334 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 5545 T334 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
[junit4:junit4]   2> 5546 T334 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 5555 T334 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 6505 T334 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 6512 T334 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 6527 T334 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 6527 T334 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr
[junit4:junit4]   2> 6528 T334 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 6546 T334 oasc.CoreContainer.<init> New CoreContainer 15160065
[junit4:junit4]   2> 6547 T334 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\
[junit4:junit4]   2> 6547 T334 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 6549 T334 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\index/
[junit4:junit4]   2> 6552 T334 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 6553 T334 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 6554 T334 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 6554 T334 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 6560 T334 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 6566 T334 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\index forceNew:false
[junit4:junit4]   2> 6570 T334 oass.SolrIndexSearcher.<init> Opening Searcher@c30c24 main
[junit4:junit4]   2> 6570 T334 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 6575 T334 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 6576 T334 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 6576 T334 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 6577 T334 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 6578 T334 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 6578 T334 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 6578 T334 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 6579 T334 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 6580 T334 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 6580 T334 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 6581 T334 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 6581 T334 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 6596 T341 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c30c24 main{StandardDirectoryReader(segments_6:17 _5(5.0):C2)}
[junit4:junit4]   2> 6596 T334 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> ASYNC  NEW_CORE C50 name=collection1 org.apache.solr.core.SolrCore@1e3a8e
[junit4:junit4]   2> 6610 T334 C50 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@14b52a2),segFN=segments_6,generation=6,filenames=[_5.si, _5.inf, _5.pst, _5.fld, segments_6]
[junit4:junit4]   2> 6610 T334 C50 oasc.SolrDeletionPolicy.updateCommits newest commit = 6
[junit4:junit4]   2> 6613 T334 C50 UPDATE [collection1] webapp=null path=null params={} {add=[D1 (1413012024403689472)]} 0 9
[junit4:junit4]   2> 6627 T334 C50 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 6652 T334 C50 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@14b52a2),segFN=segments_6,generation=6,filenames=[_5.si, _5.inf, _5.pst, _5.fld, segments_6]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@14b52a2),segFN=segments_7,generation=7,filenames=[_5.si, _5_1.liv, _6.pst, _5.inf, segments_7, _6.fld, _6.si, _6.inf, _5.pst, _5.fld]
[junit4:junit4]   2> 6653 T334 C50 oasc.SolrDeletionPolicy.updateCommits newest commit = 7
[junit4:junit4]   2> 6665 T334 C50 oass.SolrIndexSearcher.<init> Opening Searcher@395bb0 main
[junit4:junit4]   2> 6666 T334 C50 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 6666 T341 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@395bb0 main{StandardDirectoryReader(segments_7:20 _5(5.0):C2/1 _6(5.0):C1)}
[junit4:junit4]   2> 6666 T334 C50 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 6667 T334 C50 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 40
[junit4:junit4]   2> 6677 T334 C50 REQ [collection1] webapp=null path=null params={q=id:D1} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 6678 T334 C50 REQ [collection1] webapp=null path=null params={getVersions=2&qt=/get} status=0 QTime=0 
[junit4:junit4]   2> 6679 T334 oas.SolrTestCaseJ4.tearDown ###Ending testVersionsOnRestart
[junit4:junit4]   2> 6685 T334 oas.SolrTestCaseJ4.setUp ###Starting testBufferingFlags
[junit4:junit4]   2> 6699 T334 C50 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 6701 T334 C50 oass.SolrIndexSearcher.<init> Opening Searcher@15582e5 realtime
[junit4:junit4]   2> 6702 T334 C50 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 6703 T334 C50 REQ [collection1] webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true} status=0 QTime=4 
[junit4:junit4]   2> 6710 T334 C50 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 6713 T334 C50 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@14b52a2),segFN=segments_7,generation=7,filenames=[_5.si, _5_1.liv, _6.pst, _5.inf, segments_7, _6.fld, _6.si, _6.inf, _5.pst, _5.fld]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@14b52a2),segFN=segments_8,generation=8,filenames=[segments_8]
[junit4:junit4]   2> 6714 T334 C50 oasc.SolrDeletionPolicy.updateCommits newest commit = 8
[junit4:junit4]   2> 6723 T334 C50 oass.SolrIndexSearcher.<init> Opening Searcher@9c9e65 main
[junit4:junit4]   2> 6724 T334 C50 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 6725 T341 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@9c9e65 main{StandardDirectoryReader(segments_7:21:nrt)}
[junit4:junit4]   2> 6724 T334 C50 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 6727 T334 C50 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 18
[junit4:junit4]   2> 6735 T334 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 6749 T334 C50 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=1 
[junit4:junit4]   2> 6758 T334 C50 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 6766 T334 C50 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 6767 T334 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=15160065
[junit4:junit4]   2> 6768 T334 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@1e3a8e
[junit4:junit4]   2> 6769 T334 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 6774 T334 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=2,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=1,cumulative_adds=1,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=1}
[junit4:junit4]   2> 6774 T334 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 6782 T334 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 6783 T334 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4:junit4]   2> 6786 T334 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 6789 T334 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 6893 T334 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 6985 T334 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
[junit4:junit4]   2> 6987 T334 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 6995 T334 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 7801 T334 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 7805 T334 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 7815 T334 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 7816 T334 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr
[junit4:junit4]   2> 7816 T334 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 7829 T334 oasc.CoreContainer.<init> New CoreContainer 4076617
[junit4:junit4]   2> 7829 T334 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\
[junit4:junit4]   2> 7830 T334 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 7831 T334 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\index/
[junit4:junit4]   2> 7832 T334 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 7833 T334 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 7833 T334 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 7833 T334 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 7836 T334 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 7840 T334 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\index forceNew:false
[junit4:junit4]   2> 7842 T334 oass.SolrIndexSearcher.<init> Opening Searcher@1ce812 main
[junit4:junit4]   2> 7842 T334 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 7846 T334 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 7846 T334 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 7847 T334 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 7847 T334 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 7848 T334 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 7848 T334 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 7848 T334 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 7848 T334 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 7848 T334 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 7849 T334 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 7849 T334 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 7849 T334 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 7858 T342 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ce812 main{StandardDirectoryReader(segments_8:21)}
[junit4:junit4]   2> 7858 T334 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> ASYNC  NEW_CORE C51 name=collection1 org.apache.solr.core.SolrCore@14aeb29
[junit4:junit4]   2> 7859 T343 C51 oasu.UpdateLog$LogReplayer.doReplay WARNING Starting log replay tlog{file=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\tlog\tlog.0000000000000000008 refcount=2} active=false starting pos=0
[junit4:junit4]   2> 7865 T343 C51 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1c48525),segFN=segments_8,generation=8,filenames=[segments_8]
[junit4:junit4]   2> 7866 T343 C51 oasc.SolrDeletionPolicy.updateCommits newest commit = 8
[junit4:junit4]   2> 7866 T343 C51 oasu.DirectUpdateHandler2.addDoc Reordered DBQs detected.  Update=add{flags=10,_version_=101,id=Q1} DBQs=[DBQ{version=1413012018990940160,q=id:A11}]
[junit4:junit4]   2> 7870 T343 C51 oass.SolrIndexSearcher.<init> Opening Searcher@73138a realtime
[junit4:junit4]   2> 7870 T343 C51 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 7871 T343 C51 oasu.DirectUpdateHandler2.addDoc Reordered DBQs detected.  Update=add{flags=10,_version_=102,id=Q2} DBQs=[DBQ{version=1413012018990940160,q=id:A11}]
[junit4:junit4]   2> 7875 T343 C51 oass.SolrIndexSearcher.<init> Opening Searcher@1b36cc5 realtime
[junit4:junit4]   2> 7875 T343 C51 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 7876 T343 C51 oasu.DirectUpdateHandler2.addDoc Reordered DBQs detected.  Update=add{flags=10,_version_=103,id=Q3} DBQs=[DBQ{version=1413012018990940160,q=id:A11}]
[junit4:junit4]   2> 7880 T343 C51 oass.SolrIndexSearcher.<init> Opening Searcher@10d7e2b realtime
[junit4:junit4]   2> 7881 T343 C51 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 7881 T343 C51 oasu.DirectUpdateHandler2.commit start commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 7893 T343 C51 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1c48525),segFN=segments_8,generation=8,filenames=[segments_8]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1c48525),segFN=segments_9,generation=9,filenames=[_7.inf, _7.si, _7.pst, _8.pst, _8.fld, segments_9, _8.si, _9.inf, _7.fld, _9.fld, _9.si, _8.inf, _9.pst]
[junit4:junit4]   2> 7893 T343 C51 oasc.SolrDeletionPolicy.updateCommits newest commit = 9
[junit4:junit4]   2> 7897 T343 C51 oass.SolrIndexSearcher.<init> Opening Searcher@1a13f7a main
[junit4:junit4]   2> 7898 T343 C51 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 7898 T343 C51 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 7899 T342 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1a13f7a main{StandardDirectoryReader(segments_8:27:nrt _7(5.0):C1 _8(5.0):C1 _9(5.0):C1)}
[junit4:junit4]   2> 7900 T343 C51 oasu.UpdateLog$LogReplayer.run WARNING Log replay finished. recoveryInfo=RecoveryInfo{adds=3 deletes=0 deleteByQuery=0 errors=0 positionOfStart=0}
[junit4:junit4]   2> 7900 T334 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=4076617
[junit4:junit4]   2> 7901 T334 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@14aeb29
[junit4:junit4]   2> 7901 T334 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 7902 T334 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=3,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> 7904 T334 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 7926 T334 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 7927 T334 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4:junit4]   2> 7929 T334 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 7930 T334 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 8004 T334 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 8070 T334 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
[junit4:junit4]   2> 8070 T334 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 8081 T334 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 8719 T334 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 8723 T334 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 8732 T334 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 8733 T334 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr
[junit4:junit4]   2> 8733 T334 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 8747 T334 oasc.CoreContainer.<init> New CoreContainer 7778392
[junit4:junit4]   2> 8749 T334 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\
[junit4:junit4]   2> 8749 T334 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 8751 T334 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\index/
[junit4:junit4]   2> 8752 T334 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 8753 T334 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 8753 T334 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 8753 T334 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 8757 T334 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 8759 T334 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\index forceNew:false
[junit4:junit4]   2> 8765 T334 oass.SolrIndexSearcher.<init> Opening Searcher@cd597f main
[junit4:junit4]   2> 8765 T334 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 8769 T334 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 8769 T334 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 8770 T334 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 8770 T334 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 8770 T334 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 8771 T334 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 8771 T334 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 8771 T334 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 8772 T334 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 8772 T334 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 8772 T334 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 8773 T334 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 8782 T344 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@cd597f main{StandardDirectoryReader(segments_9:27 _7(5.0):C1 _8(5.0):C1 _9(5.0):C1)}
[junit4:junit4]   2> 8782 T334 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> ASYNC  NEW_CORE C52 name=collection1 org.apache.solr.core.SolrCore@17acfe1
[junit4:junit4]   2> 8798 T334 C52 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@97a3ad),segFN=segments_9,generation=9,filenames=[_7.inf, _7.si, _7.pst, _8.pst, _8.fld, segments_9, _8.si, _9.inf, _7.fld, _9.fld, _9.pst, _8.inf, _9.si]
[junit4:junit4]   2> 8799 T334 C52 oasc.SolrDeletionPolicy.updateCommits newest commit = 9
[junit4:junit4]   2> 8800 T334 C52 oasu.DirectUpdateHandler2.addDoc Reordered DBQs detected.  Update=add{flags=0,_version_=114,id=Q4} DBQs=[DBQ{version=1413012018990940160,q=id:A11}]
[junit4:junit4]   2> 8808 T334 C52 oass.SolrIndexSearcher.<init> Opening Searcher@a94fa realtime
[junit4:junit4]   2> 8808 T334 C52 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 8808 T334 C52 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=18 
[junit4:junit4]   2> 8813 T334 C52 oasu.DirectUpdateHandler2.addDoc Reordered DBQs detected.  Update=add{flags=0,_version_=115,id=Q5} DBQs=[DBQ{version=1413012018990940160,q=id:A11}]
[junit4:junit4]   2> 8818 T334 C52 oass.SolrIndexSearcher.<init> Opening Searcher@a465a3 realtime
[junit4:junit4]   2> 8819 T334 C52 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 8819 T334 C52 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=6 
[junit4:junit4]   2> 8828 T334 C52 oasu.DirectUpdateHandler2.addDoc Reordered DBQs detected.  Update=add{flags=0,_version_=116,id=Q6} DBQs=[DBQ{version=1413012018990940160,q=id:A11}]
[junit4:junit4]   2> 8835 T334 C52 oass.SolrIndexSearcher.<init> Opening Searcher@1aefe9c realtime
[junit4:junit4]   2> 8836 T334 C52 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 8836 T334 C52 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=8 
[junit4:junit4]   2> 8841 T334 C52 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 8846 T334 C52 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@97a3ad),segFN=segments_9,generation=9,filenames=[_7.inf, _7.si, _7.pst, _8.pst, _8.fld, segments_9, _8.si, _9.inf, _7.fld, _9.fld, _9.pst, _8.inf, _9.si]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@97a3ad),segFN=segments_a,generation=10,filenames=[_c.inf, _7.si, _a.pst, _c.pst, _b.pst, _8.si, _a.inf, _9.inf, _7.fld, _c.si, _b.fld, _b.si, _9.si, _7.inf, segments_a, _7.pst, _a.si, _8.pst, _8.fld, _b.inf, _a.fld, _9.fld, _c.fld, _8.inf, _9.pst]
[junit4:junit4]   2> 8846 T334 C52 oasc.SolrDeletionPolicy.updateCommits newest commit = 10
[junit4:junit4]   2> 8849 T334 C52 oass.SolrIndexSearcher.<init> Opening Searcher@133af0 main
[junit4:junit4]   2> 8850 T334 C52 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 8850 T344 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@133af0 main{StandardDirectoryReader(segments_9:33:nrt _7(5.0):C1 _8(5.0):C1 _9(5.0):C1 _a(5.0):C1 _b(5.0):C1 _c(5.0):C1)}
[junit4:junit4]   2> 8850 T334 C52 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 8852 T334 C52 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 11
[junit4:junit4]   2> 8872 T334 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=7778392
[junit4:junit4]   2> 8872 T334 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@17acfe1
[junit4:junit4]   2> 8873 T334 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 8873 T334 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=3,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> 8873 T334 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 8898 T334 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 8898 T334 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4:junit4]   2> 8901 T334 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 8902 T334 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 8969 T334 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 9033 T334 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
[junit4:junit4]   2> 9034 T334 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 9040 T334 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 9680 T334 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 9686 T334 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 9696 T334 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 9696 T334 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr
[junit4:junit4]   2> 9696 T334 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 9710 T334 oasc.CoreContainer.<init> New CoreContainer 18530653
[junit4:junit4]   2> 9710 T334 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\
[junit4:junit4]   2> 9710 T334 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 9712 T334 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\index/
[junit4:junit4]   2> 9714 T334 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 9714 T334 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 9714 T334 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 9715 T334 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 9719 T334 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 9724 T334 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\index forceNew:false
[junit4:junit4]   2> 9731 T334 oass.SolrIndexSearcher.<init> Opening Searcher@151f412 main
[junit4:junit4]   2> 9731 T334 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 9736 T334 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 9736 T334 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 9737 T334 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 9737 T334 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 9737 T334 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 9737 T334 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 9737 T334 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 9737 T334 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 9738 T334 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 9738 T334 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 9738 T334 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 9739 T334 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 9748 T345 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@151f412 main{StandardDirectoryReader(segments_a:33 _7(5.0):C1 _8(5.0):C1 _9(5.0):C1 _a(5.0):C1 _b(5.0):C1 _c(5.0):C1)}
[junit4:junit4]   2> 9748 T334 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 9749 T334 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> ASYNC  NEW_CORE C53 name=collection1 org.apache.solr.core.SolrCore@197439e
[junit4:junit4]   2> 9767 T334 C53 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@7686f8),segFN=segments_a,generation=10,filenames=[_c.inf, _7.si, _a.pst, _c.pst, _b.pst, _8.si, _a.inf, _9.inf, _7.fld, _c.si, _b.si, _b.fld, _9.si, _7.inf, segments_a, _7.pst, _a.si, _8.pst, _8.fld, _b.inf, _a.fld, _9.fld, _c.fld, _8.inf, _9.pst]
[junit4:junit4]   2> 9768 T334 C53 oasc.SolrDeletionPolicy.updateCommits newest commit = 10
[junit4:junit4]   2> 9768 T334 C53 oasu.DirectUpdateHandler2.addDoc Reordered DBQs detected.  Update=add{flags=0,_version_=117,id=Q7} DBQs=[DBQ{version=1413012018990940160,q=id:A11}]
[junit4:junit4]   2> 9778 T334 C53 oass.SolrIndexSearcher.<init> Opening Searcher@eae88f realtime
[junit4:junit4]   2> 9779 T334 C53 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 9779 T334 C53 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=21 
[junit4:junit4]   2> 9779 T334 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=18530653
[junit4:junit4]   2> 9780 T334 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@197439e
[junit4:junit4]   2> 9780 T334 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 9780 T334 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=1,adds=1,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=1,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> 9781 T334 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 9805 T334 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 9805 T334 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4:junit4]   2> 9807 T334 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 9808 T334 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 9869 T334 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 9947 T334 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
[junit4:junit4]   2> 9948 T334 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 9953 T334 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 10697 T334 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 10701 T334 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 10709 T334 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 10710 T334 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr
[junit4:junit4]   2> 10711 T334 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 10723 T334 oasc.CoreContainer.<init> New CoreContainer 26103553
[junit4:junit4]   2> 10724 T334 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\
[junit4:junit4]   2> 10724 T334 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 10726 T334 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\index/
[junit4:junit4]   2> 10727 T334 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 10727 T334 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 10728 T334 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 10729 T334 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 10731 T334 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 10735 T334 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\index forceNew:false
[junit4:junit4]   2> 10742 T334 oass.SolrIndexSearcher.<init> Opening Searcher@920dde main
[junit4:junit4]   2> 10742 T334 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 10746 T334 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 10747 T334 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 10747 T334 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 10747 T334 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 10748 T334 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 10748 T334 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 10748 T334 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 10749 T334 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 10749 T334 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 10749 T334 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 10750 T334 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 10750 T334 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 10761 T346 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@920dde main{StandardDirectoryReader(segments_a:33 _7(5.0):C1 _8(5.0):C1 _9(5.0):C1 _a(5.0):C1 _b(5.0):C1 _c(5.0):C1)}
[junit4:junit4]   2> 10761 T334 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> ASYNC  NEW_CORE C54 name=collection1 org.apache.solr.core.SolrCore@1b8d279
[junit4:junit4]   2> 10766 T347 C54 oasu.UpdateLog$LogReplayer.doReplay WARNING Starting log replay tlog{file=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\tlog\tlog.0000000000000000010 refcount=2} active=false starting pos=0
[junit4:junit4]   2> 10776 T347 C54 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@bf31c),segFN=segments_a,generation=10,filenames=[_c.inf, _7.si, _a.pst, _c.pst, _b.pst, _8.si, _a.inf, _9.inf, _7.fld, _c.si, _b.si, _b.fld, _9.si, _7.inf, segments_a, _7.pst, _a.si, _8.pst, _8.fld, _b.inf, _a.fld, _9.fld, _c.fld, _8.inf, _9.pst]
[junit4:junit4]   2> 10777 T347 C54 oasc.SolrDeletionPolicy.updateCommits newest commit = 10
[junit4:junit4]   2> 10777 T347 C54 oasu.DirectUpdateHandler2.addDoc Reordered DBQs detected.  Update=add{flags=10,_version_=117,id=Q7} DBQs=[DBQ{version=1413012018990940160,q=id:A11}]
[junit4:junit4]   2> 10787 T347 C54 oass.SolrIndexSearcher.<init> Opening Searcher@119e011 realtime
[junit4:junit4]   2> 10787 T347 C54 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 10787 T347 C54 oasu.DirectUpdateHandler2.commit start commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 10791 T347 C54 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@bf31c),segFN=segments_a,generation=10,filenames=[_c.inf, _7.si, _a.pst, _c.pst, _b.pst, _8.si, _a.inf, _9.inf, _7.fld, _c.si, _b.si, _b.fld, _9.si, _7.inf, segments_a, _7.pst, _a.si, _8.pst, _8.fld, _b.inf, _a.fld, _9.fld, _c.fld, _8.inf, _9.pst]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@bf31c),segFN=segments_b,generation=11,filenames=[_c.inf, _7.si, _a.pst, _c.pst, _d.pst, _b.pst, _8.si, _a.inf, _9.inf, _d.inf, _7.fld, _c.si, _b.si, _b.fld, _9.si, _7.inf, segments_b, _7.pst, _a.si, _8.pst, _8.fld, _b.inf, _a.fld, _d.si, _9.fld, _c.fld, _8.inf, _9.pst, _d.fld]
[junit4:junit4]   2> 10791 T347 C54 oasc.SolrDeletionPolicy.updateCommits newest commit = 11
[junit4:junit4]   2> 10793 T347 C54 oass.SolrIndexSearcher.<init> Opening Searcher@1dfe086 main
[junit4:junit4]   2> 10793 T347 C54 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 10794 T347 C54 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 10794 T346 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1dfe086 main{StandardDirectoryReader(segments_a:35:nrt _7(5.0):C1 _8(5.0):C1 _9(5.0):C1 _a(5.0):C1 _b(5.0):C1 _c(5.0):C1 _d(5.0):C1)}
[junit4:junit4]   2> 10795 T347 C54 oasu.UpdateLog$LogReplayer.run WARNING Log replay finished. recoveryInfo=RecoveryInfo{adds=1 deletes=0 deleteByQuery=0 errors=0 positionOfStart=0}
[junit4:junit4]   2> 10795 T334 oas.SolrTestCaseJ4.tearDown ###Ending testBufferingFlags
[junit4:junit4]   2> 10798 T334 oas.SolrTestCaseJ4.setUp ###Starting testBuffering
[junit4:junit4]   2> 10811 T334 C54 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 10817 T334 C54 oass.SolrIndexSearcher.<init> Opening Searcher@1f60505 realtime
[junit4:junit4]   2> 10817 T334 C54 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 10818 T334 C54 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> 10822 T334 C54 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 10825 T334 C54 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@bf31c),segFN=segments_b,generation=11,filenames=[_c.inf, _7.si, _a.pst, _c.pst, _d.pst, _b.pst, _8.si, _a.inf, _9.inf, _d.inf, _7.fld, _c.si, _b.si, _b.fld, _9.si, _7.inf, segments_b, _7.pst, _a.si, _8.pst, _8.fld, _b.inf, _a.fld, _d.si, _9.fld, _c.fld, _8.inf, _9.pst, _d.fld]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@bf31c),segFN=segments_c,generation=12,filenames=[segments_c]
[junit4:junit4]   2> 10826 T334 C54 oasc.SolrDeletionPolicy.updateCommits newest commit = 12
[junit4:junit4]   2> 10840 T334 C54 oass.SolrIndexSearcher.<init> Opening Searcher@13da2f0 main
[junit4:junit4]   2> 10841 T334 C54 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 10841 T346 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@13da2f0 main{StandardDirectoryReader(segments_b:36:nrt)}
[junit4:junit4]   2> 10841 T334 C54 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 10852 T334 C54 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 30
[junit4:junit4]   2> 10866 T334 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 10866 T334 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 10876 T334 C54 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=2 
[junit4:junit4]   2> 10881 T334 C54 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 10886 T334 C54 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&_version_=-1017&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 10893 T334 C54 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 10898 T334 C54 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 10906 T334 C54 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> 10907 T334 C54 REQ [collection1] webapp=null path=null params={getVersions=6&qt=/get} status=0 QTime=1 
[junit4:junit4]   2> 10913 T334 C54 oasup.DistributedUpdateProcessor.processCommit Ignoring commit while not ACTIVE - state: BUFFERING replay:0
[junit4:junit4]   2> 10913 T334 C54 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 0
[junit4:junit4]   2> 10915 T334 C54 REQ [collection1] webapp=null path=null params={getVersions=6&qt=/get} status=0 QTime=1 
[junit4:junit4]   2> 10915 T334 C54 REQ [collection1] webapp=null path=null params={q=*:*} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 10915 T334 C54 REQ [collection1] webapp=null path=null params={id=B3&qt=/get} status=0 QTime=0 
[junit4:junit4]   2> 10916 T347 C54 oasu.UpdateLog$LogReplayer.doReplay WARNING Starting log replay tlog{file=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\tlog\tlog.0000000000000000013 refcount=2} active=true starting pos=0
[junit4:junit4]   2> 10926 T347 C54 oass.SolrIndexSearcher.<init> Opening Searcher@ab8e20 realtime
[junit4:junit4]   2> 10926 T347 C54 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 10928 T347 C54 oasu.DirectUpdateHandler2.commit start commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 10934 T347 C54 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@bf31c),segFN=segments_c,generation=12,filenames=[segments_c]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@bf31c),segFN=segments_d,generation=13,filenames=[segments_d, _f.fld, _f.pst, _f.inf, _f.si]
[junit4:junit4]   2> 10934 T347 C54 oasc.SolrDeletionPolicy.updateCommits newest commit = 13
[junit4:junit4]   2> 10938 T347 C54 oass.SolrIndexSearcher.<init> Opening Searcher@ba648 main
[junit4:junit4]   2> 10938 T347 C54 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 10939 T346 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@ba648 main{StandardDirectoryReader(segments_d:42:nrt _f(5.0):C2)}
[junit4:junit4]   2> 10940 T347 C54 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 10940 T347 C54 oasu.UpdateLog$LogReplayer.run WARNING Log replay finished. recoveryInfo=RecoveryInfo{adds=4 deletes=1 deleteByQuery=1 errors=0 positionOfStart=0}
[junit4:junit4]   2> 10941 T334 C54 REQ [collection1] webapp=null path=null params={getVersions=6&qt=/get} status=0 QTime=1 
[junit4:junit4]   2> 10942 T334 C54 REQ [collection1] webapp=null path=null params={q=*:*} hits=2 status=0 QTime=1 
[junit4:junit4]   2> 10942 T334 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 10942 T334 C54 REQ [collection1] webapp=null path=null params={id=B3&qt=/get} status=0 QTime=0 
[junit4:junit4]   2> 10959 T334 C54 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=1 
[junit4:junit4]   2> 10964 T334 C54 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 10972 T334 C54 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> 10977 T334 C54 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 10982 T334 C54 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 10988 T334 C54 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 10993 T334 C54 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&_version_=-3000&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 10993 T334 C54 REQ [collection1] webapp=null path=null params={getVersions=13&qt=/get} status=0 QTime=0 
[junit4:junit4]   2> 10994 T347 C54 oasu.UpdateLog$LogReplayer.doReplay WARNING Starting log replay tlog{file=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\tlog\tlog.0000000000000000014 refcount=2} active=true starting pos=0
[junit4:junit4]   2> 11000 T334 C54 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 11003 T334 C54 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> 11009 T334 C54 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> 11016 T347 C54 oass.SolrIndexSearcher.<init> Opening Searcher@260fb6 realtime
[junit4:junit4]   2> 11016 T347 C54 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 11017 T347 C54 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> 11021 T347 C54 oass.SolrIndexSearcher.<init> Opening Searcher@280c42 realtime
[junit4:junit4]   2> 11021 T347 C54 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 11022 T347 C54 oasu.DirectUpdateHandler2.commit start commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 11026 T347 C54 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@bf31c),segFN=segments_d,generation=13,filenames=[segments_d, _f.fld, _f.pst, _f.inf, _f.si]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@bf31c),segFN=segments_e,generation=14,filenames=[segments_e, _g.si, _h.inf, _f.fld, _h.fld, _g.inf, _f.inf, _f.si, _f_1.liv, _h.si, _f.pst, _g_1.liv, _g.fld, _h.pst, _g.pst]
[junit4:junit4]   2> 11026 T347 C54 oasc.SolrDeletionPolicy.updateCommits newest commit = 14
[junit4:junit4]   2> 11029 T347 C54 oass.SolrIndexSearcher.<init> Opening Searcher@8b719f main
[junit4:junit4]   2> 11029 T347 C54 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 11030 T346 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@8b719f main{StandardDirectoryReader(segments_e:48:nrt _f(5.0):C2/1 _g(5.0):C4/2 _h(5.0):C1)}
[junit4:junit4]   2> 11030 T347 C54 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 11030 T347 C54 oasu.UpdateLog$LogReplayer.run WARNING Log replay finished. recoveryInfo=RecoveryInfo{adds=6 deletes=3 deleteByQuery=1 errors=0 positionOfStart=0}
[junit4:junit4]   2> 11030 T334 C54 REQ [collection1] webapp=null path=null params={fl=id,_version_&sort=id+asc&q=*:*} hits=4 status=0 QTime=0 
[junit4:junit4]   2> 11031 T334 oas.SolrTestCaseJ4.tearDown ###Ending testBuffering
[junit4:junit4]   2> 11034 T334 oas.SolrTestCaseJ4.setUp ###Starting testCorruptLog
[junit4:junit4]   2> 11046 T334 C54 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 11049 T334 C54 oass.SolrIndexSearcher.<init> Opening Searcher@10c10a4 realtime
[junit4:junit4]   2> 11049 T334 C54 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 11050 T334 C54 REQ [collection1] webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true} status=0 QTime=4 
[junit4:junit4]   2> 11054 T334 C54 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 11057 T334 C54 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@bf31c),segFN=segments_e,generation=14,filenames=[segments_e, _g.si, _h.inf, _f.fld, _h.fld, _g.inf, _f.inf, _f.si, _f_1.liv, _h.si, _f.pst, _g_1.liv, _g.fld, _h.pst, _g.pst]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@bf31c),segFN=segments_f,generation=15,filenames=[segments_f]
[junit4:junit4]   2> 11058 T334 C54 oasc.SolrDeletionPolicy.updateCommits newest commit = 15
[junit4:junit4]   2> 11066 T334 C54 oass.SolrIndexSearcher.<init> Opening Searcher@1637e59 main
[junit4:junit4]   2> 11067 T334 C54 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 11067 T346 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1637e59 main{StandardDirectoryReader(segments_e:49:nrt)}
[junit4:junit4]   2> 11067 T334 C54 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 11072 T334 C54 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 18
[junit4:junit4]   2> 11084 T334 C54 UPDATE [collection1] webapp=null path=null params={} {add=[G1 (1413012029098164224)]} 0 3
[junit4:junit4]   2> 11089 T334 C54 UPDATE [collection1] webapp=null path=null params={} {add=[G2 (1413012029106552832)]} 0 0
[junit4:junit4]   2> 11099 T334 C54 UPDATE [collection1] webapp=null path=null params={} {add=[G3 (1413012029115990016)]} 0 1
[junit4:junit4]   2> 11101 T334 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=26103553
[junit4:junit4]   2> 11102 T334 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@1b8d279
[junit4:junit4]   2> 11103 T334 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 11103 T334 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=5,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=3,adds=3,deletesById=0,deletesByQuery=0,errors=2,cumulative_adds=13,cumulative_deletesById=2,cumulative_deletesByQuery=4,cumulative_errors=2}
[junit4:junit4]   2> 11103 T334 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 11109 T334 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 11113 T334 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4:junit4]   2> 11115 T334 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 11117 T334 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 11179 T334 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 11243 T334 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
[junit4:junit4]   2> 11243 T334 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 11249 T334 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 11863 T334 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 11867 T334 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 11877 T334 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 11877 T334 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr
[junit4:junit4]   2> 11877 T334 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 11890 T334 oasc.CoreContainer.<init> New CoreContainer 2230819
[junit4:junit4]   2> 11891 T334 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\
[junit4:junit4]   2> 11892 T334 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 11893 T334 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\index/
[junit4:junit4]   2> 11894 T334 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 11895 T334 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 11896 T334 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 11896 T334 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 11899 T334 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 11903 T334 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\index forceNew:false
[junit4:junit4]   2> 11903 T334 oass.SolrIndexSearcher.<init> Opening Searcher@82112e main
[junit4:junit4]   2> 11903 T334 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 11907 T334 oasc.SolrException.log Ignoring exception matching Failure to open existing log file
[junit4:junit4]   2> 11908 T334 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 11909 T334 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 11909 T334 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 11909 T334 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 11909 T334 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 11910 T334 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 11910 T334 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 11911 T334 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 11911 T334 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 11911 T334 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 11912 T334 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 11912 T334 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 11922 T348 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@82112e main{StandardDirectoryReader(segments_f:49)}
[junit4:junit4]   2> 11922 T334 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> ASYNC  NEW_CORE C55 name=collection1 org.apache.solr.core.SolrCore@2dad75
[junit4:junit4]   2> 11926 T334 C55 REQ [collection1] webapp=null path=null params={q=*:*} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 11939 T334 C55 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@3c934b; maxCacheMB=0.1669921875 maxMergeSizeMB=0.3896484375)),segFN=segments_f,generation=15,filenames=[segments_f]
[junit4:junit4]   2> 11940 T334 C55 oasc.SolrDeletionPolicy.updateCommits newest commit = 15
[junit4:junit4]   2> 11940 T334 C55 oasu.DirectUpdateHandler2.addDoc Reordered DBQs detected.  Update=add{flags=0,_version_=104,id=G4} DBQs=[DBQ{version=1413012018990940160,q=id:A11}, DBQ{version=3000,q=id:B2 OR id:B8}, DBQ{version=1017,q=id:B1 id:B11 id:B2 id:B3}]
[junit4:junit4]   2> 11947 T334 C55 oass.SolrIndexSearcher.<init> Opening Searcher@17c71c7 realtime
[junit4:junit4]   2> 11948 T334 C55 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 11949 T334 C55 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=15 
[junit4:junit4]   2> 11963 T334 C55 oasu.DirectUpdateHandler2.addDoc Reordered DBQs detected.  Update=add{flags=0,_version_=105,id=G5} DBQs=[DBQ{version=1413012018990940160,q=id:A11}, DBQ{version=3000,q=id:B2 OR id:B8}, DBQ{version=1017,q=id:B1 id:B11 id:B2 id:B3}]
[junit4:junit4]   2> 11970 T334 C55 oass.SolrIndexSearcher.<init> Opening Searcher@395e27 realtime
[junit4:junit4]   2> 11971 T334 C55 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 11971 T334 C55 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=8 
[junit4:junit4]   2> 11982 T334 C55 oasu.DirectUpdateHandler2.addDoc Reordered DBQs detected.  Update=add{flags=0,_version_=106,id=G6} DBQs=[DBQ{version=1413012018990940160,q=id:A11}, DBQ{version=3000,q=id:B2 OR id:B8}, DBQ{version=1017,q=id:B1 id:B11 id:B2 id:B3}]
[junit4:junit4]   2> 11987 T334 C55 oass.SolrIndexSearcher.<init> Opening Searcher@7536f2 realtime
[junit4:junit4]   2> 11988 T334 C55 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 11989 T334 C55 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=7 
[junit4:junit4]   2> 11989 T334 C55 REQ [collection1] webapp=null path=null params={getVersions=3&qt=/get} status=0 QTime=0 
[junit4:junit4]   2> 11989 T334 oas.SolrTestCaseJ4.tearDown ###Ending testCorruptLog
[junit4:junit4]   2> 11993 T334 oas.SolrTestCaseJ4.setUp ###Starting testCleanShutdown
[junit4:junit4]   2> 12005 T334 C55 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 12011 T334 C55 oass.SolrIndexSearcher.<init> Opening Searcher@122005a realtime
[junit4:junit4]   2> 12012 T334 C55 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 12017 T334 C55 REQ [collection1] webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true} status=0 QTime=12 
[junit4:junit4]   2> 12023 T334 C55 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 12031 T334 C55 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@3c934b; maxCacheMB=0.1669921875 maxMergeSizeMB=0.3896484375)),segFN=segments_f,generation=15,filenames=[segments_f]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@3c934b; maxCacheMB=0.1669921875 maxMergeSizeMB=0.3896484375)),segFN=segments_g,generation=16,filenames=[segments_g]
[junit4:junit4]   2> 12031 T334 C55 oasc.SolrDeletionPolicy.updateCommits newest commit = 16
[junit4:junit4]   2> 12034 T334 C55 oass.SolrIndexSearcher.<init> Opening Searcher@1f169db main
[junit4:junit4]   2> 12035 T334 C55 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 12035 T348 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1f169db main{StandardDirectoryReader(segments_f:56:nrt)}
[junit4:junit4]   2> 12035 T334 C55 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 12036 T334 C55 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 13
[junit4:junit4]   2> 12053 T334 C55 UPDATE [collection1] webapp=null path=null params={} {add=[E1 (1413012030115282944)]} 0 2
[junit4:junit4]   2> 12061 T334 C55 UPDATE [collection1] webapp=null path=null params={} {add=[E2 (1413012030125768704)]} 0 1
[junit4:junit4]   2> 12062 T334 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=2230819
[junit4:junit4]   2> 12063 T334 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@2dad75
[junit4:junit4]   2> 12064 T334 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 12064 T334 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=2,adds=2,deletesById=0,deletesByQuery=0,errors=1,cumulative_adds=5,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=1}
[junit4:junit4]   2> 12064 T334 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 12079 T334 C55 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@3c934b; maxCacheMB=0.1669921875 maxMergeSizeMB=0.3896484375)),segFN=segments_g,generation=16,filenames=[segments_g]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@3c934b; maxCacheMB=0.1669921875 maxMergeSizeMB=0.3896484375)),segFN=segments_h,generation=17,filenames=[_l.si, _l.fld, _l.pst, _l.inf, segments_h]
[junit4:junit4]   2> 12080 T334 C55 oasc.SolrDeletionPolicy.updateCommits newest commit = 17
[junit4:junit4]   2> 12089 T334 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 12090 T334 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4:junit4]   2> 12092 T334 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 12093 T334 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 12160 T334 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 12218 T334 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
[junit4:junit4]   2> 12219 T334 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 12224 T334 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 12845 T334 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 12849 T334 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 12863 T334 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 12863 T334 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr
[junit4:junit4]   2> 12863 T334 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 12876 T334 oasc.CoreContainer.<init> New CoreContainer 8966034
[junit4:junit4]   2> 12877 T334 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\
[junit4:junit4]   2> 12877 T334 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 12878 T334 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\index/
[junit4:junit4]   2> 12880 T334 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 12880 T334 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 12881 T334 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 12881 T334 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 12884 T334 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 12888 T334 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\index forceNew:false
[junit4:junit4]   2> 12891 T334 oass.SolrIndexSearcher.<init> Opening Searcher@c012aa main
[junit4:junit4]   2> 12892 T334 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 12898 T334 oasc.SolrException.log SEVERE Failure to open existing log file (non fatal) C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\tlog\tlog.0000000000000000016:java.lang.RuntimeException: Invalid version (expected 2, but 0) or the data in not in 'javabin' format
[junit4:junit4]   2> 		at org.apache.solr.common.util.JavaBinCodec.unmarshal(JavaBinCodec.java:109)
[junit4:junit4]   2> 		at org.apache.solr.update.TransactionLog.readHeader(TransactionLog.java:253)
[junit4:junit4]   2> 		at org.apache.solr.update.TransactionLog.<init>(TransactionLog.java:159)
[junit4:junit4]   2> 		at org.apache.solr.update.UpdateLog.init(UpdateLog.java:200)
[junit4:junit4]   2> 		at org.apache.solr.update.UpdateHandler.initLog(UpdateHandler.java:94)
[junit4:junit4]   2> 		at org.apache.solr.update.UpdateHandler.<init>(UpdateHandler.java:123)
[junit4:junit4]   2> 		at org.apache.solr.update.DirectUpdateHandler2.<init>(DirectUpdateHandler2.java:97)
[junit4:junit4]   2> 		at sun.reflect.GeneratedConstructorAccessor121.newInstance(Unknown Source)
[junit4:junit4]   2> 		at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
[junit4:junit4]   2> 		at java.lang.reflect.Constructor.newInstance(Constructor.java:525)
[junit4:junit4]   2> 		at org.apache.solr.core.SolrCore.createInstance(SolrCore.java:476)
[junit4:junit4]   2> 		at org.apache.solr.core.SolrCore.createUpdateHandler(SolrCore.java:544)
[junit4:junit4]   2> 		at org.apache.solr.core.SolrCore.<init>(SolrCore.java:707)
[junit4:junit4]   2> 		at org.apache.solr.core.SolrCore.<init>(SolrCore.java:567)
[junit4:junit4]   2> 		at org.apache.solr.util.TestHarness$Initializer.initialize(TestHarness.java:190)
[junit4:junit4]   2> 		at org.apache.solr.util.TestHarness.<init>(TestHarness.java:114)
[junit4:junit4]   2> 		at org.apache.solr.util.TestHarness.<init>(TestHarness.java:109)
[junit4:junit4]   2> 		at org.apache.solr.util.TestHarness.<init>(TestHarness.java:99)
[junit4:junit4]   2> 		at org.apache.solr.SolrTestCaseJ4.createCore(SolrTestCaseJ4.java:362)
[junit4:junit4]   2> 		at org.apache.solr.search.TestRecovery.testCleanShutdown(TestRecovery.java:659)
[junit4:junit4]   2> 		at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]   2> 		at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
[junit4:junit4]   2> 		at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4:junit4]   2> 		at java.lang.reflect.Method.invoke(Method.java:601)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
[junit4:junit4]   2> 		at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]   2> 		at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]   2> 		at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]   2> 	
[junit4:junit4]   2> 12903 T334 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 12903 T334 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 12904 T334 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 12905 T334 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 12905 T334 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 12905 T334 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 12905 T334 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 12906 T334 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 12906 T334 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 12906 T334 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 12907 T334 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 12907 T334 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 12916 T349 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c012aa main{StandardDirectoryReader(segments_h:58 _l(5.0):C2)}
[junit4:junit4]   2> 12916 T334 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> ASYNC  NEW_CORE C56 name=collection1 org.apache.solr.core.SolrCore@1752758
[junit4:junit4]   2> 12922 T334 C56 REQ [collection1] webapp=null path=null params={q=*:*} hits=2 status=0 QTime=0 
[junit4:junit4]   2> 12923 T334 oas.SolrTestCaseJ4.tearDown ###Ending testCleanShutdown
[junit4:junit4]   2> 12928 T334 oas.SolrTestCaseJ4.setUp ###Starting testRecoveryMultipleLogs
[junit4:junit4]   2> 12942 T334 C56 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 12952 T334 C56 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@96b4e7),segFN=segments_h,generation=17,filenames=[_l.si, _l.pst, _l.fld, _l.inf, segments_h]
[junit4:junit4]   2> 12952 T334 C56 oasc.SolrDeletionPolicy.updateCommits newest commit = 17
[junit4:junit4]   2> 12952 T334 C56 oass.SolrIndexSearcher.<init> Opening Searcher@15b32ea realtime
[junit4:junit4]   2> 12952 T334 C56 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 12953 T334 C56 REQ [collection1] webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true} status=0 QTime=11 
[junit4:junit4]   2> 12958 T334 C56 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 12960 T334 C56 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@96b4e7),segFN=segments_h,generation=17,filenames=[_l.si, _l.pst, _l.fld, _l.inf, segments_h]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@96b4e7),segFN=segments_i,generation=18,filenames=[segments_i]
[junit4:junit4]   2> 12960 T334 C56 oasc.SolrDeletionPolicy.updateCommits newest commit = 18
[junit4:junit4]   2> 12964 T334 C56 oass.SolrIndexSearcher.<init> Opening Searcher@1859334 main
[junit4:junit4]   2> 12964 T334 C56 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 12965 T334 C56 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 12966 T349 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1859334 main{StandardDirectoryReader(segments_h:59:nrt)}
[junit4:junit4]   2> 12967 T334 C56 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 9
[junit4:junit4]   2> 12986 T334 C56 UPDATE [collection1] webapp=null path=null params={} {add=[AAAAAA (1413012031093604352)]} 0 2
[junit4:junit4]   2> 12993 T334 C56 UPDATE [collection1] webapp=null path=null params={} {add=[BBBBBB (1413012031101992960)]} 0 1
[junit4:junit4]   2> 13003 T334 C56 UPDATE [collection1] webapp=null path=null params={} {add=[CCCCCC (1413012031113527296)]} 0 0
[junit4:junit4]   2> 13006 T334 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=8966034
[junit4:junit4]   2> 13006 T334 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@1752758
[junit4:junit4]   2> 13008 T334 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 13010 T334 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> 13010 T334 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 13015 T334 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 13019 T334 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4:junit4]   2> 13020 T334 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 13021 T334 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 13088 T334 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 13150 T334 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
[junit4:junit4]   2> 13151 T334 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 13156 T334 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 13775 T334 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 13780 T334 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 13790 T334 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 13790 T334 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr
[junit4:junit4]   2> 13790 T334 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 13807 T334 oasc.CoreContainer.<init> New CoreContainer 17632387
[junit4:junit4]   2> 13807 T334 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\
[junit4:junit4]   2> 13807 T334 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 13808 T334 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\index/
[junit4:junit4]   2> 13811 T334 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 13811 T334 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 13812 T334 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 13812 T334 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 13814 T334 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 13819 T334 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\index forceNew:false
[junit4:junit4]   2> 13820 T334 oass.SolrIndexSearcher.<init> Opening Searcher@161373c main
[junit4:junit4]   2> 13820 T334 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 13823 T334 oasc.SolrException.log SEVERE Failure to open existing log file (non fatal) C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\tlog\tlog.0000000000000000016:java.lang.RuntimeException: Invalid version (expected 2, but 0) or the data in not in 'javabin' format
[junit4:junit4]   2> 		at org.apache.solr.common.util.JavaBinCodec.unmarshal(JavaBinCodec.java:109)
[junit4:junit4]   2> 		at org.apache.solr.update.TransactionLog.readHeader(TransactionLog.java:253)
[junit4:junit4]   2> 		at org.apache.solr.update.TransactionLog.<init>(TransactionLog.java:159)
[junit4:junit4]   2> 		at org.apache.solr.update.UpdateLog.init(UpdateLog.java:200)
[junit4:junit4]   2> 		at org.apache.solr.update.UpdateHandler.initLog(UpdateHandler.java:94)
[junit4:junit4]   2> 		at org.apache.solr.update.UpdateHandler.<init>(UpdateHandler.java:123)
[junit4:junit4]   2> 		at org.apache.solr.update.DirectUpdateHandler2.<init>(DirectUpdateHandler2.java:97)
[junit4:junit4]   2> 		at sun.reflect.GeneratedConstructorAccessor121.newInstance(Unknown Source)
[junit4:junit4]   2> 		at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
[junit4:junit4]   2> 		at java.lang.reflect.Constructor.newInstance(Constructor.java:525)
[junit4:junit4]   2> 		at org.apache.solr.core.SolrCore.createInstance(SolrCore.java:476)
[junit4:junit4]   2> 		at org.apache.solr.core.SolrCore.createUpdateHandler(SolrCore.java:544)
[junit4:junit4]   2> 		at org.apache.solr.core.SolrCore.<init>(SolrCore.java:707)
[junit4:junit4]   2> 		at org.apache.solr.core.SolrCore.<init>(SolrCore.java:567)
[junit4:junit4]   2> 		at org.apache.solr.util.TestHarness$Initializer.initialize(TestHarness.java:190)
[junit4:junit4]   2> 		at org.apache.solr.util.TestHarness.<init>(TestHarness.java:114)
[junit4:junit4]   2> 		at org.apache.solr.util.TestHarness.<init>(TestHarness.java:109)
[junit4:junit4]   2> 		at org.apache.solr.util.TestHarness.<init>(TestHarness.java:99)
[junit4:junit4]   2> 		at org.apache.solr.SolrTestCaseJ4.createCore(SolrTestCaseJ4.java:362)
[junit4:junit4]   2> 		at org.apache.solr.search.TestRecovery.testRecoveryMultipleLogs(TestRecovery.java:1013)
[junit4:junit4]   2> 		at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]   2> 		at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
[junit4:junit4]   2> 		at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4:junit4]   2> 		at java.lang.reflect.Method.invoke(Method.java:601)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
[junit4:junit4]   2> 		at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]   2> 		at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]   2> 		at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]   2> 	
[junit4:junit4]   2> 13826 T334 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 13827 T334 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 13827 T334 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 13827 T334 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 13828 T334 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 13828 T334 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 13828 T334 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 13829 T334 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 13829 T334 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 13829 T334 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 13830 T334 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 13830 T334 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 13839 T350 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@161373c main{StandardDirectoryReader(segments_i:59)}
[junit4:junit4]   2> 13839 T334 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> ASYNC  NEW_CORE C57 name=collection1 org.apache.solr.core.SolrCore@1b8c21a
[junit4:junit4]   2> 13840 T351 C57 oasu.UpdateLog$LogReplayer.doReplay WARNING Starting log replay tlog{file=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\tlog\tlog.0000000000000000020 refcount=2} active=false starting pos=0
[junit4:junit4]   2> 13849 T351 C57 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@17292af; maxCacheMB=0.3916015625 maxMergeSizeMB=0.3154296875)),segFN=segments_i,generation=18,filenames=[segments_i]
[junit4:junit4]   2> 13850 T351 C57 oasc.SolrDeletionPolicy.updateCommits newest commit = 18
[junit4:junit4]   2> 13851 T351 C57 oasc.SolrException.log Ignoring exception matching OutOfBoundsException
[junit4:junit4]   2> 13851 T351 C57 oasu.DirectUpdateHandler2.commit start commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 13862 T351 C57 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@17292af; maxCacheMB=0.3916015625 maxMergeSizeMB=0.3154296875)),segFN=segments_i,generation=18,filenames=[segments_i]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@17292af; maxCacheMB=0.3916015625 maxMergeSizeMB=0.3154296875)),segFN=segments_j,generation=19,filenames=[_m.si, _m.inf, _m.pst, _m.fld, segments_j]
[junit4:junit4]   2> 13863 T351 C57 oasc.SolrDeletionPolicy.updateCommits newest commit = 19
[junit4:junit4]   2> 13868 T351 C57 oass.SolrIndexSearcher.<init> Opening Searcher@931da1 main
[junit4:junit4]   2> 13868 T351 C57 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 13869 T351 C57 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 13869 T350 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@931da1 main{StandardDirectoryReader(segments_j:61 _m(5.0):C3)}
[junit4:junit4]   2> 13869 T351 C57 oasu.UpdateLog$LogReplayer.doReplay WARNING Starting log replay tlog{file=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\tlog\tlog.0000000000000000021 refcount=2} active=false starting pos=0
[junit4:junit4]   2> 13871 T351 C57 oasc.SolrException.log Ignoring exception matching OutOfBoundsException
[junit4:junit4]   2> 13871 T351 C57 oasu.DirectUpdateHandler2.commit start commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 13884 T351 C57 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@17292af; maxCacheMB=0.3916015625 maxMergeSizeMB=0.3154296875)),segFN=segments_j,generation=19,filenames=[_m.si, _m.inf, _m.pst, _m.fld, segments_j]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@17292af; maxCacheMB=0.3916015625 maxMergeSizeMB=0.3154296875)),segFN=segments_k,generation=20,filenames=[_n.inf, _m.si, _m.inf, _n.si, _n.pst, _m.pst, _m.fld, segments_k, _n.fld]
[junit4:junit4]   2> 13884 T351 C57 oasc.SolrDeletionPolicy.updateCommits newest commit = 20
[junit4:junit4]   2> 13892 T351 C57 oass.SolrIndexSearcher.<init> Opening Searcher@1db253f main
[junit4:junit4]   2> 13892 T351 C57 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 13892 T351 C57 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 13893 T350 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1db253f main{StandardDirectoryReader(segments_k:63 _m(5.0):C3 _n(5.0):C3)}
[junit4:junit4]   2> 13893 T351 C57 oasu.UpdateLog$LogReplayer.run WARNING Log replay finished. recoveryInfo=RecoveryInfo{adds=6 deletes=0 deleteByQuery=0 errors=0 positionOfStart=0}
[junit4:junit4]   2> 13894 T334 C57 REQ [collection1] webapp=null path=null params={q=*:*} hits=6 status=0 QTime=1 
[junit4:junit4]   2> 13895 T334 oas.SolrTestCaseJ4.tearDown ###Ending testRecoveryMultipleLogs
[junit4:junit4]   2> 13898 T334 oas.SolrTestCaseJ4.setUp ###Starting testDropBuffered
[junit4:junit4]   2> 13912 T334 C57 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 13913 T334 C57 oass.SolrIndexSearcher.<init> Opening Searcher@e0fab9 realtime
[junit4:junit4]   2> 13914 T334 C57 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 13914 T334 C57 REQ [collection1] webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true} status=0 QTime=2 
[junit4:junit4]   2> 13919 T334 C57 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 13925 T334 C57 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@17292af; maxCacheMB=0.3916015625 maxMergeSizeMB=0.3154296875)),segFN=segments_k,generation=20,filenames=[_n.inf, _m.si, _m.inf, _n.si, _n.pst, _m.pst, _m.fld, segments_k, _n.fld]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@17292af; maxCacheMB=0.3916015625 maxMergeSizeMB=0.3154296875)),segFN=segments_l,generation=21,filenames=[segments_l]
[junit4:junit4]   2> 13926 T334 C57 oasc.SolrDeletionPolicy.updateCommits newest commit = 21
[junit4:junit4]   2> 13933 T334 C57 oass.SolrIndexSearcher.<init> Opening Searcher@ed18bb main
[junit4:junit4]   2> 13934 T334 C57 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 13934 T350 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@ed18bb main{StandardDirectoryReader(segments_k:64:nrt)}
[junit4:junit4]   2> 13934 T334 C57 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 13935 T334 C57 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 16
[junit4:junit4]   2> 13949 T334 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 13949 T334 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 13959 T334 C57 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=2 
[junit4:junit4]   2> 13970 T334 C57 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 13974 T334 C57 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=1 
[junit4:junit4]   2> 13974 T334 oasu.UpdateLog.dropBufferedUpdates Dropping buffered updates FSUpdateLog{state=BUFFERING, tlog=tlog{file=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\tlog\tlog.0000000000000000025 refcount=1}}
[junit4:junit4]   2> 13975 T334 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=tlog{file=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\tlog\tlog.0000000000000000025 refcount=1}}
[junit4:junit4]   2> 13980 T334 C57 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 13985 T334 C57 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 13986 T351 C57 oasu.UpdateLog$LogReplayer.doReplay WARNING Starting log replay tlog{file=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\tlog\tlog.0000000000000000025 refcount=2} active=true starting pos=0
[junit4:junit4]   2> 13989 T351 C57 oasu.DirectUpdateHandler2.commit start commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 14009 T351 C57 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@17292af; maxCacheMB=0.3916015625 maxMergeSizeMB=0.3154296875)),segFN=segments_l,generation=21,filenames=[segments_l]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@17292af; maxCacheMB=0.3916015625 maxMergeSizeMB=0.3154296875)),segFN=segments_m,generation=22,filenames=[segments_m, _o.si, _o.inf, _o.fld, _o.pst]
[junit4:junit4]   2> 14009 T351 C57 oasc.SolrDeletionPolicy.updateCommits newest commit = 22
[junit4:junit4]   2> 14012 T351 C57 oass.SolrIndexSearcher.<init> Opening Searcher@1761d26 main
[junit4:junit4]   2> 14013 T351 C57 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 14013 T350 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1761d26 main{StandardDirectoryReader(segments_m:66:nrt _o(5.0):C2)}
[junit4:junit4]   2> 14019 T351 C57 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 14019 T351 C57 oasu.UpdateLog$LogReplayer.run WARNING Log replay finished. recoveryInfo=RecoveryInfo{adds=2 deletes=0 deleteByQuery=0 errors=0 positionOfStart=0}
[junit4:junit4]   2> 14020 T334 C57 REQ [collection1] webapp=null path=null params={getVersions=2&qt=/get} status=0 QTime=1 
[junit4:junit4]   2> 14034 T334 C57 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=3 
[junit4:junit4]   2> 14046 T334 C57 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=1 
[junit4:junit4]   2> 14046 T334 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=tlog{file=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\tlog\tlog.0000000000000000026 refcount=1}}
[junit4:junit4]   2> 14052 T334 C57 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=1 
[junit4:junit4]   2> 14057 T334 C57 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 14057 T334 oasu.UpdateLog.dropBufferedUpdates Dropping buffered updates FSUpdateLog{state=BUFFERING, tlog=tlog{file=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\tlog\tlog.0000000000000000026 refcount=1}}
[junit4:junit4]   2> 14058 T334 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=tlog{file=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\tlog\tlog.0000000000000000026 refcount=1}}
[junit4:junit4]   2> 14062 T334 C57 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 14068 T334 C57 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 14068 T351 C57 oasu.UpdateLog$LogReplayer.doReplay WARNING Starting log replay tlog{file=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\tlog\tlog.0000000000000000026 refcount=2} active=true starting pos=92
[junit4:junit4]   2> 14071 T351 C57 oasu.DirectUpdateHandler2.commit start commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 14087 T351 C57 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@17292af; maxCacheMB=0.3916015625 maxMergeSizeMB=0.3154296875)),segFN=segments_m,generation=22,filenames=[segments_m, _o.si, _o.inf, _o.fld, _o.pst]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@17292af; maxCacheMB=0.3916015625 maxMergeSizeMB=0.3154296875)),segFN=segments_n,generation=23,filenames=[_p.fld, _p.pst, _p.inf, segments_n, _o.si, _o.inf, _p.si, _o.fld, _o.pst]
[junit4:junit4]   2> 14087 T351 C57 oasc.SolrDeletionPolicy.updateCommits newest commit = 23
[junit4:junit4]   2> 14090 T351 C57 oass.SolrIndexSearcher.<init> Opening Searcher@19184a6 main
[junit4:junit4]   2> 14090 T351 C57 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 14091 T350 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@19184a6 main{StandardDirectoryReader(segments_n:68:nrt _o(5.0):C2 _p(5.0):C4)}
[junit4:junit4]   2> 14092 T351 C57 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 14092 T351 C57 oasu.UpdateLog$LogReplayer.run WARNING Log replay finished. recoveryInfo=RecoveryInfo{adds=2 deletes=0 deleteByQuery=0 errors=0 positionOfStart=92}
[junit4:junit4]   2> 14093 T334 C57 REQ [collection1] webapp=null path=null params={fl=id,_version_&sort=_version_+asc&q=*:*} hits=6 status=0 QTime=1 
[junit4:junit4]   2> 14094 T334 C57 REQ [collection1] webapp=null path=null params={getVersions=6&qt=/get} status=0 QTime=0 
[junit4:junit4]   2> 14095 T334 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 14108 T334 C57 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=1 
[junit4:junit4]   2> 14114 T334 C57 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 14114 T334 oasu.UpdateLog.dropBufferedUpdates Dropping buffered updates FSUpdateLog{state=BUFFERING, tlog=tlog{file=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\tlog\tlog.0000000000000000027 refcount=1}}
[junit4:junit4]   2> 14120 T334 C57 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=2 
[junit4:junit4]   2> 14136 T334 C57 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=1 
[junit4:junit4]   2> 14140 T334 C57 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 14158 T334 C57 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@17292af; maxCacheMB=0.3916015625 maxMergeSizeMB=0.3154296875)),segFN=segments_n,generation=23,filenames=[_p.fld, _p.pst, _p.inf, segments_n, _o.si, _o.inf, _p.si, _o.fld, _o.pst]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@17292af; maxCacheMB=0.3916015625 maxMergeSizeMB=0.3154296875)),segFN=segments_o,generation=24,filenames=[_q.si, _p.fld, _q.inf, _p.pst, _p.inf, segments_o, _q.fld, _o.si, _o.inf, _p.si, _o.fld, _o.pst, _q.pst]
[junit4:junit4]   2> 14158 T334 C57 oasc.SolrDeletionPolicy.updateCommits newest commit = 24
[junit4:junit4]   2> 14163 T334 C57 oass.SolrIndexSearcher.<init> Opening Searcher@31d3aa main
[junit4:junit4]   2> 14163 T334 C57 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 14164 T350 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@31d3aa main{StandardDirectoryReader(segments_o:70:nrt _o(5.0):C2 _p(5.0):C4 _q(5.0):C2)}
[junit4:junit4]   2> 14165 T334 C57 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 14165 T334 C57 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 25
[junit4:junit4]   2> 14171 T334 C57 REQ [collection1] webapp=null path=null params={getVersions=2&qt=/get} status=0 QTime=0 
[junit4:junit4]   2> 14172 T334 C57 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> 14182 T334 C57 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=3 
[junit4:junit4]   2> 14182 T334 oas.SolrTestCaseJ4.tearDown ###Ending testDropBuffered
[junit4:junit4]   2> 14187 T334 oas.SolrTestCaseJ4.setUp ###Starting testTruncatedLog
[junit4:junit4]   2> 14203 T334 C57 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 14206 T334 C57 oass.SolrIndexSearcher.<init> Opening Searcher@dc8ec7 realtime
[junit4:junit4]   2> 14207 T334 C57 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 14207 T334 C57 REQ [collection1] webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true} status=0 QTime=4 
[junit4:junit4]   2> 14212 T334 C57 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 14219 T334 C57 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@17292af; maxCacheMB=0.3916015625 maxMergeSizeMB=0.3154296875)),segFN=segments_o,generation=24,filenames=[_q.si, _p.fld, _q.inf, _p.pst, _p.inf, segments_o, _q.fld, _o.si, _o.inf, _p.si, _o.fld, _o.pst, _q.pst]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347553269846\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@17292af; maxCacheMB=0.3916015625 maxMergeSizeMB=0.3154296875)),segFN=segments_p,generation=25,filenames=[segments_p]
[junit4:junit4]   2> 14219 T334 C57 oasc.SolrDeletionPolicy.updateCommits newest commit = 25
[junit4:junit4]   2> 14228 T334 C57 oass.SolrIndexSearcher.<init> Opening Searcher@1536f64 main
[junit4:junit4]   2> 14228 T334 C57 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 14229 T350 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1536f64 main{StandardDirectoryReader(segments_o:72:nrt)}
[junit4:junit4]   2> 14230 T334 C57 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 14234 T334 C57 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 22
[junit4:junit4]   2> 14246 T334 C57 UPDATE [collection1] webapp=null path=null params={} {add=[F1 (1413012032413761536)]} 0 3
[junit4:junit4]   2> 14256 T334 C57 UPDATE [collection1] webapp=null path=null params={} {add=[F2 (1413012032426344448)]} 0 1
[junit4:junit4]   2> 14262 T334 C57 UPDATE [collection1] webapp=null path=null params={} {add=[F3 (1413012032433684480)]} 0 0
[junit4:junit4]   2> 14264 T334 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=17632387
[junit4:junit4]   2> 14264 T334 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@1b8c21a
[junit4:junit4]   2> 14265 T334 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 14265 T334 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=7,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=3,adds=3,deletesById=0,deletesByQuery=0,errors=2,cumulative_adds=18,cumulative_deletesById=0,cumulative_deletesByQuery=2,cumulative_errors=2}
[junit4:junit4]   2> 14265 T334 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 14277 T334 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 14282 T334 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4:junit4]   2> 14286 T334 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 14288 T334 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 14370 T334 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 14431 T334 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
[junit4:junit4]   2> 14432 T334 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 14437 T334 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 15069 T334 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 15073 T334 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 15082 T334 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 15083 T334 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr
[junit4:junit4]   2> 15083 T334 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 15094 T334 oasc.CoreContainer.<init> New CoreContainer 21538967
[junit4:junit4]   2> 15095 T334 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\
[junit4:junit4]   2> 15095 T334 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 15097 T334 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\index/
[junit4:junit4]   2> 15098 T334 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 15098 T334 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 15099 T334 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 15099 T334 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 15101 T334 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 15105 T334 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\index forceNew:false
[junit4:junit4]   2> 15106 T334 oass.SolrIndexSearcher.<init> Opening Searcher@11682d1 main
[junit4:junit4]   2> 15106 T334 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 15109 T334 oasc.SolrException.log SEVERE Failure to open existing log file (non fatal) C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\tlog\tlog.0000000000000000016:java.lang.RuntimeException: Invalid version (expected 2, but 0) or the data in not in 'javabin' format
[junit4:junit4]   2> 		at org.apache.solr.common.util.JavaBinCodec.unmarshal(JavaBinCodec.java:109)
[junit4:junit4]   2> 		at org.apache.solr.update.TransactionLog.readHeader(TransactionLog.java:253)
[junit4:junit4]   2> 		at org.apache.solr.update.TransactionLog.<init>(TransactionLog.java:159)
[junit4:junit4]   2> 		at org.apache.solr.update.UpdateLog.init(UpdateLog.java:200)
[junit4:junit4]   2> 		at org.apache.solr.update.UpdateHandler.initLog(UpdateHandler.java:94)
[junit4:junit4]   2> 		at org.apache.solr.update.UpdateHandler.<init>(UpdateHandler.java:123)
[junit4:junit4]   2> 		at org.apache.solr.update.DirectUpdateHandler2.<init>(DirectUpdateHandler2.java:97)
[junit4:junit4]   2> 		at sun.reflect.GeneratedConstructorAccessor121.newInstance(Unknown Source)
[junit4:junit4]   2> 		at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
[junit4:junit4]   2> 		at java.lang.reflect.Constructor.newInstance(Constructor.java:525)
[junit4:junit4]   2> 		at org.apache.solr.core.SolrCore.createInstance(SolrCore.java:476)
[junit4:junit4]   2> 		at org.apache.solr.core.SolrCore.createUpdateHandler(SolrCore.java:544)
[junit4:junit4]   2> 		at org.apache.solr.core.SolrCore.<init>(SolrCore.java:707)
[junit4:junit4]   2> 		at org.apache.solr.core.SolrCore.<init>(SolrCore.java:567)
[junit4:junit4]   2> 		at org.apache.solr.util.TestHarness$Initializer.initialize(TestHarness.java:190)
[junit4:junit4]   2> 		at org.apache.solr.util.TestHarness.<init>(TestHarness.java:114)
[junit4:junit4]   2> 		at org.apache.solr.util.TestHarness.<init>(TestHarness.java:109)
[junit4:junit4]   2> 		at org.apache.solr.util.TestHarness.<init>(TestHarness.java:99)
[junit4:junit4]   2> 		at org.apache.solr.SolrTestCaseJ4.createCore(SolrTestCaseJ4.java:362)
[junit4:junit4]   2> 		at org.apache.solr.search.TestRecovery.testTruncatedLog(TestRecovery.java:843)
[junit4:junit4]   2> 		at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]   2> 		at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
[junit4:junit4]   2> 		at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4:junit4]   2> 		at java.lang.reflect.Method.invoke(Method.java:601)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
[junit4:junit4]   2> 		at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]   2> 		at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesti

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

ARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 15242 T352 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@370bd0 main{StandardDirectoryReader(segments_q:81:nrt)}
[junit4:junit4]   2> 15244 T334 C58 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 15244 T334 C58 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 12
[junit4:junit4]   2> 15262 T334 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=21538967
[junit4:junit4]   2> 15262 T334 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@1701e4c
[junit4:junit4]   2> 15263 T334 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 15265 T334 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=2,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=1,cumulative_adds=6,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=1}
[junit4:junit4]   2> 15265 T334 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 15271 T334 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 15282 T334 oas.SolrTestCaseJ4.tearDown ###Ending testRemoveOldLogs
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestRecovery -Dtests.method=testRemoveOldLogs -Dtests.seed=60190B42E904DE2B -Dtests.slow=true -Dtests.locale=vi_VN -Dtests.timezone=Africa/Mbabane -Dtests.file.encoding=ISO-8859-1
[junit4:junit4] FAILURE 0.14s | TestRecovery.testRemoveOldLogs <<<
[junit4:junit4]    > Throwable #1: java.lang.AssertionError: expected:<0> but was:<1>
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([60190B42E904DE2B:4185CAE9213E57D3]:0)
[junit4:junit4]    > 	at org.junit.Assert.fail(Assert.java:93)
[junit4:junit4]    > 	at org.junit.Assert.failNotEquals(Assert.java:647)
[junit4:junit4]    > 	at org.junit.Assert.assertEquals(Assert.java:128)
[junit4:junit4]    > 	at org.junit.Assert.assertEquals(Assert.java:472)
[junit4:junit4]    > 	at org.junit.Assert.assertEquals(Assert.java:456)
[junit4:junit4]    > 	at org.apache.solr.search.TestRecovery.testRemoveOldLogs(TestRecovery.java:721)
[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> 15336 T334 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> !!!! WARNING: best effort to remove C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\tlog\tlog.0000000000000000016 FAILED !!!!!
[junit4:junit4]   2> !!!! WARNING: best effort to remove C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846\tlog FAILED !!!!!
[junit4:junit4]   2> !!!! WARNING: best effort to remove C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347553269846 FAILED !!!!!
[junit4:junit4]   2> NOTE: test params are: codec=SimpleText, sim=RandomSimilarityProvider(queryNorm=false,coord=no): {}, locale=vi_VN, timezone=Africa/Mbabane
[junit4:junit4]   2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.7.0_07 (32-bit)/cpus=2,threads=1,free=38319224,total=83427328
[junit4:junit4]   2> NOTE: All tests run in this JVM: [PreAnalyzedFieldTest, FieldAnalysisRequestHandlerTest, TestElisionMultitermQuery, TestDistributedGrouping, PathHierarchyTokenizerFactoryTest, SortByFunctionTest, SampleTest, XmlUpdateRequestHandlerTest, CSVRequestHandlerTest, HighlighterTest, SpatialFilterTest, TestRTGBase, TestDFRSimilarityFactory, ClusterStateTest, DebugComponentTest, IndexSchemaRuntimeFieldTest, SuggesterFSTTest, CurrencyFieldTest, TestDocSet, TestReload, TestJmxMonitoredMap, TestCodecSupport, TestLMDirichletSimilarityFactory, NotRequiredUniqueKeyTest, ReturnFieldsTest, WordBreakSolrSpellCheckerTest, DistributedQueryElevationComponentTest, SpellCheckCollatorTest, TermsComponentTest, TestFunctionQuery, TestFaceting, FastVectorHighlighterTest, TestRecovery]
[junit4:junit4] Completed in 15.38s, 10 tests, 1 failure <<< FAILURES!

[...truncated 600 lines...]
BUILD FAILED
C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\build.xml:29: The following error occurred while executing this line:
C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:146: The following error occurred while executing this line:
C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\lucene\module-build.xml:63: The following error occurred while executing this line:
C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1133: The following error occurred while executing this line:
C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:810: There were test failures: 228 suites, 923 tests, 1 failure, 11 ignored (6 assumptions)

Total time: 34 minutes 57 seconds
Build step 'Invoke Ant' marked build as failure
Recording test results
Description set: Java: 32bit/jdk1.7.0_07 -client -XX:+UseSerialGC
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message