lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@sd-datasolutions.de>
Subject [JENKINS] Lucene-Solr-4.x-Windows (32bit/jdk1.6.0_35) - Build # 756 - Still Failing!
Date Thu, 13 Sep 2012 17:17:45 GMT
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-4.x-Windows/756/
Java: 32bit/jdk1.6.0_35 -server -XX:+UseParallelGC

2 tests failed.
REGRESSION:  org.apache.solr.search.TestRecovery.testCleanShutdown

Error Message:


Stack Trace:
java.lang.NullPointerException
	at __randomizedtesting.SeedInfo.seed([ACBCE4184135A9F1:32F35CDF24ACF1B4]:0)
	at org.apache.solr.util.TestHarness.getCore(TestHarness.java:212)
	at org.apache.solr.util.TestHarness$LocalRequestFactory.makeRequest(TestHarness.java:625)
	at org.apache.solr.SolrTestCaseJ4.req(SolrTestCaseJ4.java:742)
	at org.apache.solr.search.TestRecovery.testCleanShutdown(TestRecovery.java:644)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
	at java.lang.reflect.Method.invoke(Method.java:597)
	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:662)


FAILED:  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([ACBCE4184135A9F1:8D2025B3890F2009]: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:39)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
	at java.lang.reflect.Method.invoke(Method.java:597)
	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:662)




