lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Yonik Seeley <yo...@lucidworks.com>
Subject Re: [JENKINS] Lucene-Solr-4.x-Windows (32bit/jdk1.7.0_07) - Build # 755 - Still Failing!
Date Thu, 13 Sep 2012 16:09:38 GMT
Hmmm, I just checked in a new method on this test.  It didn't fail for
me but perhaps there's something that can mess up a test method that
follows it (depending on the ordering).  I'll look into it.

-Yonik
http://lucidworks.com


On Thu, Sep 13, 2012 at 12:03 PM, Policeman Jenkins Server
<jenkins@sd-datasolutions.de> wrote:
> Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-4.x-Windows/755/
> Java: 32bit/jdk1.7.0_07 -client -XX:+UseParallelGC
>
> 2 tests failed.
> REGRESSION:  org.apache.solr.search.TestRecovery.testRemoveOldLogs
>
> Error Message:
> expected:<0> but was:<1>
>
> Stack Trace:
> java.lang.AssertionError: expected:<0> but was:<1>
>         at __randomizedtesting.SeedInfo.seed([DE37D1BA010286D8:FFAB1011C9380F20]:0)
>         at org.junit.Assert.fail(Assert.java:93)
>         at org.junit.Assert.failNotEquals(Assert.java:647)
>         at org.junit.Assert.assertEquals(Assert.java:128)
>         at org.junit.Assert.assertEquals(Assert.java:472)
>         at org.junit.Assert.assertEquals(Assert.java:456)
>         at org.apache.solr.search.TestRecovery.testRemoveOldLogs(TestRecovery.java:721)
>         at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
>         at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
>         at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
>         at java.lang.reflect.Method.invoke(Method.java:601)
>         at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
>         at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
>         at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
>         at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
>         at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
>         at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
>         at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
>         at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
>         at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
>         at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
>         at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
>         at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
>         at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
>         at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
>         at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
>         at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
>         at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
>         at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
>         at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
>         at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
>         at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
>         at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
>         at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
>         at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
>         at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
>         at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
>         at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
>         at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
>         at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
>         at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
>         at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
>         at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
>         at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
>         at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
>         at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
>         at java.lang.Thread.run(Thread.java:722)
>
>
> REGRESSION:  org.apache.solr.search.TestRecovery.testDropBuffered
>
> Error Message:
>
>
> Stack Trace:
> java.lang.NullPointerException
>         at __randomizedtesting.SeedInfo.seed([DE37D1BA010286D8:FACBFAE8D44CF3C4]: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.testDropBuffered(TestRecovery.java:378)
>         at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
>         at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
>         at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
>         at java.lang.reflect.Method.invoke(Method.java:601)
>         at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
>         at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
>         at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
>         at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
>         at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
>         at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
>         at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
>         at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
>         at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
>         at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
>         at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
>         at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
>         at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
>         at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
>         at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
>         at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
>         at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
>         at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
>         at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
>         at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
>         at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
>         at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
>         at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
>         at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
>         at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
>         at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
>         at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
>         at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
>         at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
>         at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
>         at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
>         at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
>         at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
>         at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
>         at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
>         at java.lang.Thread.run(Thread.java:722)
>
>
>
>
> Build Log:
> [...truncated 7965 lines...]
> [junit4:junit4] Suite: org.apache.solr.search.TestRecovery
> [junit4:junit4]   2> 1 T3005 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-1347551817852
> [junit4:junit4]   2> 3 T3005 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> 4 T3005 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> 5 T3005 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> 57 T3005 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
> [junit4:junit4]   2> 105 T3005 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
> [junit4:junit4]   2> 105 T3005 oass.IndexSchema.readSchema Reading Solr Schema
> [junit4:junit4]   2> 110 T3005 oass.IndexSchema.readSchema Schema name=test
> [junit4:junit4]   2> 588 T3005 oass.IndexSchema.readSchema default search field in schema is text
> [junit4:junit4]   2> 594 T3005 oass.IndexSchema.readSchema unique key field: id
> [junit4:junit4]   2> 604 T3005 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
> [junit4:junit4]   2> 604 T3005 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> 604 T3005 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> 617 T3005 oasc.CoreContainer.<init> New CoreContainer 18726058
> [junit4:junit4]   2> 617 T3005 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-1347551817852\
> [junit4:junit4]   2> 617 T3005 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
> [junit4:junit4]   2> 617 T3005 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-1347551817852\index/
> [junit4:junit4]   2> 619 T3005 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347551817852\index' doesn't exist. Creating new index...
> [junit4:junit4]   2> 620 T3005 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347551817852\index forceNew:false
> [junit4:junit4]   2> 625 T3005 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-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1d6244c),segFN=segments_1,generation=1,filenames=[segments_1]
> [junit4:junit4]   2> 625 T3005 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
> [junit4:junit4]   2> 625 T3005 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
> [junit4:junit4]   2> 626 T3005 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
> [junit4:junit4]   2> 626 T3005 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
> [junit4:junit4]   2> 626 T3005 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
> [junit4:junit4]   2> 630 T3005 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
> [junit4:junit4]   2> 636 T3005 oass.SolrIndexSearcher.<init> Opening Searcher@e2f932 main
> [junit4:junit4]   2> 637 T3005 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 638 T3005 oasu.CommitTracker.<init> Hard AutoCommit: disabled
> [junit4:junit4]   2> 639 T3005 oasu.CommitTracker.<init> Soft AutoCommit: disabled
> [junit4:junit4]   2> 639 T3005 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
> [junit4:junit4]   2> 639 T3005 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
> [junit4:junit4]   2> 640 T3005 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
> [junit4:junit4]   2> 640 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
> [junit4:junit4]   2> 640 T3005 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
> [junit4:junit4]   2> 640 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
> [junit4:junit4]   2> 640 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
> [junit4:junit4]   2> 640 T3005 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
> [junit4:junit4]   2> 640 T3005 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
> [junit4:junit4]   2> 640 T3005 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
> [junit4:junit4]   2> 649 T3006 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@e2f932 main{StandardDirectoryReader(segments_1:1)}
> [junit4:junit4]   2> 649 T3005 oasc.CoreContainer.register registering core: collection1
> [junit4:junit4]   2> 650 T3005 oas.SolrTestCaseJ4.initCore ####initCore end
> [junit4:junit4]   2> 652 T3005 oas.SolrTestCaseJ4.setUp ###Starting testLogReplay
> [junit4:junit4]   2> ASYNC  NEW_CORE C187 name=collection1 org.apache.solr.core.SolrCore@bbebcc
> [junit4:junit4]   2> 659 T3005 C187 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
> [junit4:junit4]   2> 661 T3005 C187 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-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1d6244c),segFN=segments_1,generation=1,filenames=[segments_1]
> [junit4:junit4]   2> 662 T3005 C187 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
> [junit4:junit4]   2> 663 T3005 C187 oass.SolrIndexSearcher.<init> Opening Searcher@578ffe realtime
> [junit4:junit4]   2> 663 T3005 C187 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 663 T3005 C187 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> 666 T3005 C187 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
> [junit4:junit4]   2> 670 T3005 C187 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-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1d6244c),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-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1d6244c),segFN=segments_2,generation=2,filenames=[segments_2]
> [junit4:junit4]   2> 670 T3005 C187 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
> [junit4:junit4]   2> 672 T3005 C187 oass.SolrIndexSearcher.<init> Opening Searcher@1a2042e main
> [junit4:junit4]   2> 672 T3005 C187 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 673 T3006 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1a2042e main{StandardDirectoryReader(segments_1:2:nrt)}
> [junit4:junit4]   2> 673 T3005 C187 oasu.DirectUpdateHandler2.commit end_commit_flush
> [junit4:junit4]   2> 674 T3005 C187 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 8
> [junit4:junit4]   2> 693 T3005 C187 UPDATE [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[A1 (1413010495679496192)]} 0 2
> [junit4:junit4]   2> 697 T3005 C187 UPDATE [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[A11 (1413010495685787648)]} 0 0
> [junit4:junit4]   2> 701 T3005 C187 UPDATE [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[A12 (1413010495689981952)]} 0 0
> [junit4:junit4]   2> 711 T3005 C187 oass.SolrIndexSearcher.<init> Opening Searcher@1806ec3 realtime
> [junit4:junit4]   2> 711 T3005 C187 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 712 T3005 C187 UPDATE [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {deleteByQuery=id:A11 (-1413010495694176256)} 0 7
> [junit4:junit4]   2> 718 T3005 C187 UPDATE [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[A13 (1413010495704662016)]} 0 3
> [junit4:junit4]   2> 718 T3005 C187 REQ [collection1] webapp=null path=null params={q=*:*} hits=0 status=0 QTime=0
> [junit4:junit4]   2> 719 T3005 C187 REQ [collection1] webapp=null path=null params={getVersions=5&qt=/get} status=0 QTime=1
> [junit4:junit4]   2> 719 T3005 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=18726058
> [junit4:junit4]   2> 719 T3005 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@bbebcc
> [junit4:junit4]   2> 719 T3005 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
> [junit4:junit4]   2> 719 T3005 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=4,adds=4,deletesById=0,deletesByQuery=1,errors=1,cumulative_adds=4,cumulative_deletesById=0,cumulative_deletesByQuery=2,cumulative_errors=1}
> [junit4:junit4]   2> 719 T3005 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
> [junit4:junit4]   2> 728 T3005 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
> [junit4:junit4]   2> 729 T3005 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> 730 T3005 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> 732 T3005 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> 783 T3005 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
> [junit4:junit4]   2> 829 T3005 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
> [junit4:junit4]   2> 829 T3005 oass.IndexSchema.readSchema Reading Solr Schema
> [junit4:junit4]   2> 834 T3005 oass.IndexSchema.readSchema Schema name=test
> [junit4:junit4]   2> 1294 T3005 oass.IndexSchema.readSchema default search field in schema is text
> [junit4:junit4]   2> 1297 T3005 oass.IndexSchema.readSchema unique key field: id
> [junit4:junit4]   2> 1304 T3005 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
> [junit4:junit4]   2> 1304 T3005 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> 1304 T3005 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> 1314 T3005 oasc.CoreContainer.<init> New CoreContainer 30714004
> [junit4:junit4]   2> 1314 T3005 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-1347551817852\
> [junit4:junit4]   2> 1314 T3005 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
> [junit4:junit4]   2> 1316 T3005 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-1347551817852\index/
> [junit4:junit4]   2> 1317 T3005 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
> [junit4:junit4]   2> 1318 T3005 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
> [junit4:junit4]   2> 1318 T3005 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
> [junit4:junit4]   2> 1318 T3005 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
> [junit4:junit4]   2> 1320 T3005 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
> [junit4:junit4]   2> 1323 T3005 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347551817852\index forceNew:false
> [junit4:junit4]   2> 1323 T3005 oass.SolrIndexSearcher.<init> Opening Searcher@97095f main
> [junit4:junit4]   2> 1324 T3005 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 1327 T3005 oasu.CommitTracker.<init> Hard AutoCommit: disabled
> [junit4:junit4]   2> 1328 T3005 oasu.CommitTracker.<init> Soft AutoCommit: disabled
> [junit4:junit4]   2> 1328 T3005 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
> [junit4:junit4]   2> 1328 T3005 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
> [junit4:junit4]   2> 1328 T3005 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
> [junit4:junit4]   2> 1328 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
> [junit4:junit4]   2> 1329 T3005 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
> [junit4:junit4]   2> 1329 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
> [junit4:junit4]   2> 1333 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
> [junit4:junit4]   2> 1333 T3005 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
> [junit4:junit4]   2> 1333 T3005 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
> [junit4:junit4]   2> 1333 T3005 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
> [junit4:junit4]   2> 1341 T3007 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@97095f main{StandardDirectoryReader(segments_2:2)}
> [junit4:junit4]   2> 1341 T3005 oasc.CoreContainer.register registering core: collection1
> [junit4:junit4]   2> ASYNC  NEW_CORE C188 name=collection1 org.apache.solr.core.SolrCore@14a0366
> [junit4:junit4]   2> 1341 T3005 C188 REQ [collection1] webapp=null path=null params={q=*:*} hits=0 status=0 QTime=0
> [junit4:junit4]   2> 1342 T3008 C188 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-1347551817852\tlog\tlog.0000000000000000001 refcount=2} active=false starting pos=0
> [junit4:junit4]   2> 1342 T3005 C188 REQ [collection1] webapp=null path=null params={getVersions=5&qt=/get} status=0 QTime=0
> [junit4:junit4]   2> 1343 T3005 C188 REQ [collection1] webapp=null path=null params={getVersions=5&qt=/get} status=0 QTime=0
> [junit4:junit4]   2> 1346 T3008 C188 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-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1e567d0),segFN=segments_2,generation=2,filenames=[segments_2]
> [junit4:junit4]   2> 1347 T3008 C188 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
> [junit4:junit4]   2> 1347 T3008 C188 oasu.DirectUpdateHandler2.addDoc Reordered DBQs detected.  Update=add{flags=10,_version_=1413010495679496192,id=A1} DBQs=[DBQ{version=1413010495694176256,q=id:A11}]
> [junit4:junit4]   2> 1352 T3008 C188 oass.SolrIndexSearcher.<init> Opening Searcher@c96ed8 realtime
> [junit4:junit4]   2> 1352 T3008 C188 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 1352 T3008 C188 oasu.DirectUpdateHandler2.addDoc Reordered DBQs detected.  Update=add{flags=10,_version_=1413010495685787648,id=A11} DBQs=[DBQ{version=1413010495694176256,q=id:A11}]
> [junit4:junit4]   2> 1362 T3008 C188 oass.SolrIndexSearcher.<init> Opening Searcher@165c388 realtime
> [junit4:junit4]   2> 1362 T3008 C188 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 1362 T3008 C188 oasu.DirectUpdateHandler2.addDoc Reordered DBQs detected.  Update=add{flags=10,_version_=1413010495689981952,id=A12} DBQs=[DBQ{version=1413010495694176256,q=id:A11}]
> [junit4:junit4]   2> 1370 T3008 C188 oass.SolrIndexSearcher.<init> Opening Searcher@a01cee realtime
> [junit4:junit4]   2> 1371 T3008 C188 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 1372 T3008 C188 oasu.DirectUpdateHandler2.commit start commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
> [junit4:junit4]   2> 1380 T3008 C188 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-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1e567d0),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-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1e567d0),segFN=segments_3,generation=3,filenames=[_2.si, _3.frq, _0.fnm, _3.tim, _2.frq, _2.fdt, _3.fdt, _3.fnm, _2.fdx, _2.tim, _2.fnm, _3.fdx, _2.tip, _0.tim, _0.frq, _3.si, _3.tip, _0.fdx, _0.si, _0.tip, segments_3, _0.fdt]
> [junit4:junit4]   2> 1380 T3008 C188 oasc.SolrDeletionPolicy.updateCommits newest commit = 3
> [junit4:junit4]   2> 1384 T3008 C188 oass.SolrIndexSearcher.<init> Opening Searcher@8950d7 main
> [junit4:junit4]   2> 1384 T3008 C188 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 1384 T3008 C188 oasu.DirectUpdateHandler2.commit end_commit_flush
> [junit4:junit4]   2> 1384 T3007 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@8950d7 main{StandardDirectoryReader(segments_3:12:nrt _0(4.0.0.2):C1 _2(4.0.0.2):C1 _3(4.0.0.2):C1)}
> [junit4:junit4]   2> 1385 T3008 C188 oasu.UpdateLog$LogReplayer.run WARNING Log replay finished. recoveryInfo=RecoveryInfo{adds=4 deletes=0 deleteByQuery=1 errors=0 positionOfStart=0}
> [junit4:junit4]   2> 1385 T3005 C188 REQ [collection1] webapp=null path=null params={q=*:*} hits=3 status=0 QTime=0
> [junit4:junit4]   2> 1385 T3005 C188 REQ [collection1] webapp=null path=null params={getVersions=5&qt=/get} status=0 QTime=0
> [junit4:junit4]   2> 1393 T3005 C188 UPDATE [collection1] webapp=null path=null params={} {add=[A2 (1413010496412450816)]} 0 3
> [junit4:junit4]   2> 1407 T3005 C188 UPDATE [collection1] webapp=null path=null params={} {add=[A3 (1413010496430276608)]} 0 0
> [junit4:junit4]   2> 1412 T3005 C188 UPDATE [collection1] webapp=null path=null params={} {delete=[A2 (-1413010496435519488)]} 0 0
> [junit4:junit4]   2> 1416 T3005 C188 UPDATE [collection1] webapp=null path=null params={} {add=[A4 (1413010496439713792)]} 0 0
> [junit4:junit4]   2> 1418 T3005 C188 REQ [collection1] webapp=null path=null params={q=*:*} hits=3 status=0 QTime=0
> [junit4:junit4]   2> 1419 T3005 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=30714004
> [junit4:junit4]   2> 1419 T3005 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@14a0366
> [junit4:junit4]   2> 1420 T3005 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
> [junit4:junit4]   2> 1420 T3005 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> 1420 T3005 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
> [junit4:junit4]   2> 1436 T3005 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
> [junit4:junit4]   2> 1436 T3005 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> 1438 T3005 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> 1439 T3005 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> 1490 T3005 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
> [junit4:junit4]   2> 1533 T3005 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
> [junit4:junit4]   2> 1534 T3005 oass.IndexSchema.readSchema Reading Solr Schema
> [junit4:junit4]   2> 1539 T3005 oass.IndexSchema.readSchema Schema name=test
> [junit4:junit4]   2> 2007 T3005 oass.IndexSchema.readSchema default search field in schema is text
> [junit4:junit4]   2> 2010 T3005 oass.IndexSchema.readSchema unique key field: id
> [junit4:junit4]   2> 2018 T3005 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
> [junit4:junit4]   2> 2018 T3005 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> 2019 T3005 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> 2030 T3005 oasc.CoreContainer.<init> New CoreContainer 16987686
> [junit4:junit4]   2> 2030 T3005 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-1347551817852\
> [junit4:junit4]   2> 2030 T3005 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
> [junit4:junit4]   2> 2030 T3005 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-1347551817852\index/
> [junit4:junit4]   2> 2032 T3005 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
> [junit4:junit4]   2> 2033 T3005 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
> [junit4:junit4]   2> 2033 T3005 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
> [junit4:junit4]   2> 2034 T3005 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
> [junit4:junit4]   2> 2035 T3005 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
> [junit4:junit4]   2> 2038 T3005 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347551817852\index forceNew:false
> [junit4:junit4]   2> 2043 T3005 oass.SolrIndexSearcher.<init> Opening Searcher@b8987d main
> [junit4:junit4]   2> 2044 T3005 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 2046 T3005 oasu.CommitTracker.<init> Hard AutoCommit: disabled
> [junit4:junit4]   2> 2046 T3005 oasu.CommitTracker.<init> Soft AutoCommit: disabled
> [junit4:junit4]   2> 2046 T3005 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
> [junit4:junit4]   2> 2047 T3005 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
> [junit4:junit4]   2> 2047 T3005 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
> [junit4:junit4]   2> 2047 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
> [junit4:junit4]   2> 2048 T3005 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
> [junit4:junit4]   2> 2048 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
> [junit4:junit4]   2> 2048 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
> [junit4:junit4]   2> 2049 T3005 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
> [junit4:junit4]   2> 2049 T3005 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
> [junit4:junit4]   2> 2049 T3005 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
> [junit4:junit4]   2> 2056 T3009 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@b8987d main{StandardDirectoryReader(segments_3:12 _0(4.0.0.2):C1 _2(4.0.0.2):C1 _3(4.0.0.2):C1)}
> [junit4:junit4]   2> 2056 T3005 oasc.CoreContainer.register registering core: collection1
> [junit4:junit4]   2> ASYNC  NEW_CORE C189 name=collection1 org.apache.solr.core.SolrCore@1f3cf4c
> [junit4:junit4]   2> 2056 T3010 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-1347551817852\tlog\tlog.0000000000000000003 refcount=2} active=false starting pos=0
> [junit4:junit4]   2> 2064 T3010 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-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1d24439),segFN=segments_3,generation=3,filenames=[_2.si, _3.frq, _0.fnm, _3.tim, _2.fdt, _2.frq, _3.fnm, _3.fdt, _2.fdx, _2.fnm, _2.tim, _3.fdx, _2.tip, _0.frq, _0.tim, _3.tip, _3.si, _0.fdx, _0.si, _0.tip, segments_3, _0.fdt]
> [junit4:junit4]   2> 2064 T3010 C189 oasc.SolrDeletionPolicy.updateCommits newest commit = 3
> [junit4:junit4]   2> 2066 T3010 C189 oasu.DirectUpdateHandler2.commit start commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
> [junit4:junit4]   2> 2075 T3010 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-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1d24439),segFN=segments_3,generation=3,filenames=[_2.si, _3.frq, _0.fnm, _3.tim, _2.fdt, _2.frq, _3.fnm, _3.fdt, _2.fdx, _2.fnm, _2.tim, _3.fdx, _2.tip, _0.frq, _0.tim, _3.tip, _3.si, _0.fdx, _0.si, _0.tip, segments_3, _0.fdt]
> [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-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1d24439),segFN=segments_4,generation=4,filenames=[_2.si, _3.frq, _3.tim, _4.fnm, _3.fdt, _3.fnm, _2.tim, _4.fdx, _4.frq, _4.tip, _0.si, _0.fnm, _4.tim, _2.frq, _2.fdt, _4.si, _4.fdt, _2.fdx, _2.fnm, _3.fdx, _2.tip, _0.tim, _0.frq, _0.fdx, _3.si, _3.tip, _0.tip, _0.fdt, segments_4]
> [junit4:junit4]   2> 2075 T3010 C189 oasc.SolrDeletionPolicy.updateCommits newest commit = 4
> [junit4:junit4]   2> 2086 T3010 C189 oass.SolrIndexSearcher.<init> Opening Searcher@15e54a9 main
> [junit4:junit4]   2> 2086 T3010 C189 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 2086 T3010 C189 oasu.DirectUpdateHandler2.commit end_commit_flush
> [junit4:junit4]   2> 2086 T3009 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@15e54a9 main{StandardDirectoryReader(segments_4:14 _0(4.0.0.2):C1 _2(4.0.0.2):C1 _3(4.0.0.2):C1 _4(4.0.0.2):C2)}
> [junit4:junit4]   2> 2086 T3010 C189 oasu.UpdateLog$LogReplayer.run WARNING Log replay finished. recoveryInfo=RecoveryInfo{adds=3 deletes=1 deleteByQuery=0 errors=0 positionOfStart=0}
> [junit4:junit4]   2> 2086 T3005 C189 REQ [collection1] webapp=null path=null params={q=*:*} hits=5 status=0 QTime=0
> [junit4:junit4]   2> 2086 T3005 C189 REQ [collection1] webapp=null path=null params={q=id:A2} hits=0 status=0 QTime=0
> [junit4:junit4]   2> 2087 T3005 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=16987686
> [junit4:junit4]   2> 2087 T3005 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@1f3cf4c
> [junit4:junit4]   2> 2088 T3005 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
> [junit4:junit4]   2> 2089 T3005 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> 2090 T3005 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
> [junit4:junit4]   2> 2107 T3005 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
> [junit4:junit4]   2> 2108 T3005 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> 2109 T3005 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> 2110 T3005 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> 2161 T3005 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
> [junit4:junit4]   2> 2214 T3005 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
> [junit4:junit4]   2> 2214 T3005 oass.IndexSchema.readSchema Reading Solr Schema
> [junit4:junit4]   2> 2220 T3005 oass.IndexSchema.readSchema Schema name=test
> [junit4:junit4]   2> 2757 T3005 oass.IndexSchema.readSchema default search field in schema is text
> [junit4:junit4]   2> 2762 T3005 oass.IndexSchema.readSchema unique key field: id
> [junit4:junit4]   2> 2769 T3005 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
> [junit4:junit4]   2> 2769 T3005 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> 2769 T3005 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> 2782 T3005 oasc.CoreContainer.<init> New CoreContainer 17038485
> [junit4:junit4]   2> 2782 T3005 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-1347551817852\
> [junit4:junit4]   2> 2782 T3005 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
> [junit4:junit4]   2> 2782 T3005 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-1347551817852\index/
> [junit4:junit4]   2> 2785 T3005 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
> [junit4:junit4]   2> 2785 T3005 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
> [junit4:junit4]   2> 2785 T3005 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
> [junit4:junit4]   2> 2786 T3005 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
> [junit4:junit4]   2> 2786 T3005 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
> [junit4:junit4]   2> 2789 T3005 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347551817852\index forceNew:false
> [junit4:junit4]   2> 2796 T3005 oass.SolrIndexSearcher.<init> Opening Searcher@f6fb05 main
> [junit4:junit4]   2> 2796 T3005 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 2798 T3005 oasu.CommitTracker.<init> Hard AutoCommit: disabled
> [junit4:junit4]   2> 2798 T3005 oasu.CommitTracker.<init> Soft AutoCommit: disabled
> [junit4:junit4]   2> 2799 T3005 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
> [junit4:junit4]   2> 2800 T3005 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
> [junit4:junit4]   2> 2800 T3005 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
> [junit4:junit4]   2> 2800 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
> [junit4:junit4]   2> 2800 T3005 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
> [junit4:junit4]   2> 2801 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
> [junit4:junit4]   2> 2801 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
> [junit4:junit4]   2> 2802 T3005 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
> [junit4:junit4]   2> 2802 T3005 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
> [junit4:junit4]   2> 2802 T3005 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
> [junit4:junit4]   2> 2810 T3011 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@f6fb05 main{StandardDirectoryReader(segments_4:14 _0(4.0.0.2):C1 _2(4.0.0.2):C1 _3(4.0.0.2):C1 _4(4.0.0.2):C2)}
> [junit4:junit4]   2> 2810 T3005 oasc.CoreContainer.register registering core: collection1
> [junit4:junit4]   2> ASYNC  NEW_CORE C190 name=collection1 org.apache.solr.core.SolrCore@18babbb
> [junit4:junit4]   2> 2811 T3005 C190 REQ [collection1] webapp=null path=null params={q=*:*} hits=5 status=0 QTime=1
> [junit4:junit4]   2> 2901 T3005 oas.SolrTestCaseJ4.tearDown ###Ending testLogReplay
> [junit4:junit4]   2> 2904 T3005 oas.SolrTestCaseJ4.setUp ###Starting testBuffering
> [junit4:junit4]   2> 2912 T3005 C190 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
> [junit4:junit4]   2> 2921 T3005 C190 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-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@832905),segFN=segments_4,generation=4,filenames=[_2.si, _3.frq, _3.tim, _4.fnm, _3.fdt, _3.fnm, _2.tim, _4.fdx, _4.frq, _4.tip, _0.si, _0.fnm, _4.tim, _2.frq, _2.fdt, _4.fdt, _4.si, _2.fdx, _2.fnm, _3.fdx, _2.tip, _0.tim, _0.frq, _0.fdx, _3.si, _3.tip, _0.tip, _0.fdt, segments_4]
> [junit4:junit4]   2> 2922 T3005 C190 oasc.SolrDeletionPolicy.updateCommits newest commit = 4
> [junit4:junit4]   2> 2925 T3005 C190 oass.SolrIndexSearcher.<init> Opening Searcher@4625c5 realtime
> [junit4:junit4]   2> 2925 T3005 C190 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 2927 T3005 C190 REQ [collection1] webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true} status=0 QTime=15
> [junit4:junit4]   2> 2933 T3005 C190 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
> [junit4:junit4]   2> 2935 T3005 C190 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-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@832905),segFN=segments_4,generation=4,filenames=[_2.si, _3.frq, _3.tim, _4.fnm, _3.fdt, _3.fnm, _2.tim, _4.fdx, _4.frq, _4.tip, _0.si, _0.fnm, _4.tim, _2.frq, _2.fdt, _4.fdt, _4.si, _2.fdx, _2.fnm, _3.fdx, _2.tip, _0.tim, _0.frq, _0.fdx, _3.si, _3.tip, _0.tip, _0.fdt, segments_4]
> [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-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@832905),segFN=segments_5,generation=5,filenames=[segments_5]
> [junit4:junit4]   2> 2936 T3005 C190 oasc.SolrDeletionPolicy.updateCommits newest commit = 5
> [junit4:junit4]   2> 2955 T3005 C190 oass.SolrIndexSearcher.<init> Opening Searcher@1073c2c main
> [junit4:junit4]   2> 2955 T3005 C190 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 2956 T3011 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1073c2c main{StandardDirectoryReader(segments_4:15:nrt)}
> [junit4:junit4]   2> 2956 T3005 C190 oasu.DirectUpdateHandler2.commit end_commit_flush
> [junit4:junit4]   2> 2958 T3005 C190 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 26
> [junit4:junit4]   2> 2976 T3005 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
> [junit4:junit4]   2> 2976 T3005 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
> [junit4:junit4]   2> 2987 T3005 C190 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=2
> [junit4:junit4]   2> 2994 T3005 C190 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0
> [junit4:junit4]   2> 3001 T3005 C190 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&_version_=-1017&wt=json&indent=true} status=0 QTime=0
> [junit4:junit4]   2> 3008 T3005 C190 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0
> [junit4:junit4]   2> 3014 T3005 C190 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0
> [junit4:junit4]   2> 3022 T3005 C190 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> 3023 T3005 C190 REQ [collection1] webapp=null path=null params={getVersions=6&qt=/get} status=0 QTime=1
> [junit4:junit4]   2> 3029 T3005 C190 oasup.DistributedUpdateProcessor.processCommit Ignoring commit while not ACTIVE - state: BUFFERING replay:0
> [junit4:junit4]   2> 3029 T3005 C190 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 1
> [junit4:junit4]   2> 3033 T3005 C190 REQ [collection1] webapp=null path=null params={getVersions=6&qt=/get} status=0 QTime=1
> [junit4:junit4]   2> 3033 T3005 C190 REQ [collection1] webapp=null path=null params={q=*:*} hits=0 status=0 QTime=0
> [junit4:junit4]   2> 3034 T3005 C190 REQ [collection1] webapp=null path=null params={id=B3&qt=/get} status=0 QTime=0
> [junit4:junit4]   2> 3035 T3012 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-1347551817852\tlog\tlog.0000000000000000005 refcount=2} active=true starting pos=0
> [junit4:junit4]   2> 3058 T3012 C190 oass.SolrIndexSearcher.<init> Opening Searcher@49ec05 realtime
> [junit4:junit4]   2> 3058 T3012 C190 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 3061 T3012 C190 oasu.DirectUpdateHandler2.commit start commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
> [junit4:junit4]   2> 3068 T3012 C190 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-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@832905),segFN=segments_5,generation=5,filenames=[segments_5]
> [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-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@832905),segFN=segments_6,generation=6,filenames=[_6.tip, _6.frq, _6.tim, _6.fdx, _6.si, _6.fnm, _6.fdt, segments_6]
> [junit4:junit4]   2> 3068 T3012 C190 oasc.SolrDeletionPolicy.updateCommits newest commit = 6
> [junit4:junit4]   2> 3073 T3012 C190 oass.SolrIndexSearcher.<init> Opening Searcher@1cb20ca main
> [junit4:junit4]   2> 3073 T3012 C190 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 3075 T3012 C190 oasu.DirectUpdateHandler2.commit end_commit_flush
> [junit4:junit4]   2> 3075 T3011 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1cb20ca main{StandardDirectoryReader(segments_6:21:nrt _6(4.0.0.2):C2)}
> [junit4:junit4]   2> 3075 T3012 C190 oasu.UpdateLog$LogReplayer.run WARNING Log replay finished. recoveryInfo=RecoveryInfo{adds=4 deletes=1 deleteByQuery=1 errors=0 positionOfStart=0}
> [junit4:junit4]   2> 3076 T3005 C190 REQ [collection1] webapp=null path=null params={getVersions=6&qt=/get} status=0 QTime=1
> [junit4:junit4]   2> 3076 T3005 C190 REQ [collection1] webapp=null path=null params={q=*:*} hits=2 status=0 QTime=0
> [junit4:junit4]   2> 3077 T3005 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
> [junit4:junit4]   2> 3077 T3005 C190 REQ [collection1] webapp=null path=null params={id=B3&qt=/get} status=0 QTime=0
> [junit4:junit4]   2> 3088 T3005 C190 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0
> [junit4:junit4]   2> 3093 T3005 C190 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0
> [junit4:junit4]   2> 3097 T3005 C190 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> 3101 T3005 C190 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0
> [junit4:junit4]   2> 3106 T3005 C190 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0
> [junit4:junit4]   2> 3110 T3005 C190 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0
> [junit4:junit4]   2> 3115 T3005 C190 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&_version_=-3000&wt=json&indent=true} status=0 QTime=0
> [junit4:junit4]   2> 3116 T3005 C190 REQ [collection1] webapp=null path=null params={getVersions=13&qt=/get} status=0 QTime=0
> [junit4:junit4]   2> 3116 T3012 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-1347551817852\tlog\tlog.0000000000000000006 refcount=2} active=true starting pos=0
> [junit4:junit4]   2> 3120 T3005 C190 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0
> [junit4:junit4]   2> 3124 T3005 C190 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> 3128 T3005 C190 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> 3139 T3012 C190 oass.SolrIndexSearcher.<init> Opening Searcher@ee579d realtime
> [junit4:junit4]   2> 3140 T3012 C190 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 3140 T3012 C190 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> 3148 T3012 C190 oass.SolrIndexSearcher.<init> Opening Searcher@1044309 realtime
> [junit4:junit4]   2> 3148 T3012 C190 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 3148 T3012 C190 oasu.DirectUpdateHandler2.commit start commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
> [junit4:junit4]   2> 3155 T3012 C190 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-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@832905),segFN=segments_6,generation=6,filenames=[_6.tip, _6.frq, _6.tim, _6.fdx, _6.si, _6.fnm, _6.fdt, segments_6]
> [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-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@832905),segFN=segments_7,generation=7,filenames=[_7_1.del, _6.frq, _6.fdx, _8.frq, _7.si, _7.fdx, _8.tim, _6_1.del, _8.tip, _7.fdt, _8.si, _7.fnm, segments_7, _8.fnm, _6.tip, _6.tim, _7.frq, _7.tim, _6.fnm, _6.si, _8.fdx, _6.fdt, _8.fdt, _7.tip]
> [junit4:junit4]   2> 3156 T3012 C190 oasc.SolrDeletionPolicy.updateCommits newest commit = 7
> [junit4:junit4]   2> 3157 T3012 C190 oass.SolrIndexSearcher.<init> Opening Searcher@11ba9b0 main
> [junit4:junit4]   2> 3157 T3012 C190 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 3158 T3012 C190 oasu.DirectUpdateHandler2.commit end_commit_flush
> [junit4:junit4]   2> 3158 T3011 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@11ba9b0 main{StandardDirectoryReader(segments_7:27:nrt _6(4.0.0.2):C2/1 _7(4.0.0.2):C4/2 _8(4.0.0.2):C1)}
> [junit4:junit4]   2> 3159 T3012 C190 oasu.UpdateLog$LogReplayer.run WARNING Log replay finished. recoveryInfo=RecoveryInfo{adds=6 deletes=3 deleteByQuery=1 errors=0 positionOfStart=0}
> [junit4:junit4]   2> 3159 T3005 C190 REQ [collection1] webapp=null path=null params={fl=id,_version_&sort=id+asc&q=*:*} hits=4 status=0 QTime=0
> [junit4:junit4]   2> 3160 T3005 oas.SolrTestCaseJ4.tearDown ###Ending testBuffering
> [junit4:junit4]   2> 3164 T3005 oas.SolrTestCaseJ4.setUp ###Starting testTruncatedLog
> [junit4:junit4]   2> 3174 T3005 C190 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
> [junit4:junit4]   2> 3179 T3005 C190 oass.SolrIndexSearcher.<init> Opening Searcher@1dcdb25 realtime
> [junit4:junit4]   2> 3179 T3005 C190 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 3179 T3005 C190 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> 3183 T3005 C190 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
> [junit4:junit4]   2> 3186 T3005 C190 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-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@832905),segFN=segments_7,generation=7,filenames=[_7_1.del, _6.frq, _6.fdx, _8.frq, _7.si, _7.fdx, _8.tim, _6_1.del, _8.tip, _7.fdt, _8.si, _7.fnm, segments_7, _8.fnm, _6.tip, _6.tim, _7.frq, _7.tim, _6.fnm, _6.si, _8.fdx, _6.fdt, _8.fdt, _7.tip]
> [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-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@832905),segFN=segments_8,generation=8,filenames=[segments_8]
> [junit4:junit4]   2> 3186 T3005 C190 oasc.SolrDeletionPolicy.updateCommits newest commit = 8
> [junit4:junit4]   2> 3198 T3005 C190 oass.SolrIndexSearcher.<init> Opening Searcher@1f32576 main
> [junit4:junit4]   2> 3198 T3005 C190 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 3199 T3011 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1f32576 main{StandardDirectoryReader(segments_7:28:nrt)}
> [junit4:junit4]   2> 3199 T3005 C190 oasu.DirectUpdateHandler2.commit end_commit_flush
> [junit4:junit4]   2> 3207 T3005 C190 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 24
> [junit4:junit4]   2> 3217 T3005 C190 UPDATE [collection1] webapp=null path=null params={} {add=[F1 (1413010498325053440)]} 0 3
> [junit4:junit4]   2> 3225 T3005 C190 UPDATE [collection1] webapp=null path=null params={} {add=[F2 (1413010498335539200)]} 0 1
> [junit4:junit4]   2> 3231 T3005 C190 UPDATE [collection1] webapp=null path=null params={} {add=[F3 (1413010498342879232)]} 0 0
> [junit4:junit4]   2> 3233 T3005 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=17038485
> [junit4:junit4]   2> 3233 T3005 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@18babbb
> [junit4:junit4]   2> 3234 T3005 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
> [junit4:junit4]   2> 3234 T3005 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=4,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=3,adds=3,deletesById=0,deletesByQuery=0,errors=2,cumulative_adds=12,cumulative_deletesById=2,cumulative_deletesByQuery=4,cumulative_errors=2}
> [junit4:junit4]   2> 3234 T3005 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
> [junit4:junit4]   2> 3240 T3005 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
> [junit4:junit4]   2> 3241 T3005 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> 3244 T3005 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> 3245 T3005 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> 3337 T3005 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
> [junit4:junit4]   2> 3387 T3005 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
> [junit4:junit4]   2> 3388 T3005 oass.IndexSchema.readSchema Reading Solr Schema
> [junit4:junit4]   2> 3393 T3005 oass.IndexSchema.readSchema Schema name=test
> [junit4:junit4]   2> 3912 T3005 oass.IndexSchema.readSchema default search field in schema is text
> [junit4:junit4]   2> 3915 T3005 oass.IndexSchema.readSchema unique key field: id
> [junit4:junit4]   2> 3924 T3005 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
> [junit4:junit4]   2> 3924 T3005 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> 3924 T3005 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> 3936 T3005 oasc.CoreContainer.<init> New CoreContainer 32089649
> [junit4:junit4]   2> 3937 T3005 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-1347551817852\
> [junit4:junit4]   2> 3937 T3005 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
> [junit4:junit4]   2> 3939 T3005 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-1347551817852\index/
> [junit4:junit4]   2> 3939 T3005 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
> [junit4:junit4]   2> 3939 T3005 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
> [junit4:junit4]   2> 3940 T3005 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
> [junit4:junit4]   2> 3940 T3005 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
> [junit4:junit4]   2> 3943 T3005 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
> [junit4:junit4]   2> 3946 T3005 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347551817852\index forceNew:false
> [junit4:junit4]   2> 3947 T3005 oass.SolrIndexSearcher.<init> Opening Searcher@1a14807 main
> [junit4:junit4]   2> 3948 T3005 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 3950 T3005 oasu.CommitTracker.<init> Hard AutoCommit: disabled
> [junit4:junit4]   2> 3950 T3005 oasu.CommitTracker.<init> Soft AutoCommit: disabled
> [junit4:junit4]   2> 3950 T3005 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
> [junit4:junit4]   2> 3951 T3005 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
> [junit4:junit4]   2> 3951 T3005 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
> [junit4:junit4]   2> 3951 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
> [junit4:junit4]   2> 3952 T3005 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
> [junit4:junit4]   2> 3952 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
> [junit4:junit4]   2> 3952 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
> [junit4:junit4]   2> 3952 T3005 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
> [junit4:junit4]   2> 3952 T3005 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
> [junit4:junit4]   2> 3952 T3005 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
> [junit4:junit4]   2> 3960 T3013 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1a14807 main{StandardDirectoryReader(segments_8:28)}
> [junit4:junit4]   2> 3960 T3005 oasc.CoreContainer.register registering core: collection1
> [junit4:junit4]   2> ASYNC  NEW_CORE C191 name=collection1 org.apache.solr.core.SolrCore@c91f4d
> [junit4:junit4]   2> 3961 T3014 C191 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-1347551817852\tlog\tlog.0000000000000000008 refcount=2} active=false starting pos=0
> [junit4:junit4]   2> 3965 T3014 C191 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-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1c13320),segFN=segments_8,generation=8,filenames=[segments_8]
> [junit4:junit4]   2> 3966 T3014 C191 oasc.SolrDeletionPolicy.updateCommits newest commit = 8
> [junit4:junit4]   2> 3967 T3014 C191 oasc.SolrException.log Ignoring exception matching OutOfBoundsException
> [junit4:junit4]   2> 3967 T3014 C191 oasu.DirectUpdateHandler2.commit start commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
> [junit4:junit4]   2> 3973 T3014 C191 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-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1c13320),segFN=segments_8,generation=8,filenames=[segments_8]
> [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-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1c13320),segFN=segments_9,generation=9,filenames=[segments_9, _9.fnm, _9.fdt, _9.si, _9.fdx, _9.tip, _9.frq, _9.tim]
> [junit4:junit4]   2> 3973 T3014 C191 oasc.SolrDeletionPolicy.updateCommits newest commit = 9
> [junit4:junit4]   2> 3979 T3014 C191 oass.SolrIndexSearcher.<init> Opening Searcher@255057 main
> [junit4:junit4]   2> 3980 T3014 C191 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 3980 T3014 C191 oasu.DirectUpdateHandler2.commit end_commit_flush
> [junit4:junit4]   2> 3980 T3013 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@255057 main{StandardDirectoryReader(segments_9:30 _9(4.0.0.2):C3)}
> [junit4:junit4]   2> 3981 T3014 C191 oasu.UpdateLog$LogReplayer.run WARNING Log replay finished. recoveryInfo=RecoveryInfo{adds=3 deletes=0 deleteByQuery=0 errors=0 positionOfStart=0}
> [junit4:junit4]   2> 3981 T3005 C191 REQ [collection1] webapp=null path=null params={q=*:*} hits=3 status=0 QTime=0
> [junit4:junit4]   2> 3993 T3005 C191 oasu.DirectUpdateHandler2.addDoc Reordered DBQs detected.  Update=add{flags=0,_version_=104,id=F4} DBQs=[DBQ{version=1413010495694176256,q=id:A11}, DBQ{version=3000,q=id:B2 OR id:B8}, DBQ{version=1017,q=id:B1 id:B11 id:B2 id:B3}]
> [junit4:junit4]   2> 4005 T3005 C191 oass.SolrIndexSearcher.<init> Opening Searcher@852945 realtime
> [junit4:junit4]   2> 4005 T3005 C191 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 4005 T3005 C191 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=13
> [junit4:junit4]   2> 4010 T3005 C191 oasu.DirectUpdateHandler2.addDoc Reordered DBQs detected.  Update=add{flags=0,_version_=105,id=F5} DBQs=[DBQ{version=1413010495694176256,q=id:A11}, DBQ{version=3000,q=id:B2 OR id:B8}, DBQ{version=1017,q=id:B1 id:B11 id:B2 id:B3}]
> [junit4:junit4]   2> 4018 T3005 C191 oass.SolrIndexSearcher.<init> Opening Searcher@1a572fd realtime
> [junit4:junit4]   2> 4018 T3005 C191 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 4018 T3005 C191 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=9
> [junit4:junit4]   2> 4023 T3005 C191 oasu.DirectUpdateHandler2.addDoc Reordered DBQs detected.  Update=add{flags=0,_version_=106,id=F6} DBQs=[DBQ{version=1413010495694176256,q=id:A11}, DBQ{version=3000,q=id:B2 OR id:B8}, DBQ{version=1017,q=id:B1 id:B11 id:B2 id:B3}]
> [junit4:junit4]   2> 4031 T3005 C191 oass.SolrIndexSearcher.<init> Opening Searcher@e0b475 realtime
> [junit4:junit4]   2> 4031 T3005 C191 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 4031 T3005 C191 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=9
> [junit4:junit4]   2> 4032 T3005 C191 REQ [collection1] webapp=null path=null params={getVersions=3&qt=/get} status=0 QTime=1
> [junit4:junit4]   2> 4032 T3005 oas.SolrTestCaseJ4.tearDown ###Ending testTruncatedLog
> [junit4:junit4]   2> 4036 T3005 oas.SolrTestCaseJ4.setUp ###Starting testVersionsOnRestart
> [junit4:junit4]   2> 4041 T3005 C191 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
> [junit4:junit4]   2> 4053 T3005 C191 oass.SolrIndexSearcher.<init> Opening Searcher@11af2f2 realtime
> [junit4:junit4]   2> 4053 T3005 C191 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 4062 T3005 C191 REQ [collection1] webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true} status=0 QTime=22
> [junit4:junit4]   2> 4068 T3005 C191 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
> [junit4:junit4]   2> 4072 T3005 C191 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-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1c13320),segFN=segments_9,generation=9,filenames=[segments_9, _9.fnm, _9.fdt, _9.si, _9.fdx, _9.tip, _9.frq, _9.tim]
> [junit4:junit4]   2>            commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1c13320),segFN=segments_a,generation=10,filenames=[segments_a]
> [junit4:junit4]   2> 4073 T3005 C191 oasc.SolrDeletionPolicy.updateCommits newest commit = 10
> [junit4:junit4]   2> 4078 T3005 C191 oass.SolrIndexSearcher.<init> Opening Searcher@19db06c main
> [junit4:junit4]   2> 4078 T3005 C191 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 4080 T3013 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@19db06c main{StandardDirectoryReader(segments_9:37:nrt)}
> [junit4:junit4]   2> 4080 T3005 C191 oasu.DirectUpdateHandler2.commit end_commit_flush
> [junit4:junit4]   2> 4080 T3005 C191 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 12
> [junit4:junit4]   2> 4100 T3005 C191 UPDATE [collection1] webapp=null path=null params={} {add=[D1 (1413010499251994624)]} 0 2
> [junit4:junit4]   2> 4105 T3005 C191 UPDATE [collection1] webapp=null path=null params={} {add=[D2 (1413010499259334656)]} 0 0
> [junit4:junit4]   2> 4111 T3005 C191 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
> [junit4:junit4]   2> 4121 T3005 C191 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-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1c13320),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-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1c13320),segFN=segments_b,generation=11,filenames=[_d.frq, _d.tim, _d.fdt, segments_b, _d.si, _d.fnm, _d.tip, _d.fdx]
> [junit4:junit4]   2> 4121 T3005 C191 oasc.SolrDeletionPolicy.updateCommits newest commit = 11
> [junit4:junit4]   2> 4125 T3005 C191 oass.SolrIndexSearcher.<init> Opening Searcher@136f6b0 main
> [junit4:junit4]   2> 4126 T3005 C191 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 4126 T3013 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@136f6b0 main{StandardDirectoryReader(segments_b:39:nrt _d(4.0.0.2):C2)}
> [junit4:junit4]   2> 4126 T3005 C191 oasu.DirectUpdateHandler2.commit end_commit_flush
> [junit4:junit4]   2> 4127 T3005 C191 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 16
> [junit4:junit4]   2> 4131 T3005 C191 REQ [collection1] webapp=null path=null params={q=id:D1} hits=1 status=0 QTime=0
> [junit4:junit4]   2> 4132 T3005 C191 REQ [collection1] webapp=null path=null params={q=id:D2} hits=1 status=0 QTime=1
> [junit4:junit4]   2> 4132 T3005 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=32089649
> [junit4:junit4]   2> 4133 T3005 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@c91f4d
> [junit4:junit4]   2> 4134 T3005 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
> [junit4:junit4]   2> 4134 T3005 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=3,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=1,cumulative_adds=8,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=1}
> [junit4:junit4]   2> 4134 T3005 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
> [junit4:junit4]   2> 4142 T3005 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
> [junit4:junit4]   2> 4143 T3005 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> 4145 T3005 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> 4145 T3005 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> 4204 T3005 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
> [junit4:junit4]   2> 4258 T3005 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
> [junit4:junit4]   2> 4258 T3005 oass.IndexSchema.readSchema Reading Solr Schema
> [junit4:junit4]   2> 4264 T3005 oass.IndexSchema.readSchema Schema name=test
> [junit4:junit4]   2> 4786 T3005 oass.IndexSchema.readSchema default search field in schema is text
> [junit4:junit4]   2> 4790 T3005 oass.IndexSchema.readSchema unique key field: id
> [junit4:junit4]   2> 4799 T3005 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
> [junit4:junit4]   2> 4800 T3005 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> 4800 T3005 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> 4816 T3005 oasc.CoreContainer.<init> New CoreContainer 12684998
> [junit4:junit4]   2> 4816 T3005 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-1347551817852\
> [junit4:junit4]   2> 4816 T3005 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
> [junit4:junit4]   2> 4817 T3005 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-1347551817852\index/
> [junit4:junit4]   2> 4819 T3005 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
> [junit4:junit4]   2> 4819 T3005 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
> [junit4:junit4]   2> 4819 T3005 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
> [junit4:junit4]   2> 4820 T3005 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
> [junit4:junit4]   2> 4822 T3005 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
> [junit4:junit4]   2> 4826 T3005 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347551817852\index forceNew:false
> [junit4:junit4]   2> 4828 T3005 oass.SolrIndexSearcher.<init> Opening Searcher@87ea9e main
> [junit4:junit4]   2> 4829 T3005 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.BaseDirectoryWrapper
> [junit4:junit4]   2> 4831 T3005 oasu.CommitTracker.<init> Hard AutoCommit: disabled
> [junit4:junit4]   2> 4832 T3005 oasu.CommitTracker.<init> Soft AutoCommit: disabled
> [junit4:junit4]   2> 4832 T3005 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
> [junit4:junit4]   2> 4833 T3005 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
> [junit4:junit4]   2> 4833 T3005 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
> [junit4:junit4]   2> 4833 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
> [junit4:junit4]   2> 4834 T3005 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
> [junit4:junit4]   2> 4834 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
> [junit4:junit4]   2> 4834 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
> [junit4:junit4]   2> 4835 T3005 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
> [junit4:junit4]   2> 4835 T3005 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
> [junit4:junit4]   2> 4835 T3005 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
> [junit4:junit4]   2> 4842 T3015 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@87ea9e main{StandardDirectoryReader(segments_b:39 _d(4.0.0.2):C2)}
> [junit4:junit4]   2> 4842 T3005 oasc.CoreContainer.register registering core: collection1
> [junit4:junit4]   2> ASYNC  NEW_CORE C192 name=collection1 org.apache.solr.core.SolrCore@7cedd
> [junit4:junit4]   2> 4851 T3005 C192 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
> [junit4:junit4]   2>            commit{dir=BaseDirectoryWrapper(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@8d427b),segFN=segments_b,generation=11,filenames=[_d.tim, _d.frq, _d.fdt, segments_b, _d.fnm, _d.si, _d.tip, _d.fdx]
> [junit4:junit4]   2> 4851 T3005 C192 oasc.SolrDeletionPolicy.updateCommits newest commit = 11
> [junit4:junit4]   2> 4853 T3005 C192 UPDATE [collection1] webapp=null path=null params={} {add=[D1 (1413010500038426624)]} 0 5
> [junit4:junit4]   2> 4862 T3005 C192 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
> [junit4:junit4]   2> 4881 T3005 C192 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
> [junit4:junit4]   2>            commit{dir=BaseDirectoryWrapper(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@8d427b),segFN=segments_b,generation=11,filenames=[_d.tim, _d.frq, _d.fdt, segments_b, _d.fnm, _d.si, _d.tip, _d.fdx]
> [junit4:junit4]   2>            commit{dir=BaseDirectoryWrapper(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@8d427b),segFN=segments_c,generation=12,filenames=[_d.frq, _d.tim, segments_c, _d_1.del, _e.frq, _d.fnm, _e.fdx, _e.tim, _e.tip, _d.fdx, _d.fdt, _e.si, _e.fdt, _d.si, _d.tip, _e.fnm]
> [junit4:junit4]   2> 4882 T3005 C192 oasc.SolrDeletionPolicy.updateCommits newest commit = 12
> [junit4:junit4]   2> 4889 T3005 C192 oass.SolrIndexSearcher.<init> Opening Searcher@10c374e main
> [junit4:junit4]   2> 4889 T3005 C192 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.BaseDirectoryWrapper
> [junit4:junit4]   2> 4889 T3005 C192 oasu.DirectUpdateHandler2.commit end_commit_flush
> [junit4:junit4]   2> 4890 T3015 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@10c374e main{StandardDirectoryReader(segments_c:42 _d(4.0.0.2):C2/1 _e(4.0.0.2):C1)}
> [junit4:junit4]   2> 4890 T3005 C192 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 28
> [junit4:junit4]   2> 4896 T3005 C192 REQ [collection1] webapp=null path=null params={q=id:D1} hits=1 status=0 QTime=0
> [junit4:junit4]   2> 4896 T3005 C192 REQ [collection1] webapp=null path=null params={getVersions=2&qt=/get} status=0 QTime=0
> [junit4:junit4]   2> 4897 T3005 oas.SolrTestCaseJ4.tearDown ###Ending testVersionsOnRestart
> [junit4:junit4]   2> 4901 T3005 oas.SolrTestCaseJ4.setUp ###Starting testRecoveryMultipleLogs
> [junit4:junit4]   2> 4909 T3005 C192 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
> [junit4:junit4]   2> 4911 T3005 C192 oass.SolrIndexSearcher.<init> Opening Searcher@576d6f realtime
> [junit4:junit4]   2> 4912 T3005 C192 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.BaseDirectoryWrapper
> [junit4:junit4]   2> 4912 T3005 C192 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> 4916 T3005 C192 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
> [junit4:junit4]   2> 4922 T3005 C192 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
> [junit4:junit4]   2>            commit{dir=BaseDirectoryWrapper(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@8d427b),segFN=segments_c,generation=12,filenames=[_d.frq, _d.tim, segments_c, _d_1.del, _e.frq, _d.fnm, _e.fdx, _e.tim, _e.tip, _d.fdx, _d.fdt, _e.si, _e.fdt, _d.si, _d.tip, _e.fnm]
> [junit4:junit4]   2>            commit{dir=BaseDirectoryWrapper(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@8d427b),segFN=segments_d,generation=13,filenames=[segments_d]
> [junit4:junit4]   2> 4922 T3005 C192 oasc.SolrDeletionPolicy.updateCommits newest commit = 13
> [junit4:junit4]   2> 4934 T3005 C192 oass.SolrIndexSearcher.<init> Opening Searcher@1edde7f main
> [junit4:junit4]   2> 4935 T3005 C192 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.BaseDirectoryWrapper
> [junit4:junit4]   2> 4935 T3005 C192 oasu.DirectUpdateHandler2.commit end_commit_flush
> [junit4:junit4]   2> 4935 T3015 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1edde7f main{StandardDirectoryReader(segments_c:43:nrt)}
> [junit4:junit4]   2> 4936 T3005 C192 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 20
> [junit4:junit4]   2> 4948 T3005 C192 UPDATE [collection1] webapp=null path=null params={} {add=[AAAAAA (1413010500139089920)]} 0 4
> [junit4:junit4]   2> 4953 T3005 C192 UPDATE [collection1] webapp=null path=null params={} {add=[BBBBBB (1413010500148527104)]} 0 0
> [junit4:junit4]   2> 4959 T3005 C192 UPDATE [collection1] webapp=null path=null params={} {add=[CCCCCC (1413010500153769984)]} 0 1
> [junit4:junit4]   2> 4961 T3005 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=12684998
> [junit4:junit4]   2> 4961 T3005 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@7cedd
> [junit4:junit4]   2> 4961 T3005 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
> [junit4:junit4]   2> 4966 T3005 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> 4967 T3005 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
> [junit4:junit4]   2> 4973 T3005 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
> [junit4:junit4]   2> 4975 T3005 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> 4977 T3005 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> 4979 T3005 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> 5035 T3005 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
> [junit4:junit4]   2> 5088 T3005 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
> [junit4:junit4]   2> 5088 T3005 oass.IndexSchema.readSchema Reading Solr Schema
> [junit4:junit4]   2> 5093 T3005 oass.IndexSchema.readSchema Schema name=test
> [junit4:junit4]   2> 5645 T3005 oass.IndexSchema.readSchema default search field in schema is text
> [junit4:junit4]   2> 5648 T3005 oass.IndexSchema.readSchema unique key field: id
> [junit4:junit4]   2> 5657 T3005 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
> [junit4:junit4]   2> 5658 T3005 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> 5658 T3005 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> 5671 T3005 oasc.CoreContainer.<init> New CoreContainer 31951526
> [junit4:junit4]   2> 5671 T3005 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-1347551817852\
> [junit4:junit4]   2> 5671 T3005 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
> [junit4:junit4]   2> 5672 T3005 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-1347551817852\index/
> [junit4:junit4]   2> 5673 T3005 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
> [junit4:junit4]   2> 5673 T3005 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
> [junit4:junit4]   2> 5674 T3005 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
> [junit4:junit4]   2> 5674 T3005 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
> [junit4:junit4]   2> 5677 T3005 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
> [junit4:junit4]   2> 5686 T3005 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347551817852\index forceNew:false
> [junit4:junit4]   2> 5687 T3005 oass.SolrIndexSearcher.<init> Opening Searcher@1b70d7e main
> [junit4:junit4]   2> 5688 T3005 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 5692 T3005 oasu.CommitTracker.<init> Hard AutoCommit: disabled
> [junit4:junit4]   2> 5693 T3005 oasu.CommitTracker.<init> Soft AutoCommit: disabled
> [junit4:junit4]   2> 5693 T3005 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
> [junit4:junit4]   2> 5693 T3005 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
> [junit4:junit4]   2> 5694 T3005 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
> [junit4:junit4]   2> 5694 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
> [junit4:junit4]   2> 5695 T3005 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
> [junit4:junit4]   2> 5695 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
> [junit4:junit4]   2> 5696 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
> [junit4:junit4]   2> 5697 T3005 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
> [junit4:junit4]   2> 5697 T3005 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
> [junit4:junit4]   2> 5698 T3005 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
> [junit4:junit4]   2> 5714 T3016 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1b70d7e main{StandardDirectoryReader(segments_d:43)}
> [junit4:junit4]   2> 5714 T3005 oasc.CoreContainer.register registering core: collection1
> [junit4:junit4]   2> ASYNC  NEW_CORE C193 name=collection1 org.apache.solr.core.SolrCore@1e700e0
> [junit4:junit4]   2> 5715 T3017 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-1347551817852\tlog\tlog.0000000000000000014 refcount=2} active=false starting pos=0
> [junit4:junit4]   2> 5721 T3017 C193 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-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@95ff22),segFN=segments_d,generation=13,filenames=[segments_d]
> [junit4:junit4]   2> 5722 T3017 C193 oasc.SolrDeletionPolicy.updateCommits newest commit = 13
> [junit4:junit4]   2> 5724 T3017 C193 oasc.SolrException.log Ignoring exception matching OutOfBoundsException
> [junit4:junit4]   2> 5725 T3017 C193 oasu.DirectUpdateHandler2.commit start commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
> [junit4:junit4]   2> 5731 T3017 C193 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-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@95ff22),segFN=segments_d,generation=13,filenames=[segments_d]
> [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-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@95ff22),segFN=segments_e,generation=14,filenames=[segments_e, _f.fdx, _f.tip, _f.fnm, _f.tim, _f.frq, _f.si, _f.fdt]
> [junit4:junit4]   2> 5731 T3017 C193 oasc.SolrDeletionPolicy.updateCommits newest commit = 14
> [junit4:junit4]   2> 5737 T3017 C193 oass.SolrIndexSearcher.<init> Opening Searcher@1fd71e7 main
> [junit4:junit4]   2> 5738 T3017 C193 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 5738 T3017 C193 oasu.DirectUpdateHandler2.commit end_commit_flush
> [junit4:junit4]   2> 5738 T3016 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1fd71e7 main{StandardDirectoryReader(segments_e:45 _f(4.0.0.2):C3)}
> [junit4:junit4]   2> 5739 T3017 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-1347551817852\tlog\tlog.0000000000000000015 refcount=2} active=false starting pos=0
> [junit4:junit4]   2> 5739 T3017 C193 oasc.SolrException.log Ignoring exception matching OutOfBoundsException
> [junit4:junit4]   2> 5740 T3017 C193 oasu.DirectUpdateHandler2.commit start commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
> [junit4:junit4]   2> 5746 T3017 C193 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-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@95ff22),segFN=segments_e,generation=14,filenames=[segments_e, _f.fdx, _f.tip, _f.fnm, _f.tim, _f.frq, _f.si, _f.fdt]
> [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-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@95ff22),segFN=segments_f,generation=15,filenames=[_g.fdt, segments_f, _g.si, _f.tip, _g.fdx, _f.tim, _g.tim, _g.tip, _g.frq, _f.fdt, _f.si, _f.fdx, _g.fnm, _f.fnm, _f.frq]
> [junit4:junit4]   2> 5746 T3017 C193 oasc.SolrDeletionPolicy.updateCommits newest commit = 15
> [junit4:junit4]   2> 5754 T3017 C193 oass.SolrIndexSearcher.<init> Opening Searcher@11700ff main
> [junit4:junit4]   2> 5754 T3017 C193 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 5755 T3017 C193 oasu.DirectUpdateHandler2.commit end_commit_flush
> [junit4:junit4]   2> 5755 T3016 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@11700ff main{StandardDirectoryReader(segments_f:47 _f(4.0.0.2):C3 _g(4.0.0.2):C3)}
> [junit4:junit4]   2> 5756 T3017 C193 oasu.UpdateLog$LogReplayer.run WARNING Log replay finished. recoveryInfo=RecoveryInfo{adds=6 deletes=0 deleteByQuery=0 errors=0 positionOfStart=0}
> [junit4:junit4]   2> 5756 T3005 C193 REQ [collection1] webapp=null path=null params={q=*:*} hits=6 status=0 QTime=0
> [junit4:junit4]   2> 5757 T3005 oas.SolrTestCaseJ4.tearDown ###Ending testRecoveryMultipleLogs
> [junit4:junit4]   2> 5760 T3005 oas.SolrTestCaseJ4.setUp ###Starting testCorruptLog
> [junit4:junit4]   2> 5772 T3005 C193 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
> [junit4:junit4]   2> 5774 T3005 C193 oass.SolrIndexSearcher.<init> Opening Searcher@d7c9e realtime
> [junit4:junit4]   2> 5774 T3005 C193 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 5775 T3005 C193 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> 5779 T3005 C193 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
> [junit4:junit4]   2> 5781 T3005 C193 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-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@95ff22),segFN=segments_f,generation=15,filenames=[_g.fdt, segments_f, _g.si, _f.tip, _g.fdx, _f.tim, _g.tim, _g.tip, _g.frq, _f.fdt, _f.si, _f.fdx, _g.fnm, _f.fnm, _f.frq]
> [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-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@95ff22),segFN=segments_g,generation=16,filenames=[segments_g]
> [junit4:junit4]   2> 5782 T3005 C193 oasc.SolrDeletionPolicy.updateCommits newest commit = 16
> [junit4:junit4]   2> 5790 T3005 C193 oass.SolrIndexSearcher.<init> Opening Searcher@17308bd main
> [junit4:junit4]   2> 5791 T3005 C193 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 5791 T3016 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@17308bd main{StandardDirectoryReader(segments_f:48:nrt)}
> [junit4:junit4]   2> 5791 T3005 C193 oasu.DirectUpdateHandler2.commit end_commit_flush
> [junit4:junit4]   2> 5792 T3005 C193 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 13
> [junit4:junit4]   2> 5811 T3005 C193 UPDATE [collection1] webapp=null path=null params={} {add=[G1 (1413010501045059584)]} 0 3
> [junit4:junit4]   2> 5817 T3005 C193 UPDATE [collection1] webapp=null path=null params={} {add=[G2 (1413010501054496768)]} 0 0
> [junit4:junit4]   2> 5823 T3005 C193 UPDATE [collection1] webapp=null path=null params={} {add=[G3 (1413010501060788224)]} 0 0
> [junit4:junit4]   2> 5825 T3005 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=31951526
> [junit4:junit4]   2> 5825 T3005 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@1e700e0
> [junit4:junit4]   2> 5826 T3005 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
> [junit4:junit4]   2> 5831 T3005 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=3,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=3,adds=3,deletesById=0,deletesByQuery=0,errors=1,cumulative_adds=9,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=1}
> [junit4:junit4]   2> 5832 T3005 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
> [junit4:junit4]   2> 5839 T3005 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
> [junit4:junit4]   2> 5841 T3005 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> 5844 T3005 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> 5844 T3005 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> 5902 T3005 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
> [junit4:junit4]   2> 5953 T3005 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
> [junit4:junit4]   2> 5954 T3005 oass.IndexSchema.readSchema Reading Solr Schema
> [junit4:junit4]   2> 5959 T3005 oass.IndexSchema.readSchema Schema name=test
> [junit4:junit4]   2> 6494 T3005 oass.IndexSchema.readSchema default search field in schema is text
> [junit4:junit4]   2> 6497 T3005 oass.IndexSchema.readSchema unique key field: id
> [junit4:junit4]   2> 6505 T3005 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
> [junit4:junit4]   2> 6506 T3005 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> 6506 T3005 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> 6518 T3005 oasc.CoreContainer.<init> New CoreContainer 8272850
> [junit4:junit4]   2> 6519 T3005 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-1347551817852\
> [junit4:junit4]   2> 6519 T3005 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
> [junit4:junit4]   2> 6520 T3005 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-1347551817852\index/
> [junit4:junit4]   2> 6522 T3005 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
> [junit4:junit4]   2> 6522 T3005 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
> [junit4:junit4]   2> 6522 T3005 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
> [junit4:junit4]   2> 6523 T3005 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
> [junit4:junit4]   2> 6525 T3005 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
> [junit4:junit4]   2> 6529 T3005 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347551817852\index forceNew:false
> [junit4:junit4]   2> 6530 T3005 oass.SolrIndexSearcher.<init> Opening Searcher@1b969e3 main
> [junit4:junit4]   2> 6530 T3005 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.BaseDirectoryWrapper
> [junit4:junit4]   2> 6533 T3005 oasc.SolrException.log Ignoring exception matching Failure to open existing log file
> [junit4:junit4]   2> 6534 T3005 oasu.CommitTracker.<init> Hard AutoCommit: disabled
> [junit4:junit4]   2> 6534 T3005 oasu.CommitTracker.<init> Soft AutoCommit: disabled
> [junit4:junit4]   2> 6535 T3005 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
> [junit4:junit4]   2> 6535 T3005 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
> [junit4:junit4]   2> 6535 T3005 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
> [junit4:junit4]   2> 6536 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
> [junit4:junit4]   2> 6536 T3005 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
> [junit4:junit4]   2> 6536 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
> [junit4:junit4]   2> 6536 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
> [junit4:junit4]   2> 6537 T3005 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
> [junit4:junit4]   2> 6537 T3005 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
> [junit4:junit4]   2> 6537 T3005 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
> [junit4:junit4]   2> 6545 T3018 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1b969e3 main{StandardDirectoryReader(segments_g:48)}
> [junit4:junit4]   2> 6545 T3005 oasc.CoreContainer.register registering core: collection1
> [junit4:junit4]   2> ASYNC  NEW_CORE C194 name=collection1 org.apache.solr.core.SolrCore@8cf9e5
> [junit4:junit4]   2> 6548 T3005 C194 REQ [collection1] webapp=null path=null params={q=*:*} hits=0 status=0 QTime=0
> [junit4:junit4]   2> 6560 T3005 C194 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
> [junit4:junit4]   2>            commit{dir=BaseDirectoryWrapper(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f23d1),segFN=segments_g,generation=16,filenames=[segments_g]
> [junit4:junit4]   2> 6561 T3005 C194 oasc.SolrDeletionPolicy.updateCommits newest commit = 16
> [junit4:junit4]   2> 6561 T3005 C194 oasu.DirectUpdateHandler2.addDoc Reordered DBQs detected.  Update=add{flags=0,_version_=104,id=G4} DBQs=[DBQ{version=1413010495694176256,q=id:A11}, DBQ{version=3000,q=id:B2 OR id:B8}, DBQ{version=1017,q=id:B1 id:B11 id:B2 id:B3}]
> [junit4:junit4]   2> 6567 T3005 C194 oass.SolrIndexSearcher.<init> Opening Searcher@18b1dd1 realtime
> [junit4:junit4]   2> 6567 T3005 C194 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.BaseDirectoryWrapper
> [junit4:junit4]   2> 6568 T3005 C194 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=12
> [junit4:junit4]   2> 6572 T3005 C194 oasu.DirectUpdateHandler2.addDoc Reordered DBQs detected.  Update=add{flags=0,_version_=105,id=G5} DBQs=[DBQ{version=1413010495694176256,q=id:A11}, DBQ{version=3000,q=id:B2 OR id:B8}, DBQ{version=1017,q=id:B1 id:B11 id:B2 id:B3}]
> [junit4:junit4]   2> 6578 T3005 C194 oass.SolrIndexSearcher.<init> Opening Searcher@112fc7 realtime
> [junit4:junit4]   2> 6578 T3005 C194 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.BaseDirectoryWrapper
> [junit4:junit4]   2> 6579 T3005 C194 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=7
> [junit4:junit4]   2> 6584 T3005 C194 oasu.DirectUpdateHandler2.addDoc Reordered DBQs detected.  Update=add{flags=0,_version_=106,id=G6} DBQs=[DBQ{version=1413010495694176256,q=id:A11}, DBQ{version=3000,q=id:B2 OR id:B8}, DBQ{version=1017,q=id:B1 id:B11 id:B2 id:B3}]
> [junit4:junit4]   2> 6590 T3005 C194 oass.SolrIndexSearcher.<init> Opening Searcher@efbd1c realtime
> [junit4:junit4]   2> 6591 T3005 C194 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.BaseDirectoryWrapper
> [junit4:junit4]   2> 6592 T3005 C194 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=8
> [junit4:junit4]   2> 6593 T3005 C194 REQ [collection1] webapp=null path=null params={getVersions=3&qt=/get} status=0 QTime=1
> [junit4:junit4]   2> 6593 T3005 oas.SolrTestCaseJ4.tearDown ###Ending testCorruptLog
> [junit4:junit4]   2> 6597 T3005 oas.SolrTestCaseJ4.setUp ###Starting testBufferingFlags
> [junit4:junit4]   2> 6603 T3005 C194 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
> [junit4:junit4]   2> 6628 T3005 C194 oass.SolrIndexSearcher.<init> Opening Searcher@118e09d realtime
> [junit4:junit4]   2> 6629 T3005 C194 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.BaseDirectoryWrapper
> [junit4:junit4]   2> 6637 T3005 C194 REQ [collection1] webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true} status=0 QTime=34
> [junit4:junit4]   2> 6643 T3005 C194 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
> [junit4:junit4]   2> 6651 T3005 C194 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
> [junit4:junit4]   2>            commit{dir=BaseDirectoryWrapper(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f23d1),segFN=segments_g,generation=16,filenames=[segments_g]
> [junit4:junit4]   2>            commit{dir=BaseDirectoryWrapper(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@16f23d1),segFN=segments_h,generation=17,filenames=[segments_h]
> [junit4:junit4]   2> 6651 T3005 C194 oasc.SolrDeletionPolicy.updateCommits newest commit = 17
> [junit4:junit4]   2> 6654 T3005 C194 oass.SolrIndexSearcher.<init> Opening Searcher@6007dd main
> [junit4:junit4]   2> 6654 T3005 C194 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.BaseDirectoryWrapper
> [junit4:junit4]   2> 6655 T3018 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6007dd main{StandardDirectoryReader(segments_g:55:nrt)}
> [junit4:junit4]   2> 6657 T3005 C194 oasu.DirectUpdateHandler2.commit end_commit_flush
> [junit4:junit4]   2> 6657 T3005 C194 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 14
> [junit4:junit4]   2> 6671 T3005 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
> [junit4:junit4]   2> 6682 T3005 C194 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=2
> [junit4:junit4]   2> 6686 T3005 C194 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0
> [junit4:junit4]   2> 6690 T3005 C194 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0
> [junit4:junit4]   2> 6691 T3005 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=8272850
> [junit4:junit4]   2> 6691 T3005 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@8cf9e5
> [junit4:junit4]   2> 6694 T3005 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
> [junit4:junit4]   2> 6694 T3005 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=1,cumulative_adds=3,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=1}
> [junit4:junit4]   2> 6694 T3005 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
> [junit4:junit4]   2> 6699 T3005 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
> [junit4:junit4]   2> 6700 T3005 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> 6702 T3005 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> 6703 T3005 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> 6758 T3005 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
> [junit4:junit4]   2> 6809 T3005 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
> [junit4:junit4]   2> 6809 T3005 oass.IndexSchema.readSchema Reading Solr Schema
> [junit4:junit4]   2> 6815 T3005 oass.IndexSchema.readSchema Schema name=test
> [junit4:junit4]   2> 7338 T3005 oass.IndexSchema.readSchema default search field in schema is text
> [junit4:junit4]   2> 7343 T3005 oass.IndexSchema.readSchema unique key field: id
> [junit4:junit4]   2> 7355 T3005 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
> [junit4:junit4]   2> 7356 T3005 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> 7356 T3005 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> 7374 T3005 oasc.CoreContainer.<init> New CoreContainer 21777133
> [junit4:junit4]   2> 7374 T3005 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-1347551817852\
> [junit4:junit4]   2> 7374 T3005 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
> [junit4:junit4]   2> 7375 T3005 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-1347551817852\index/
> [junit4:junit4]   2> 7378 T3005 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
> [junit4:junit4]   2> 7378 T3005 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
> [junit4:junit4]   2> 7379 T3005 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
> [junit4:junit4]   2> 7380 T3005 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
> [junit4:junit4]   2> 7383 T3005 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
> [junit4:junit4]   2> 7388 T3005 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347551817852\index forceNew:false
> [junit4:junit4]   2> 7389 T3005 oass.SolrIndexSearcher.<init> Opening Searcher@61d177 main
> [junit4:junit4]   2> 7390 T3005 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 7396 T3005 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-1347551817852\tlog\tlog.0000000000000000019: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.GeneratedConstructorAccessor129.newInstance(Unknown Source)
> [junit4:junit4]   2>            at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
> [junit4:junit4]   2>            at java.lang.reflect.Constructor.newInstance(Constructor.java:525)
> [junit4:junit4]   2>            at org.apache.solr.core.SolrCore.createInstance(SolrCore.java:476)
> [junit4:junit4]   2>            at org.apache.solr.core.SolrCore.createUpdateHandler(SolrCore.java:544)
> [junit4:junit4]   2>            at org.apache.solr.core.SolrCore.<init>(SolrCore.java:707)
> [junit4:junit4]   2>            at org.apache.solr.core.SolrCore.<init>(SolrCore.java:567)
> [junit4:junit4]   2>            at org.apache.solr.util.TestHarness$Initializer.initialize(TestHarness.java:190)
> [junit4:junit4]   2>            at org.apache.solr.util.TestHarness.<init>(TestHarness.java:114)
> [junit4:junit4]   2>            at org.apache.solr.util.TestHarness.<init>(TestHarness.java:109)
> [junit4:junit4]   2>            at org.apache.solr.util.TestHarness.<init>(TestHarness.java:99)
> [junit4:junit4]   2>            at org.apache.solr.SolrTestCaseJ4.createCore(SolrTestCaseJ4.java:362)
> [junit4:junit4]   2>            at org.apache.solr.search.TestRecovery.testBufferingFlags(TestRecovery.java:522)
> [junit4:junit4]   2>            at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> [junit4:junit4]   2>            at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
> [junit4:junit4]   2>            at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> [junit4:junit4]   2>            at java.lang.reflect.Method.invoke(Method.java:601)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
> [junit4:junit4]   2>            at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
> [junit4:junit4]   2>            at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
> [junit4:junit4]   2>            at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
> [junit4:junit4]   2>            at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
> [junit4:junit4]   2>            at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
> [junit4:junit4]   2>            at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
> [junit4:junit4]   2>            at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
> [junit4:junit4]   2>            at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
> [junit4:junit4]   2>            at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
> [junit4:junit4]   2>            at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
> [junit4:junit4]   2>            at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
> [junit4:junit4]   2>            at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
> [junit4:junit4]   2>            at java.lang.Thread.run(Thread.java:722)
> [junit4:junit4]   2>
> [junit4:junit4]   2> 7401 T3005 oasu.CommitTracker.<init> Hard AutoCommit: disabled
> [junit4:junit4]   2> 7401 T3005 oasu.CommitTracker.<init> Soft AutoCommit: disabled
> [junit4:junit4]   2> 7402 T3005 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
> [junit4:junit4]   2> 7402 T3005 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
> [junit4:junit4]   2> 7403 T3005 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
> [junit4:junit4]   2> 7403 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
> [junit4:junit4]   2> 7404 T3005 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
> [junit4:junit4]   2> 7404 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
> [junit4:junit4]   2> 7404 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
> [junit4:junit4]   2> 7405 T3005 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
> [junit4:junit4]   2> 7405 T3005 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
> [junit4:junit4]   2> 7406 T3005 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
> [junit4:junit4]   2> 7420 T3019 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@61d177 main{StandardDirectoryReader(segments_h:55)}
> [junit4:junit4]   2> 7420 T3005 oasc.CoreContainer.register registering core: collection1
> [junit4:junit4]   2> ASYNC  NEW_CORE C195 name=collection1 org.apache.solr.core.SolrCore@1ddea28
> [junit4:junit4]   2> 7421 T3020 C195 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-1347551817852\tlog\tlog.0000000000000000021 refcount=2} active=false starting pos=0
> [junit4:junit4]   2> 7430 T3020 C195 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-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@132e37f),segFN=segments_h,generation=17,filenames=[segments_h]
> [junit4:junit4]   2> 7430 T3020 C195 oasc.SolrDeletionPolicy.updateCommits newest commit = 17
> [junit4:junit4]   2> 7430 T3020 C195 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> 7436 T3020 C195 oass.SolrIndexSearcher.<init> Opening Searcher@57cf74 realtime
> [junit4:junit4]   2> 7436 T3020 C195 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 7437 T3020 C195 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> 7444 T3020 C195 oass.SolrIndexSearcher.<init> Opening Searcher@1bcb013 realtime
> [junit4:junit4]   2> 7444 T3020 C195 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 7444 T3020 C195 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> 7451 T3020 C195 oass.SolrIndexSearcher.<init> Opening Searcher@1fd863 realtime
> [junit4:junit4]   2> 7452 T3020 C195 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 7452 T3020 C195 oasu.DirectUpdateHandler2.commit start commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
> [junit4:junit4]   2> 7456 T3020 C195 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-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@132e37f),segFN=segments_h,generation=17,filenames=[segments_h]
> [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-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@132e37f),segFN=segments_i,generation=18,filenames=[_l.fnm, _k.fdt, _l.si, _m.frq, _k.fdx, _l.frq, segments_i, _k.fnm, _m.si, _m.fdt, _m.tip, _k.tim, _l.tip, _l.fdx, _m.tim, _m.fdx, _l.tim, _l.fdt, _k.tip, _k.frq, _m.fnm, _k.si]
> [junit4:junit4]   2> 7456 T3020 C195 oasc.SolrDeletionPolicy.updateCommits newest commit = 18
> [junit4:junit4]   2> 7458 T3020 C195 oass.SolrIndexSearcher.<init> Opening Searcher@11ed938 main
> [junit4:junit4]   2> 7458 T3020 C195 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 7459 T3020 C195 oasu.DirectUpdateHandler2.commit end_commit_flush
> [junit4:junit4]   2> 7459 T3019 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@11ed938 main{StandardDirectoryReader(segments_h:61:nrt _k(4.0.0.2):C1 _l(4.0.0.2):C1 _m(4.0.0.2):C1)}
> [junit4:junit4]   2> 7460 T3020 C195 oasu.UpdateLog$LogReplayer.run WARNING Log replay finished. recoveryInfo=RecoveryInfo{adds=3 deletes=0 deleteByQuery=0 errors=0 positionOfStart=0}
> [junit4:junit4]   2> 7460 T3005 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=21777133
> [junit4:junit4]   2> 7461 T3005 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@1ddea28
> [junit4:junit4]   2> 7461 T3005 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
> [junit4:junit4]   2> 7461 T3005 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> 7462 T3005 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
> [junit4:junit4]   2> 7481 T3005 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
> [junit4:junit4]   2> 7481 T3005 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> 7483 T3005 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> 7484 T3005 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> 7543 T3005 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
> [junit4:junit4]   2> 7604 T3005 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
> [junit4:junit4]   2> 7605 T3005 oass.IndexSchema.readSchema Reading Solr Schema
> [junit4:junit4]   2> 7610 T3005 oass.IndexSchema.readSchema Schema name=test
> [junit4:junit4]   2> 8146 T3005 oass.IndexSchema.readSchema default search field in schema is text
> [junit4:junit4]   2> 8151 T3005 oass.IndexSchema.readSchema unique key field: id
> [junit4:junit4]   2> 8161 T3005 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
> [junit4:junit4]   2> 8162 T3005 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> 8163 T3005 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> 8179 T3005 oasc.CoreContainer.<init> New CoreContainer 25889715
> [junit4:junit4]   2> 8180 T3005 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-1347551817852\
> [junit4:junit4]   2> 8180 T3005 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
> [junit4:junit4]   2> 8182 T3005 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-1347551817852\index/
> [junit4:junit4]   2> 8185 T3005 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
> [junit4:junit4]   2> 8185 T3005 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
> [junit4:junit4]   2> 8185 T3005 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
> [junit4:junit4]   2> 8186 T3005 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
> [junit4:junit4]   2> 8190 T3005 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
> [junit4:junit4]   2> 8196 T3005 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347551817852\index forceNew:false
> [junit4:junit4]   2> 8204 T3005 oass.SolrIndexSearcher.<init> Opening Searcher@186c035 main
> [junit4:junit4]   2> 8205 T3005 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 8211 T3005 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-1347551817852\tlog\tlog.0000000000000000019: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.GeneratedConstructorAccessor129.newInstance(Unknown Source)
> [junit4:junit4]   2>            at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
> [junit4:junit4]   2>            at java.lang.reflect.Constructor.newInstance(Constructor.java:525)
> [junit4:junit4]   2>            at org.apache.solr.core.SolrCore.createInstance(SolrCore.java:476)
> [junit4:junit4]   2>            at org.apache.solr.core.SolrCore.createUpdateHandler(SolrCore.java:544)
> [junit4:junit4]   2>            at org.apache.solr.core.SolrCore.<init>(SolrCore.java:707)
> [junit4:junit4]   2>            at org.apache.solr.core.SolrCore.<init>(SolrCore.java:567)
> [junit4:junit4]   2>            at org.apache.solr.util.TestHarness$Initializer.initialize(TestHarness.java:190)
> [junit4:junit4]   2>            at org.apache.solr.util.TestHarness.<init>(TestHarness.java:114)
> [junit4:junit4]   2>            at org.apache.solr.util.TestHarness.<init>(TestHarness.java:109)
> [junit4:junit4]   2>            at org.apache.solr.util.TestHarness.<init>(TestHarness.java:99)
> [junit4:junit4]   2>            at org.apache.solr.SolrTestCaseJ4.createCore(SolrTestCaseJ4.java:362)
> [junit4:junit4]   2>            at org.apache.solr.search.TestRecovery.testBufferingFlags(TestRecovery.java:538)
> [junit4:junit4]   2>            at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> [junit4:junit4]   2>            at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
> [junit4:junit4]   2>            at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> [junit4:junit4]   2>            at java.lang.reflect.Method.invoke(Method.java:601)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
> [junit4:junit4]   2>            at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
> [junit4:junit4]   2>            at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
> [junit4:junit4]   2>            at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
> [junit4:junit4]   2>            at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
> [junit4:junit4]   2>            at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
> [junit4:junit4]   2>            at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
> [junit4:junit4]   2>            at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
> [junit4:junit4]   2>            at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
> [junit4:junit4]   2>            at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
> [junit4:junit4]   2>            at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
> [junit4:junit4]   2>            at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
> [junit4:junit4]   2>            at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
> [junit4:junit4]   2>            at java.lang.Thread.run(Thread.java:722)
> [junit4:junit4]   2>
> [junit4:junit4]   2> 8215 T3005 oasu.CommitTracker.<init> Hard AutoCommit: disabled
> [junit4:junit4]   2> 8216 T3005 oasu.CommitTracker.<init> Soft AutoCommit: disabled
> [junit4:junit4]   2> 8217 T3005 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
> [junit4:junit4]   2> 8217 T3005 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
> [junit4:junit4]   2> 8218 T3005 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
> [junit4:junit4]   2> 8218 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
> [junit4:junit4]   2> 8218 T3005 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
> [junit4:junit4]   2> 8218 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
> [junit4:junit4]   2> 8219 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
> [junit4:junit4]   2> 8219 T3005 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
> [junit4:junit4]   2> 8220 T3005 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
> [junit4:junit4]   2> 8220 T3005 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
> [junit4:junit4]   2> 8234 T3021 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@186c035 main{StandardDirectoryReader(segments_i:61 _k(4.0.0.2):C1 _l(4.0.0.2):C1 _m(4.0.0.2):C1)}
> [junit4:junit4]   2> 8234 T3005 oasc.CoreContainer.register registering core: collection1
> [junit4:junit4]   2> ASYNC  NEW_CORE C196 name=collection1 org.apache.solr.core.SolrCore@b1e1d
> [junit4:junit4]   2> 8264 T3005 C196 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-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@5ae75b),segFN=segments_i,generation=18,filenames=[_l.fnm, _k.fdt, _l.si, _m.frq, _k.fdx, _l.frq, segments_i, _k.fnm, _m.si, _m.tip, _m.fdt, _k.tim, _l.tip, _l.fdx, _m.tim, _m.fdx, _l.tim, _l.fdt, _k.tip, _k.frq, _m.fnm, _k.si]
> [junit4:junit4]   2> 8265 T3005 C196 oasc.SolrDeletionPolicy.updateCommits newest commit = 18
> [junit4:junit4]   2> 8265 T3005 C196 oasu.DirectUpdateHandler2.addDoc Reordered DBQs detected.  Update=add{flags=0,_version_=114,id=Q4} 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> 8281 T3005 C196 oass.SolrIndexSearcher.<init> Opening Searcher@131fb9f realtime
> [junit4:junit4]   2> 8282 T3005 C196 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 8283 T3005 C196 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=32
> [junit4:junit4]   2> 8290 T3005 C196 oasu.DirectUpdateHandler2.addDoc Reordered DBQs detected.  Update=add{flags=0,_version_=115,id=Q5} 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> 8300 T3005 C196 oass.SolrIndexSearcher.<init> Opening Searcher@c6bebb realtime
> [junit4:junit4]   2> 8301 T3005 C196 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 8301 T3005 C196 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=11
> [junit4:junit4]   2> 8309 T3005 C196 oasu.DirectUpdateHandler2.addDoc Reordered DBQs detected.  Update=add{flags=0,_version_=116,id=Q6} 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> 8319 T3005 C196 oass.SolrIndexSearcher.<init> Opening Searcher@1500ccc realtime
> [junit4:junit4]   2> 8320 T3005 C196 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 8321 T3005 C196 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=12
> [junit4:junit4]   2> 8326 T3005 C196 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
> [junit4:junit4]   2> 8334 T3005 C196 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-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@5ae75b),segFN=segments_i,generation=18,filenames=[_l.fnm, _k.fdt, _l.si, _m.frq, _k.fdx, _l.frq, segments_i, _k.fnm, _m.si, _m.tip, _m.fdt, _k.tim, _l.tip, _l.fdx, _m.tim, _m.fdx, _l.tim, _l.fdt, _k.tip, _k.frq, _m.fnm, _k.si]
> [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-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@5ae75b),segFN=segments_j,generation=19,filenames=[_p.fdx, _l.fnm, _p.fnm, _l.si, _k.fdt, _p.fdt, _m.frq, _k.fdx, _l.frq, _k.fnm, _p.frq, _m.si, _n.tip, _n.si, _m.tip, _l.tip, _k.tim, _n.tim, _n.frq, _m.tim, _l.tim, _o.si, _k.tip, _p.tim, _k.si, _m.fnm, _p.tip, _o.frq, _n.fnm, _o.fdt, _o.fnm, _o.fdx, _n.fdt, _p.si, _n.fdx, segments_j, _o.tip, _o.tim, _m.fdt, _l.fdx, _m.fdx, _l.fdt, _k.frq]
> [junit4:junit4]   2> 8335 T3005 C196 oasc.SolrDeletionPolicy.updateCommits newest commit = 19
> [junit4:junit4]   2> 8339 T3005 C196 oass.SolrIndexSearcher.<init> Opening Searcher@1189a92 main
> [junit4:junit4]   2> 8339 T3005 C196 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 8340 T3021 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1189a92 main{StandardDirectoryReader(segments_i:67:nrt _k(4.0.0.2):C1 _l(4.0.0.2):C1 _m(4.0.0.2):C1 _n(4.0.0.2):C1 _o(4.0.0.2):C1 _p(4.0.0.2):C1)}
> [junit4:junit4]   2> 8343 T3005 C196 oasu.DirectUpdateHandler2.commit end_commit_flush
> [junit4:junit4]   2> 8343 T3005 C196 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 17
> [junit4:junit4]   2> 8358 T3005 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=25889715
> [junit4:junit4]   2> 8359 T3005 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@b1e1d
> [junit4:junit4]   2> 8359 T3005 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
> [junit4:junit4]   2> 8360 T3005 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> 8360 T3005 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
> [junit4:junit4]   2> 8398 T3005 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
> [junit4:junit4]   2> 8398 T3005 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> 8402 T3005 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> 8404 T3005 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> 8484 T3005 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
> [junit4:junit4]   2> 8553 T3005 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
> [junit4:junit4]   2> 8554 T3005 oass.IndexSchema.readSchema Reading Solr Schema
> [junit4:junit4]   2> 8558 T3005 oass.IndexSchema.readSchema Schema name=test
> [junit4:junit4]   2> 9197 T3005 oass.IndexSchema.readSchema default search field in schema is text
> [junit4:junit4]   2> 9202 T3005 oass.IndexSchema.readSchema unique key field: id
> [junit4:junit4]   2> 9214 T3005 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
> [junit4:junit4]   2> 9215 T3005 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> 9215 T3005 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> 9229 T3005 oasc.CoreContainer.<init> New CoreContainer 3148291
> [junit4:junit4]   2> 9229 T3005 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-1347551817852\
> [junit4:junit4]   2> 9229 T3005 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
> [junit4:junit4]   2> 9232 T3005 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-1347551817852\index/
> [junit4:junit4]   2> 9235 T3005 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
> [junit4:junit4]   2> 9235 T3005 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
> [junit4:junit4]   2> 9235 T3005 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
> [junit4:junit4]   2> 9236 T3005 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
> [junit4:junit4]   2> 9239 T3005 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
> [junit4:junit4]   2> 9245 T3005 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347551817852\index forceNew:false
> [junit4:junit4]   2> 9259 T3005 oass.SolrIndexSearcher.<init> Opening Searcher@1c034c3 main
> [junit4:junit4]   2> 9259 T3005 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 9264 T3005 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-1347551817852\tlog\tlog.0000000000000000019: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.GeneratedConstructorAccessor129.newInstance(Unknown Source)
> [junit4:junit4]   2>            at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
> [junit4:junit4]   2>            at java.lang.reflect.Constructor.newInstance(Constructor.java:525)
> [junit4:junit4]   2>            at org.apache.solr.core.SolrCore.createInstance(SolrCore.java:476)
> [junit4:junit4]   2>            at org.apache.solr.core.SolrCore.createUpdateHandler(SolrCore.java:544)
> [junit4:junit4]   2>            at org.apache.solr.core.SolrCore.<init>(SolrCore.java:707)
> [junit4:junit4]   2>            at org.apache.solr.core.SolrCore.<init>(SolrCore.java:567)
> [junit4:junit4]   2>            at org.apache.solr.util.TestHarness$Initializer.initialize(TestHarness.java:190)
> [junit4:junit4]   2>            at org.apache.solr.util.TestHarness.<init>(TestHarness.java:114)
> [junit4:junit4]   2>            at org.apache.solr.util.TestHarness.<init>(TestHarness.java:109)
> [junit4:junit4]   2>            at org.apache.solr.util.TestHarness.<init>(TestHarness.java:99)
> [junit4:junit4]   2>            at org.apache.solr.SolrTestCaseJ4.createCore(SolrTestCaseJ4.java:362)
> [junit4:junit4]   2>            at org.apache.solr.search.TestRecovery.testBufferingFlags(TestRecovery.java:554)
> [junit4:junit4]   2>            at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> [junit4:junit4]   2>            at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
> [junit4:junit4]   2>            at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> [junit4:junit4]   2>            at java.lang.reflect.Method.invoke(Method.java:601)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
> [junit4:junit4]   2>            at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
> [junit4:junit4]   2>            at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
> [junit4:junit4]   2>            at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
> [junit4:junit4]   2>            at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
> [junit4:junit4]   2>            at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
> [junit4:junit4]   2>            at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
> [junit4:junit4]   2>            at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
> [junit4:junit4]   2>            at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
> [junit4:junit4]   2>            at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
> [junit4:junit4]   2>            at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
> [junit4:junit4]   2>            at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
> [junit4:junit4]   2>            at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
> [junit4:junit4]   2>            at java.lang.Thread.run(Thread.java:722)
> [junit4:junit4]   2>
> [junit4:junit4]   2> 9268 T3005 oasu.CommitTracker.<init> Hard AutoCommit: disabled
> [junit4:junit4]   2> 9268 T3005 oasu.CommitTracker.<init> Soft AutoCommit: disabled
> [junit4:junit4]   2> 9269 T3005 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
> [junit4:junit4]   2> 9269 T3005 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
> [junit4:junit4]   2> 9270 T3005 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
> [junit4:junit4]   2> 9270 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
> [junit4:junit4]   2> 9271 T3005 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
> [junit4:junit4]   2> 9271 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
> [junit4:junit4]   2> 9272 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
> [junit4:junit4]   2> 9272 T3005 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
> [junit4:junit4]   2> 9273 T3005 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
> [junit4:junit4]   2> 9273 T3005 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
> [junit4:junit4]   2> 9286 T3022 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1c034c3 main{StandardDirectoryReader(segments_j:67 _k(4.0.0.2):C1 _l(4.0.0.2):C1 _m(4.0.0.2):C1 _n(4.0.0.2):C1 _o(4.0.0.2):C1 _p(4.0.0.2):C1)}
> [junit4:junit4]   2> 9286 T3005 oasc.CoreContainer.register registering core: collection1
> [junit4:junit4]   2> 9290 T3005 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
> [junit4:junit4]   2> ASYNC  NEW_CORE C197 name=collection1 org.apache.solr.core.SolrCore@1db6302
> [junit4:junit4]   2> 9319 T3005 C197 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-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1c8f345),segFN=segments_j,generation=19,filenames=[_p.fdx, _l.fnm, _p.fnm, _l.si, _k.fdt, _p.fdt, _m.frq, _k.fdx, _l.frq, _k.fnm, _p.frq, _m.si, _n.si, _n.tip, _m.tip, _l.tip, _k.tim, _n.tim, _n.frq, _m.tim, _l.tim, _o.si, _k.tip, _p.tim, _k.si, _m.fnm, _p.tip, _o.frq, _n.fnm, _o.fdt, _o.fnm, _o.fdx, _n.fdt, _p.si, _n.fdx, segments_j, _o.tip, _o.tim, _m.fdt, _l.fdx, _m.fdx, _l.fdt, _k.frq]
> [junit4:junit4]   2> 9320 T3005 C197 oasc.SolrDeletionPolicy.updateCommits newest commit = 19
> [junit4:junit4]   2> 9321 T3005 C197 oasu.DirectUpdateHandler2.addDoc Reordered DBQs detected.  Update=add{flags=0,_version_=117,id=Q7} DBQs=[DBQ{version=3000,q=id:B2 OR id:B8}]
> [junit4:junit4]   2> 9341 T3005 C197 oass.SolrIndexSearcher.<init> Opening Searcher@dda1d4 realtime
> [junit4:junit4]   2> 9341 T3005 C197 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 9341 T3005 C197 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=41
> [junit4:junit4]   2> 9342 T3005 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=3148291
> [junit4:junit4]   2> 9342 T3005 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@1db6302
> [junit4:junit4]   2> 9343 T3005 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
> [junit4:junit4]   2> 9343 T3005 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=1,adds=1,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=1,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
> [junit4:junit4]   2> 9344 T3005 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
> [junit4:junit4]   2> 9386 T3005 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
> [junit4:junit4]   2> 9387 T3005 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> 9389 T3005 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> 9392 T3005 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> 9469 T3005 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
> [junit4:junit4]   2> 9524 T3005 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
> [junit4:junit4]   2> 9524 T3005 oass.IndexSchema.readSchema Reading Solr Schema
> [junit4:junit4]   2> 9529 T3005 oass.IndexSchema.readSchema Schema name=test
> [junit4:junit4]   2> 10073 T3005 oass.IndexSchema.readSchema default search field in schema is text
> [junit4:junit4]   2> 10078 T3005 oass.IndexSchema.readSchema unique key field: id
> [junit4:junit4]   2> 10086 T3005 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
> [junit4:junit4]   2> 10086 T3005 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> 10086 T3005 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> 10099 T3005 oasc.CoreContainer.<init> New CoreContainer 5953808
> [junit4:junit4]   2> 10099 T3005 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-1347551817852\
> [junit4:junit4]   2> 10099 T3005 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
> [junit4:junit4]   2> 10099 T3005 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-1347551817852\index/
> [junit4:junit4]   2> 10102 T3005 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
> [junit4:junit4]   2> 10103 T3005 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
> [junit4:junit4]   2> 10103 T3005 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
> [junit4:junit4]   2> 10103 T3005 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
> [junit4:junit4]   2> 10107 T3005 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
> [junit4:junit4]   2> 10112 T3005 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347551817852\index forceNew:false
> [junit4:junit4]   2> 10125 T3005 oass.SolrIndexSearcher.<init> Opening Searcher@1b14e6c main
> [junit4:junit4]   2> 10126 T3005 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 10132 T3005 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-1347551817852\tlog\tlog.0000000000000000019: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.GeneratedConstructorAccessor129.newInstance(Unknown Source)
> [junit4:junit4]   2>            at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
> [junit4:junit4]   2>            at java.lang.reflect.Constructor.newInstance(Constructor.java:525)
> [junit4:junit4]   2>            at org.apache.solr.core.SolrCore.createInstance(SolrCore.java:476)
> [junit4:junit4]   2>            at org.apache.solr.core.SolrCore.createUpdateHandler(SolrCore.java:544)
> [junit4:junit4]   2>            at org.apache.solr.core.SolrCore.<init>(SolrCore.java:707)
> [junit4:junit4]   2>            at org.apache.solr.core.SolrCore.<init>(SolrCore.java:567)
> [junit4:junit4]   2>            at org.apache.solr.util.TestHarness$Initializer.initialize(TestHarness.java:190)
> [junit4:junit4]   2>            at org.apache.solr.util.TestHarness.<init>(TestHarness.java:114)
> [junit4:junit4]   2>            at org.apache.solr.util.TestHarness.<init>(TestHarness.java:109)
> [junit4:junit4]   2>            at org.apache.solr.util.TestHarness.<init>(TestHarness.java:99)
> [junit4:junit4]   2>            at org.apache.solr.SolrTestCaseJ4.createCore(SolrTestCaseJ4.java:362)
> [junit4:junit4]   2>            at org.apache.solr.search.TestRecovery.testBufferingFlags(TestRecovery.java:569)
> [junit4:junit4]   2>            at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> [junit4:junit4]   2>            at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
> [junit4:junit4]   2>            at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> [junit4:junit4]   2>            at java.lang.reflect.Method.invoke(Method.java:601)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
> [junit4:junit4]   2>            at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
> [junit4:junit4]   2>            at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
> [junit4:junit4]   2>            at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
> [junit4:junit4]   2>            at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
> [junit4:junit4]   2>            at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
> [junit4:junit4]   2>            at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
> [junit4:junit4]   2>            at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
> [junit4:junit4]   2>            at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
> [junit4:junit4]   2>            at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
> [junit4:junit4]   2>            at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
> [junit4:junit4]   2>            at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
> [junit4:junit4]   2>            at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
> [junit4:junit4]   2>            at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
> [junit4:junit4]   2>            at java.lang.Thread.run(Thread.java:722)
> [junit4:junit4]   2>
> [junit4:junit4]   2> 10136 T3005 oasu.CommitTracker.<init> Hard AutoCommit: disabled
> [junit4:junit4]   2> 10136 T3005 oasu.CommitTracker.<init> Soft AutoCommit: disabled
> [junit4:junit4]   2> 10137 T3005 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
> [junit4:junit4]   2> 10137 T3005 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
> [junit4:junit4]   2> 10138 T3005 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
> [junit4:junit4]   2> 10139 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
> [junit4:junit4]   2> 10139 T3005 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
> [junit4:junit4]   2> 10139 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
> [junit4:junit4]   2> 10139 T3005 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
> [junit4:junit4]   2> 10139 T3005 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
> [junit4:junit4]   2> 10140 T3005 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
> [junit4:junit4]   2> 10140 T3005 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
> [junit4:junit4]   2> 10153 T3023 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1b14e6c main{StandardDirectoryReader(segments_j:67 _k(4.0.0.2):C1 _l(4.0.0.2):C1 _m(4.0.0.2):C1 _n(4.0.0.2):C1 _o(4.0.0.2):C1 _p(4.0.0.2):C1)}
> [junit4:junit4]   2> 10153 T3005 oasc.CoreContainer.register registering core: collection1
> [junit4:junit4]   2> ASYNC  NEW_CORE C198 name=collection1 org.apache.solr.core.SolrCore@1422af5
> [junit4:junit4]   2> 10154 T3024 C198 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-1347551817852\tlog\tlog.0000000000000000023 refcount=2} active=false starting pos=0
> [junit4:junit4]   2> 10172 T3024 C198 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-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@a8c056),segFN=segments_j,generation=19,filenames=[_p.fdx, _l.fnm, _p.fnm, _l.si, _k.fdt, _p.fdt, _m.frq, _k.fdx, _l.frq, _k.fnm, _p.frq, _m.si, _n.si, _n.tip, _m.tip, _l.tip, _k.tim, _n.tim, _n.frq, _m.tim, _l.tim, _o.si, _k.tip, _p.tim, _k.si, _m.fnm, _p.tip, _o.frq, _n.fnm, _o.fdt, _o.fnm, _o.fdx, _n.fdt, _p.si, _n.fdx, segments_j, _o.tip, _o.tim, _m.fdt, _l.fdx, _m.fdx, _l.fdt, _k.frq]
> [junit4:junit4]   2> 10173 T3024 C198 oasc.SolrDeletionPolicy.updateCommits newest commit = 19
> [junit4:junit4]   2> 10173 T3024 C198 oasu.DirectUpdateHandler2.addDoc Reordered DBQs detected.  Update=add{flags=10,_version_=117,id=Q7} DBQs=[DBQ{version=3000,q=id:B2 OR id:B8}]
> [junit4:junit4]   2> 10187 T3024 C198 oass.SolrIndexSearcher.<init> Opening Searcher@9730cc realtime
> [junit4:junit4]   2> 10188 T3024 C198 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 10188 T3024 C198 oasu.DirectUpdateHandler2.commit start commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
> [junit4:junit4]   2> 10193 T3024 C198 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-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@a8c056),segFN=segments_j,generation=19,filenames=[_p.fdx, _l.fnm, _p.fnm, _l.si, _k.fdt, _p.fdt, _m.frq, _k.fdx, _l.frq, _k.fnm, _p.frq, _m.si, _n.si, _n.tip, _m.tip, _l.tip, _k.tim, _n.tim, _n.frq, _m.tim, _l.tim, _o.si, _k.tip, _p.tim, _k.si, _m.fnm, _p.tip, _o.frq, _n.fnm, _o.fdt, _o.fnm, _o.fdx, _n.fdt, _p.si, _n.fdx, segments_j, _o.tip, _o.tim, _m.fdt, _l.fdx, _m.fdx, _l.fdt, _k.frq]
> [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-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@a8c056),segFN=segments_k,generation=20,filenames=[_p.fdx, _l.si, _m.frq, _p.fdt, _q.fdx, _k.fnm, _q.fdt, _m.si, _q.si, _k.tim, _n.frq, _k.tip, _o.si, _m.fnm, _k.si, _n.fnm, _o.frq, _o.fnm, _q.frq, segments_k, _o.tip, _o.tim, _l.fdx, _l.fdt, _q.fnm, _l.fnm, _p.fnm, _k.fdt, _k.fdx, _l.frq, _p.frq, _n.tip, _n.si, _m.tip, _l.tip, _n.tim, _m.tim, _l.tim, _p.tim, _p.tip, _o.fdt, _o.fdx, _q.tip, _n.fdt, _q.tim, _n.fdx, _p.si, _m.fdt, _m.fdx, _k.frq]
> [junit4:junit4]   2> 10193 T3024 C198 oasc.SolrDeletionPolicy.updateCommits newest commit = 20
> [junit4:junit4]   2> 10196 T3024 C198 oass.SolrIndexSearcher.<init> Opening Searcher@1f561ca main
> [junit4:junit4]   2> 10196 T3024 C198 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 10197 T3024 C198 oasu.DirectUpdateHandler2.commit end_commit_flush
> [junit4:junit4]   2> 10197 T3023 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1f561ca main{StandardDirectoryReader(segments_j:69:nrt _k(4.0.0.2):C1 _l(4.0.0.2):C1 _m(4.0.0.2):C1 _n(4.0.0.2):C1 _o(4.0.0.2):C1 _p(4.0.0.2):C1 _q(4.0.0.2):C1)}
> [junit4:junit4]   2> 10198 T3024 C198 oasu.UpdateLog$LogReplayer.run WARNING Log replay finished. recoveryInfo=RecoveryInfo{adds=1 deletes=0 deleteByQuery=0 errors=0 positionOfStart=0}
> [junit4:junit4]   2> 10198 T3005 oas.SolrTestCaseJ4.tearDown ###Ending testBufferingFlags
> [junit4:junit4]   2> 10202 T3005 oas.SolrTestCaseJ4.setUp ###Starting testCleanShutdown
> [junit4:junit4]   2> 10214 T3005 C198 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
> [junit4:junit4]   2> 10223 T3005 C198 oass.SolrIndexSearcher.<init> Opening Searcher@10beb9f realtime
> [junit4:junit4]   2> 10224 T3005 C198 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 10224 T3005 C198 REQ [collection1] webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true} status=0 QTime=10
> [junit4:junit4]   2> 10229 T3005 C198 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
> [junit4:junit4]   2> 10231 T3005 C198 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-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@a8c056),segFN=segments_k,generation=20,filenames=[_p.fdx, _l.si, _m.frq, _p.fdt, _q.fdx, _k.fnm, _q.fdt, _m.si, _q.si, _k.tim, _n.frq, _k.tip, _o.si, _m.fnm, _k.si, _n.fnm, _o.frq, _o.fnm, _q.frq, segments_k, _o.tip, _o.tim, _l.fdx, _l.fdt, _q.fnm, _l.fnm, _p.fnm, _k.fdt, _k.fdx, _l.frq, _p.frq, _n.tip, _n.si, _m.tip, _l.tip, _n.tim, _m.tim, _l.tim, _p.tim, _p.tip, _o.fdt, _o.fdx, _q.tip, _n.fdt, _q.tim, _n.fdx, _p.si, _m.fdt, _m.fdx, _k.frq]
> [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-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@a8c056),segFN=segments_l,generation=21,filenames=[segments_l]
> [junit4:junit4]   2> 10232 T3005 C198 oasc.SolrDeletionPolicy.updateCommits newest commit = 21
> [junit4:junit4]   2> 10258 T3005 C198 oass.SolrIndexSearcher.<init> Opening Searcher@16aff74 main
> [junit4:junit4]   2> 10258 T3005 C198 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
> [junit4:junit4]   2> 10259 T3023 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@16aff74 main{StandardDirectoryReader(segments_k:70:nrt)}
> [junit4:junit4]   2> 10258 T3005 C198 oasu.DirectUpdateHandler2.commit end_commit_flush
> [junit4:junit4]   2> 10281 T3005 C198 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 53
> [junit4:junit4]   2> 10313 T3005 C198 UPDATE [collection1] webapp=null path=null params={} {add=[E1 (1413010505763651584)]} 0 5
> [junit4:junit4]   2> 10319 T3005 C198 UPDATE [collection1] webapp=null path=null params={} {add=[E2 (1413010505775185920)]} 0 0
> [junit4:junit4]   2> 10323 T3005 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=5953808
> [junit4:junit4]   2> 10323 T3005 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@1422af5
> [junit4:junit4]   2> 10324 T3005 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
> [junit4:junit4]   2> 10325 T3005 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=2,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=2,adds=2,deletesById=0,deletesByQuery=0,errors=1,cumulative_adds=3,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=1}
> [junit4:junit4]   2> 10325 T3005 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
> [junit4:junit4]   2> 10338 T3005 C198 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-1347551817852\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@a8c056),segFN=segments_l,generation=21,filenames=[segments_l]
> [junit4:junit4]   2>            commit{dir=Moc
>
> [...truncated too long message...]
>
> .lang.NullPointerException
> [junit4:junit4]    >    at __randomizedtesting.SeedInfo.seed([DE37D1BA010286D8:FACBFAE8D44CF3C4]: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.testDropBuffered(TestRecovery.java:378)
> [junit4:junit4]    >    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> [junit4:junit4]    >    at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
> [junit4:junit4]    >    at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> [junit4:junit4]    >    at java.lang.reflect.Method.invoke(Method.java:601)
> [junit4:junit4]    >    at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
> [junit4:junit4]    >    at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
> [junit4:junit4]    >    at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
> [junit4:junit4]    >    at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
> [junit4:junit4]    >    at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
> [junit4:junit4]    >    at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
> [junit4:junit4]    >    at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
> [junit4:junit4]    >    at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
> [junit4:junit4]    >    at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
> [junit4:junit4]    >    at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
> [junit4:junit4]    >    at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
> [junit4:junit4]    >    at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
> [junit4:junit4]    >    at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
> [junit4:junit4]    >    at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
> [junit4:junit4]    >    at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
> [junit4:junit4]    >    at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
> [junit4:junit4]    >    at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
> [junit4:junit4]    >    at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
> [junit4:junit4]    >    at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
> [junit4:junit4]    >    at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
> [junit4:junit4]    >    at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
> [junit4:junit4]    >    at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
> [junit4:junit4]    >    at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
> [junit4:junit4]    >    at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
> [junit4:junit4]    >    at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
> [junit4:junit4]    >    at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
> [junit4:junit4]    >    at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
> [junit4:junit4]    >    at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
> [junit4:junit4]    >    at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
> [junit4:junit4]    >    at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
> [junit4:junit4]    >    at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
> [junit4:junit4]    >    at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
> [junit4:junit4]    >    at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
> [junit4:junit4]    >    at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
> [junit4:junit4]    >    at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
> [junit4:junit4]    >    at java.lang.Thread.run(Thread.java:722)
> [junit4:junit4]   2> 11235 T3005 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-1347551817852\tlog\tlog.0000000000000000019 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-1347551817852\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-1347551817852 FAILED !!!!!
> [junit4:junit4]   2> NOTE: test params are: codec=Appending, sim=DefaultSimilarity, locale=da, timezone=Africa/Ceuta
> [junit4:junit4]   2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.7.0_07 (32-bit)/cpus=2,threads=1,free=124160688,total=263061504
> [junit4:junit4]   2> NOTE: All tests run in this JVM: [TestDocumentBuilder, TestPluginEnable, AnalysisAfterCoreReloadTest, TestSurroundQueryParser, PrimitiveFieldTypeTest, TestDFRSimilarityFactory, FastVectorHighlighterTest, DOMUtilTest, TestCollationKeyRangeQueries, SearchHandlerTest, TestWordDelimiterFilterFactory, FieldMutatingUpdateProcessorTest, StatsComponentTest, JsonLoaderTest, SolrPluginUtilsTest, TestIndexSearcher, CoreAdminHandlerTest, TestSolrXMLSerializer, MoreLikeThisHandlerTest, MBeansHandlerTest, DirectSolrConnectionTest, TestSystemIdResolver, SolrCmdDistributorTest, TestSort, TestConfig, SampleTest, FullSolrCloudDistribCmdsTest, TestFastWriter, BinaryUpdateRequestHandlerTest, TestReplicationHandler, TestNumberUtils, NotRequiredUniqueKeyTest, TestFastLRUCache, ClusterStateTest, TestLuceneMatchVersion, DefaultValueUpdateProcessorTest, PrimUtilsTest, TestSearchPerf, EchoParamsTest, TestTrie, TestBadConfig, TestLRUCache, TestSolrDeletionPolicy2, TestCharFilters, DocumentAnalysisRequestHandlerTest, TestExtendedDismaxParser, DisMaxRequestHandlerTest, FileBasedSpellCheckerTest, TestCodecSupport, PingRequestHandlerTest, URLClassifyProcessorTest, TestPHPSerializedResponseWriter, DebugComponentTest, TestPerFieldSimilarity, DirectSolrSpellCheckerTest, BasicDistributedZk2Test, QueryElevationComponentTest, ConvertedLegacyTest, WordBreakSolrSpellCheckerTest, SolrCoreCheckLockOnStartupTest, BasicFunctionalityTest, TestFoldingMultitermQuery, TestJmxMonitoredMap, TestCSVLoader, TestFiltering, TestStressReorder, TestMultiCoreConfBootstrap, PreAnalyzedFieldTest, QueryParsingTest, UUIDFieldTest, LoggingHandlerTest, CSVRequestHandlerTest, ReturnFieldsTest, TestIBSimilarityFactory, TestIndexingPerformance, TestCoreContainer, SolrCoreTest, TermsComponentTest, TestLFUCache, ZkNodePropsTest, DistributedSpellCheckComponentTest, DistributedTermsComponentTest, UpdateParamsTest, SuggesterTSTTest, StandardRequestHandlerTest, BasicDistributedZkTest, TestOmitPositions, SimplePostToolTest, TestRandomFaceting, TestValueSourceCache, TestMergePolicyConfig, SOLR749Test, TestQuerySenderListener, IndexSchemaTest, CoreContainerCoreInitFailuresTest, TestPropInject, TimeZoneUtilsTest, NumericFieldsTest, StatelessScriptUpdateProcessorFactoryTest, TestUpdate, TestDocSet, IndexBasedSpellCheckerTest, NoCacheHeaderTest, OverseerTest, SimpleFacetsTest, CacheHeaderTest, LeaderElectionTest, AlternateDirectoryTest, HighlighterTest, RecoveryZkTest, SpellCheckComponentTest, LeaderElectionIntegrationTest, TestRealTimeGet, ZkControllerTest, TestDistributedGrouping, TestDistributedSearch, SpellCheckCollatorTest, SoftAutoCommitTest, AutoCommitTest, BasicZkTest, ZkSolrClientTest, TestFaceting, TestHashPartitioner, TestRecovery]
> [junit4:junit4] Completed in 11.27s, 10 tests, 1 failure, 1 error <<< FAILURES!
>
> [...truncated 309 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, 11 ignored (6 assumptions)
>
> Total time: 39 minutes 26 seconds
> Build step 'Invoke Ant' marked build as failure
> Recording test results
> Description set: Java: 32bit/jdk1.7.0_07 -client -XX:+UseParallelGC
> Email was triggered for: Failure
> Sending email for trigger: Failure
>

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


Mime
View raw message