lucene-dev mailing list archives

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

3 tests failed.
FAILED:  org.apache.solr.search.TestRecovery.testCorruptLog

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

Stack Trace:
java.lang.AssertionError: expected:<0> but was:<1>
	at __randomizedtesting.SeedInfo.seed([62AC97EF719F0566:9071233C8BA754BA]: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.deleteLogs(TestRecovery.java:1046)
	at org.apache.solr.search.TestRecovery.testCorruptLog(TestRecovery.java:920)
	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)


FAILED:  org.apache.solr.search.TestRecovery.testBufferingFlags

Error Message:


Stack Trace:
java.lang.NullPointerException
	at __randomizedtesting.SeedInfo.seed([62AC97EF719F0566:E356F6A2C6D76B9E]: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.testBufferingFlags(TestRecovery.java:503)
	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)


FAILED:  org.apache.solr.search.TestRecovery.testRemoveOldLogs

Error Message:
java.lang.NullPointerException

Stack Trace:
java.lang.RuntimeException: java.lang.NullPointerException
	at __randomizedtesting.SeedInfo.seed([62AC97EF719F0566:43305644B9A58C9E]:0)
	at org.apache.solr.search.TestRecovery.clearIndex(TestRecovery.java:80)
	at org.apache.solr.search.TestRecovery.testRemoveOldLogs(TestRecovery.java:709)
	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)
Caused by: java.lang.NullPointerException
	at org.apache.solr.util.TestHarness.getCore(TestHarness.java:212)
	at org.apache.solr.SolrTestCaseJ4.updateJ(SolrTestCaseJ4.java:797)
	at org.apache.solr.SolrTestCaseJ4.deleteByQueryAndGetVersion(SolrTestCaseJ4.java:947)
	at org.apache.solr.search.TestRecovery.clearIndex(TestRecovery.java:78)
	... 41 more