Build Log:
[...truncated 7252 lines...]
[junit4:junit4] Suite: org.apache.solr.search.TestRecovery
[junit4:junit4]   2> 3 T2913 oas.SolrTestCaseJ4.initCore ####initCore
[junit4:junit4]   2> Creating dataDir: C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314
[junit4:junit4]   2> 4 T2913 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4:junit4]   2> 6 T2913 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 7 T2913 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 74 T2913 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 134 T2913 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
[junit4:junit4]   2> 135 T2913 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 141 T2913 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 807 T2913 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 811 T2913 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 824 T2913 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 824 T2913 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr
[junit4:junit4]   2> 825 T2913 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 843 T2913 oasc.CoreContainer.<init> New CoreContainer 13982975
[junit4:junit4]   2> 843 T2913 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\
[junit4:junit4]   2> 844 T2913 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 845 T2913 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\index/
[junit4:junit4]   2> 846 T2913 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 847 T2913 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\index forceNew:false
[junit4:junit4]   2> 853 T2913 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@b6aba),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 853 T2913 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 855 T2913 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 855 T2913 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 855 T2913 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 856 T2913 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 861 T2913 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 871 T2913 oass.SolrIndexSearcher.<init> Opening Searcher@12f48d6 main
[junit4:junit4]   2> 872 T2913 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 874 T2913 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 874 T2913 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 874 T2913 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 875 T2913 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 875 T2913 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 875 T2913 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 877 T2913 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 877 T2913 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 877 T2913 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 877 T2913 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 878 T2913 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 878 T2913 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 890 T2914 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@12f48d6 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 890 T2913 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 891 T2913 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4:junit4]   2> 895 T2913 oas.SolrTestCaseJ4.setUp ###Starting testVersionsOnRestart
[junit4:junit4]   2> ASYNC  NEW_CORE C186 name=collection1 org.apache.solr.core.SolrCore@3d9dfd
[junit4:junit4]   2> 900 T2913 C186 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 905 T2913 C186 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@b6aba),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 905 T2913 C186 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 906 T2913 C186 oass.SolrIndexSearcher.<init> Opening Searcher@bade5f realtime
[junit4:junit4]   2> 907 T2913 C186 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 907 T2913 C186 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> 913 T2913 C186 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 916 T2913 C186 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@b6aba),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@b6aba),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 916 T2913 C186 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 919 T2913 C186 oass.SolrIndexSearcher.<init> Opening Searcher@95a4a9 main
[junit4:junit4]   2> 919 T2913 C186 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 920 T2914 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@95a4a9 main{StandardDirectoryReader(segments_1:2:nrt)}
[junit4:junit4]   2> 920 T2913 C186 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 921 T2913 C186 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 8
[junit4:junit4]   2> 944 T2913 C186 UPDATE [collection1] webapp=null path=null params={} {add=[D1 (1413015076603953152)]} 0 3
[junit4:junit4]   2> 951 T2913 C186 UPDATE [collection1] webapp=null path=null params={} {add=[D2 (1413015076614438912)]} 0 0
[junit4:junit4]   2> 959 T2913 C186 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 966 T2913 C186 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@b6aba),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@b6aba),segFN=segments_3,generation=3,filenames=[_0.inf, _0.si, _0.pst, _0.fld, segments_3]
[junit4:junit4]   2> 966 T2913 C186 oasc.SolrDeletionPolicy.updateCommits newest commit = 3
[junit4:junit4]   2> 971 T2913 C186 oass.SolrIndexSearcher.<init> Opening Searcher@15b0f65 main
[junit4:junit4]   2> 971 T2913 C186 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 972 T2914 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@15b0f65 main{StandardDirectoryReader(segments_3:4:nrt _0(4.0.0.2):C2)}
[junit4:junit4]   2> 972 T2913 C186 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 973 T2913 C186 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 16
[junit4:junit4]   2> 978 T2913 C186 REQ [collection1] webapp=null path=null params={q=id:D1} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 979 T2913 C186 REQ [collection1] webapp=null path=null params={q=id:D2} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 980 T2913 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=13982975
[junit4:junit4]   2> 980 T2913 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@3d9dfd
[junit4:junit4]   2> 981 T2913 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 981 T2913 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> 981 T2913 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 988 T2913 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 989 T2913 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4:junit4]   2> 990 T2913 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 992 T2913 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 1057 T2913 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 1116 T2913 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
[junit4:junit4]   2> 1117 T2913 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 1124 T2913 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 1787 T2913 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 1792 T2913 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 1803 T2913 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 1803 T2913 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr
[junit4:junit4]   2> 1803 T2913 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 1818 T2913 oasc.CoreContainer.<init> New CoreContainer 11287181
[junit4:junit4]   2> 1818 T2913 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\
[junit4:junit4]   2> 1818 T2913 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 1819 T2913 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\index/
[junit4:junit4]   2> 1821 T2913 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 1821 T2913 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 1822 T2913 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 1822 T2913 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 1825 T2913 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 1830 T2913 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\index forceNew:false
[junit4:junit4]   2> 1833 T2913 oass.SolrIndexSearcher.<init> Opening Searcher@11b03c7 main
[junit4:junit4]   2> 1833 T2913 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 1837 T2913 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 1837 T2913 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 1838 T2913 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 1838 T2913 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 1839 T2913 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 1839 T2913 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 1839 T2913 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 1839 T2913 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 1839 T2913 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 1840 T2913 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 1840 T2913 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 1840 T2913 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 1852 T2915 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@11b03c7 main{StandardDirectoryReader(segments_3:4 _0(4.0.0.2):C2)}
[junit4:junit4]   2> 1852 T2913 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> ASYNC  NEW_CORE C187 name=collection1 org.apache.solr.core.SolrCore@15573d3
[junit4:junit4]   2> 1864 T2913 C187 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@c3ee5a),segFN=segments_3,generation=3,filenames=[_0.inf, _0.si, _0.pst, _0.fld, segments_3]
[junit4:junit4]   2> 1865 T2913 C187 oasc.SolrDeletionPolicy.updateCommits newest commit = 3
[junit4:junit4]   2> 1867 T2913 C187 UPDATE [collection1] webapp=null path=null params={} {add=[D1 (1413015077565497344)]} 0 9
[junit4:junit4]   2> 1876 T2913 C187 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 1885 T2913 C187 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@c3ee5a),segFN=segments_3,generation=3,filenames=[_0.inf, _0.si, _0.pst, _0.fld, segments_3]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@c3ee5a),segFN=segments_4,generation=4,filenames=[_1.pst, _1.fld, _1.si, _1.inf, _0.inf, _0_1.liv, _0.si, _0.pst, _0.fld, segments_4]
[junit4:junit4]   2> 1886 T2913 C187 oasc.SolrDeletionPolicy.updateCommits newest commit = 4
[junit4:junit4]   2> 1896 T2913 C187 oass.SolrIndexSearcher.<init> Opening Searcher@13b6d66 main
[junit4:junit4]   2> 1897 T2913 C187 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 1897 T2915 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@13b6d66 main{StandardDirectoryReader(segments_4:7 _0(4.0.0.2):C2/1 _1(4.0.0.2):C1)}
[junit4:junit4]   2> 1897 T2913 C187 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 1898 T2913 C187 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 22
[junit4:junit4]   2> 1906 T2913 C187 REQ [collection1] webapp=null path=null params={q=id:D1} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 1907 T2913 C187 REQ [collection1] webapp=null path=null params={getVersions=2&qt=/get} status=0 QTime=1 
[junit4:junit4]   2> 1907 T2913 oas.SolrTestCaseJ4.tearDown ###Ending testVersionsOnRestart
[junit4:junit4]   2> 1913 T2913 oas.SolrTestCaseJ4.setUp ###Starting testCorruptLog
[junit4:junit4]   2> 1924 T2913 C187 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 1926 T2913 C187 oass.SolrIndexSearcher.<init> Opening Searcher@1f8b722 realtime
[junit4:junit4]   2> 1926 T2913 C187 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 1928 T2913 C187 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> 1933 T2913 C187 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 1937 T2913 C187 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@c3ee5a),segFN=segments_4,generation=4,filenames=[_1.pst, _1.fld, _1.si, _1.inf, _0.inf, _0_1.liv, _0.si, _0.pst, _0.fld, segments_4]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@c3ee5a),segFN=segments_5,generation=5,filenames=[segments_5]
[junit4:junit4]   2> 1937 T2913 C187 oasc.SolrDeletionPolicy.updateCommits newest commit = 5
[junit4:junit4]   2> 1944 T2913 C187 oass.SolrIndexSearcher.<init> Opening Searcher@1b198cf main
[junit4:junit4]   2> 1946 T2913 C187 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 1947 T2915 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1b198cf main{StandardDirectoryReader(segments_4:8:nrt)}
[junit4:junit4]   2> 1947 T2913 C187 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 1948 T2913 C187 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 15
[junit4:junit4]   2> 1961 T2913 C187 UPDATE [collection1] webapp=null path=null params={} {add=[G1 (1413015077670354944)]} 0 3
[junit4:junit4]   2> 1967 T2913 C187 UPDATE [collection1] webapp=null path=null params={} {add=[G2 (1413015077679792128)]} 0 0
[junit4:junit4]   2> 1974 T2913 C187 UPDATE [collection1] webapp=null path=null params={} {add=[G3 (1413015077687132160)]} 0 0
[junit4:junit4]   2> 1977 T2913 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=11287181
[junit4:junit4]   2> 1977 T2913 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@15573d3
[junit4:junit4]   2> 1978 T2913 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 1984 T2913 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=2,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=3,adds=3,deletesById=0,deletesByQuery=0,errors=1,cumulative_adds=4,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=1}
[junit4:junit4]   2> 1985 T2913 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 1992 T2913 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 1995 T2913 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4:junit4]   2> 1997 T2913 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 1999 T2913 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 2070 T2913 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 2138 T2913 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
[junit4:junit4]   2> 2139 T2913 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 2144 T2913 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 2812 T2913 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 2818 T2913 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 2829 T2913 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2830 T2913 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr
[junit4:junit4]   2> 2830 T2913 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 2845 T2913 oasc.CoreContainer.<init> New CoreContainer 15569568
[junit4:junit4]   2> 2846 T2913 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\
[junit4:junit4]   2> 2846 T2913 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 2848 T2913 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\index/
[junit4:junit4]   2> 2850 T2913 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 2850 T2913 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 2851 T2913 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 2851 T2913 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 2854 T2913 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 2858 T2913 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\index forceNew:false
[junit4:junit4]   2> 2860 T2913 oass.SolrIndexSearcher.<init> Opening Searcher@ace0b4 main
[junit4:junit4]   2> 2860 T2913 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 2862 T2913 oasc.SolrException.log Ignoring exception matching Failure to open existing log file
[junit4:junit4]   2> 2863 T2913 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 2863 T2913 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 2863 T2913 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 2864 T2913 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 2864 T2913 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 2864 T2913 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 2864 T2913 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 2865 T2913 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 2865 T2913 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 2865 T2913 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 2866 T2913 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 2866 T2913 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 2878 T2916 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@ace0b4 main{StandardDirectoryReader(segments_5:8)}
[junit4:junit4]   2> 2878 T2913 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> ASYNC  NEW_CORE C188 name=collection1 org.apache.solr.core.SolrCore@f447c2
[junit4:junit4]   2> 2882 T2913 C188 REQ [collection1] webapp=null path=null params={q=*:*} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 2901 T2913 C188 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1eadf8f),segFN=segments_5,generation=5,filenames=[segments_5]
[junit4:junit4]   2> 2901 T2913 C188 oasc.SolrDeletionPolicy.updateCommits newest commit = 5
[junit4:junit4]   2> 2907 T2913 C188 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=15 
[junit4:junit4]   2> 2914 T2913 C188 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 2919 T2913 C188 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 2920 T2913 C188 REQ [collection1] webapp=null path=null params={getVersions=3&qt=/get} status=0 QTime=0 
[junit4:junit4]   2> 2925 T2913 C188 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 2931 T2913 C188 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1eadf8f),segFN=segments_5,generation=5,filenames=[segments_5]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1eadf8f),segFN=segments_6,generation=6,filenames=[_2.si, _2.pst, _2.fld, _2.inf, segments_6]
[junit4:junit4]   2> 2931 T2913 C188 oasc.SolrDeletionPolicy.updateCommits newest commit = 6
[junit4:junit4]   2> 2939 T2913 C188 oass.SolrIndexSearcher.<init> Opening Searcher@306bf5 main
[junit4:junit4]   2> 2939 T2913 C188 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 2939 T2916 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@306bf5 main{StandardDirectoryReader(segments_6:10 _2(4.0.0.2):C3)}
[junit4:junit4]   2> 2939 T2913 C188 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 2940 T2913 C188 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 15
[junit4:junit4]   2> 2957 T2913 C188 REQ [collection1] webapp=null path=null params={q=*:*} hits=3 status=0 QTime=0 
[junit4:junit4]   2> 2957 T2913 oas.SolrTestCaseJ4.tearDown ###Ending testCorruptLog
[junit4:junit4]   2> 2962 T2913 oas.SolrTestCaseJ4.setUp ###Starting testLogReplay
[junit4:junit4]   2> 2973 T2913 C188 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 2975 T2913 C188 oass.SolrIndexSearcher.<init> Opening Searcher@13db7aa realtime
[junit4:junit4]   2> 2975 T2913 C188 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 2976 T2913 C188 REQ [collection1] webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true} status=0 QTime=3 
[junit4:junit4]   2> 2981 T2913 C188 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 3018 T2913 C188 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1eadf8f),segFN=segments_6,generation=6,filenames=[_2.si, _2.pst, _2.fld, _2.inf, segments_6]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1eadf8f),segFN=segments_7,generation=7,filenames=[segments_7]
[junit4:junit4]   2> 3019 T2913 C188 oasc.SolrDeletionPolicy.updateCommits newest commit = 7
[junit4:junit4]   2> 3024 T2913 C188 oass.SolrIndexSearcher.<init> Opening Searcher@894c4 main
[junit4:junit4]   2> 3024 T2913 C188 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 3025 T2916 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@894c4 main{StandardDirectoryReader(segments_6:11:nrt)}
[junit4:junit4]   2> 3025 T2913 C188 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3026 T2913 C188 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 45
[junit4:junit4]   2> 3042 T2913 C188 UPDATE [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[A1 (1413015078803865600)]} 0 3
[junit4:junit4]   2> 3048 T2913 C188 UPDATE [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[A11 (1413015078812254208)]} 0 1
[junit4:junit4]   2> 3053 T2913 C188 UPDATE [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[A12 (1413015078818545664)]} 0 0
[junit4:junit4]   2> 3066 T2913 C188 oass.SolrIndexSearcher.<init> Opening Searcher@82374a realtime
[junit4:junit4]   2> 3067 T2913 C188 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 3067 T2913 C188 UPDATE [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {deleteByQuery=id:A11 (-1413015078823788544)} 0 9
[junit4:junit4]   2> 3081 T2913 C188 UPDATE [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[A13 (1413015078846857216)]} 0 1
[junit4:junit4]   2> 3082 T2913 C188 REQ [collection1] webapp=null path=null params={q=*:*} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 3082 T2913 C188 REQ [collection1] webapp=null path=null params={getVersions=5&qt=/get} status=0 QTime=0 
[junit4:junit4]   2> 3082 T2913 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=15569568
[junit4:junit4]   2> 3082 T2913 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@f447c2
[junit4:junit4]   2> 3084 T2913 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 3084 T2913 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=2,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=4,adds=4,deletesById=0,deletesByQuery=1,errors=1,cumulative_adds=7,cumulative_deletesById=0,cumulative_deletesByQuery=2,cumulative_errors=1}
[junit4:junit4]   2> 3084 T2913 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 3094 T2913 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 3094 T2913 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4:junit4]   2> 3097 T2913 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 3099 T2913 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 3166 T2913 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 3237 T2913 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
[junit4:junit4]   2> 3238 T2913 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 3244 T2913 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 3960 T2913 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 3965 T2913 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 3976 T2913 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3976 T2913 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr
[junit4:junit4]   2> 3977 T2913 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 3990 T2913 oasc.CoreContainer.<init> New CoreContainer 20300271
[junit4:junit4]   2> 3990 T2913 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\
[junit4:junit4]   2> 3991 T2913 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 3993 T2913 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\index/
[junit4:junit4]   2> 3994 T2913 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 3995 T2913 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 3995 T2913 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 3995 T2913 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 3998 T2913 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 4003 T2913 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\index forceNew:false
[junit4:junit4]   2> 4004 T2913 oass.SolrIndexSearcher.<init> Opening Searcher@cb1fe8 main
[junit4:junit4]   2> 4004 T2913 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 4007 T2913 oasc.SolrException.log SEVERE Failure to open existing log file (non fatal) C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\tlog\tlog.0000000000000000004: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.GeneratedConstructorAccessor119.newInstance(Unknown Source)
[junit4:junit4]   2> 		at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
[junit4:junit4]   2> 		at java.lang.reflect.Constructor.newInstance(Constructor.java:513)
[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.testLogReplay(TestRecovery.java:127)
[junit4:junit4]   2> 		at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]   2> 		at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
[junit4:junit4]   2> 		at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit4:junit4]   2> 		at java.lang.reflect.Method.invoke(Method.java:597)
[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:662)
[junit4:junit4]   2> 	
[junit4:junit4]   2> 4011 T2913 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 4011 T2913 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 4011 T2913 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 4012 T2913 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 4012 T2913 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 4012 T2913 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 4012 T2913 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 4013 T2913 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 4013 T2913 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 4013 T2913 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 4014 T2913 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 4014 T2913 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 4025 T2917 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@cb1fe8 main{StandardDirectoryReader(segments_7:11)}
[junit4:junit4]   2> 4025 T2913 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> ASYNC  NEW_CORE C189 name=collection1 org.apache.solr.core.SolrCore@bda5de
[junit4:junit4]   2> 4026 T2918 C189 oasu.UpdateLog$LogReplayer.doReplay WARNING Starting log replay tlog{file=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\tlog\tlog.0000000000000000007 refcount=2} active=false starting pos=0
[junit4:junit4]   2> 4034 T2913 C189 REQ [collection1] webapp=null path=null params={q=*:*} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 4035 T2913 C189 REQ [collection1] webapp=null path=null params={getVersions=5&qt=/get} status=0 QTime=1 
[junit4:junit4]   2> 4035 T2913 C189 REQ [collection1] webapp=null path=null params={getVersions=5&qt=/get} status=0 QTime=0 
[junit4:junit4]   2> 4043 T2918 C189 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@4f26ed),segFN=segments_7,generation=7,filenames=[segments_7]
[junit4:junit4]   2> 4043 T2918 C189 oasc.SolrDeletionPolicy.updateCommits newest commit = 7
[junit4:junit4]   2> 4044 T2918 C189 oasu.DirectUpdateHandler2.addDoc Reordered DBQs detected.  Update=add{flags=10,_version_=1413015078803865600,id=A1} DBQs=[DBQ{version=1413015078823788544,q=id:A11}]
[junit4:junit4]   2> 4048 T2918 C189 oass.SolrIndexSearcher.<init> Opening Searcher@158193c realtime
[junit4:junit4]   2> 4049 T2918 C189 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 4049 T2918 C189 oasu.DirectUpdateHandler2.addDoc Reordered DBQs detected.  Update=add{flags=10,_version_=1413015078812254208,id=A11} DBQs=[DBQ{version=1413015078823788544,q=id:A11}]
[junit4:junit4]   2> 4056 T2918 C189 oass.SolrIndexSearcher.<init> Opening Searcher@1b5559c realtime
[junit4:junit4]   2> 4056 T2918 C189 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 4057 T2918 C189 oasu.DirectUpdateHandler2.addDoc Reordered DBQs detected.  Update=add{flags=10,_version_=1413015078818545664,id=A12} DBQs=[DBQ{version=1413015078823788544,q=id:A11}]
[junit4:junit4]   2> 4063 T2918 C189 oass.SolrIndexSearcher.<init> Opening Searcher@ae6203 realtime
[junit4:junit4]   2> 4063 T2918 C189 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 4066 T2918 C189 oasu.DirectUpdateHandler2.commit start commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 4072 T2918 C189 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@4f26ed),segFN=segments_7,generation=7,filenames=[segments_7]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@4f26ed),segFN=segments_8,generation=8,filenames=[_3.fld, _6.pst, _5.pst, _5.fld, _3.pst, _5.si, _3.inf, _5.inf, segments_8, _6.fld, _3.si, _6.inf, _6.si]
[junit4:junit4]   2> 4073 T2918 C189 oasc.SolrDeletionPolicy.updateCommits newest commit = 8
[junit4:junit4]   2> 4077 T2918 C189 oass.SolrIndexSearcher.<init> Opening Searcher@1f6402a main
[junit4:junit4]   2> 4077 T2918 C189 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 4077 T2918 C189 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 4078 T2917 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1f6402a main{StandardDirectoryReader(segments_8:21:nrt _3(4.0.0.2):C1 _5(4.0.0.2):C1 _6(4.0.0.2):C1)}
[junit4:junit4]   2> 4078 T2918 C189 oasu.UpdateLog$LogReplayer.run WARNING Log replay finished. recoveryInfo=RecoveryInfo{adds=4 deletes=0 deleteByQuery=1 errors=0 positionOfStart=0}
[junit4:junit4]   2> 4078 T2913 C189 REQ [collection1] webapp=null path=null params={q=*:*} hits=3 status=0 QTime=0 
[junit4:junit4]   2> 4079 T2913 C189 REQ [collection1] webapp=null path=null params={getVersions=5&qt=/get} status=0 QTime=0 
[junit4:junit4]   2> 4091 T2913 C189 UPDATE [collection1] webapp=null path=null params={} {add=[A2 (1413015079903821824)]} 0 3
[junit4:junit4]   2> 4115 T2913 C189 UPDATE [collection1] webapp=null path=null params={} {add=[A3 (1413015079931084800)]} 0 1
[junit4:junit4]   2> 4122 T2913 C189 UPDATE [collection1] webapp=null path=null params={} {delete=[A2 (-1413015079939473408)]} 0 0
[junit4:junit4]   2> 4131 T2913 C189 UPDATE [collection1] webapp=null path=null params={} {add=[A4 (1413015079947862016)]} 0 1
[junit4:junit4]   2> 4133 T2913 C189 REQ [collection1] webapp=null path=null params={q=*:*} hits=3 status=0 QTime=0 
[junit4:junit4]   2> 4133 T2913 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=20300271
[junit4:junit4]   2> 4133 T2913 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@bda5de
[junit4:junit4]   2> 4134 T2913 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 4134 T2913 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> 4134 T2913 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 4153 T2913 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 4154 T2913 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4:junit4]   2> 4158 T2913 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 4159 T2913 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 4234 T2913 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 4300 T2913 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
[junit4:junit4]   2> 4301 T2913 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 4306 T2913 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 5044 T2913 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 5048 T2913 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 5059 T2913 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 5059 T2913 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr
[junit4:junit4]   2> 5059 T2913 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 5074 T2913 oasc.CoreContainer.<init> New CoreContainer 10948412
[junit4:junit4]   2> 5074 T2913 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\
[junit4:junit4]   2> 5074 T2913 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 5076 T2913 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\index/
[junit4:junit4]   2> 5077 T2913 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 5078 T2913 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 5078 T2913 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 5078 T2913 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 5082 T2913 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 5323 T2913 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\index forceNew:false
[junit4:junit4]   2> 5330 T2913 oass.SolrIndexSearcher.<init> Opening Searcher@ad5280 main
[junit4:junit4]   2> 5330 T2913 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 5333 T2913 oasc.SolrException.log SEVERE Failure to open existing log file (non fatal) C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\tlog\tlog.0000000000000000004: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.GeneratedConstructorAccessor119.newInstance(Unknown Source)
[junit4:junit4]   2> 		at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
[junit4:junit4]   2> 		at java.lang.reflect.Constructor.newInstance(Constructor.java:513)
[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.testLogReplay(TestRecovery.java:160)
[junit4:junit4]   2> 		at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]   2> 		at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
[junit4:junit4]   2> 		at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit4:junit4]   2> 		at java.lang.reflect.Method.invoke(Method.java:597)
[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:662)
[junit4:junit4]   2> 	
[junit4:junit4]   2> 5337 T2913 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 5337 T2913 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 5338 T2913 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 5338 T2913 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 5339 T2913 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 5339 T2913 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 5339 T2913 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 5339 T2913 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 5340 T2913 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 5340 T2913 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 5341 T2913 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 5341 T2913 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 5352 T2919 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@ad5280 main{StandardDirectoryReader(segments_8:21 _3(4.0.0.2):C1 _5(4.0.0.2):C1 _6(4.0.0.2):C1)}
[junit4:junit4]   2> 5352 T2913 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> ASYNC  NEW_CORE C190 name=collection1 org.apache.solr.core.SolrCore@10034ec
[junit4:junit4]   2> 5354 T2920 C190 oasu.UpdateLog$LogReplayer.doReplay WARNING Starting log replay tlog{file=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\tlog\tlog.0000000000000000009 refcount=2} active=false starting pos=0
[junit4:junit4]   2> 5367 T2920 C190 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@22faf4),segFN=segments_8,generation=8,filenames=[_3.fld, _6.pst, _5.pst, _5.fld, _3.pst, _5.si, _3.inf, _5.inf, segments_8, _6.fld, _3.si, _6.si, _6.inf]
[junit4:junit4]   2> 5367 T2920 C190 oasc.SolrDeletionPolicy.updateCommits newest commit = 8
[junit4:junit4]   2> 5368 T2920 C190 oasu.DirectUpdateHandler2.commit start commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 5378 T2920 C190 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@22faf4),segFN=segments_8,generation=8,filenames=[_3.fld, _6.pst, _5.pst, _5.fld, _3.pst, _5.si, _3.inf, _5.inf, segments_8, _6.fld, _3.si, _6.si, _6.inf]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@22faf4),segFN=segments_9,generation=9,filenames=[_3.fld, _6.pst, _7.inf, _7.si, _7.pst, _5.pst, _5.fld, _3.pst, segments_9, _5.si, _3.inf, _7.fld, _5.inf, _6.fld, _3.si, _6.si, _6.inf]
[junit4:junit4]   2> 5378 T2920 C190 oasc.SolrDeletionPolicy.updateCommits newest commit = 9
[junit4:junit4]   2> 5391 T2920 C190 oass.SolrIndexSearcher.<init> Opening Searcher@1bf67f9 main
[junit4:junit4]   2> 5392 T2920 C190 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 5392 T2920 C190 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 5392 T2919 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1bf67f9 main{StandardDirectoryReader(segments_9:23 _3(4.0.0.2):C1 _5(4.0.0.2):C1 _6(4.0.0.2):C1 _7(4.0.0.2):C2)}
[junit4:junit4]   2> 5392 T2920 C190 oasu.UpdateLog$LogReplayer.run WARNING Log replay finished. recoveryInfo=RecoveryInfo{adds=3 deletes=1 deleteByQuery=0 errors=0 positionOfStart=0}
[junit4:junit4]   2> 5393 T2913 C190 REQ [collection1] webapp=null path=null params={q=*:*} hits=5 status=0 QTime=0 
[junit4:junit4]   2> 5393 T2913 C190 REQ [collection1] webapp=null path=null params={q=id:A2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 5393 T2913 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=10948412
[junit4:junit4]   2> 5393 T2913 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@10034ec
[junit4:junit4]   2> 5394 T2913 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 5394 T2913 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> 5395 T2913 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 5412 T2913 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 5412 T2913 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4:junit4]   2> 5415 T2913 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 5416 T2913 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 5483 T2913 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 5543 T2913 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
[junit4:junit4]   2> 5544 T2913 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 5550 T2913 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 6197 T2913 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 6201 T2913 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 6213 T2913 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 6214 T2913 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr
[junit4:junit4]   2> 6214 T2913 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 6227 T2913 oasc.CoreContainer.<init> New CoreContainer 5930256
[junit4:junit4]   2> 6228 T2913 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\
[junit4:junit4]   2> 6228 T2913 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 6228 T2913 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\index/
[junit4:junit4]   2> 6232 T2913 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 6232 T2913 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 6232 T2913 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 6233 T2913 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 6234 T2913 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 6240 T2913 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\index forceNew:false
[junit4:junit4]   2> 6246 T2913 oass.SolrIndexSearcher.<init> Opening Searcher@1ee5a81 main
[junit4:junit4]   2> 6246 T2913 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 6251 T2913 oasc.SolrException.log SEVERE Failure to open existing log file (non fatal) C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\tlog\tlog.0000000000000000004: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.GeneratedConstructorAccessor119.newInstance(Unknown Source)
[junit4:junit4]   2> 		at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
[junit4:junit4]   2> 		at java.lang.reflect.Constructor.newInstance(Constructor.java:513)
[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.testLogReplay(TestRecovery.java:172)
[junit4:junit4]   2> 		at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]   2> 		at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
[junit4:junit4]   2> 		at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit4:junit4]   2> 		at java.lang.reflect.Method.invoke(Method.java:597)
[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:662)
[junit4:junit4]   2> 	
[junit4:junit4]   2> 6253 T2913 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 6254 T2913 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 6254 T2913 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 6254 T2913 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 6255 T2913 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 6255 T2913 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 6255 T2913 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 6256 T2913 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 6256 T2913 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 6256 T2913 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 6256 T2913 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 6258 T2913 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 6270 T2921 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ee5a81 main{StandardDirectoryReader(segments_9:23 _3(4.0.0.2):C1 _5(4.0.0.2):C1 _6(4.0.0.2):C1 _7(4.0.0.2):C2)}
[junit4:junit4]   2> 6270 T2913 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> ASYNC  NEW_CORE C191 name=collection1 org.apache.solr.core.SolrCore@19260d0
[junit4:junit4]   2> 6273 T2913 C191 REQ [collection1] webapp=null path=null params={q=*:*} hits=5 status=0 QTime=0 
[junit4:junit4]   2> 6390 T2913 oas.SolrTestCaseJ4.tearDown ###Ending testLogReplay
[junit4:junit4]   2> 6401 T2913 oas.SolrTestCaseJ4.setUp ###Starting testTruncatedLog
[junit4:junit4]   2> 6426 T2913 C191 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 6438 T2913 C191 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@105c15),segFN=segments_9,generation=9,filenames=[_3.fld, _6.pst, _7.inf, _7.si, _7.pst, _5.pst, _5.fld, _3.pst, segments_9, _5.si, _3.inf, _7.fld, _5.inf, _6.fld, _3.si, _6.si, _6.inf]
[junit4:junit4]   2> 6438 T2913 C191 oasc.SolrDeletionPolicy.updateCommits newest commit = 9
[junit4:junit4]   2> 6441 T2913 C191 oass.SolrIndexSearcher.<init> Opening Searcher@c567f9 realtime
[junit4:junit4]   2> 6441 T2913 C191 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 6441 T2913 C191 REQ [collection1] webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true} status=0 QTime=16 
[junit4:junit4]   2> 6446 T2913 C191 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 6450 T2913 C191 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@105c15),segFN=segments_9,generation=9,filenames=[_3.fld, _6.pst, _7.inf, _7.si, _7.pst, _5.pst, _5.fld, _3.pst, segments_9, _5.si, _3.inf, _7.fld, _5.inf, _6.fld, _3.si, _6.si, _6.inf]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@105c15),segFN=segments_a,generation=10,filenames=[segments_a]
[junit4:junit4]   2> 6450 T2913 C191 oasc.SolrDeletionPolicy.updateCommits newest commit = 10
[junit4:junit4]   2> 6464 T2913 C191 oass.SolrIndexSearcher.<init> Opening Searcher@970ba7 main
[junit4:junit4]   2> 6464 T2913 C191 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 6465 T2921 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@970ba7 main{StandardDirectoryReader(segments_9:24:nrt)}
[junit4:junit4]   2> 6465 T2913 C191 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 6467 T2913 C191 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 21
[junit4:junit4]   2> 6489 T2913 C191 UPDATE [collection1] webapp=null path=null params={} {add=[F1 (1413015082417258496)]} 0 4
[junit4:junit4]   2> 6494 T2913 C191 UPDATE [collection1] webapp=null path=null params={} {add=[F2 (1413015082426695680)]} 0 0
[junit4:junit4]   2> 6501 T2913 C191 UPDATE [collection1] webapp=null path=null params={} {add=[F3 (1413015082434035712)]} 0 0
[junit4:junit4]   2> 6503 T2913 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=5930256
[junit4:junit4]   2> 6503 T2913 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@19260d0
[junit4:junit4]   2> 6503 T2913 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 6512 T2913 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> 6512 T2913 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 6518 T2913 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 6520 T2913 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4:junit4]   2> 6521 T2913 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 6523 T2913 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 6591 T2913 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 6652 T2913 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
[junit4:junit4]   2> 6653 T2913 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 6657 T2913 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 7307 T2913 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 7311 T2913 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 7321 T2913 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 7322 T2913 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr
[junit4:junit4]   2> 7322 T2913 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 7334 T2913 oasc.CoreContainer.<init> New CoreContainer 27890995
[junit4:junit4]   2> 7334 T2913 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\
[junit4:junit4]   2> 7335 T2913 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 7336 T2913 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\index/
[junit4:junit4]   2> 7338 T2913 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 7339 T2913 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 7339 T2913 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 7339 T2913 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 7342 T2913 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 7346 T2913 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\index forceNew:false
[junit4:junit4]   2> 7347 T2913 oass.SolrIndexSearcher.<init> Opening Searcher@16531a3 main
[junit4:junit4]   2> 7347 T2913 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 7349 T2913 oasc.SolrException.log SEVERE Failure to open existing log file (non fatal) C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\tlog\tlog.0000000000000000004: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.GeneratedConstructorAccessor119.newInstance(Unknown Source)
[junit4:junit4]   2> 		at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
[junit4:junit4]   2> 		at java.lang.reflect.Constructor.newInstance(Constructor.java:513)
[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:39)
[junit4:junit4]   2> 		at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit4:junit4]   2> 		at java.lang.reflect.Method.invoke(Method.java:597)
[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:662)
[junit4:junit4]   2> 	
[junit4:junit4]   2> 7353 T2913 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 7353 T2913 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 7353 T2913 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 7354 T2913 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 7354 T2913 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 7354 T2913 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 7355 T2913 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 7355 T2913 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 7355 T2913 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 7355 T2913 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 7355 T2913 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 7355 T2913 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 7366 T2922 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@16531a3 main{StandardDirectoryReader(segments_a:24)}
[junit4:junit4]   2> 7366 T2913 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> ASYNC  NEW_CORE C192 name=collection1 org.apache.solr.core.SolrCore@19fdba4
[junit4:junit4]   2> 7367 T2923 C192 oasu.UpdateLog$LogReplayer.doReplay WARNING Starting log replay tlog{file=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\tlog\tlog.0000000000000000011 refcount=2} active=false starting pos=0
[junit4:junit4]   2> 7375 T2923 C192 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@5a24cf),segFN=segments_a,generation=10,filenames=[segments_a]
[junit4:junit4]   2> 7375 T2923 C192 oasc.SolrDeletionPolicy.updateCommits newest commit = 10
[junit4:junit4]   2> 7376 T2923 C192 oasc.SolrException.log Ignoring exception matching OutOfBoundsException
[junit4:junit4]   2> 7377 T2923 C192 oasu.DirectUpdateHandler2.commit start commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 7382 T2923 C192 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@5a24cf),segFN=segments_a,generation=10,filenames=[segments_a]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@5a24cf),segFN=segments_b,generation=11,filenames=[_8.si, segments_b, _8.inf, _8.pst, _8.fld]
[junit4:junit4]   2> 7382 T2923 C192 oasc.SolrDeletionPolicy.updateCommits newest commit = 11
[junit4:junit4]   2> 7390 T2923 C192 oass.SolrIndexSearcher.<init> Opening Searcher@1a76bca main
[junit4:junit4]   2> 7390 T2923 C192 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 7390 T2923 C192 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 7390 T2922 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1a76bca main{StandardDirectoryReader(segments_b:26 _8(4.0.0.2):C3)}
[junit4:junit4]   2> 7390 T2923 C192 oasu.UpdateLog$LogReplayer.run WARNING Log replay finished. recoveryInfo=RecoveryInfo{adds=3 deletes=0 deleteByQuery=0 errors=0 positionOfStart=0}
[junit4:junit4]   2> 7391 T2913 C192 REQ [collection1] webapp=null path=null params={q=*:*} hits=3 status=0 QTime=0 
[junit4:junit4]   2> 7404 T2913 C192 oasu.DirectUpdateHandler2.addDoc Reordered DBQs detected.  Update=add{flags=0,_version_=104,id=F4} DBQs=[DBQ{version=1413015078823788544,q=id:A11}]
[junit4:junit4]   2> 7415 T2913 C192 oass.SolrIndexSearcher.<init> Opening Searcher@3cdf56 realtime
[junit4:junit4]   2> 7415 T2913 C192 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 7416 T2913 C192 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=14 
[junit4:junit4]   2> 7421 T2913 C192 oasu.DirectUpdateHandler2.addDoc Reordered DBQs detected.  Update=add{flags=0,_version_=105,id=F5} DBQs=[DBQ{version=1413015078823788544,q=id:A11}]
[junit4:junit4]   2> 7427 T2913 C192 oass.SolrIndexSearcher.<init> Opening Searcher@b56935 realtime
[junit4:junit4]   2> 7427 T2913 C192 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 7427 T2913 C192 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=6 
[junit4:junit4]   2> 7432 T2913 C192 oasu.DirectUpdateHandler2.addDoc Reordered DBQs detected.  Update=add{flags=0,_version_=106,id=F6} DBQs=[DBQ{version=1413015078823788544,q=id:A11}]
[junit4:junit4]   2> 7436 T2913 C192 oass.SolrIndexSearcher.<init> Opening Searcher@12fe305 realtime
[junit4:junit4]   2> 7437 T2913 C192 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 7438 T2913 C192 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=6 
[junit4:junit4]   2> 7438 T2913 C192 REQ [collection1] webapp=null path=null params={getVersions=3&qt=/get} status=0 QTime=0 
[junit4:junit4]   2> 7438 T2913 oas.SolrTestCaseJ4.tearDown ###Ending testTruncatedLog
[junit4:junit4]   2> 7442 T2913 oas.SolrTestCaseJ4.setUp ###Starting testBuffering
[junit4:junit4]   2> 7449 T2913 C192 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 7458 T2913 C192 oass.SolrIndexSearcher.<init> Opening Searcher@ebf2c1 realtime
[junit4:junit4]   2> 7458 T2913 C192 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 7463 T2913 C192 REQ [collection1] webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true} status=0 QTime=14 
[junit4:junit4]   2> 7469 T2913 C192 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 7473 T2913 C192 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@5a24cf),segFN=segments_b,generation=11,filenames=[_8.si, segments_b, _8.inf, _8.pst, _8.fld]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@5a24cf),segFN=segments_c,generation=12,filenames=[segments_c]
[junit4:junit4]   2> 7475 T2913 C192 oasc.SolrDeletionPolicy.updateCommits newest commit = 12
[junit4:junit4]   2> 7479 T2913 C192 oass.SolrIndexSearcher.<init> Opening Searcher@1b68da8 main
[junit4:junit4]   2> 7480 T2913 C192 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 7480 T2913 C192 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 7480 T2922 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1b68da8 main{StandardDirectoryReader(segments_b:33:nrt)}
[junit4:junit4]   2> 7481 T2913 C192 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 12
[junit4:junit4]   2> 7495 T2913 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 7495 T2913 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 7505 T2913 C192 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=1 
[junit4:junit4]   2> 7510 T2913 C192 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 7515 T2913 C192 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&_version_=-1017&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 7521 T2913 C192 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 7526 T2913 C192 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 7530 T2913 C192 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> 7531 T2913 C192 REQ [collection1] webapp=null path=null params={getVersions=6&qt=/get} status=0 QTime=0 
[junit4:junit4]   2> 7535 T2913 C192 oasup.DistributedUpdateProcessor.processCommit Ignoring commit while not ACTIVE - state: BUFFERING replay:0
[junit4:junit4]   2> 7536 T2913 C192 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 1
[junit4:junit4]   2> 7538 T2913 C192 REQ [collection1] webapp=null path=null params={getVersions=6&qt=/get} status=0 QTime=1 
[junit4:junit4]   2> 7538 T2913 C192 REQ [collection1] webapp=null path=null params={q=*:*} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 7538 T2913 C192 REQ [collection1] webapp=null path=null params={id=B3&qt=/get} status=0 QTime=0 
[junit4:junit4]   2> 7539 T2923 C192 oasu.UpdateLog$LogReplayer.doReplay WARNING Starting log replay tlog{file=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\tlog\tlog.0000000000000000014 refcount=2} active=true starting pos=0
[junit4:junit4]   2> 7548 T2923 C192 oass.SolrIndexSearcher.<init> Opening Searcher@660a5a realtime
[junit4:junit4]   2> 7548 T2923 C192 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 7551 T2923 C192 oasu.DirectUpdateHandler2.commit start commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 7560 T2923 C192 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@5a24cf),segFN=segments_c,generation=12,filenames=[segments_c]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@5a24cf),segFN=segments_d,generation=13,filenames=[_d.inf, segments_d, _d.si, _d.pst, _d.fld]
[junit4:junit4]   2> 7561 T2923 C192 oasc.SolrDeletionPolicy.updateCommits newest commit = 13
[junit4:junit4]   2> 7564 T2923 C192 oass.SolrIndexSearcher.<init> Opening Searcher@b7604c main
[junit4:junit4]   2> 7564 T2923 C192 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 7565 T2922 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@b7604c main{StandardDirectoryReader(segments_d:39:nrt _d(4.0.0.2):C2)}
[junit4:junit4]   2> 7566 T2923 C192 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 7566 T2923 C192 oasu.UpdateLog$LogReplayer.run WARNING Log replay finished. recoveryInfo=RecoveryInfo{adds=4 deletes=1 deleteByQuery=1 errors=0 positionOfStart=0}
[junit4:junit4]   2> 7567 T2913 C192 REQ [collection1] webapp=null path=null params={getVersions=6&qt=/get} status=0 QTime=1 
[junit4:junit4]   2> 7567 T2913 C192 REQ [collection1] webapp=null path=null params={q=*:*} hits=2 status=0 QTime=0 
[junit4:junit4]   2> 7567 T2913 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 7568 T2913 C192 REQ [collection1] webapp=null path=null params={id=B3&qt=/get} status=0 QTime=0 
[junit4:junit4]   2> 7579 T2913 C192 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=1 
[junit4:junit4]   2> 7585 T2913 C192 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 7590 T2913 C192 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> 7594 T2913 C192 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 7599 T2913 C192 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 7604 T2913 C192 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=1 
[junit4:junit4]   2> 7608 T2913 C192 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&_version_=-3000&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 7609 T2913 C192 REQ [collection1] webapp=null path=null params={getVersions=13&qt=/get} status=0 QTime=1 
[junit4:junit4]   2> 7609 T2923 C192 oasu.UpdateLog$LogReplayer.doReplay WARNING Starting log replay tlog{file=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\tlog\tlog.0000000000000000015 refcount=2} active=true starting pos=0
[junit4:junit4]   2> 7613 T2913 C192 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 7618 T2913 C192 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> 7623 T2913 C192 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> 7628 T2923 C192 oass.SolrIndexSearcher.<init> Opening Searcher@1da2ae realtime
[junit4:junit4]   2> 7628 T2923 C192 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 7628 T2923 C192 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> 7631 T2923 C192 oass.SolrIndexSearcher.<init> Opening Searcher@10f22cf realtime
[junit4:junit4]   2> 7631 T2923 C192 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 7632 T2923 C192 oasu.DirectUpdateHandler2.commit start commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 7637 T2923 C192 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@5a24cf),segFN=segments_d,generation=13,filenames=[_d.inf, segments_d, _d.si, _d.pst, _d.fld]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@5a24cf),segFN=segments_e,generation=14,filenames=[segments_e, _e.inf, _d_1.liv, _e_1.liv, _f.fld, _f.inf, _d.pst, _f.si, _d.inf, _e.si, _f.pst, _d.si, _e.fld, _e.pst, _d.fld]
[junit4:junit4]   2> 7637 T2923 C192 oasc.SolrDeletionPolicy.updateCommits newest commit = 14
[junit4:junit4]   2> 7639 T2923 C192 oass.SolrIndexSearcher.<init> Opening Searcher@eca3ea main
[junit4:junit4]   2> 7639 T2923 C192 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 7640 T2923 C192 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 7640 T2922 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@eca3ea main{StandardDirectoryReader(segments_e:45:nrt _d(4.0.0.2):C2/1 _e(4.0.0.2):C4/2 _f(4.0.0.2):C1)}
[junit4:junit4]   2> 7641 T2923 C192 oasu.UpdateLog$LogReplayer.run WARNING Log replay finished. recoveryInfo=RecoveryInfo{adds=6 deletes=3 deleteByQuery=1 errors=0 positionOfStart=0}
[junit4:junit4]   2> 7641 T2913 C192 REQ [collection1] webapp=null path=null params={fl=id,_version_&sort=id+asc&q=*:*} hits=4 status=0 QTime=0 
[junit4:junit4]   2> 7642 T2913 oas.SolrTestCaseJ4.tearDown ###Ending testBuffering
[junit4:junit4]   2> 7646 T2913 oas.SolrTestCaseJ4.setUp ###Starting testRecoveryMultipleLogs
[junit4:junit4]   2> 7658 T2913 C192 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 7661 T2913 C192 oass.SolrIndexSearcher.<init> Opening Searcher@1618c1e realtime
[junit4:junit4]   2> 7662 T2913 C192 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 7662 T2913 C192 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> 7666 T2913 C192 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 7668 T2913 C192 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@5a24cf),segFN=segments_e,generation=14,filenames=[segments_e, _e.inf, _d_1.liv, _e_1.liv, _f.fld, _f.inf, _d.pst, _f.si, _d.inf, _e.si, _f.pst, _d.si, _e.fld, _e.pst, _d.fld]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@5a24cf),segFN=segments_f,generation=15,filenames=[segments_f]
[junit4:junit4]   2> 7669 T2913 C192 oasc.SolrDeletionPolicy.updateCommits newest commit = 15
[junit4:junit4]   2> 7679 T2913 C192 oass.SolrIndexSearcher.<init> Opening Searcher@c704d8 main
[junit4:junit4]   2> 7684 T2913 C192 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 7684 T2922 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c704d8 main{StandardDirectoryReader(segments_e:46:nrt)}
[junit4:junit4]   2> 7684 T2913 C192 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 7688 T2913 C192 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 22
[junit4:junit4]   2> 7699 T2913 C192 UPDATE [collection1] webapp=null path=null params={} {add=[AAAAAA (1413015083687084032)]} 0 3
[junit4:junit4]   2> 7705 T2913 C192 UPDATE [collection1] webapp=null path=null params={} {add=[BBBBBB (1413015083696521216)]} 0 0
[junit4:junit4]   2> 7710 T2913 C192 UPDATE [collection1] webapp=null path=null params={} {add=[CCCCCC (1413015083701764096)]} 0 1
[junit4:junit4]   2> 7711 T2913 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=27890995
[junit4:junit4]   2> 7711 T2913 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@19fdba4
[junit4:junit4]   2> 7712 T2913 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 7712 T2913 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=18,cumulative_deletesById=2,cumulative_deletesByQuery=4,cumulative_errors=2}
[junit4:junit4]   2> 7712 T2913 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 7719 T2913 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 7722 T2913 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4:junit4]   2> 7724 T2913 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 7725 T2913 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 7786 T2913 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 7845 T2913 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
[junit4:junit4]   2> 7847 T2913 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 7852 T2913 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 8428 T2913 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 8432 T2913 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 8442 T2913 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 8443 T2913 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr
[junit4:junit4]   2> 8443 T2913 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 8455 T2913 oasc.CoreContainer.<init> New CoreContainer 8825469
[junit4:junit4]   2> 8456 T2913 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\
[junit4:junit4]   2> 8456 T2913 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 8457 T2913 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\index/
[junit4:junit4]   2> 8458 T2913 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 8459 T2913 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 8459 T2913 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 8459 T2913 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 8462 T2913 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 8466 T2913 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\index forceNew:false
[junit4:junit4]   2> 8467 T2913 oass.SolrIndexSearcher.<init> Opening Searcher@85176f main
[junit4:junit4]   2> 8467 T2913 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 8469 T2913 oasc.SolrException.log SEVERE Failure to open existing log file (non fatal) C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\tlog\tlog.0000000000000000004: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.GeneratedConstructorAccessor119.newInstance(Unknown Source)
[junit4:junit4]   2> 		at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
[junit4:junit4]   2> 		at java.lang.reflect.Constructor.newInstance(Constructor.java:513)
[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:996)
[junit4:junit4]   2> 		at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]   2> 		at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
[junit4:junit4]   2> 		at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit4:junit4]   2> 		at java.lang.reflect.Method.invoke(Method.java:597)
[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:662)
[junit4:junit4]   2> 	
[junit4:junit4]   2> 8472 T2913 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 8473 T2913 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 8473 T2913 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 8473 T2913 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 8473 T2913 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 8474 T2913 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 8474 T2913 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 8474 T2913 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 8474 T2913 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 8475 T2913 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 8475 T2913 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 8475 T2913 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 8485 T2924 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@85176f main{StandardDirectoryReader(segments_f:46)}
[junit4:junit4]   2> 8485 T2913 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> ASYNC  NEW_CORE C193 name=collection1 org.apache.solr.core.SolrCore@400020
[junit4:junit4]   2> 8485 T2925 C193 oasu.UpdateLog$LogReplayer.doReplay WARNING Starting log replay tlog{file=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\tlog\tlog.0000000000000000017 refcount=2} active=false starting pos=0
[junit4:junit4]   2> 8492 T2925 C193 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@69fa9b),segFN=segments_f,generation=15,filenames=[segments_f]
[junit4:junit4]   2> 8492 T2925 C193 oasc.SolrDeletionPolicy.updateCommits newest commit = 15
[junit4:junit4]   2> 8493 T2925 C193 oasc.SolrException.log Ignoring exception matching OutOfBoundsException
[junit4:junit4]   2> 8493 T2925 C193 oasu.DirectUpdateHandler2.commit start commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 8498 T2925 C193 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@69fa9b),segFN=segments_f,generation=15,filenames=[segments_f]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@69fa9b),segFN=segments_g,generation=16,filenames=[_g.si, _g.fld, _g.inf, _g.pst, segments_g]
[junit4:junit4]   2> 8498 T2925 C193 oasc.SolrDeletionPolicy.updateCommits newest commit = 16
[junit4:junit4]   2> 8504 T2925 C193 oass.SolrIndexSearcher.<init> Opening Searcher@1ff191f main
[junit4:junit4]   2> 8504 T2925 C193 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 8505 T2925 C193 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 8505 T2924 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ff191f main{StandardDirectoryReader(segments_g:48 _g(4.0.0.2):C3)}
[junit4:junit4]   2> 8505 T2925 C193 oasu.UpdateLog$LogReplayer.doReplay WARNING Starting log replay tlog{file=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\tlog\tlog.0000000000000000018 refcount=2} active=false starting pos=0
[junit4:junit4]   2> 8506 T2925 C193 oasc.SolrException.log Ignoring exception matching OutOfBoundsException
[junit4:junit4]   2> 8506 T2925 C193 oasu.DirectUpdateHandler2.commit start commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 8512 T2925 C193 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@69fa9b),segFN=segments_g,generation=16,filenames=[_g.si, _g.fld, _g.inf, _g.pst, segments_g]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@69fa9b),segFN=segments_h,generation=17,filenames=[_g.si, _h.inf, _h.si, _h.fld, _g.fld, _g.inf, _h.pst, _g.pst, segments_h]
[junit4:junit4]   2> 8512 T2925 C193 oasc.SolrDeletionPolicy.updateCommits newest commit = 17
[junit4:junit4]   2> 8519 T2925 C193 oass.SolrIndexSearcher.<init> Opening Searcher@1af90ee main
[junit4:junit4]   2> 8519 T2925 C193 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 8520 T2925 C193 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 8520 T2924 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1af90ee main{StandardDirectoryReader(segments_h:50 _g(4.0.0.2):C3 _h(4.0.0.2):C3)}
[junit4:junit4]   2> 8520 T2925 C193 oasu.UpdateLog$LogReplayer.run WARNING Log replay finished. recoveryInfo=RecoveryInfo{adds=6 deletes=0 deleteByQuery=0 errors=0 positionOfStart=0}
[junit4:junit4]   2> 8521 T2913 C193 REQ [collection1] webapp=null path=null params={q=*:*} hits=6 status=0 QTime=1 
[junit4:junit4]   2> 8521 T2913 oas.SolrTestCaseJ4.tearDown ###Ending testRecoveryMultipleLogs
[junit4:junit4]   2> 8527 T2913 oas.SolrTestCaseJ4.setUp ###Starting testDropBuffered
[junit4:junit4]   2> 8540 T2913 C193 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 8543 T2913 C193 oass.SolrIndexSearcher.<init> Opening Searcher@17e8939 realtime
[junit4:junit4]   2> 8543 T2913 C193 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 8544 T2913 C193 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> 8548 T2913 C193 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 8550 T2913 C193 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@69fa9b),segFN=segments_h,generation=17,filenames=[_g.si, _h.inf, _h.si, _h.fld, _g.fld, _g.inf, _h.pst, _g.pst, segments_h]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@69fa9b),segFN=segments_i,generation=18,filenames=[segments_i]
[junit4:junit4]   2> 8551 T2913 C193 oasc.SolrDeletionPolicy.updateCommits newest commit = 18
[junit4:junit4]   2> 8557 T2913 C193 oass.SolrIndexSearcher.<init> Opening Searcher@be14fc main
[junit4:junit4]   2> 8557 T2913 C193 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 8558 T2924 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@be14fc main{StandardDirectoryReader(segments_h:51:nrt)}
[junit4:junit4]   2> 8558 T2913 C193 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 8558 T2913 C193 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 10
[junit4:junit4]   2> 8570 T2913 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 8570 T2913 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 8580 T2913 C193 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=2 
[junit4:junit4]   2> 8585 T2913 C193 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=1 
[junit4:junit4]   2> 8589 T2913 C193 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 8590 T2913 oasu.UpdateLog.dropBufferedUpdates Dropping buffered updates FSUpdateLog{state=BUFFERING, tlog=tlog{file=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\tlog\tlog.0000000000000000022 refcount=1}}
[junit4:junit4]   2> 8590 T2913 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=tlog{file=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\tlog\tlog.0000000000000000022 refcount=1}}
[junit4:junit4]   2> 8595 T2913 C193 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 8599 T2913 C193 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 8600 T2925 C193 oasu.UpdateLog$LogReplayer.doReplay WARNING Starting log replay tlog{file=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\tlog\tlog.0000000000000000022 refcount=2} active=true starting pos=0
[junit4:junit4]   2> 8601 T2925 C193 oasu.DirectUpdateHandler2.commit start commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 8611 T2925 C193 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@69fa9b),segFN=segments_i,generation=18,filenames=[segments_i]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@69fa9b),segFN=segments_j,generation=19,filenames=[_i.pst, _i.fld, _i.si, segments_j, _i.inf]
[junit4:junit4]   2> 8611 T2925 C193 oasc.SolrDeletionPolicy.updateCommits newest commit = 19
[junit4:junit4]   2> 8614 T2925 C193 oass.SolrIndexSearcher.<init> Opening Searcher@62e18a main
[junit4:junit4]   2> 8614 T2925 C193 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 8615 T2924 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@62e18a main{StandardDirectoryReader(segments_j:53:nrt _i(4.0.0.2):C2)}
[junit4:junit4]   2> 8617 T2925 C193 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 8617 T2925 C193 oasu.UpdateLog$LogReplayer.run WARNING Log replay finished. recoveryInfo=RecoveryInfo{adds=2 deletes=0 deleteByQuery=0 errors=0 positionOfStart=0}
[junit4:junit4]   2> 8618 T2913 C193 REQ [collection1] webapp=null path=null params={getVersions=2&qt=/get} status=0 QTime=1 
[junit4:junit4]   2> 8632 T2913 C193 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=3 
[junit4:junit4]   2> 8640 T2913 C193 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 8641 T2913 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=tlog{file=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\tlog\tlog.0000000000000000023 refcount=1}}
[junit4:junit4]   2> 8648 T2913 C193 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 8654 T2913 C193 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 8654 T2913 oasu.UpdateLog.dropBufferedUpdates Dropping buffered updates FSUpdateLog{state=BUFFERING, tlog=tlog{file=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\tlog\tlog.0000000000000000023 refcount=1}}
[junit4:junit4]   2> 8655 T2913 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=tlog{file=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\tlog\tlog.0000000000000000023 refcount=1}}
[junit4:junit4]   2> 8660 T2913 C193 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 8666 T2913 C193 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=1 
[junit4:junit4]   2> 8666 T2925 C193 oasu.UpdateLog$LogReplayer.doReplay WARNING Starting log replay tlog{file=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\tlog\tlog.0000000000000000023 refcount=2} active=true starting pos=92
[junit4:junit4]   2> 8667 T2925 C193 oasu.DirectUpdateHandler2.commit start commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 8675 T2925 C193 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@69fa9b),segFN=segments_j,generation=19,filenames=[_i.pst, _i.fld, _i.si, segments_j, _i.inf]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@69fa9b),segFN=segments_k,generation=20,filenames=[_j.pst, _j.si, _i.pst, _j.inf, _i.fld, segments_k, _j.fld, _i.si, _i.inf]
[junit4:junit4]   2> 8675 T2925 C193 oasc.SolrDeletionPolicy.updateCommits newest commit = 20
[junit4:junit4]   2> 8679 T2925 C193 oass.SolrIndexSearcher.<init> Opening Searcher@16bdf8b main
[junit4:junit4]   2> 8680 T2925 C193 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 8681 T2924 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@16bdf8b main{StandardDirectoryReader(segments_k:55:nrt _i(4.0.0.2):C2 _j(4.0.0.2):C4)}
[junit4:junit4]   2> 8683 T2925 C193 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 8683 T2925 C193 oasu.UpdateLog$LogReplayer.run WARNING Log replay finished. recoveryInfo=RecoveryInfo{adds=2 deletes=0 deleteByQuery=0 errors=0 positionOfStart=92}
[junit4:junit4]   2> 8685 T2913 C193 REQ [collection1] webapp=null path=null params={fl=id,_version_&sort=_version_+asc&q=*:*} hits=6 status=0 QTime=0 
[junit4:junit4]   2> 8687 T2913 C193 REQ [collection1] webapp=null path=null params={getVersions=6&qt=/get} status=0 QTime=1 
[junit4:junit4]   2> 8687 T2913 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 8703 T2913 C193 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=2 
[junit4:junit4]   2> 8708 T2913 C193 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 8709 T2913 oasu.UpdateLog.dropBufferedUpdates Dropping buffered updates FSUpdateLog{state=BUFFERING, tlog=tlog{file=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\tlog\tlog.0000000000000000024 refcount=1}}
[junit4:junit4]   2> 8717 T2913 C193 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=4 
[junit4:junit4]   2> 8722 T2913 C193 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 8727 T2913 C193 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 8736 T2913 C193 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@69fa9b),segFN=segments_k,generation=20,filenames=[_j.pst, _j.si, _i.pst, _j.inf, _i.fld, segments_k, _j.fld, _i.si, _i.inf]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@69fa9b),segFN=segments_l,generation=21,filenames=[_j.pst, _j.si, _i.pst, _j.inf, _i.fld, segments_l, _i.si, _i.inf, _k.pst, _j.fld, _k.inf, _k.fld, _k.si]
[junit4:junit4]   2> 8736 T2913 C193 oasc.SolrDeletionPolicy.updateCommits newest commit = 21
[junit4:junit4]   2> 8741 T2913 C193 oass.SolrIndexSearcher.<init> Opening Searcher@1f9859a main
[junit4:junit4]   2> 8741 T2913 C193 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 8741 T2924 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1f9859a main{StandardDirectoryReader(segments_l:57:nrt _i(4.0.0.2):C2 _j(4.0.0.2):C4 _k(4.0.0.2):C2)}
[junit4:junit4]   2> 8743 T2913 C193 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 8744 T2913 C193 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 17
[junit4:junit4]   2> 8750 T2913 C193 REQ [collection1] webapp=null path=null params={getVersions=2&qt=/get} status=0 QTime=1 
[junit4:junit4]   2> 8751 T2913 C193 REQ [collection1] webapp=null path=null params={fl=id,_version_&sort=_version_+desc&q=*:*&rows=2} hits=8 status=0 QTime=1 
[junit4:junit4]   2> 8763 T2913 C193 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=4 
[junit4:junit4]   2> 8763 T2913 oas.SolrTestCaseJ4.tearDown ###Ending testDropBuffered
[junit4:junit4]   2> 8767 T2913 oas.SolrTestCaseJ4.setUp ###Starting testBufferingFlags
[junit4:junit4]   2> 8774 T2913 C193 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 8778 T2913 C193 oass.SolrIndexSearcher.<init> Opening Searcher@cc564d realtime
[junit4:junit4]   2> 8778 T2913 C193 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 8779 T2913 C193 REQ [collection1] webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true} status=0 QTime=5 
[junit4:junit4]   2> 8786 T2913 C193 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 8788 T2913 C193 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@69fa9b),segFN=segments_l,generation=21,filenames=[_j.pst, _j.si, _i.pst, _j.inf, _i.fld, segments_l, _i.si, _i.inf, _k.pst, _j.fld, _k.inf, _k.fld, _k.si]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@69fa9b),segFN=segments_m,generation=22,filenames=[segments_m]
[junit4:junit4]   2> 8789 T2913 C193 oasc.SolrDeletionPolicy.updateCommits newest commit = 22
[junit4:junit4]   2> 8795 T2913 C193 oass.SolrIndexSearcher.<init> Opening Searcher@1531a12 main
[junit4:junit4]   2> 8796 T2913 C193 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 8797 T2924 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1531a12 main{StandardDirectoryReader(segments_l:59:nrt)}
[junit4:junit4]   2> 8798 T2913 C193 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 8803 T2913 C193 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 18
[junit4:junit4]   2> 8808 T2913 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 8817 T2913 C193 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=2 
[junit4:junit4]   2> 8823 T2913 C193 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 8828 T2913 C193 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 8828 T2913 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=8825469
[junit4:junit4]   2> 8828 T2913 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@400020
[junit4:junit4]   2> 8829 T2913 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 8829 T2913 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=7,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=2,cumulative_adds=15,cumulative_deletesById=0,cumulative_deletesByQuery=2,cumulative_errors=2}
[junit4:junit4]   2> 8829 T2913 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 8835 T2913 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 8836 T2913 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4:junit4]   2> 8838 T2913 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 8839 T2913 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 8900 T2913 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 8963 T2913 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
[junit4:junit4]   2> 8964 T2913 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 8969 T2913 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 9539 T2913 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 9543 T2913 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 9552 T2913 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 9552 T2913 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr
[junit4:junit4]   2> 9553 T2913 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 9569 T2913 oasc.CoreContainer.<init> New CoreContainer 654495
[junit4:junit4]   2> 9569 T2913 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\
[junit4:junit4]   2> 9570 T2913 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 9571 T2913 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\index/
[junit4:junit4]   2> 9574 T2913 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 9574 T2913 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 9575 T2913 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 9575 T2913 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 9579 T2913 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 9587 T2913 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\index forceNew:false
[junit4:junit4]   2> 9588 T2913 oass.SolrIndexSearcher.<init> Opening Searcher@8a54d2 main
[junit4:junit4]   2> 9589 T2913 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 9593 T2913 oasc.SolrException.log SEVERE Failure to open existing log file (non fatal) C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\tlog\tlog.0000000000000000004: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.GeneratedConstructorAccessor119.newInstance(Unknown Source)
[junit4:junit4]   2> 		at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
[junit4:junit4]   2> 		at java.lang.reflect.Constructor.newInstance(Constructor.java:513)
[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.testBufferingFlags(TestRecovery.java:522)
[junit4:junit4]   2> 		at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]   2> 		at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
[junit4:junit4]   2> 		at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit4:junit4]   2> 		at java.lang.reflect.Method.invoke(Method.java:597)
[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:662)
[junit4:junit4]   2> 	
[junit4:junit4]   2> 9599 T2913 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 9599 T2913 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 9599 T2913 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 9600 T2913 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 9600 T2913 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 9600 T2913 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 9601 T2913 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 9601 T2913 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 9601 T2913 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 9602 T2913 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 9602 T2913 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 9602 T2913 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 9612 T2926 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@8a54d2 main{StandardDirectoryReader(segments_m:59)}
[junit4:junit4]   2> 9612 T2913 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> ASYNC  NEW_CORE C194 name=collection1 org.apache.solr.core.SolrCore@14459b2
[junit4:junit4]   2> 9613 T2927 C194 oasu.UpdateLog$LogReplayer.doReplay WARNING Starting log replay tlog{file=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\tlog\tlog.0000000000000000026 refcount=2} active=false starting pos=0
[junit4:junit4]   2> 9621 T2927 C194 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@247a8a),segFN=segments_m,generation=22,filenames=[segments_m]
[junit4:junit4]   2> 9622 T2927 C194 oasc.SolrDeletionPolicy.updateCommits newest commit = 22
[junit4:junit4]   2> 9622 T2927 C194 oasu.DirectUpdateHandler2.addDoc Reordered DBQs detected.  Update=add{flags=10,_version_=101,id=Q1} DBQs=[DBQ{version=3000,q=id:B2 OR id:B8}, DBQ{version=1017,q=id:B1 id:B11 id:B2 id:B3}]
[junit4:junit4]   2> 9627 T2927 C194 oass.SolrIndexSearcher.<init> Opening Searcher@16cc65c realtime
[junit4:junit4]   2> 9627 T2927 C194 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 9627 T2927 C194 oasu.DirectUpdateHandler2.addDoc Reordered DBQs detected.  Update=add{flags=10,_version_=102,id=Q2} DBQs=[DBQ{version=3000,q=id:B2 OR id:B8}, DBQ{version=1017,q=id:B1 id:B11 id:B2 id:B3}]
[junit4:junit4]   2> 9631 T2927 C194 oass.SolrIndexSearcher.<init> Opening Searcher@85617a realtime
[junit4:junit4]   2> 9632 T2927 C194 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 9632 T2927 C194 oasu.DirectUpdateHandler2.addDoc Reordered DBQs detected.  Update=add{flags=10,_version_=103,id=Q3} DBQs=[DBQ{version=3000,q=id:B2 OR id:B8}, DBQ{version=1017,q=id:B1 id:B11 id:B2 id:B3}]
[junit4:junit4]   2> 9636 T2927 C194 oass.SolrIndexSearcher.<init> Opening Searcher@62bdfa realtime
[junit4:junit4]   2> 9636 T2927 C194 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 9636 T2927 C194 oasu.DirectUpdateHandler2.commit start commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 9639 T2927 C194 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@247a8a),segFN=segments_m,generation=22,filenames=[segments_m]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347556186314\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@247a8a),segFN=segments_n,generation=23,filenames=[_m.pst, _n.pst, _m.fld, segments_n, _o.inf, _o.fld, _m.inf, _m.si, _n.inf, _n.si, _o.si, _o.pst, _n.fld]
[junit4:junit4]   2> 9639 T2927 C194 oasc.SolrDeletionPolicy.updateCommits newest commit = 23
[junit4:junit4]   2> 9642 T2927 C194 oass.SolrIndexSearcher.<init> Opening Searcher@dfd524 main
[junit4:junit4]   2> 9642 T2927 C194 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 9642 T2927 C194 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 9642 T2926 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@dfd524 main{StandardDirectoryReader(segments_m:65:nrt _m(4.0.0.2):C1 _n(4.0.0.2):C1 _o(4.0.0.2):C1)}
[junit4:junit4]   2> 9643 T2927 C194 oasu.UpdateLog$LogReplayer.run WARNING Log replay finished. recoveryInfo=RecoveryInfo{adds=3 deletes=0 deleteByQuery=0 errors=0 positionOfStart=0}
[junit4:junit4]   2> 9643 T2913 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=654495
[junit4:junit4]   2> 9643 T2913 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@14459b2
[junit4:junit4]   2> 9644 T2913 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 9644 T2913 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> 9645 T2913 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 9658 T2913 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 9659 T2913 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4:junit4]   2> 9662 T2913 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 9663 T2913 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 9724 T2913 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 9780 T2913 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
[junit4:junit4]   2> 9781 T2913 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 9786 T2913 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 10383 T2913 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 10387 T2913 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 10397 T2913 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 10398 T2913 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr
[junit4:junit4]   2> 10398 T2913 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 10411 T2913 oasc.CoreContainer.<init> New CoreContainer 31580427
[junit4:junit4]   2> 10411 T2913 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\
[junit4:junit4]   2> 10411 T2913 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 10413 T2913 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\index/
[junit4:junit4]   2> 10415 T2913 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 10415 T2913 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 10416 T2913 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 10416 T2913 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 10419 T2913 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 10424 T2913 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\index forceNew:false
[junit4:junit4]   2> 10429 T2913 oass.SolrIndexSearcher.<init> Opening Searcher@1e07e4a main
[junit4:junit4]   2> 10429 T2913 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 10432 T2913 oasc.SolrException.log SEVERE Failure to open existing log file (non fatal) C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\tlog\tlog.0000000000000000004: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.GeneratedConstructorAccessor119.newInstance(Unknown Source)
[junit4:junit4]   2> 		at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
[junit4:junit4]   2> 		at java.lang.reflect.Constructor.newInstance(Constructor.java:513)
[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.testBufferingFlags(TestRecovery.java:538)
[junit4:junit4]   2> 		a

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

> Throwable #1: java.lang.NullPointerException
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([ACBCE4184135A9F1:32F35CDF24ACF1B4]:0)
[junit4:junit4]    > 	at org.apache.solr.util.TestHarness.getCore(TestHarness.java:212)
[junit4:junit4]    > 	at org.apache.solr.util.TestHarness$LocalRequestFactory.makeRequest(TestHarness.java:625)
[junit4:junit4]    > 	at org.apache.solr.SolrTestCaseJ4.req(SolrTestCaseJ4.java:742)
[junit4:junit4]    > 	at org.apache.solr.search.TestRecovery.testCleanShutdown(TestRecovery.java:644)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
[junit4:junit4]    > 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit4:junit4]    > 	at java.lang.reflect.Method.invoke(Method.java:597)
[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:662)
[junit4:junit4]   2> 12407 T2913 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> !!!! WARNING: best effort to remove C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\tlog\tlog.0000000000000000004 FAILED !!!!!
[junit4:junit4]   2> !!!! WARNING: best effort to remove C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314\tlog FAILED !!!!!
[junit4:junit4]   2> !!!! WARNING: best effort to remove C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347556186314 FAILED !!!!!
[junit4:junit4]   2> NOTE: test params are: codec=SimpleText, sim=DefaultSimilarity, locale=fr_FR, timezone=Navajo
[junit4:junit4]   2> NOTE: Windows 7 6.1 x86/Sun Microsystems Inc. 1.6.0_35 (32-bit)/cpus=2,threads=1,free=67202544,total=165347328
[junit4:junit4]   2> NOTE: All tests run in this JVM: [NumericFieldsTest, TestLFUCache, TestQueryTypes, SpellingQueryConverterTest, PrimitiveFieldTypeTest, MultiTermTest, SolrRequestParserTest, BasicZkTest, TestSolrDeletionPolicy2, TestFastLRUCache, UniqFieldsUpdateProcessorFactoryTest, SampleTest, TestRandomFaceting, TestPluginEnable, DirectSolrConnectionTest, XsltUpdateRequestHandlerTest, TermVectorComponentDistributedTest, PolyFieldTest, SimplePostToolTest, TestWordDelimiterFilterFactory, TestCoreContainer, BinaryUpdateRequestHandlerTest, TestSort, TestHashPartitioner, TestBinaryResponseWriter, TestStressRecovery, FullSolrCloudDistribCmdsTest, TestDFRSimilarityFactory, TestConfig, PeerSyncTest, TestPerFieldSimilarity, MoreLikeThisHandlerTest, TestFastWriter, TestPseudoReturnFields, LeaderElectionIntegrationTest, TestBM25SimilarityFactory, SolrCoreCheckLockOnStartupTest, TestIndexingPerformance, WordBreakSolrSpellCheckerTest, CacheHeaderTest, TestSolrDeletionPolicy1, DOMUtilTest, HighlighterTest, AlternateDirectoryTest, ScriptEngineTest, TestUpdate, TestRTGBase, StandardRequestHandlerTest, TestCharFilters, PluginInfoTest, SuggesterWFSTTest, XmlUpdateRequestHandlerTest, ConvertedLegacyTest, TestIBSimilarityFactory, OverseerTest, TestQuerySenderListener, DocumentAnalysisRequestHandlerTest, TestLRUCache, TestArbitraryIndexDir, ZkCLITest, TermVectorComponentTest, CoreContainerCoreInitFailuresTest, TestStressLucene, SpellCheckCollatorTest, TestDistributedGrouping, SortByFunctionTest, AutoCommitTest, TestIndexSearcher, DirectUpdateHandlerOptimizeTest, SuggesterFSTTest, TestSuggestSpellingConverter, QueryParsingTest, DateFieldTest, JSONWriterTest, TestWriterPerf, TestOmitPositions, ShowFileRequestHandlerTest, DirectSolrSpellCheckerTest, IndexBasedSpellCheckerTest, TestJoin, TestCollationKeyRangeQueries, CurrencyFieldTest, TestFaceting, SOLR749Test, DistanceFunctionTest, TestDocumentBuilder, TestLMDirichletSimilarityFactory, JsonLoaderTest, TestGroupingSearch, ClusterStateTest, TestStressReorder, ClusterStateUpdateTest, TestNumberUtils, TestFastOutputStream, EchoParamsTest, RAMDirectoryFactoryTest, TestSurroundQueryParser, SolrInfoMBeanTest, SearchHandlerTest, SoftAutoCommitTest, ZkControllerTest, LukeRequestHandlerTest, SolrCoreTest, DefaultValueUpdateProcessorTest, TestTrie, FileUtilsTest, SpatialFilterTest, BadIndexSchemaTest, TestPropInject, DistributedQueryElevationComponentTest, BasicDistributedZkTest, RecoveryZkTest, SpellCheckComponentTest, TestReplicationHandler, LeaderElectionTest, DistributedSpellCheckComponentTest, TestRealTimeGet, TestDistributedSearch, TestExtendedDismaxParser, ZkSolrClientTest, DistributedTermsComponentTest, StatsComponentTest, TestRecovery]
[junit4:junit4] Completed in 12.44s, 10 tests, 1 failure, 1 error <<< FAILURES!

[...truncated 310 lines...]
BUILD FAILED
C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\build.xml:29: The following error occurred while executing this line:
C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build.xml:146: The following error occurred while executing this line:
C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\lucene\module-build.xml:63: The following error occurred while executing this line:
C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:1133: The following error occurred while executing this line:
C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:810: There were test failures: 229 suites, 927 tests, 1 error, 1 failure, 12 ignored (7 assumptions)

Total time: 38 minutes 15 seconds
Build step 'Invoke Ant' marked build as failure
Recording test results
Description set: Java: 32bit/jdk1.6.0_35 -server -XX:+UseParallelGC
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message