Build Log:
[...truncated 7546 lines...]
[junit4:junit4] Suite: org.apache.solr.search.TestRecovery
[junit4:junit4]   2> 2 T68 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-1347565953668
[junit4:junit4]   2> 4 T68 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> 9 T68 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> 10 T68 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> 83 T68 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 145 T68 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
[junit4:junit4]   2> 145 T68 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 152 T68 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 745 T68 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 749 T68 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 769 T68 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 769 T68 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> 769 T68 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> 819 T68 oasc.CoreContainer.<init> New CoreContainer 31097764
[junit4:junit4]   2> 819 T68 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-1347565953668\
[junit4:junit4]   2> 819 T68 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 823 T68 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-1347565953668\index/
[junit4:junit4]   2> 823 T68 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory 'C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347565953668\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 838 T68 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347565953668\index forceNew:false
[junit4:junit4]   2> 843 T68 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1c2f9b7),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 843 T68 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 844 T68 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 845 T68 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 845 T68 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 845 T68 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 849 T68 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 871 T68 oass.SolrIndexSearcher.<init> Opening Searcher@b3c509 main
[junit4:junit4]   2> 871 T68 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 900 T68 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 901 T68 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 901 T68 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 901 T68 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 902 T68 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 902 T68 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 902 T68 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 903 T68 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 903 T68 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 903 T68 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 903 T68 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 904 T68 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 917 T69 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@b3c509 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 917 T68 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 921 T68 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4:junit4]   2> 924 T68 oas.SolrTestCaseJ4.setUp ###Starting testTruncatedLog
[junit4:junit4]   2> ASYNC  NEW_CORE C13 name=collection1 org.apache.solr.core.SolrCore@15ae104
[junit4:junit4]   2> 935 T68 C13 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 939 T68 C13 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1c2f9b7),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 940 T68 C13 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 941 T68 C13 oass.SolrIndexSearcher.<init> Opening Searcher@149fc9b realtime
[junit4:junit4]   2> 942 T68 C13 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 943 T68 C13 REQ [collection1] webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true} status=0 QTime=8 
[junit4:junit4]   2> 946 T68 C13 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 949 T68 C13 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1c2f9b7),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1c2f9b7),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 949 T68 C13 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 952 T68 C13 oass.SolrIndexSearcher.<init> Opening Searcher@9ca517 main
[junit4:junit4]   2> 953 T68 C13 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 953 T69 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@9ca517 main{StandardDirectoryReader(segments_1:2:nrt)}
[junit4:junit4]   2> 953 T68 C13 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 955 T68 C13 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 9
[junit4:junit4]   2> 1008 T68 C13 UPDATE [collection1] webapp=null path=null params={} {add=[F1 (1413025318456786944)]} 0 33
[junit4:junit4]   2> 1013 T68 C13 UPDATE [collection1] webapp=null path=null params={} {add=[F2 (1413025318492438528)]} 0 0
[junit4:junit4]   2> 1020 T68 C13 UPDATE [collection1] webapp=null path=null params={} {add=[F3 (1413025318498729984)]} 0 1
[junit4:junit4]   2> 1021 T68 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=31097764
[junit4:junit4]   2> 1022 T68 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@15ae104
[junit4:junit4]   2> 1023 T68 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 1023 T68 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=3,adds=3,deletesById=0,deletesByQuery=0,errors=1,cumulative_adds=3,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=1}
[junit4:junit4]   2> 1023 T68 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 1030 T68 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 1031 T68 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> 1035 T68 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> 1037 T68 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> 1100 T68 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 1161 T68 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
[junit4:junit4]   2> 1162 T68 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 1168 T68 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 1780 T68 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 1785 T68 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 1813 T68 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 1813 T68 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> 1814 T68 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> 1827 T68 oasc.CoreContainer.<init> New CoreContainer 7325999
[junit4:junit4]   2> 1828 T68 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-1347565953668\
[junit4:junit4]   2> 1828 T68 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 1829 T68 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-1347565953668\index/
[junit4:junit4]   2> 1840 T68 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 1840 T68 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 1841 T68 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 1842 T68 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 1844 T68 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 1848 T68 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347565953668\index forceNew:false
[junit4:junit4]   2> 1849 T68 oass.SolrIndexSearcher.<init> Opening Searcher@5a8bf4 main
[junit4:junit4]   2> 1849 T68 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 1862 T68 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 1863 T68 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 1863 T68 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 1864 T68 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 1864 T68 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 1864 T68 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 1865 T68 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 1865 T68 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 1868 T68 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 1869 T68 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 1869 T68 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 1869 T68 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 1881 T70 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5a8bf4 main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 1881 T68 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> ASYNC  NEW_CORE C14 name=collection1 org.apache.solr.core.SolrCore@1e523c0
[junit4:junit4]   2> 1889 T71 C14 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-1347565953668\tlog\tlog.0000000000000000001 refcount=2} active=false starting pos=0
[junit4:junit4]   2> 1899 T71 C14 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@2332b6),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 1899 T71 C14 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 1902 T71 C14 oasc.SolrException.log Ignoring exception matching OutOfBoundsException
[junit4:junit4]   2> 1902 T71 C14 oasu.DirectUpdateHandler2.commit start commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 1920 T71 C14 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@2332b6),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@2332b6),segFN=segments_3,generation=3,filenames=[_0_Asserting_0.tim, _0_Asserting_0.frq, _0.fnm, _0_Asserting_0.tip, _0_SimpleText_0.pst, _0.fdx, _0.si, _0.fdt, segments_3]
[junit4:junit4]   2> 1921 T71 C14 oasc.SolrDeletionPolicy.updateCommits newest commit = 3
[junit4:junit4]   2> 1938 T71 C14 oass.SolrIndexSearcher.<init> Opening Searcher@1399994 main
[junit4:junit4]   2> 1938 T71 C14 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 1938 T71 C14 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 1939 T70 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1399994 main{StandardDirectoryReader(segments_3:4 _0(4.0.0.2):C3)}
[junit4:junit4]   2> 1939 T71 C14 oasu.UpdateLog$LogReplayer.run WARNING Log replay finished. recoveryInfo=RecoveryInfo{adds=3 deletes=0 deleteByQuery=0 errors=0 positionOfStart=0}
[junit4:junit4]   2> 1940 T68 C14 REQ [collection1] webapp=null path=null params={q=*:*} hits=3 status=0 QTime=0 
[junit4:junit4]   2> 1955 T68 C14 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=3 
[junit4:junit4]   2> 1960 T68 C14 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 1965 T68 C14 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 1969 T68 C14 REQ [collection1] webapp=null path=null params={getVersions=3&qt=/get} status=0 QTime=4 
[junit4:junit4]   2> 1970 T68 oas.SolrTestCaseJ4.tearDown ###Ending testTruncatedLog
[junit4:junit4]   2> 1975 T68 oas.SolrTestCaseJ4.setUp ###Starting testRecoveryMultipleLogs
[junit4:junit4]   2> 1986 T68 C14 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 1998 T68 C14 oass.SolrIndexSearcher.<init> Opening Searcher@1d11499 realtime
[junit4:junit4]   2> 1999 T68 C14 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 1999 T68 C14 REQ [collection1] webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true} status=0 QTime=13 
[junit4:junit4]   2> 2008 T68 C14 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 2010 T68 C14 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@2332b6),segFN=segments_3,generation=3,filenames=[_0_Asserting_0.tim, _0_Asserting_0.frq, _0.fnm, _0_Asserting_0.tip, _0_SimpleText_0.pst, _0.fdx, _0.si, _0.fdt, segments_3]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@2332b6),segFN=segments_4,generation=4,filenames=[segments_4]
[junit4:junit4]   2> 2012 T68 C14 oasc.SolrDeletionPolicy.updateCommits newest commit = 4
[junit4:junit4]   2> 2017 T68 C14 oass.SolrIndexSearcher.<init> Opening Searcher@114be2e main
[junit4:junit4]   2> 2018 T68 C14 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 2019 T70 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@114be2e main{StandardDirectoryReader(segments_3:6:nrt)}
[junit4:junit4]   2> 2019 T68 C14 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 2020 T68 C14 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 12
[junit4:junit4]   2> 2045 T68 C14 UPDATE [collection1] webapp=null path=null params={} {add=[AAAAAA (1413025319569326080)]} 0 5
[junit4:junit4]   2> 2054 T68 C14 UPDATE [collection1] webapp=null path=null params={} {add=[BBBBBB (1413025319584006144)]} 0 1
[junit4:junit4]   2> 2060 T68 C14 UPDATE [collection1] webapp=null path=null params={} {add=[CCCCCC (1413025319590297600)]} 0 1
[junit4:junit4]   2> 2062 T68 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=7325999
[junit4:junit4]   2> 2063 T68 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@1e523c0
[junit4:junit4]   2> 2063 T68 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 2067 T68 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=9,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=1}
[junit4:junit4]   2> 2070 T68 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 2079 T68 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 2083 T68 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> 2085 T68 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> 2087 T68 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> 2157 T68 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 2235 T68 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
[junit4:junit4]   2> 2236 T68 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 2242 T68 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 2915 T68 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 2918 T68 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 2944 T68 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2945 T68 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> 2946 T68 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> 2968 T68 oasc.CoreContainer.<init> New CoreContainer 17138692
[junit4:junit4]   2> 2969 T68 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-1347565953668\
[junit4:junit4]   2> 2969 T68 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 2970 T68 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-1347565953668\index/
[junit4:junit4]   2> 2975 T68 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 2976 T68 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 2976 T68 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 2977 T68 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 2982 T68 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 2988 T68 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347565953668\index forceNew:false
[junit4:junit4]   2> 2990 T68 oass.SolrIndexSearcher.<init> Opening Searcher@1f74b8 main
[junit4:junit4]   2> 2990 T68 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 2992 T68 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 2993 T68 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 2995 T68 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 2996 T68 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 2996 T68 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 2997 T68 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 2997 T68 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 2998 T68 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 2998 T68 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 2998 T68 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 2998 T68 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 2999 T68 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 3015 T72 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1f74b8 main{StandardDirectoryReader(segments_4:6)}
[junit4:junit4]   2> 3015 T68 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> ASYNC  NEW_CORE C15 name=collection1 org.apache.solr.core.SolrCore@1f85993
[junit4:junit4]   2> 3017 T73 C15 oasu.UpdateLog$LogReplayer.doReplay WARNING Starting log replay tlog{file=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347565953668\tlog\tlog.0000000000000000004 refcount=2} active=false starting pos=0
[junit4:junit4]   2> 3022 T73 C15 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-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@19df3c2),segFN=segments_4,generation=4,filenames=[segments_4]
[junit4:junit4]   2> 3022 T73 C15 oasc.SolrDeletionPolicy.updateCommits newest commit = 4
[junit4:junit4]   2> 3026 T73 C15 oasc.SolrException.log Ignoring exception matching OutOfBoundsException
[junit4:junit4]   2> 3027 T73 C15 oasu.DirectUpdateHandler2.commit start commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 3037 T73 C15 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-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@19df3c2),segFN=segments_4,generation=4,filenames=[segments_4]
[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-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@19df3c2),segFN=segments_5,generation=5,filenames=[_2.si, _2_Asserting_0.tim, _2_Asserting_0.tip, _2_SimpleText_0.pst, _2_Asserting_0.frq, _2.fdt, segments_5, _2.fdx, _2.fnm]
[junit4:junit4]   2> 3037 T73 C15 oasc.SolrDeletionPolicy.updateCommits newest commit = 5
[junit4:junit4]   2> 3046 T73 C15 oass.SolrIndexSearcher.<init> Opening Searcher@1338d6a main
[junit4:junit4]   2> 3047 T73 C15 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 3047 T73 C15 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3048 T72 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1338d6a main{StandardDirectoryReader(segments_5:8 _2(4.0.0.2):C3)}
[junit4:junit4]   2> 3048 T73 C15 oasu.UpdateLog$LogReplayer.doReplay WARNING Starting log replay tlog{file=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347565953668\tlog\tlog.0000000000000000005 refcount=2} active=false starting pos=0
[junit4:junit4]   2> 3054 T73 C15 oasc.SolrException.log Ignoring exception matching OutOfBoundsException
[junit4:junit4]   2> 3055 T73 C15 oasu.DirectUpdateHandler2.commit start commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 3079 T73 C15 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-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@19df3c2),segFN=segments_5,generation=5,filenames=[_2.si, _2_Asserting_0.tim, _2_Asserting_0.tip, _2_SimpleText_0.pst, _2_Asserting_0.frq, _2.fdt, segments_5, _2.fdx, _2.fnm]
[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-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@19df3c2),segFN=segments_6,generation=6,filenames=[_2.si, _2_SimpleText_0.pst, _2.fdt, _3.fdt, _3.fnm, _2.fdx, _2.fnm, _3_Asserting_0.frq, _3.fdx, _2_Asserting_0.tim, _2_Asserting_0.tip, _3_Asserting_0.tim, _3_Asserting_0.tip, _3_SimpleText_0.pst, _2_Asserting_0.frq, _3.si, segments_6]
[junit4:junit4]   2> 3080 T73 C15 oasc.SolrDeletionPolicy.updateCommits newest commit = 6
[junit4:junit4]   2> 3094 T73 C15 oass.SolrIndexSearcher.<init> Opening Searcher@1dc6f63 main
[junit4:junit4]   2> 3094 T73 C15 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 3094 T73 C15 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3094 T72 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1dc6f63 main{StandardDirectoryReader(segments_6:10 _2(4.0.0.2):C3 _3(4.0.0.2):C3)}
[junit4:junit4]   2> 3095 T73 C15 oasu.UpdateLog$LogReplayer.run WARNING Log replay finished. recoveryInfo=RecoveryInfo{adds=6 deletes=0 deleteByQuery=0 errors=0 positionOfStart=0}
[junit4:junit4]   2> 3097 T68 C15 REQ [collection1] webapp=null path=null params={q=*:*} hits=6 status=0 QTime=2 
[junit4:junit4]   2> 3098 T68 oas.SolrTestCaseJ4.tearDown ###Ending testRecoveryMultipleLogs
[junit4:junit4]   2> 3105 T68 oas.SolrTestCaseJ4.setUp ###Starting testLogReplay
[junit4:junit4]   2> 3121 T68 C15 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 3126 T68 C15 oass.SolrIndexSearcher.<init> Opening Searcher@152d619 realtime
[junit4:junit4]   2> 3126 T68 C15 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 3126 T68 C15 REQ [collection1] webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true} status=0 QTime=6 
[junit4:junit4]   2> 3135 T68 C15 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 3139 T68 C15 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-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@19df3c2),segFN=segments_6,generation=6,filenames=[_2.si, _2_SimpleText_0.pst, _2.fdt, _3.fdt, _3.fnm, _2.fdx, _2.fnm, _3_Asserting_0.frq, _3.fdx, _2_Asserting_0.tim, _2_Asserting_0.tip, _3_Asserting_0.tim, _3_Asserting_0.tip, _3_SimpleText_0.pst, _2_Asserting_0.frq, _3.si, segments_6]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@19df3c2),segFN=segments_7,generation=7,filenames=[segments_7]
[junit4:junit4]   2> 3141 T68 C15 oasc.SolrDeletionPolicy.updateCommits newest commit = 7
[junit4:junit4]   2> 3150 T68 C15 oass.SolrIndexSearcher.<init> Opening Searcher@1a2fd7a main
[junit4:junit4]   2> 3150 T68 C15 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 3151 T68 C15 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3151 T72 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1a2fd7a main{StandardDirectoryReader(segments_6:11:nrt)}
[junit4:junit4]   2> 3154 T68 C15 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 19
[junit4:junit4]   2> 3187 T68 C15 UPDATE [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[A1 (1413025320767848448)]} 0 4
[junit4:junit4]   2> 3193 T68 C15 UPDATE [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[A11 (1413025320778334208)]} 0 0
[junit4:junit4]   2> 3200 T68 C15 UPDATE [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[A12 (1413025320785674240)]} 0 2
[junit4:junit4]   2> 3409 T68 C15 oass.SolrIndexSearcher.<init> Opening Searcher@1d1a93 realtime
[junit4:junit4]   2> 3410 T68 C15 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 3410 T68 C15 UPDATE [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {deleteByQuery=id:A11 (-1413025320789868544)} 0 206
[junit4:junit4]   2> 3425 T68 C15 UPDATE [collection1] webapp=null path=null params={versions=true&wt=json&indent=true} {add=[A13 (1413025321019506688)]} 0 2
[junit4:junit4]   2> 3427 T68 C15 REQ [collection1] webapp=null path=null params={q=*:*} hits=0 status=0 QTime=2 
[junit4:junit4]   2> 3428 T68 C15 REQ [collection1] webapp=null path=null params={getVersions=5&qt=/get} status=0 QTime=1 
[junit4:junit4]   2> 3428 T68 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=17138692
[junit4:junit4]   2> 3428 T68 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@1f85993
[junit4:junit4]   2> 3430 T68 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 3430 T68 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=3,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=4,adds=4,deletesById=0,deletesByQuery=1,errors=1,cumulative_adds=10,cumulative_deletesById=0,cumulative_deletesByQuery=2,cumulative_errors=1}
[junit4:junit4]   2> 3430 T68 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 3444 T68 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 3445 T68 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> 3447 T68 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> 3449 T68 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> 3529 T68 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 3596 T68 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
[junit4:junit4]   2> 3597 T68 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 3605 T68 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 4286 T68 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 4292 T68 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 4311 T68 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 4311 T68 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> 4311 T68 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> 4326 T68 oasc.CoreContainer.<init> New CoreContainer 23812807
[junit4:junit4]   2> 4328 T68 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-1347565953668\
[junit4:junit4]   2> 4328 T68 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 4329 T68 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-1347565953668\index/
[junit4:junit4]   2> 4332 T68 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 4332 T68 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 4333 T68 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 4333 T68 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 4337 T68 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 4342 T68 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347565953668\index forceNew:false
[junit4:junit4]   2> 4343 T68 oass.SolrIndexSearcher.<init> Opening Searcher@1d4605a main
[junit4:junit4]   2> 4343 T68 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 4347 T68 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 4348 T68 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 4348 T68 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 4350 T68 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 4350 T68 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 4350 T68 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 4350 T68 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 4350 T68 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 4351 T68 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 4351 T68 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 4352 T68 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 4352 T68 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 4365 T74 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1d4605a main{StandardDirectoryReader(segments_7:11)}
[junit4:junit4]   2> 4365 T68 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> ASYNC  NEW_CORE C16 name=collection1 org.apache.solr.core.SolrCore@10c66cc
[junit4:junit4]   2> 4366 T75 C16 oasu.UpdateLog$LogReplayer.doReplay WARNING Starting log replay tlog{file=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347565953668\tlog\tlog.0000000000000000009 refcount=2} active=false starting pos=0
[junit4:junit4]   2> 4367 T68 C16 REQ [collection1] webapp=null path=null params={q=*:*} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 4367 T68 C16 REQ [collection1] webapp=null path=null params={getVersions=5&qt=/get} status=0 QTime=0 
[junit4:junit4]   2> 4369 T68 C16 REQ [collection1] webapp=null path=null params={getVersions=5&qt=/get} status=0 QTime=1 
[junit4:junit4]   2> 4373 T75 C16 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@193d42),segFN=segments_7,generation=7,filenames=[segments_7]
[junit4:junit4]   2> 4375 T75 C16 oasc.SolrDeletionPolicy.updateCommits newest commit = 7
[junit4:junit4]   2> 4375 T75 C16 oasu.DirectUpdateHandler2.addDoc Reordered DBQs detected.  Update=add{flags=10,_version_=1413025320767848448,id=A1} DBQs=[DBQ{version=1413025320789868544,q=id:A11}]
[junit4:junit4]   2> 4386 T75 C16 oass.SolrIndexSearcher.<init> Opening Searcher@1cf0fe4 realtime
[junit4:junit4]   2> 4386 T75 C16 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 4387 T75 C16 oasu.DirectUpdateHandler2.addDoc Reordered DBQs detected.  Update=add{flags=10,_version_=1413025320778334208,id=A11} DBQs=[DBQ{version=1413025320789868544,q=id:A11}]
[junit4:junit4]   2> 4403 T75 C16 oass.SolrIndexSearcher.<init> Opening Searcher@18bd39a realtime
[junit4:junit4]   2> 4403 T75 C16 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 4403 T75 C16 oasu.DirectUpdateHandler2.addDoc Reordered DBQs detected.  Update=add{flags=10,_version_=1413025320785674240,id=A12} DBQs=[DBQ{version=1413025320789868544,q=id:A11}]
[junit4:junit4]   2> 4419 T75 C16 oass.SolrIndexSearcher.<init> Opening Searcher@c3bce7 realtime
[junit4:junit4]   2> 4420 T75 C16 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 4425 T75 C16 oasu.DirectUpdateHandler2.commit start commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 4445 T75 C16 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@193d42),segFN=segments_7,generation=7,filenames=[segments_7]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@193d42),segFN=segments_8,generation=8,filenames=[_6.fdx, _6_Asserting_0.frq, _4.fnm, _7.si, _4_Asserting_0.tip, _7_Asserting_0.frq, _6_Asserting_0.tim, _4.fdx, _7.fnm, _6_Asserting_0.tip, _4_Asserting_0.tim, _7_Asserting_0.tip, _4_Asserting_0.frq, _7_Asserting_0.tim, _7.fdx, _4.si, _4.fdt, _6_SimpleText_0.pst, _7.fdt, _4_SimpleText_0.pst, _7_SimpleText_0.pst, segments_8, _6.fnm, _6.si, _6.fdt]
[junit4:junit4]   2> 4445 T75 C16 oasc.SolrDeletionPolicy.updateCommits newest commit = 8
[junit4:junit4]   2> 4450 T75 C16 oass.SolrIndexSearcher.<init> Opening Searcher@edf5d0 main
[junit4:junit4]   2> 4450 T75 C16 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 4452 T75 C16 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 4452 T74 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@edf5d0 main{StandardDirectoryReader(segments_8:21:nrt _4(4.0.0.2):C1 _6(4.0.0.2):C1 _7(4.0.0.2):C1)}
[junit4:junit4]   2> 4452 T75 C16 oasu.UpdateLog$LogReplayer.run WARNING Log replay finished. recoveryInfo=RecoveryInfo{adds=4 deletes=0 deleteByQuery=1 errors=0 positionOfStart=0}
[junit4:junit4]   2> 4453 T68 C16 REQ [collection1] webapp=null path=null params={q=*:*} hits=3 status=0 QTime=0 
[junit4:junit4]   2> 4454 T68 C16 REQ [collection1] webapp=null path=null params={getVersions=5&qt=/get} status=0 QTime=1 
[junit4:junit4]   2> 4468 T68 C16 UPDATE [collection1] webapp=null path=null params={} {add=[A2 (1413025322108977152)]} 0 6
[junit4:junit4]   2> 4485 T68 C16 UPDATE [collection1] webapp=null path=null params={} {add=[A3 (1413025322133094400)]} 0 1
[junit4:junit4]   2> 4492 T68 C16 UPDATE [collection1] webapp=null path=null params={} {delete=[A2 (-1413025322140434432)]} 0 0
[junit4:junit4]   2> 4499 T68 C16 UPDATE [collection1] webapp=null path=null params={} {add=[A4 (1413025322147774464)]} 0 1
[junit4:junit4]   2> 4501 T68 C16 REQ [collection1] webapp=null path=null params={q=*:*} hits=3 status=0 QTime=0 
[junit4:junit4]   2> 4506 T68 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=23812807
[junit4:junit4]   2> 4506 T68 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@10c66cc
[junit4:junit4]   2> 4507 T68 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 4507 T68 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> 4509 T68 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 4548 T68 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 4548 T68 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> 4550 T68 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> 4552 T68 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> 4622 T68 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 4683 T68 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
[junit4:junit4]   2> 4684 T68 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 4691 T68 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 5329 T68 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 5333 T68 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 5355 T68 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 5355 T68 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> 5355 T68 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> 5371 T68 oasc.CoreContainer.<init> New CoreContainer 26699305
[junit4:junit4]   2> 5371 T68 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-1347565953668\
[junit4:junit4]   2> 5372 T68 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 5373 T68 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-1347565953668\index/
[junit4:junit4]   2> 5375 T68 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 5376 T68 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 5377 T68 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 5377 T68 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 5379 T68 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 5385 T68 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347565953668\index forceNew:false
[junit4:junit4]   2> 5395 T68 oass.SolrIndexSearcher.<init> Opening Searcher@1af6511 main
[junit4:junit4]   2> 5395 T68 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 5405 T68 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 5405 T68 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 5406 T68 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 5406 T68 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 5407 T68 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 5407 T68 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 5408 T68 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 5408 T68 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 5409 T68 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 5409 T68 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 5409 T68 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 5410 T68 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 5422 T68 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 5454 T76 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1af6511 main{StandardDirectoryReader(segments_8:21 _4(4.0.0.2):C1 _6(4.0.0.2):C1 _7(4.0.0.2):C1)}
[junit4:junit4]   2> ASYNC  NEW_CORE C17 name=collection1 org.apache.solr.core.SolrCore@8245a6
[junit4:junit4]   2> 5461 T77 C17 oasu.UpdateLog$LogReplayer.doReplay WARNING Starting log replay tlog{file=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347565953668\tlog\tlog.0000000000000000011 refcount=2} active=false starting pos=0
[junit4:junit4]   2> 5478 T77 C17 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-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ab8136),segFN=segments_8,generation=8,filenames=[_6.fdx, _6_Asserting_0.frq, _4.fnm, _7.si, _4_Asserting_0.tip, _6_Asserting_0.tim, _7_Asserting_0.frq, _4.fdx, _7.fnm, _6_Asserting_0.tip, _4_Asserting_0.tim, _7_Asserting_0.tip, _7_Asserting_0.tim, _4_Asserting_0.frq, _7.fdx, _4.fdt, _4.si, _6_SimpleText_0.pst, _7.fdt, _4_SimpleText_0.pst, _7_SimpleText_0.pst, segments_8, _6.si, _6.fnm, _6.fdt]
[junit4:junit4]   2> 5478 T77 C17 oasc.SolrDeletionPolicy.updateCommits newest commit = 8
[junit4:junit4]   2> 5481 T77 C17 oasu.DirectUpdateHandler2.commit start commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 5498 T77 C17 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-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ab8136),segFN=segments_8,generation=8,filenames=[_6.fdx, _6_Asserting_0.frq, _4.fnm, _7.si, _4_Asserting_0.tip, _6_Asserting_0.tim, _7_Asserting_0.frq, _4.fdx, _7.fnm, _6_Asserting_0.tip, _4_Asserting_0.tim, _7_Asserting_0.tip, _7_Asserting_0.tim, _4_Asserting_0.frq, _7.fdx, _4.fdt, _4.si, _6_SimpleText_0.pst, _7.fdt, _4_SimpleText_0.pst, _7_SimpleText_0.pst, segments_8, _6.si, _6.fnm, _6.fdt]
[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-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ab8136),segFN=segments_9,generation=9,filenames=[_8_Asserting_0.frq, _6.fdx, _6_Asserting_0.frq, _4.fnm, _7.si, _4_Asserting_0.tip, _6_Asserting_0.tim, _7_Asserting_0.frq, _8.si, _4.fdx, _7.fnm, _6_Asserting_0.tip, _4_Asserting_0.tim, _7_Asserting_0.tip, _7_Asserting_0.tim, _4_Asserting_0.frq, _8_Asserting_0.tim, _7.fdx, _4.fdt, _4.si, _8_Asserting_0.tip, _6_SimpleText_0.pst, _7.fdt, segments_9, _4_SimpleText_0.pst, _7_SimpleText_0.pst, _8.fnm, _6.si, _6.fnm, _8.fdx, _6.fdt, _8_SimpleText_0.pst, _8.fdt]
[junit4:junit4]   2> 5500 T77 C17 oasc.SolrDeletionPolicy.updateCommits newest commit = 9
[junit4:junit4]   2> 5515 T77 C17 oass.SolrIndexSearcher.<init> Opening Searcher@1f5f9e6 main
[junit4:junit4]   2> 5515 T77 C17 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 5515 T77 C17 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 5515 T76 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1f5f9e6 main{StandardDirectoryReader(segments_9:23 _4(4.0.0.2):C1 _6(4.0.0.2):C1 _7(4.0.0.2):C1 _8(4.0.0.2):C2)}
[junit4:junit4]   2> 5516 T77 C17 oasu.UpdateLog$LogReplayer.run WARNING Log replay finished. recoveryInfo=RecoveryInfo{adds=3 deletes=1 deleteByQuery=0 errors=0 positionOfStart=0}
[junit4:junit4]   2> 5516 T68 C17 REQ [collection1] webapp=null path=null params={q=*:*} hits=5 status=0 QTime=0 
[junit4:junit4]   2> 5520 T68 C17 REQ [collection1] webapp=null path=null params={q=id:A2} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 5520 T68 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=26699305
[junit4:junit4]   2> 5520 T68 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@8245a6
[junit4:junit4]   2> 5522 T68 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 5524 T68 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> 5525 T68 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 5559 T68 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 5560 T68 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> 5563 T68 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> 5564 T68 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> 5641 T68 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 5705 T68 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
[junit4:junit4]   2> 5706 T68 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 5713 T68 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 6490 T68 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 6493 T68 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 6514 T68 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 6514 T68 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> 6515 T68 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> 6537 T68 oasc.CoreContainer.<init> New CoreContainer 17325566
[junit4:junit4]   2> 6537 T68 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-1347565953668\
[junit4:junit4]   2> 6537 T68 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 6540 T68 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-1347565953668\index/
[junit4:junit4]   2> 6543 T68 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 6544 T68 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 6544 T68 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 6545 T68 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 6548 T68 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 6556 T68 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347565953668\index forceNew:false
[junit4:junit4]   2> 6569 T68 oass.SolrIndexSearcher.<init> Opening Searcher@9532e1 main
[junit4:junit4]   2> 6569 T68 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 6574 T68 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 6574 T68 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 6575 T68 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 6575 T68 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 6577 T68 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 6577 T68 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 6578 T68 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 6578 T68 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 6578 T68 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 6579 T68 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 6579 T68 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 6579 T68 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 6591 T68 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 6594 T78 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@9532e1 main{StandardDirectoryReader(segments_9:23 _4(4.0.0.2):C1 _6(4.0.0.2):C1 _7(4.0.0.2):C1 _8(4.0.0.2):C2)}
[junit4:junit4]   2> ASYNC  NEW_CORE C18 name=collection1 org.apache.solr.core.SolrCore@ea67a2
[junit4:junit4]   2> 6595 T68 C18 REQ [collection1] webapp=null path=null params={q=*:*} hits=5 status=0 QTime=2 
[junit4:junit4]   2> 6714 T68 oas.SolrTestCaseJ4.tearDown ###Ending testLogReplay
[junit4:junit4]   2> 6721 T68 oas.SolrTestCaseJ4.setUp ###Starting testDropBuffered
[junit4:junit4]   2> 6732 T68 C18 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 6749 T68 C18 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-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@192fd15),segFN=segments_9,generation=9,filenames=[_8_Asserting_0.frq, _6.fdx, _6_Asserting_0.frq, _4.fnm, _7.si, _4_Asserting_0.tip, _6_Asserting_0.tim, _7_Asserting_0.frq, _8.si, _4.fdx, _7.fnm, _6_Asserting_0.tip, _4_Asserting_0.tim, _7_Asserting_0.tip, _7_Asserting_0.tim, _4_Asserting_0.frq, _8_Asserting_0.tim, _7.fdx, _4.fdt, _4.si, _8_Asserting_0.tip, _6_SimpleText_0.pst, _7.fdt, segments_9, _4_SimpleText_0.pst, _7_SimpleText_0.pst, _8.fnm, _6.si, _6.fnm, _8.fdx, _6.fdt, _8_SimpleText_0.pst, _8.fdt]
[junit4:junit4]   2> 6750 T68 C18 oasc.SolrDeletionPolicy.updateCommits newest commit = 9
[junit4:junit4]   2> 6755 T68 C18 oass.SolrIndexSearcher.<init> Opening Searcher@42dc22 realtime
[junit4:junit4]   2> 6756 T68 C18 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 6757 T68 C18 REQ [collection1] webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true} status=0 QTime=25 
[junit4:junit4]   2> 6765 T68 C18 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 6770 T68 C18 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-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@192fd15),segFN=segments_9,generation=9,filenames=[_8_Asserting_0.frq, _6.fdx, _6_Asserting_0.frq, _4.fnm, _7.si, _4_Asserting_0.tip, _6_Asserting_0.tim, _7_Asserting_0.frq, _8.si, _4.fdx, _7.fnm, _6_Asserting_0.tip, _4_Asserting_0.tim, _7_Asserting_0.tip, _7_Asserting_0.tim, _4_Asserting_0.frq, _8_Asserting_0.tim, _7.fdx, _4.fdt, _4.si, _8_Asserting_0.tip, _6_SimpleText_0.pst, _7.fdt, segments_9, _4_SimpleText_0.pst, _7_SimpleText_0.pst, _8.fnm, _6.si, _6.fnm, _8.fdx, _6.fdt, _8_SimpleText_0.pst, _8.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-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@192fd15),segFN=segments_a,generation=10,filenames=[segments_a]
[junit4:junit4]   2> 6770 T68 C18 oasc.SolrDeletionPolicy.updateCommits newest commit = 10
[junit4:junit4]   2> 6798 T68 C18 oass.SolrIndexSearcher.<init> Opening Searcher@1764474 main
[junit4:junit4]   2> 6799 T68 C18 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 6800 T78 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1764474 main{StandardDirectoryReader(segments_9:24:nrt)}
[junit4:junit4]   2> 6799 T68 C18 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 6801 T68 C18 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 37
[junit4:junit4]   2> 6830 T68 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 6830 T68 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 6851 T68 C18 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=2 
[junit4:junit4]   2> 6858 T68 C18 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=1 
[junit4:junit4]   2> 6864 T68 C18 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 6864 T68 oasu.UpdateLog.dropBufferedUpdates Dropping buffered updates FSUpdateLog{state=BUFFERING, tlog=tlog{file=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347565953668\tlog\tlog.0000000000000000013 refcount=1}}
[junit4:junit4]   2> 6865 T68 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=tlog{file=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347565953668\tlog\tlog.0000000000000000013 refcount=1}}
[junit4:junit4]   2> 6872 T68 C18 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=1 
[junit4:junit4]   2> 6878 T68 C18 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=1 
[junit4:junit4]   2> 6879 T79 C18 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-1347565953668\tlog\tlog.0000000000000000013 refcount=2} active=true starting pos=0
[junit4:junit4]   2> 6888 T79 C18 oasu.DirectUpdateHandler2.commit start commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 6918 T79 C18 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-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@192fd15),segFN=segments_a,generation=10,filenames=[segments_a]
[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-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@192fd15),segFN=segments_b,generation=11,filenames=[_9_Asserting_0.frq, segments_b, _9.fnm, _9_Asserting_0.tip, _9.fdt, _9.si, _9.fdx, _9_SimpleText_0.pst, _9_Asserting_0.tim]
[junit4:junit4]   2> 6919 T79 C18 oasc.SolrDeletionPolicy.updateCommits newest commit = 11
[junit4:junit4]   2> 6925 T79 C18 oass.SolrIndexSearcher.<init> Opening Searcher@581399 main
[junit4:junit4]   2> 6925 T79 C18 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 6926 T78 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@581399 main{StandardDirectoryReader(segments_b:26:nrt _9(4.0.0.2):C2)}
[junit4:junit4]   2> 6928 T79 C18 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 6928 T79 C18 oasu.UpdateLog$LogReplayer.run WARNING Log replay finished. recoveryInfo=RecoveryInfo{adds=2 deletes=0 deleteByQuery=0 errors=0 positionOfStart=0}
[junit4:junit4]   2> 6929 T68 C18 REQ [collection1] webapp=null path=null params={getVersions=2&qt=/get} status=0 QTime=0 
[junit4:junit4]   2> 6953 T68 C18 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=6 
[junit4:junit4]   2> 6963 T68 C18 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=1 
[junit4:junit4]   2> 6966 T68 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=tlog{file=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347565953668\tlog\tlog.0000000000000000014 refcount=1}}
[junit4:junit4]   2> 6975 T68 C18 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=1 
[junit4:junit4]   2> 6982 T68 C18 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=1 
[junit4:junit4]   2> 6982 T68 oasu.UpdateLog.dropBufferedUpdates Dropping buffered updates FSUpdateLog{state=BUFFERING, tlog=tlog{file=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347565953668\tlog\tlog.0000000000000000014 refcount=1}}
[junit4:junit4]   2> 6983 T68 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=tlog{file=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347565953668\tlog\tlog.0000000000000000014 refcount=1}}
[junit4:junit4]   2> 6990 T68 C18 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 7004 T68 C18 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 7005 T79 C18 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-1347565953668\tlog\tlog.0000000000000000014 refcount=2} active=true starting pos=92
[junit4:junit4]   2> 7009 T79 C18 oasu.DirectUpdateHandler2.commit start commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 7025 T79 C18 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-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@192fd15),segFN=segments_b,generation=11,filenames=[_9_Asserting_0.frq, segments_b, _9.fnm, _9_Asserting_0.tip, _9.fdt, _9.si, _9.fdx, _9_SimpleText_0.pst, _9_Asserting_0.tim]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@192fd15),segFN=segments_c,generation=12,filenames=[segments_c, _9_Asserting_0.frq, _a_SimpleText_0.pst, _9.fnm, _9_Asserting_0.tip, _9.fdt, _a.fdt, _a.si, _9.fdx, _a.fdx, _9_SimpleText_0.pst, _9_Asserting_0.tim, _a_Asserting_0.frq, _a_Asserting_0.tip, _a_Asserting_0.tim, _9.si, _a.fnm]
[junit4:junit4]   2> 7025 T79 C18 oasc.SolrDeletionPolicy.updateCommits newest commit = 12
[junit4:junit4]   2> 7034 T79 C18 oass.SolrIndexSearcher.<init> Opening Searcher@19c0948 main
[junit4:junit4]   2> 7034 T79 C18 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 7035 T79 C18 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 7035 T78 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@19c0948 main{StandardDirectoryReader(segments_c:28:nrt _9(4.0.0.2):C2 _a(4.0.0.2):C4)}
[junit4:junit4]   2> 7036 T79 C18 oasu.UpdateLog$LogReplayer.run WARNING Log replay finished. recoveryInfo=RecoveryInfo{adds=2 deletes=0 deleteByQuery=0 errors=0 positionOfStart=92}
[junit4:junit4]   2> 7039 T68 C18 REQ [collection1] webapp=null path=null params={fl=id,_version_&sort=_version_+asc&q=*:*} hits=6 status=0 QTime=3 
[junit4:junit4]   2> 7043 T68 C18 REQ [collection1] webapp=null path=null params={getVersions=6&qt=/get} status=0 QTime=1 
[junit4:junit4]   2> 7043 T68 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 7065 T68 C18 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=3 
[junit4:junit4]   2> 7073 T68 C18 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=1 
[junit4:junit4]   2> 7075 T68 oasu.UpdateLog.dropBufferedUpdates Dropping buffered updates FSUpdateLog{state=BUFFERING, tlog=tlog{file=C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347565953668\tlog\tlog.0000000000000000015 refcount=1}}
[junit4:junit4]   2> 7086 T68 C18 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=3 
[junit4:junit4]   2> 7095 T68 C18 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=1 
[junit4:junit4]   2> 7107 T68 C18 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 7130 T68 C18 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-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@192fd15),segFN=segments_c,generation=12,filenames=[segments_c, _9_Asserting_0.frq, _a_SimpleText_0.pst, _9.fnm, _9_Asserting_0.tip, _9.fdt, _a.fdt, _a.si, _9.fdx, _a.fdx, _9_SimpleText_0.pst, _9_Asserting_0.tim, _a_Asserting_0.frq, _a_Asserting_0.tip, _a_Asserting_0.tim, _9.si, _a.fnm]
[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-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@192fd15),segFN=segments_d,generation=13,filenames=[_a_SimpleText_0.pst, _9_Asserting_0.tip, _a.fdt, _b_Asserting_0.tim, _b_Asserting_0.frq, _a.fdx, _b_Asserting_0.tip, _9_Asserting_0.tim, _a_Asserting_0.tip, _b.si, _a_Asserting_0.tim, _9.si, segments_d, _9_Asserting_0.frq, _b_SimpleText_0.pst, _9.fnm, _9.fdt, _a.si, _9.fdx, _9_SimpleText_0.pst, _a_Asserting_0.frq, _b.fnm, _b.fdt, _a.fnm, _b.fdx]
[junit4:junit4]   2> 7130 T68 C18 oasc.SolrDeletionPolicy.updateCommits newest commit = 13
[junit4:junit4]   2> 7141 T68 C18 oass.SolrIndexSearcher.<init> Opening Searcher@1474205 main
[junit4:junit4]   2> 7141 T68 C18 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 7142 T78 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1474205 main{StandardDirectoryReader(segments_d:30:nrt _9(4.0.0.2):C2 _a(4.0.0.2):C4 _b(4.0.0.2):C2)}
[junit4:junit4]   2> 7142 T68 C18 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 7142 T68 C18 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 35
[junit4:junit4]   2> 7152 T68 C18 REQ [collection1] webapp=null path=null params={getVersions=2&qt=/get} status=0 QTime=1 
[junit4:junit4]   2> 7153 T68 C18 REQ [collection1] webapp=null path=null params={fl=id,_version_&sort=_version_+desc&q=*:*&rows=2} hits=8 status=0 QTime=0 
[junit4:junit4]   2> 7170 T68 C18 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=4 
[junit4:junit4]   2> 7171 T68 oas.SolrTestCaseJ4.tearDown ###Ending testDropBuffered
[junit4:junit4]   2> 7175 T68 oas.SolrTestCaseJ4.setUp ###Starting testBuffering
[junit4:junit4]   2> 7183 T68 C18 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 7192 T68 C18 oass.SolrIndexSearcher.<init> Opening Searcher@242c99 realtime
[junit4:junit4]   2> 7193 T68 C18 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 7193 T68 C18 REQ [collection1] webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true} status=0 QTime=11 
[junit4:junit4]   2> 7201 T68 C18 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 7204 T68 C18 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-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@192fd15),segFN=segments_d,generation=13,filenames=[_a_SimpleText_0.pst, _9_Asserting_0.tip, _a.fdt, _b_Asserting_0.tim, _b_Asserting_0.frq, _a.fdx, _b_Asserting_0.tip, _9_Asserting_0.tim, _a_Asserting_0.tip, _b.si, _a_Asserting_0.tim, _9.si, segments_d, _9_Asserting_0.frq, _b_SimpleText_0.pst, _9.fnm, _9.fdt, _a.si, _9.fdx, _9_SimpleText_0.pst, _a_Asserting_0.frq, _b.fnm, _b.fdt, _a.fnm, _b.fdx]
[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-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@192fd15),segFN=segments_e,generation=14,filenames=[segments_e]
[junit4:junit4]   2> 7205 T68 C18 oasc.SolrDeletionPolicy.updateCommits newest commit = 14
[junit4:junit4]   2> 7227 T68 C18 oass.SolrIndexSearcher.<init> Opening Searcher@14dcfe1 main
[junit4:junit4]   2> 7229 T68 C18 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 7230 T78 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@14dcfe1 main{StandardDirectoryReader(segments_d:32:nrt)}
[junit4:junit4]   2> 7230 T68 C18 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 7244 T68 C18 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 43
[junit4:junit4]   2> 7252 T68 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 7252 T68 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 7273 T68 C18 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=3 
[junit4:junit4]   2> 7283 T68 C18 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 7291 T68 C18 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&_version_=-1017&wt=json&indent=true} status=0 QTime=1 
[junit4:junit4]   2> 7299 T68 C18 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 7311 T68 C18 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 7322 T68 C18 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> 7326 T68 C18 REQ [collection1] webapp=null path=null params={getVersions=6&qt=/get} status=0 QTime=2 
[junit4:junit4]   2> 7335 T68 C18 oasup.DistributedUpdateProcessor.processCommit Ignoring commit while not ACTIVE - state: BUFFERING replay:0
[junit4:junit4]   2> 7335 T68 C18 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 1
[junit4:junit4]   2> 7339 T68 C18 REQ [collection1] webapp=null path=null params={getVersions=6&qt=/get} status=0 QTime=1 
[junit4:junit4]   2> 7340 T68 C18 REQ [collection1] webapp=null path=null params={q=*:*} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 7340 T68 C18 REQ [collection1] webapp=null path=null params={id=B3&qt=/get} status=0 QTime=0 
[junit4:junit4]   2> 7342 T79 C18 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-1347565953668\tlog\tlog.0000000000000000017 refcount=2} active=true starting pos=0
[junit4:junit4]   2> 7371 T79 C18 oass.SolrIndexSearcher.<init> Opening Searcher@109c80d realtime
[junit4:junit4]   2> 7372 T79 C18 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 7376 T79 C18 oasu.DirectUpdateHandler2.commit start commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 7388 T79 C18 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-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@192fd15),segFN=segments_e,generation=14,filenames=[segments_e]
[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-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@192fd15),segFN=segments_f,generation=15,filenames=[_e_SimpleText_0.pst, segments_f, _e.si, _e.fdt, _e_Asserting_0.tim, _e_Asserting_0.frq, _e.fdx, _e.fnm, _e_Asserting_0.tip]
[junit4:junit4]   2> 7388 T79 C18 oasc.SolrDeletionPolicy.updateCommits newest commit = 15
[junit4:junit4]   2> 7394 T79 C18 oass.SolrIndexSearcher.<init> Opening Searcher@5fdba8 main
[junit4:junit4]   2> 7394 T79 C18 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 7395 T78 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5fdba8 main{StandardDirectoryReader(segments_f:38:nrt _e(4.0.0.2):C2)}
[junit4:junit4]   2> 7398 T79 C18 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 7398 T79 C18 oasu.UpdateLog$LogReplayer.run WARNING Log replay finished. recoveryInfo=RecoveryInfo{adds=4 deletes=1 deleteByQuery=1 errors=0 positionOfStart=0}
[junit4:junit4]   2> 7400 T68 C18 REQ [collection1] webapp=null path=null params={getVersions=6&qt=/get} status=0 QTime=1 
[junit4:junit4]   2> 7401 T68 C18 REQ [collection1] webapp=null path=null params={q=*:*} hits=2 status=0 QTime=0 
[junit4:junit4]   2> 7404 T68 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 7405 T68 C18 REQ [collection1] webapp=null path=null params={id=B3&qt=/get} status=0 QTime=1 
[junit4:junit4]   2> 7430 T68 C18 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=3 
[junit4:junit4]   2> 7438 T68 C18 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 7448 T68 C18 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&_version_=-940&versions=true&wt=json&indent=true} status=0 QTime=2 
[junit4:junit4]   2> 7459 T68 C18 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 7470 T68 C18 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 7478 T68 C18 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 7485 T68 C18 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&_version_=-3000&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 7486 T68 C18 REQ [collection1] webapp=null path=null params={getVersions=13&qt=/get} status=0 QTime=0 
[junit4:junit4]   2> 7487 T79 C18 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-1347565953668\tlog\tlog.0000000000000000018 refcount=2} active=true starting pos=0
[junit4:junit4]   2> 7494 T68 C18 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=0 
[junit4:junit4]   2> 7500 T68 C18 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> 7509 T68 C18 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> 7530 T79 C18 oass.SolrIndexSearcher.<init> Opening Searcher@9ec89f realtime
[junit4:junit4]   2> 7531 T79 C18 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 7532 T79 C18 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> 7548 T79 C18 oass.SolrIndexSearcher.<init> Opening Searcher@1304c79 realtime
[junit4:junit4]   2> 7548 T79 C18 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 7549 T79 C18 oasu.DirectUpdateHandler2.commit start commit{flags=2,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 7563 T79 C18 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-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@192fd15),segFN=segments_f,generation=15,filenames=[_e_SimpleText_0.pst, segments_f, _e.si, _e.fdt, _e_Asserting_0.tim, _e_Asserting_0.frq, _e.fdx, _e.fnm, _e_Asserting_0.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-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@192fd15),segFN=segments_g,generation=16,filenames=[_g.si, _f_SimpleText_0.pst, _e.fdx, _f_Asserting_0.tim, _f.si, _e_SimpleText_0.pst, _g.fnm, _e.fdt, _e_1.del, _e.fnm, _e_Asserting_0.tip, _g_Asserting_0.tim, _g.fdt, _g_Asserting_0.tip, _g.fdx, _e_Asserting_0.tim, _f_Asserting_0.frq, segments_g, _f.fdt, _f_Asserting_0.tip, _f.fdx, _e.si, _f_1.del, _f.fnm, _g_Asserting_0.frq, _e_Asserting_0.frq, _g_SimpleText_0.pst]
[junit4:junit4]   2> 7565 T79 C18 oasc.SolrDeletionPolicy.updateCommits newest commit = 16
[junit4:junit4]   2> 7568 T79 C18 oass.SolrIndexSearcher.<init> Opening Searcher@57a6bf main
[junit4:junit4]   2> 7568 T79 C18 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 7569 T78 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@57a6bf main{StandardDirectoryReader(segments_g:44:nrt _e(4.0.0.2):C2/1 _f(4.0.0.2):C4/2 _g(4.0.0.2):C1)}
[junit4:junit4]   2> 7570 T79 C18 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 7571 T79 C18 oasu.UpdateLog$LogReplayer.run WARNING Log replay finished. recoveryInfo=RecoveryInfo{adds=6 deletes=3 deleteByQuery=1 errors=0 positionOfStart=0}
[junit4:junit4]   2> 7607 T68 C18 REQ [collection1] webapp=null path=null params={fl=id,_version_&sort=id+asc&q=*:*} hits=4 status=0 QTime=36 
[junit4:junit4]   2> 7610 T68 oas.SolrTestCaseJ4.tearDown ###Ending testBuffering
[junit4:junit4]   2> 7615 T68 oas.SolrTestCaseJ4.setUp ###Starting testCleanShutdown
[junit4:junit4]   2> 7639 T68 C18 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 7649 T68 C18 oass.SolrIndexSearcher.<init> Opening Searcher@1cd858f realtime
[junit4:junit4]   2> 7650 T68 C18 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 7650 T68 C18 REQ [collection1] webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true} status=0 QTime=13 
[junit4:junit4]   2> 7659 T68 C18 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 7663 T68 C18 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-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@192fd15),segFN=segments_g,generation=16,filenames=[_g.si, _f_SimpleText_0.pst, _e.fdx, _f_Asserting_0.tim, _f.si, _e_SimpleText_0.pst, _g.fnm, _e.fdt, _e_1.del, _e.fnm, _e_Asserting_0.tip, _g_Asserting_0.tim, _g.fdt, _g_Asserting_0.tip, _g.fdx, _e_Asserting_0.tim, _f_Asserting_0.frq, segments_g, _f.fdt, _f_Asserting_0.tip, _f.fdx, _e.si, _f_1.del, _f.fnm, _g_Asserting_0.frq, _e_Asserting_0.frq, _g_SimpleText_0.pst]
[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-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@192fd15),segFN=segments_h,generation=17,filenames=[segments_h]
[junit4:junit4]   2> 7664 T68 C18 oasc.SolrDeletionPolicy.updateCommits newest commit = 17
[junit4:junit4]   2> 7688 T68 C18 oass.SolrIndexSearcher.<init> Opening Searcher@15ce13a main
[junit4:junit4]   2> 7690 T68 C18 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 7691 T68 C18 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 7691 T78 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@15ce13a main{StandardDirectoryReader(segments_g:45:nrt)}
[junit4:junit4]   2> 7708 T68 C18 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 49
[junit4:junit4]   2> 7729 T68 C18 UPDATE [collection1] webapp=null path=null params={} {add=[E1 (1413025325526286336)]} 0 9
[junit4:junit4]   2> 7744 T68 C18 UPDATE [collection1] webapp=null path=null params={} {add=[E2 (1413025325549355008)]} 0 1
[junit4:junit4]   2> 7747 T68 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=17325566
[junit4:junit4]   2> 7749 T68 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@ea67a2
[junit4:junit4]   2> 7750 T68 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 7750 T68 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=8,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=2,adds=2,deletesById=0,deletesByQuery=0,errors=3,cumulative_adds=20,cumulative_deletesById=2,cumulative_deletesByQuery=5,cumulative_errors=3}
[junit4:junit4]   2> 7751 T68 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 7771 T68 C18 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-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@192fd15),segFN=segments_h,generation=17,filenames=[segments_h]
[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-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@192fd15),segFN=segments_i,generation=18,filenames=[_h.fnm, _h.fdt, _h.si, _h.fdx, _h_SimpleText_0.pst, _h_Asserting_0.frq, _h_Asserting_0.tim, segments_i, _h_Asserting_0.tip]
[junit4:junit4]   2> 7771 T68 C18 oasc.SolrDeletionPolicy.updateCommits newest commit = 18
[junit4:junit4]   2> 7794 T68 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 7794 T68 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> 7798 T68 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> 7801 T68 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> 7883 T68 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 7956 T68 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
[junit4:junit4]   2> 7957 T68 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 7964 T68 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 8676 T68 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 8682 T68 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 8701 T68 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 8702 T68 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> 8702 T68 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> 8718 T68 oasc.CoreContainer.<init> New CoreContainer 26306523
[junit4:junit4]   2> 8718 T68 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-1347565953668\
[junit4:junit4]   2> 8719 T68 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 8719 T68 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-1347565953668\index/
[junit4:junit4]   2> 8723 T68 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 8723 T68 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 8724 T68 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 8724 T68 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 8727 T68 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 8731 T68 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347565953668\index forceNew:false
[junit4:junit4]   2> 8737 T68 oass.SolrIndexSearcher.<init> Opening Searcher@f2146a main
[junit4:junit4]   2> 8737 T68 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 8743 T68 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 8743 T68 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 8745 T68 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 8745 T68 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 8745 T68 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 8746 T68 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 8746 T68 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 8746 T68 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 8746 T68 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 8746 T68 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 8746 T68 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 8747 T68 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 8760 T80 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@f2146a main{StandardDirectoryReader(segments_i:47 _h(4.0.0.2):C2)}
[junit4:junit4]   2> 8760 T68 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> ASYNC  NEW_CORE C19 name=collection1 org.apache.solr.core.SolrCore@18cb79
[junit4:junit4]   2> 8761 T68 C19 REQ [collection1] webapp=null path=null params={q=*:*} hits=2 status=0 QTime=0 
[junit4:junit4]   2> 8762 T68 oas.SolrTestCaseJ4.tearDown ###Ending testCleanShutdown
[junit4:junit4]   2> 8767 T68 oas.SolrTestCaseJ4.setUp ###Starting testVersionsOnRestart
[junit4:junit4]   2> 8776 T68 C19 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 8784 T68 C19 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@d59822),segFN=segments_i,generation=18,filenames=[_h.fnm, _h.fdt, _h.fdx, _h.si, _h_SimpleText_0.pst, _h_Asserting_0.tim, _h_Asserting_0.frq, segments_i, _h_Asserting_0.tip]
[junit4:junit4]   2> 8784 T68 C19 oasc.SolrDeletionPolicy.updateCommits newest commit = 18
[junit4:junit4]   2> 8785 T68 C19 oass.SolrIndexSearcher.<init> Opening Searcher@7f21fb realtime
[junit4:junit4]   2> 8785 T68 C19 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 8786 T68 C19 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> 8792 T68 C19 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 8796 T68 C19 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@d59822),segFN=segments_i,generation=18,filenames=[_h.fnm, _h.fdt, _h.fdx, _h.si, _h_SimpleText_0.pst, _h_Asserting_0.tim, _h_Asserting_0.frq, segments_i, _h_Asserting_0.tip]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@d59822),segFN=segments_j,generation=19,filenames=[segments_j]
[junit4:junit4]   2> 8796 T68 C19 oasc.SolrDeletionPolicy.updateCommits newest commit = 19
[junit4:junit4]   2> 8803 T68 C19 oass.SolrIndexSearcher.<init> Opening Searcher@16a00ab main
[junit4:junit4]   2> 8803 T68 C19 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 8804 T68 C19 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 8804 T80 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@16a00ab main{StandardDirectoryReader(segments_i:48:nrt)}
[junit4:junit4]   2> 8806 T68 C19 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 15
[junit4:junit4]   2> 8834 T68 C19 UPDATE [collection1] webapp=null path=null params={} {add=[D1 (1413025326687059968)]} 0 6
[junit4:junit4]   2> 8842 T68 C19 UPDATE [collection1] webapp=null path=null params={} {add=[D2 (1413025326701740032)]} 0 1
[junit4:junit4]   2> 8851 T68 C19 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 8872 T68 C19 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@d59822),segFN=segments_j,generation=19,filenames=[segments_j]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@d59822),segFN=segments_k,generation=20,filenames=[_i_Asserting_0.frq, _i_Asserting_0.tim, _i.fdx, segments_k, _i_SimpleText_0.pst, _i.fnm, _i.si, _i.fdt, _i_Asserting_0.tip]
[junit4:junit4]   2> 8872 T68 C19 oasc.SolrDeletionPolicy.updateCommits newest commit = 20
[junit4:junit4]   2> 8879 T68 C19 oass.SolrIndexSearcher.<init> Opening Searcher@3b0943 main
[junit4:junit4]   2> 8880 T68 C19 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 8881 T68 C19 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 8881 T80 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3b0943 main{StandardDirectoryReader(segments_k:50:nrt _i(4.0.0.2):C2)}
[junit4:junit4]   2> 8882 T68 C19 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 31
[junit4:junit4]   2> 8893 T68 C19 REQ [collection1] webapp=null path=null params={q=id:D1} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 8894 T68 C19 REQ [collection1] webapp=null path=null params={q=id:D2} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 8894 T68 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=26306523
[junit4:junit4]   2> 8895 T68 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@18cb79
[junit4:junit4]   2> 8897 T68 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 8897 T68 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=2,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=1,cumulative_adds=2,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=1}
[junit4:junit4]   2> 8897 T68 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 8913 T68 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 8914 T68 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> 8916 T68 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> 8918 T68 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> 9013 T68 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 9082 T68 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
[junit4:junit4]   2> 9083 T68 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 9089 T68 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 10047 T68 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 10053 T68 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 10080 T68 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 10080 T68 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> 10080 T68 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> 10101 T68 oasc.CoreContainer.<init> New CoreContainer 16391541
[junit4:junit4]   2> 10102 T68 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-1347565953668\
[junit4:junit4]   2> 10102 T68 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 10103 T68 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-1347565953668\index/
[junit4:junit4]   2> 10107 T68 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 10107 T68 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 10108 T68 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 10108 T68 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 10112 T68 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 10118 T68 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347565953668\index forceNew:false
[junit4:junit4]   2> 10125 T68 oass.SolrIndexSearcher.<init> Opening Searcher@e50395 main
[junit4:junit4]   2> 10126 T68 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 10134 T68 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 10135 T68 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 10137 T68 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 10137 T68 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 10137 T68 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 10138 T68 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 10139 T68 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 10139 T68 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 10139 T68 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 10139 T68 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 10140 T68 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 10140 T68 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 10156 T81 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@e50395 main{StandardDirectoryReader(segments_k:50 _i(4.0.0.2):C2)}
[junit4:junit4]   2> 10156 T68 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> ASYNC  NEW_CORE C20 name=collection1 org.apache.solr.core.SolrCore@150e390
[junit4:junit4]   2> 10175 T68 C20 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1264c24),segFN=segments_k,generation=20,filenames=[_i_Asserting_0.frq, _i_Asserting_0.tim, _i.fdx, segments_k, _i_SimpleText_0.pst, _i.fnm, _i.fdt, _i.si, _i_Asserting_0.tip]
[junit4:junit4]   2> 10176 T68 C20 oasc.SolrDeletionPolicy.updateCommits newest commit = 20
[junit4:junit4]   2> 10179 T68 C20 UPDATE [collection1] webapp=null path=null params={} {add=[D1 (1413025328090054656)]} 0 15
[junit4:junit4]   2> 10196 T68 C20 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 10215 T68 C20 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1264c24),segFN=segments_k,generation=20,filenames=[_i_Asserting_0.frq, _i_Asserting_0.tim, _i.fdx, segments_k, _i_SimpleText_0.pst, _i.fnm, _i.fdt, _i.si, _i_Asserting_0.tip]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1264c24),segFN=segments_l,generation=21,filenames=[_j.fdx, _j.si, _j_Asserting_0.tim, _j.fdt, _j_Asserting_0.tip, _j_Asserting_0.frq, segments_l, _i_SimpleText_0.pst, _i.si, _j_SimpleText_0.pst, _j.fnm, _i_Asserting_0.frq, _i_1.del, _i_Asserting_0.tim, _i.fdx, _i.fnm, _i.fdt, _i_Asserting_0.tip]
[junit4:junit4]   2> 10217 T68 C20 oasc.SolrDeletionPolicy.updateCommits newest commit = 21
[junit4:junit4]   2> 10227 T68 C20 oass.SolrIndexSearcher.<init> Opening Searcher@18afde8 main
[junit4:junit4]   2> 10228 T68 C20 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 10229 T81 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@18afde8 main{StandardDirectoryReader(segments_l:53 _i(4.0.0.2):C2/1 _j(4.0.0.2):C1)}
[junit4:junit4]   2> 10232 T68 C20 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 10232 T68 C20 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 36
[junit4:junit4]   2> 10242 T68 C20 REQ [collection1] webapp=null path=null params={q=id:D1} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 10246 T68 C20 REQ [collection1] webapp=null path=null params={getVersions=2&qt=/get} status=0 QTime=3 
[junit4:junit4]   2> 10246 T68 oas.SolrTestCaseJ4.tearDown ###Ending testVersionsOnRestart
[junit4:junit4]   2> 10252 T68 oas.SolrTestCaseJ4.setUp ###Starting testCorruptLog
[junit4:junit4]   2> 10263 T68 C20 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 10269 T68 C20 oass.SolrIndexSearcher.<init> Opening Searcher@127ae0 realtime
[junit4:junit4]   2> 10270 T68 C20 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 10270 T68 C20 REQ [collection1] webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true} status=0 QTime=9 
[junit4:junit4]   2> 10277 T68 C20 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 10280 T68 C20 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1264c24),segFN=segments_l,generation=21,filenames=[_j.fdx, _j.si, _j_Asserting_0.tim, _j.fdt, _j_Asserting_0.tip, _j_Asserting_0.frq, segments_l, _i_SimpleText_0.pst, _i.si, _j_SimpleText_0.pst, _j.fnm, _i_Asserting_0.frq, _i_1.del, _i_Asserting_0.tim, _i.fdx, _i.fnm, _i.fdt, _i_Asserting_0.tip]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\solrtest-TestRecovery-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1264c24),segFN=segments_m,generation=22,filenames=[segments_m]
[junit4:junit4]   2> 10280 T68 C20 oasc.SolrDeletionPolicy.updateCommits newest commit = 22
[junit4:junit4]   2> 10294 T68 C20 oass.SolrIndexSearcher.<init> Opening Searcher@1787cd6 main
[junit4:junit4]   2> 10294 T68 C20 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 10295 T68 C20 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 10296 T81 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1787cd6 main{StandardDirectoryReader(segments_l:54:nrt)}
[junit4:junit4]   2> 10299 T68 C20 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 23
[junit4:junit4]   2> 10316 T68 C20 UPDATE [collection1] webapp=null path=null params={} {add=[G1 (1413025328243146752)]} 0 4
[junit4:junit4]   2> 10332 T68 C20 UPDATE [collection1] webapp=null path=null params={} {add=[G2 (1413025328264118272)]} 0 1
[junit4:junit4]   2> 10343 T68 C20 UPDATE [collection1] webapp=null path=null params={} {add=[G3 (1413025328274604032)]} 0 1
[junit4:junit4]   2> 10346 T68 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=16391541
[junit4:junit4]   2> 10346 T68 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@150e390
[junit4:junit4]   2> 10347 T68 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 10359 T68 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> 10360 T68 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 10372 T68 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 10376 T68 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> 10381 T68 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> 10383 T68 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> 10477 T68 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 10564 T68 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
[junit4:junit4]   2> 10565 T68 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 10576 T68 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 11494 T68 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 11501 T68 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 11534 T68 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 11534 T68 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> 11536 T68 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> 11560 T68 oasc.CoreContainer.<init> New CoreContainer 6810166
[junit4:junit4]   2> 11561 T68 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-1347565953668\
[junit4:junit4]   2> 11561 T68 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 11563 T68 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-1347565953668\index/
[junit4:junit4]   2> 11567 T68 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 11567 T68 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 11569 T68 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 11569 T68 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 11574 T68 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 11582 T68 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-TestRecovery-1347565953668\index forceNew:false
[junit4:junit4]   2> 11584 T68 oass.SolrIndexSearcher.<init> Opening Searcher@2e0260 main
[junit4:junit4]   2> 11585 T68 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 11591 T68 oasc.SolrException.log Ignoring exception matching Failure to open existing log file
[junit4:junit4]   2> 11595 T68 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 11596 T68 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 11597 T68 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 11597 T68 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 11598 T68 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 11598 T68 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 11598 T68 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 11598 T68 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 11598 T68 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 11598 T68 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 11598 T68 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 11598 T68 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 11620 T82 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2e0260 main{StandardDirectoryReader(segments_m:54)}
[junit4:junit4]   2> 11620 T68 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> ASYNC  NEW_CORE C21 name=collection1 org.apache.solr.core.SolrCore@e5e447
[junit4:junit4]   2> 11626 T68 C21 REQ [collection1] webapp=null path=null params={q=*:*} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 11654 T68 C21 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-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@ac5ecd),segFN=segments_m,generation=22,filenames=[segments_m]
[junit4:junit4]   2> 11655 T68 C21 oasc.SolrDeletionPolicy.updateCommits newest commit = 22
[junit4:junit4]   2> 11656 T68 C21 oasu.DirectUpdateHandler2.addDoc Reordered DBQs detected.  Update=add{flags=0,_version_=104,id=G4} 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> 11670 T68 C21 oass.SolrIndexSearcher.<init> Opening Searcher@1c6056b realtime
[junit4:junit4]   2> 11671 T68 C21 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 11672 T68 C21 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=30 
[junit4:junit4]   2> 11678 T68 C21 oasu.DirectUpdateHandler2.addDoc Reordered DBQs detected.  Update=add{flags=0,_version_=105,id=G5} 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> 11692 T68 C21 oass.SolrIndexSearcher.<init> Opening Searcher@11c366d realtime
[junit4:junit4]   2> 11692 T68 C21 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 11692 T68 C21 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=14 
[junit4:junit4]   2> 11701 T68 C21 oasu.DirectUpdateHandler2.addDoc Reordered DBQs detected.  Update=add{flags=0,_version_=106,id=G6} 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> 11726 T68 C21 oass.SolrIndexSearcher.<init> Opening Searcher@13656ae realtime
[junit4:junit4]   2> 11727 T68 C21 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 11728 T68 C21 REQ [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true} status=0 QTime=27 
[junit4:junit4]   2> 11731 T68 C21 REQ [collection1] webapp=null path=null params={getVersions=3&qt=/get} status=0 QTime=2 
[junit4:junit4]   2> 11739 T68 C21 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 11746 T68 C21 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-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@ac5ecd),segFN=segments_m,generation=22,filenames=[segments_m]
[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-1347565953668\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@ac5ecd),segFN=segments_n,generation=23,filenames=[_k_Asserting_0.frq, _m_Asserting_0.tim, _l.fnm, _l_Asserting_0.frq, _k_Asserting_0.tip, _k.fdt, _l.si, _k_Asserting_0.tim, segments_n, _k.fdx, _k.fnm, _m_Asserting_0.tip, _m_SimpleText_0.pst, _l_SimpleText_0.pst, _m.si, _k_SimpleText_0.pst, _m.fdt, _m_Asserting_0.frq, _l.fdx, _l_Asserting_0.tip, _m.fdx, _l.fdt, _l_Asserting_0.tim, _m.fnm, _k.si]
[junit4:junit4]   2> 11748 T68 C21 oasc.SolrDeletionPolicy.updateCommits newest commit = 23
[junit4:junit4]   2> 11752 T68 C21 oass.SolrIndexSearcher.<init> Opening Searcher@8eb6c6 main
[junit4:junit4]   2> 11753 T68 C21 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 11755 T82 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@8eb6c6 main{StandardDirectoryReader(segments_m:60:nrt _k(4.0.0.2):C1 _l(4.0.0.2):C1 _m(4.0.0.2):C1)}
[junit4:junit4]   2> 11759 T68 C21 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 11759 T68 C21 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 21
[junit4:junit4]   2> 11786 T68 C21 REQ [collection1] webapp=null path=null params={q=*:*} hits=3 status=0 QTime=0 
[junit4:junit4]   2> 11788 T68 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=6810166
[junit4:junit4]   2> 11788 T68 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@e5e447
[junit4:junit4]   2> 11790 T68 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 11790 T68 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> 11792 T68 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 11948 T68 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 11973 T68 oas.SolrTestCaseJ4.tearDown ###Ending testCorruptLog
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestRecovery -Dtests.method=testCorruptLog -Dtests.seed=62AC97EF719F0566 -Dtests.slow=true -Dtests.locale=da_DK -Dtests.timezone=Europe/Luxembourg -Dtests.file.encoding=Cp1252
[junit4:junit4] FAILURE 1.83s | TestRecovery.testCorruptLog <<<
[junit4:junit4]    > Throwable #1: java.lang.AssertionError: expected:<0> but was:<1>
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([62AC97EF719F0566:9071233C8BA754BA]:0)
[junit4:junit4]    > 	at org.junit.Assert.fail(Assert.java:93)
[junit4:junit4]    > 	at org.junit.Assert.failNotEquals(Assert.java:647)
[junit4:junit4]    > 	at org.junit.Assert.assertEquals(Assert.java:128)
[junit4:junit4]    > 	at org.junit.Assert.assertEquals(Assert.java:472)
[junit4:junit4]    > 	at org.junit.Assert.assertEquals(Assert.java:456)
[junit4:junit4]    > 	at org.apache.solr.search.TestRecovery.deleteLogs(TestRecovery.java:1046)
[junit4:junit4]    > 	at org.apache.solr.search.TestRecovery.testCorruptLog(TestRecovery.java:920)
[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> 12079 T68 oas.SolrTestCaseJ4.setUp ###Starting testRemoveOldLogs
[junit4:junit4]   2> 12085 T68 oas.SolrTestCaseJ4.tearDown ###Ending testRemoveOldLogs
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestRecovery -Dtests.method=testRemoveOldLogs -Dtests.seed=62AC97EF719F0566 -Dtests.slow=true -Dtests.locale=da_DK -Dtests.timezone=Europe/Luxembourg -Dtests.file.encoding=Cp1252
[junit4:junit4] ERROR   0.03s | TestRecovery.testRemoveOldLogs <<<
[junit4:junit4]    > Throwable #1: java.lang.RuntimeException: java.lang.NullPointerException
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([62AC97EF719F0566:43305644B9A58C9E]:0)
[junit4:junit4]    > 	at org.apache.solr.search.TestRecovery.clearIndex(TestRecovery.java:80)
[junit4:junit4]    > 	at org.apache.solr.search.TestRecovery.testRemoveOldLogs(TestRecovery.java:709)
[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]    > Caused by: java.lang.NullPointerException
[junit4:junit4]    > 	at org.apache.solr.util.TestHarness.getCore(TestHarness.java:212)
[junit4:junit4]    > 	at org.apache.solr.SolrTestCaseJ4.updateJ(SolrTestCaseJ4.java:797)
[junit4:junit4]    > 	at org.apache.solr.SolrTestCaseJ4.deleteByQueryAndGetVersion(SolrTestCaseJ4.java:947)
[junit4:junit4]    > 	at org.apache.solr.search.TestRecovery.clearIndex(TestRecovery.java:78)
[junit4:junit4]    > 	... 41 more
[junit4:junit4]   2> 12105 T68 oas.SolrTestCaseJ4.setUp ###Starting testBufferingFlags
[junit4:junit4]   2> 12108 T68 oas.SolrTestCaseJ4.tearDown ###Ending testBufferingFlags
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestRecovery -Dtests.method=testBufferingFlags -Dtests.seed=62AC97EF719F0566 -Dtests.slow=true -Dtests.locale=da_DK -Dtests.timezone=Europe/Luxembourg -Dtests.file.encoding=Cp1252
[junit4:junit4] ERROR   0.02s | TestRecovery.testBufferingFlags <<<
[junit4:junit4]    > Throwable #1: java.lang.NullPointerException
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([62AC97EF719F0566:E356F6A2C6D76B9E]: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.testBufferingFlags(TestRecovery.java:503)
[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> 12118 T68 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-1347565953668\tlog\tlog.0000000000000000025 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-1347565953668\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-1347565953668 FAILED !!!!!
[junit4:junit4]   2> NOTE: test params are: codec=Lucene40: {id=PostingsFormat(name=SimpleText), _version_=PostingsFormat(name=Asserting), val_i=PostingsFormat(name=SimpleText)}, sim=RandomSimilarityProvider(queryNorm=true,coord=crazy): {}, locale=da_DK, timezone=Europe/Luxembourg
[junit4:junit4]   2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.7.0_07 (32-bit)/cpus=2,threads=1,free=16147320,total=37912576
[junit4:junit4]   2> NOTE: All tests run in this JVM: [PingRequestHandlerTest, TestWriterPerf, OpenExchangeRatesOrgProviderTest, AutoCommitTest, IndexBasedSpellCheckerTest, TestSolrCoreProperties, TermVectorComponentTest, JsonLoaderTest, DocumentBuilderTest, TestFoldingMultitermQuery, TestTrie, SpellPossibilityIteratorTest, HighlighterTest, PreAnalyzedFieldTest, TestCharFilters, TestRecovery]
[junit4:junit4] Completed in 12.22s, 10 tests, 1 failure, 2 errors <<< FAILURES!

[...truncated 654 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, 2 errors, 1 failure, 11 ignored (6 assumptions)

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



Mime
View raw message