[JENKINS] Lucene-Solr-trunk-Windows (64bit/jdk1.8.0_72) - Build # 5604 - Still Failing!

[Policeman Jenkins Server]

Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/5604/
Java: 64bit/jdk1.8.0_72 -XX:+UseCompressedOops -XX:+UseSerialGC

2 tests failed.
FAILED:  org.apache.lucene.codecs.lucene53.TestLucene53NormsFormat.testSparse

Error Message:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\core\test\J0\temp\lucene.codecs.lucene53.TestLucene53NormsFormat_3BBE714C9113ACE6-001\index-SimpleFSDirectory-001\_a_Lucene50_0.doc

Stack Trace:
java.nio.file.NoSuchFileException: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\core\test\J0\temp\lucene.codecs.lucene53.TestLucene53NormsFormat_3BBE714C9113ACE6-001\index-SimpleFSDirectory-001\_a_Lucene50_0.doc
        at 
__randomizedtesting.SeedInfo.seed([3BBE714C9113ACE6:B8D05682AE8F45C9]:0)
        at 
sun.nio.fs.WindowsException.translateToIOException(WindowsException.java:79)
        at 
sun.nio.fs.WindowsException.rethrowAsIOException(WindowsException.java:97)
        at 
sun.nio.fs.WindowsException.rethrowAsIOException(WindowsException.java:102)
        at 
sun.nio.fs.WindowsFileSystemProvider.implDelete(WindowsFileSystemProvider.java:269)
        at 
sun.nio.fs.AbstractFileSystemProvider.delete(AbstractFileSystemProvider.java:103)
        at 
org.apache.lucene.mockfile.FilterFileSystemProvider.delete(FilterFileSystemProvider.java:137)
        at 
org.apache.lucene.mockfile.FilterFileSystemProvider.delete(FilterFileSystemProvider.java:137)
        at 
org.apache.lucene.mockfile.FilterFileSystemProvider.delete(FilterFileSystemProvider.java:137)
        at 
org.apache.lucene.mockfile.FilterFileSystemProvider.delete(FilterFileSystemProvider.java:137)
        at 
org.apache.lucene.mockfile.FilterFileSystemProvider.delete(FilterFileSystemProvider.java:137)
        at java.nio.file.Files.delete(Files.java:1126)
        at 
org.apache.lucene.store.FSDirectory.privateDeleteFile(FSDirectory.java:368)
        at 
org.apache.lucene.store.FSDirectory.deletePendingFiles(FSDirectory.java:350)
        at org.apache.lucene.store.FSDirectory.close(FSDirectory.java:307)
        at org.apache.lucene.util.IOUtils.close(IOUtils.java:89)
        at org.apache.lucene.util.IOUtils.close(IOUtils.java:76)
        at 
org.apache.lucene.store.MockDirectoryWrapper.close(MockDirectoryWrapper.java:805)
        at 
org.apache.lucene.index.BaseNormsFormatTestCase.doTestNormsVersusDocValues(BaseNormsFormatTestCase.java:302)
        at 
org.apache.lucene.index.BaseNormsFormatTestCase.testSparse(BaseNormsFormatTestCase.java:147)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
        at java.lang.Thread.run(Thread.java:745)


FAILED:  org.apache.lucene.store.TestNativeFSLockFactory.testStressLocks

Error Message:
IndexWriter hit unexpected exceptions

Stack Trace:
java.lang.AssertionError: IndexWriter hit unexpected exceptions
        at 
__randomizedtesting.SeedInfo.seed([3BBE714C9113ACE6:658F3FB18DBF6480]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.assertTrue(Assert.java:43)
        at 
org.apache.lucene.store.BaseLockFactoryTestCase.testStressLocks(BaseLockFactoryTestCase.java:180)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
        at java.lang.Thread.run(Thread.java:745)




Build Log:
[...truncated 1359 lines...]
   [junit4] Suite: org.apache.lucene.codecs.lucene53.TestLucene53NormsFormat
   [junit4] IGNOR/A 0.00s J0 | TestLucene53NormsFormat.testNCommonBig
   [junit4]    > Assumption #1: 'nightly' test group is disabled (@Nightly())
   [junit4] IGNOR/A 0.00s J0 | TestLucene53NormsFormat.testMergeStability
   [junit4]    > Assumption #1: The MockRandom PF randomizes content on the 
fly, so we can't check it
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestLucene53NormsFormat -Dtests.method=testSparse 
-Dtests.seed=3BBE714C9113ACE6 -Dtests.slow=true -Dtests.locale=sr-Latn-ME 
-Dtests.timezone=EAT -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
   [junit4] ERROR   0.98s J0 | TestLucene53NormsFormat.testSparse <<<
   [junit4]    > Throwable #1: java.nio.file.NoSuchFileException: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\core\test\J0\temp\lucene.codecs.lucene53.TestLucene53NormsFormat_3BBE714C9113ACE6-001\index-SimpleFSDirectory-001\_a_Lucene50_0.doc
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([3BBE714C9113ACE6:B8D05682AE8F45C9]:0)
   [junit4]    >        at 
sun.nio.fs.WindowsException.translateToIOException(WindowsException.java:79)
   [junit4]    >        at 
sun.nio.fs.WindowsException.rethrowAsIOException(WindowsException.java:97)
   [junit4]    >        at 
sun.nio.fs.WindowsException.rethrowAsIOException(WindowsException.java:102)
   [junit4]    >        at 
sun.nio.fs.WindowsFileSystemProvider.implDelete(WindowsFileSystemProvider.java:269)
   [junit4]    >        at 
sun.nio.fs.AbstractFileSystemProvider.delete(AbstractFileSystemProvider.java:103)
   [junit4]    >        at 
org.apache.lucene.mockfile.FilterFileSystemProvider.delete(FilterFileSystemProvider.java:137)
   [junit4]    >        at 
org.apache.lucene.mockfile.FilterFileSystemProvider.delete(FilterFileSystemProvider.java:137)
   [junit4]    >        at 
org.apache.lucene.mockfile.FilterFileSystemProvider.delete(FilterFileSystemProvider.java:137)
   [junit4]    >        at 
org.apache.lucene.mockfile.FilterFileSystemProvider.delete(FilterFileSystemProvider.java:137)
   [junit4]    >        at 
org.apache.lucene.mockfile.FilterFileSystemProvider.delete(FilterFileSystemProvider.java:137)
   [junit4]    >        at java.nio.file.Files.delete(Files.java:1126)
   [junit4]    >        at 
org.apache.lucene.store.FSDirectory.privateDeleteFile(FSDirectory.java:368)
   [junit4]    >        at 
org.apache.lucene.store.FSDirectory.deletePendingFiles(FSDirectory.java:350)
   [junit4]    >        at 
org.apache.lucene.store.FSDirectory.close(FSDirectory.java:307)
   [junit4]    >        at org.apache.lucene.util.IOUtils.close(IOUtils.java:89)
   [junit4]    >        at org.apache.lucene.util.IOUtils.close(IOUtils.java:76)
   [junit4]    >        at 
org.apache.lucene.store.MockDirectoryWrapper.close(MockDirectoryWrapper.java:805)
   [junit4]    >        at 
org.apache.lucene.index.BaseNormsFormatTestCase.doTestNormsVersusDocValues(BaseNormsFormatTestCase.java:302)
   [junit4]    >        at 
org.apache.lucene.index.BaseNormsFormatTestCase.testSparse(BaseNormsFormatTestCase.java:147)
   [junit4]    >        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> NOTE: leaving temporary files on disk at: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\core\test\J0\temp\lucene.codecs.lucene53.TestLucene53NormsFormat_3BBE714C9113ACE6-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene60): {}, 
docValues:{}, sim=ClassicSimilarity, locale=sr-Latn-ME, timezone=EAT
   [junit4]   2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.8.0_72 
(64-bit)/cpus=3,threads=1,free=174382672,total=338141184
   [junit4]   2> NOTE: All tests run in this JVM: [TestLazyProxSkipping, 
TestRoaringDocIdSet, MultiCollectorTest, TestSloppyMath, TestMmapDirectory, 
TestNeedsScores, TestNamedSPILoader, TestMultiPhraseEnum, TestIndexCommit, 
TestSimpleSearchEquivalence, TestShardSearching, TestPhrasePrefixQuery, 
TestReadOnlyIndex, TestTimSorter, TestField, TestInfoStream, 
TestElevationComparator, TestMultiFields, TestBytesStore, TestTermsEnum2, 
TestBagOfPostings, TestForUtil, TestSingleInstanceLockFactory, TestStressNRT, 
TestNorms, TestIndexWriterWithThreads, TestLucene54DocValuesFormat, 
TestMinShouldMatch2, TestCollectionUtil, TestIndexWriterMaxDocs, 
TestBytesRefHash, TestRAMDirectory, TestSloppyPhraseQuery2, 
Test2BPostingsBytes, TestMultiDocValues, TestBooleanQuery, 
TestTwoPhaseCommitTool, TestDirectory, TestNRTReaderWithThreads, TestIntsRef, 
TestPerFieldPostingsFormat2, TestRecyclingByteBlockAllocator, 
TestSwappedIndexFiles, TestIsCurrent, TestSortedNumericSortField, TestBasics, 
TestSmallFloat, TestBooleanRewrites, TestIndexWriterConfig, 
TestAllFilesDetectTruncation, TestDirectPacked, TestOfflineSorter, 
TestSimpleFSDirectory, TestDirectoryReaderReopen, TestQueryCachingPolicy, 
TestCrashCausesCorruptIndex, TestIndexWriterDelete, TestSameTokenSamePosition, 
TestTerms, TestExternalCodecs, TestSegmentInfos, TestTermdocPerf, 
TestRamUsageEstimator, TestSpanMultiTermQueryWrapper, TestWindowsMMap, 
TestLucene50StoredFieldsFormat, TestSpanTermQuery, TestMergedIterator, 
TestIndexWriterExceptions, TestFlushByRamOrCountsPolicy, TestFilterIterator, 
TestSegmentTermEnum, TestPerFieldPostingsFormat, TestIndexWriterOnDiskFull, 
TestDuelingCodecs, TestCharFilter, TestFieldReuse, Test2BPagedBytes, 
TestDemoParallelLeafReader, TestClassicSimilarity, TestRollingUpdates, 
TestBytesRef, TestPostingsOffsets, TestAutomaton, Test2BNumericDocValues, 
TestManyFields, TestNumericTokenStream, TestPackedTokenAttributeImpl, 
TestAllFilesHaveCodecHeader, TestSubScorerFreqs, TestMutableValues, 
Test2BSortedDocValuesOrds, TestTrackingDirectoryWrapper, TestReqExclBulkScorer, 
TestParallelReaderEmptyIndex, TestNGramPhraseQuery, TestAtomicUpdate, 
TestVersion, TestFlex, TestRegexpQuery, TestDoc, TestPrefixInBooleanQuery, 
TestScorerPerf, TestIndexWriterReader, TestFSTs, TestSearcherManager, 
TestDeletionPolicy, TestBoolean2, TestTimeLimitingCollector, TestNeverDelete, 
TestMinimize, TestIndexWriterForceMerge, TestByteSlices, 
TestBufferedIndexInput, TestTermsEnum, TestComplexExplanations, 
TestRegexpRandom2, TestParallelCompositeReader, TestFieldCacheRewriteMethod, 
TestPagedBytes, TestTransactions, TestToken, TestCustomSearcherSort, 
TestSimilarityBase, TestBooleanMinShouldMatch, TestSpans, TestUTF32ToUTF8, 
TestDocsAndPositions, TestTermVectorsWriter, TestCharTermAttributeImpl, 
TestThreadedForceMerge, TestForceMergeForever, TestTermRangeQuery, 
TestLiveFieldValues, TestFuzzyQuery, TestWildcard, TestNoDeletionPolicy, 
TestPerSegmentDeletes, TestIntBlockPool, TestUniqueTermCount, TestUnicodeUtil, 
TestPrefixCodedTerms, TestTermScorer, TestSumDocFreq, TestDocValuesScoring, 
TestMultiLevelSkipList, TestCheckIndex, TestPayloadsOnVectors, 
TestSizeBoundedForceMerge, TestCompiledAutomaton, TestConstantScoreQuery, 
TestPrefixQuery, TestBinaryTerms, TestSimilarity, TestVirtualMethod, 
TestSimilarityProvider, TestSearch, TestDateTools, TestPriorityQueue, 
TestBinaryDocument, TestTotalHitCountCollector, TestNoMergePolicy, 
TestNoMergeScheduler, TestIndexWriterLockRelease, TestAssertions, TestNot, 
Test4GBStoredFields, TestScoreCachingWrappingScorer, Test2BPositions, 
TestIOUtils, Test2BBinaryDocValues, TestBlockPostingsFormat2, 
TestLucene50CompoundFormat, TestLucene53NormsFormat]
   [junit4] Completed [349/412 (1!)] on J0 in 1.82s, 17 tests, 1 error, 2 
skipped <<< FAILURES!

[...truncated 150 lines...]
   [junit4] Suite: org.apache.lucene.store.TestNativeFSLockFactory
   [junit4]   1> Stress Test Index Writer: close hit unexpected exception: 
java.nio.file.NoSuchFileException: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\core\test\J1\temp\lucene.store.TestNativeFSLockFactory_3BBE714C9113ACE6-001\tempDir-005\_7.cfe
   [junit4]   1> java.nio.file.NoSuchFileException: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\core\test\J1\temp\lucene.store.TestNativeFSLockFactory_3BBE714C9113ACE6-001\tempDir-005\_7.cfe
   [junit4]   1>        at 
sun.nio.fs.WindowsException.translateToIOException(WindowsException.java:79)
   [junit4]   1>        at 
sun.nio.fs.WindowsException.rethrowAsIOException(WindowsException.java:97)
   [junit4]   1>        at 
sun.nio.fs.WindowsException.rethrowAsIOException(WindowsException.java:102)
   [junit4]   1>        at 
sun.nio.fs.WindowsFileSystemProvider.implDelete(WindowsFileSystemProvider.java:269)
   [junit4]   1>        at 
sun.nio.fs.AbstractFileSystemProvider.delete(AbstractFileSystemProvider.java:103)
   [junit4]   1>        at 
org.apache.lucene.mockfile.FilterFileSystemProvider.delete(FilterFileSystemProvider.java:137)
   [junit4]   1>        at 
org.apache.lucene.mockfile.FilterFileSystemProvider.delete(FilterFileSystemProvider.java:137)
   [junit4]   1>        at 
org.apache.lucene.mockfile.FilterFileSystemProvider.delete(FilterFileSystemProvider.java:137)
   [junit4]   1>        at 
org.apache.lucene.mockfile.FilterFileSystemProvider.delete(FilterFileSystemProvider.java:137)
   [junit4]   1>        at 
org.apache.lucene.mockfile.FilterFileSystemProvider.delete(FilterFileSystemProvider.java:137)
   [junit4]   1>        at java.nio.file.Files.delete(Files.java:1126)
   [junit4]   1>        at 
org.apache.lucene.store.FSDirectory.privateDeleteFile(FSDirectory.java:368)
   [junit4]   1>        at 
org.apache.lucene.store.FSDirectory.deletePendingFiles(FSDirectory.java:350)
   [junit4]   1>        at 
org.apache.lucene.store.FSDirectory.maybeDeletePendingFiles(FSDirectory.java:361)
   [junit4]   1>        at 
org.apache.lucene.store.FSDirectory.createOutput(FSDirectory.java:252)
   [junit4]   1>        at 
org.apache.lucene.store.MockDirectoryWrapper.createOutput(MockDirectoryWrapper.java:538)
   [junit4]   1>        at 
org.apache.lucene.store.LockValidatingDirectoryWrapper.createOutput(LockValidatingDirectoryWrapper.java:44)
   [junit4]   1>        at 
org.apache.lucene.store.TrackingDirectoryWrapper.createOutput(TrackingDirectoryWrapper.java:43)
   [junit4]   1>        at 
org.apache.lucene.codecs.lucene50.Lucene50PostingsWriter.<init>(Lucene50PostingsWriter.java:105)
   [junit4]   1>        at 
org.apache.lucene.codecs.lucene50.Lucene50PostingsFormat.fieldsConsumer(Lucene50PostingsFormat.java:424)
   [junit4]   1>        at 
org.apache.lucene.codecs.asserting.AssertingPostingsFormat.fieldsConsumer(AssertingPostingsFormat.java:53)
   [junit4]   1>        at 
org.apache.lucene.codecs.perfield.PerFieldPostingsFormat$FieldsWriter.write(PerFieldPostingsFormat.java:196)
   [junit4]   1>        at 
org.apache.lucene.index.FreqProxTermsWriter.flush(FreqProxTermsWriter.java:107)
   [junit4]   1>        at 
org.apache.lucene.index.DefaultIndexingChain.flush(DefaultIndexingChain.java:134)
   [junit4]   1>        at 
org.apache.lucene.index.DocumentsWriterPerThread.flush(DocumentsWriterPerThread.java:425)
   [junit4]   1>        at 
org.apache.lucene.index.DocumentsWriter.doFlush(DocumentsWriter.java:502)
   [junit4]   1>        at 
org.apache.lucene.index.DocumentsWriter.flushAllThreads(DocumentsWriter.java:614)
   [junit4]   1>        at 
org.apache.lucene.index.IndexWriter.doFlush(IndexWriter.java:3104)
   [junit4]   1>        at 
org.apache.lucene.index.IndexWriter.flush(IndexWriter.java:3079)
   [junit4]   1>        at 
org.apache.lucene.index.IndexWriter.shutdown(IndexWriter.java:1071)
   [junit4]   1>        at 
org.apache.lucene.index.IndexWriter.close(IndexWriter.java:1116)
   [junit4]   1>        at 
org.apache.lucene.store.BaseLockFactoryTestCase$WriterThread.run(BaseLockFactoryTestCase.java:268)
   [junit4]   1> 
   [junit4]   1> TEST: WriterThread iter=0
   [junit4]   1> IFD 102 [2016-02-07T16:44:44.293Z; Thread-1204]: init: current 
segments file is "segments_1"; 
deletionPolicy=org.apache.lucene.index.KeepOnlyLastCommitDeletionPolicy@74088d32
   [junit4]   1> IFD 102 [2016-02-07T16:44:44.293Z; Thread-1204]: init: load 
commit "segments_1"
   [junit4]   1> IFD 102 [2016-02-07T16:44:44.294Z; Thread-1204]: delete "[]"
   [junit4]   1> IFD 102 [2016-02-07T16:44:44.294Z; Thread-1204]: now 
checkpoint "_0(6.0.0):c1" [1 segments ; isCommit = false]
   [junit4]   1> IFD 102 [2016-02-07T16:44:44.294Z; Thread-1204]: delete "[]"
   [junit4]   1> IFD 102 [2016-02-07T16:44:44.294Z; Thread-1204]: 0 msec to 
checkpoint
   [junit4]   1> IW 102 [2016-02-07T16:44:44.294Z; Thread-1204]: init: 
create=false
   [junit4]   1> IW 102 [2016-02-07T16:44:44.294Z; Thread-1204]: 
   [junit4]   1> 
dir=MockDirectoryWrapper(NIOFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\core\test\J1\temp\lucene.store.TestNativeFSLockFactory_3BBE714C9113ACE6-001\tempDir-005
 lockFactory=org.apache.lucene.store.NativeFSLockFactory@5a8347e2)
   [junit4]   1> index=_0(6.0.0):c1
   [junit4]   1> version=6.0.0
   [junit4]   1> analyzer=org.apache.lucene.analysis.MockAnalyzer
   [junit4]   1> ramBufferSizeMB=16.0
   [junit4]   1> maxBufferedDocs=-1
   [junit4]   1> maxBufferedDeleteTerms=-1
   [junit4]   1> mergedSegmentWarmer=null
   [junit4]   1> 
delPolicy=org.apache.lucene.index.KeepOnlyLastCommitDeletionPolicy
   [junit4]   1> commit=null
   [junit4]   1> openMode=APPEND
   [junit4]   1> similarity=org.apache.lucene.search.similarities.BM25Similarity
   [junit4]   1> mergeScheduler=ConcurrentMergeScheduler: maxThreadCount=-1, 
maxMergeCount=-1, ioThrottle=true
   [junit4]   1> codec=Asserting(Lucene60): 
{content=PostingsFormat(name=Asserting)}, docValues:{}
   [junit4]   1> infoStream=org.apache.lucene.util.PrintStreamInfoStream
   [junit4]   1> mergePolicy=[TieredMergePolicy: maxMergeAtOnce=10, 
maxMergeAtOnceExplicit=30, maxMergedSegmentMB=5120.0, floorSegmentMB=2.0, 
forceMergeDeletesPctAllowed=10.0, segmentsPerTier=10.0, 
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1
   [junit4]   1> 
indexerThreadPool=org.apache.lucene.index.DocumentsWriterPerThreadPool@1feb60c9
   [junit4]   1> readerPooling=false
   [junit4]   1> perThreadHardLimitMB=1945
   [junit4]   1> useCompoundFile=true
   [junit4]   1> commitOnClose=true
   [junit4]   1> writer=org.apache.lucene.index.IndexWriter@77d61e49
   [junit4]   1> 
   [junit4]   1> IW 102 [2016-02-07T16:44:44.294Z; Thread-1204]: 
MMapDirectory.UNMAP_SUPPORTED=true
   [junit4]   1> IW 102 [2016-02-07T16:44:44.296Z; Thread-1204]: now flush at 
close
   [junit4]   1> IW 102 [2016-02-07T16:44:44.296Z; Thread-1204]:   start flush: 
applyAllDeletes=true
   [junit4]   1> IW 102 [2016-02-07T16:44:44.296Z; Thread-1204]:   index before 
flush _0(6.0.0):c1
   [junit4]   1> DW 102 [2016-02-07T16:44:44.296Z; Thread-1204]: startFullFlush
   [junit4]   1> DW 102 [2016-02-07T16:44:44.296Z; Thread-1204]: anyChanges? 
numDocsInRam=1 deletes=false hasTickets:false pendingChangesInFullFlush: false
   [junit4]   1> DWFC 102 [2016-02-07T16:44:44.296Z; Thread-1204]: 
addFlushableState DocumentsWriterPerThread [pendingDeletes=gen=0, segment=_1, 
aborted=false, numDocsInRAM=1, deleteQueue=DWDQ: [ generation: 0 ]]
   [junit4]   1> DWPT 102 [2016-02-07T16:44:44.296Z; Thread-1204]: flush 
postings as segment _1 numDocs=1
   [junit4]   1> IW 102 [2016-02-07T16:44:44.298Z; Thread-1204]: 1 msec to 
write norms
   [junit4]   1> IW 102 [2016-02-07T16:44:44.298Z; Thread-1204]: 0 msec to 
write docValues
   [junit4]   1> IW 102 [2016-02-07T16:44:44.298Z; Thread-1204]: 0 msec to 
write points
   [junit4]   1> IW 102 [2016-02-07T16:44:44.299Z; Thread-1204]: 0 msec to 
finish stored fields
   [junit4]   1> IW 102 [2016-02-07T16:44:44.302Z; Thread-1204]: 4 msec to 
write postings and finish vectors
   [junit4]   1> IW 102 [2016-02-07T16:44:44.302Z; Thread-1204]: 0 msec to 
write fieldInfos
   [junit4]   1> DWPT 102 [2016-02-07T16:44:44.302Z; Thread-1204]: new segment 
has 0 deleted docs
   [junit4]   1> DWPT 102 [2016-02-07T16:44:44.302Z; Thread-1204]: new segment 
has no vectors; norms; no docValues; prox; freqs
   [junit4]   1> DWPT 102 [2016-02-07T16:44:44.302Z; Thread-1204]: 
flushedFiles=[_1_Asserting_0.tip, _1.nvd, _1_Asserting_0.doc, 
_1_Asserting_0.tim, _1_Asserting_0.pos, _1.fdx, _1.nvm, _1.fnm, _1.fdt]
   [junit4]   1> DWPT 102 [2016-02-07T16:44:44.302Z; Thread-1204]: flushed 
codec=Asserting(Lucene60): {content=PostingsFormat(name=Asserting)}, 
docValues:{}
   [junit4]   1> DWPT 102 [2016-02-07T16:44:44.302Z; Thread-1204]: flushed: 
segment=_1 ramUsed=0.071 MB newFlushedSize=0.001 MB docs/MB=1,132.371
   [junit4]   1> IW 102 [2016-02-07T16:44:44.302Z; Thread-1204]: create 
compound file
   [junit4]   1> DWPT 102 [2016-02-07T16:44:44.305Z; Thread-1204]: flush time 
9.8003 msec
   [junit4]   1> DW 102 [2016-02-07T16:44:44.305Z; Thread-1204]: 
publishFlushedSegment seg-private updates=null
   [junit4]   1> IW 102 [2016-02-07T16:44:44.305Z; Thread-1204]: 
publishFlushedSegment
   [junit4]   1> IW 102 [2016-02-07T16:44:44.305Z; Thread-1204]: publish sets 
newSegment delGen=1 seg=_1(6.0.0):c1
   [junit4]   1> IFD 102 [2016-02-07T16:44:44.305Z; Thread-1204]: now 
checkpoint "_0(6.0.0):c1 _1(6.0.0):c1" [2 segments ; isCommit = false]
   [junit4]   1> IFD 102 [2016-02-07T16:44:44.305Z; Thread-1204]: delete "[]"
   [junit4]   1> IFD 102 [2016-02-07T16:44:44.305Z; Thread-1204]: 0 msec to 
checkpoint
   [junit4]   1> DW 102 [2016-02-07T16:44:44.305Z; Thread-1204]: Thread-1204 
finishFullFlush success=true
   [junit4]   1> IFD 102 [2016-02-07T16:44:44.305Z; Thread-1204]: will delete 
new file "_1_Asserting_0.tip"
   [junit4]   1> IFD 102 [2016-02-07T16:44:44.305Z; Thread-1204]: will delete 
new file "_1.nvd"
   [junit4]   1> IFD 102 [2016-02-07T16:44:44.305Z; Thread-1204]: will delete 
new file "_1_Asserting_0.doc"
   [junit4]   1> IFD 102 [2016-02-07T16:44:44.305Z; Thread-1204]: will delete 
new file "_1_Asserting_0.tim"
   [junit4]   1> IFD 102 [2016-02-07T16:44:44.305Z; Thread-1204]: will delete 
new file "_1_Asserting_0.pos"
   [junit4]   1> IFD 102 [2016-02-07T16:44:44.305Z; Thread-1204]: will delete 
new file "_1.fdx"
   [junit4]   1> IFD 102 [2016-02-07T16:44:44.305Z; Thread-1204]: will delete 
new file "_1.nvm"
   [junit4]   1> IFD 102 [2016-02-07T16:44:44.305Z; Thread-1204]: will delete 
new file "_1.fnm"
   [junit4]   1> IFD 102 [2016-02-07T16:44:44.305Z; Thread-1204]: will delete 
new file "_1.fdt"
   [junit4]   1> IFD 102 [2016-02-07T16:44:44.305Z; Thread-1204]: delete 
"[_1_Asserting_0.tip, _1.nvd, _1_Asserting_0.doc, _1_Asserting_0.tim, 
_1_Asserting_0.pos, _1.fdx, _1.nvm, _1.fnm, _1.fdt]"
   [junit4]   1> IW 102 [2016-02-07T16:44:44.306Z; Thread-1204]: apply all 
deletes during flush
   [junit4]   1> IW 102 [2016-02-07T16:44:44.306Z; Thread-1204]: now apply all 
deletes for all segments maxDoc=2
   [junit4]   1> BD 102 [2016-02-07T16:44:44.306Z; Thread-1204]: applyDeletes: 
open segment readers took 0 msec
   [junit4]   1> BD 102 [2016-02-07T16:44:44.306Z; Thread-1204]: applyDeletes: 
no segments; skipping
   [junit4]   1> BD 102 [2016-02-07T16:44:44.306Z; Thread-1204]: prune 
sis=segments_1: _0(6.0.0):c1 _1(6.0.0):c1 minGen=0 packetCount=0
   [junit4]   1> TMP 102 [2016-02-07T16:44:44.306Z; Thread-1204]: findMerges: 2 
segments
   [junit4]   1> TMP 102 [2016-02-07T16:44:44.306Z; Thread-1204]:   
seg=_0(6.0.0):c1 size=0.001 MB [floored]
   [junit4]   1> TMP 102 [2016-02-07T16:44:44.306Z; Thread-1204]:   
seg=_1(6.0.0):c1 size=0.001 MB [floored]
   [junit4]   1> TMP 102 [2016-02-07T16:44:44.306Z; Thread-1204]:   
allowedSegmentCount=1 vs count=2 (eligible count=2) tooBigCount=0
   [junit4]   1> MS 102 [2016-02-07T16:44:44.306Z; Thread-1204]: 
initDynamicDefaults spins=false maxThreadCount=1 maxMergeCount=6
   [junit4]   1> MS 102 [2016-02-07T16:44:44.306Z; Thread-1204]: now merge
   [junit4]   1> MS 102 [2016-02-07T16:44:44.306Z; Thread-1204]:   index: 
_0(6.0.0):c1 _1(6.0.0):c1
   [junit4]   1> MS 102 [2016-02-07T16:44:44.306Z; Thread-1204]:   no more 
merges pending; now return
   [junit4]   1> MS 102 [2016-02-07T16:44:44.307Z; Thread-1204]: 
updateMergeThreads ioThrottle=true targetMBPerSec=10240.0 MB/sec
   [junit4]   1> MS 102 [2016-02-07T16:44:44.307Z; Thread-1204]: now merge
   [junit4]   1> MS 102 [2016-02-07T16:44:44.307Z; Thread-1204]:   index: 
_0(6.0.0):c1 _1(6.0.0):c1
   [junit4]   1> MS 102 [2016-02-07T16:44:44.307Z; Thread-1204]:   no more 
merges pending; now return
   [junit4]   1> IW 102 [2016-02-07T16:44:44.307Z; Thread-1204]: waitForMerges
   [junit4]   1> IW 102 [2016-02-07T16:44:44.307Z; Thread-1204]: waitForMerges 
done
   [junit4]   1> IW 102 [2016-02-07T16:44:44.307Z; Thread-1204]: commit: start
   [junit4]   1> IW 102 [2016-02-07T16:44:44.307Z; Thread-1204]: commit: enter 
lock
   [junit4]   1> IW 102 [2016-02-07T16:44:44.307Z; Thread-1204]: commit: now 
prepare
   [junit4]   1> IW 102 [2016-02-07T16:44:44.307Z; Thread-1204]: prepareCommit: 
flush
   [junit4]   1> IW 102 [2016-02-07T16:44:44.307Z; Thread-1204]:   index before 
flush _0(6.0.0):c1 _1(6.0.0):c1
   [junit4]   1> DW 102 [2016-02-07T16:44:44.307Z; Thread-1204]: startFullFlush
   [junit4]   1> IW 102 [2016-02-07T16:44:44.307Z; Thread-1204]: apply all 
deletes during flush
   [junit4]   1> IW 102 [2016-02-07T16:44:44.307Z; Thread-1204]: now apply all 
deletes for all segments maxDoc=2
   [junit4]   1> BD 102 [2016-02-07T16:44:44.307Z; Thread-1204]: applyDeletes: 
open segment readers took 0 msec
   [junit4]   1> BD 102 [2016-02-07T16:44:44.307Z; Thread-1204]: applyDeletes: 
no segments; skipping
   [junit4]   1> BD 102 [2016-02-07T16:44:44.307Z; Thread-1204]: prune 
sis=segments_1: _0(6.0.0):c1 _1(6.0.0):c1 minGen=0 packetCount=0
   [junit4]   1> DW 102 [2016-02-07T16:44:44.307Z; Thread-1204]: Thread-1204 
finishFullFlush success=true
   [junit4]   1> IW 102 [2016-02-07T16:44:44.307Z; Thread-1204]: startCommit(): 
start
   [junit4]   1> IW 102 [2016-02-07T16:44:44.307Z; Thread-1204]: startCommit 
index=_0(6.0.0):c1 _1(6.0.0):c1 changeCount=3
   [junit4]   1> IW 102 [2016-02-07T16:44:44.308Z; Thread-1204]: startCommit: 
wrote pending segments file "pending_segments_2"
   [junit4]   1> IW 102 [2016-02-07T16:44:44.309Z; Thread-1204]: done all 
syncs: [_1.cfs, _0.cfe, _0.si, _1.cfe, _1.si, _0.cfs]
   [junit4]   1> IW 102 [2016-02-07T16:44:44.309Z; Thread-1204]: commit: 
pendingCommit != null
   [junit4]   1> IW 102 [2016-02-07T16:44:44.309Z; Thread-1204]: commit: done 
writing segments file "segments_2"
   [junit4]   1> IFD 102 [2016-02-07T16:44:44.309Z; Thread-1204]: now 
checkpoint "_0(6.0.0):c1 _1(6.0.0):c1" [2 segments ; isCommit = true]
   [junit4]   1> IFD 102 [2016-02-07T16:44:44.309Z; Thread-1204]: 
deleteCommits: now decRef commit "segments_1"
   [junit4]   1> IFD 102 [2016-02-07T16:44:44.309Z; Thread-1204]: delete 
"[segments_1]"
   [junit4]   1> IFD 102 [2016-02-07T16:44:44.309Z; Thread-1204]: 0 msec to 
checkpoint
   [junit4]   1> IFD 102 [2016-02-07T16:44:44.309Z; Thread-1204]: delete "[]"
   [junit4]   1> IW 102 [2016-02-07T16:44:44.309Z; Thread-1204]: commit: took 
2.4 msec
   [junit4]   1> IW 102 [2016-02-07T16:44:44.309Z; Thread-1204]: commit: done
   [junit4]   1> IW 102 [2016-02-07T16:44:44.309Z; Thread-1204]: rollback
   [junit4]   1> IW 102 [2016-02-07T16:44:44.309Z; Thread-1204]: all running 
merges have aborted
   [junit4]   1> IW 102 [2016-02-07T16:44:44.309Z; Thread-1204]: rollback: done 
finish merges
   [junit4]   1> DW 102 [2016-02-07T16:44:44.309Z; Thread-1204]: abort
   [junit4]   1> DW 102 [2016-02-07T16:44:44.309Z; Thread-1204]: done abort 
success=true
   [junit4]   1> IW 102 [2016-02-07T16:44:44.309Z; Thread-1204]: rollback: 
infos=_0(6.0.0):c1 _1(6.0.0):c1
   [junit4]   1> IFD 102 [2016-02-07T16:44:44.309Z; Thread-1204]: now 
checkpoint "_0(6.0.0):c1 _1(6.0.0):c1" [2 segments ; isCommit = false]
   [junit4]   1> IFD 102 [2016-02-07T16:44:44.309Z; Thread-1204]: delete "[]"
   [junit4]   1> IFD 102 [2016-02-07T16:44:44.309Z; Thread-1204]: 0 msec to 
checkpoint
   [junit4]   1> IFD 102 [2016-02-07T16:44:44.309Z; Thread-1204]: delete "[]"
   [junit4]   1> IFD 102 [2016-02-07T16:44:44.309Z; Thread-1204]: delete "[]"
   [junit4]   1> 
   [junit4]   1> TEST: WriterThread iter=1
   [junit4]   1> IFD 103 [2016-02-07T16:44:44.311Z; Thread-1204]: init: current 
segments file is "segments_2"; 
deletionPolicy=org.apache.lucene.index.KeepOnlyLastCommitDeletionPolicy@49dd3966
   [junit4]   1> IFD 103 [2016-02-07T16:44:44.311Z; Thread-1204]: init: load 
commit "segments_2"
   [junit4]   1> IFD 103 [2016-02-07T16:44:44.311Z; Thread-1204]: delete "[]"
   [junit4]   1> IFD 103 [2016-02-07T16:44:44.311Z; Thread-1204]: now 
checkpoint "_0(6.0.0):c1 _1(6.0.0):c1" [2 segments ; isCommit = false]
   [junit4]   1> IFD 103 [2016-02-07T16:44:44.311Z; Thread-1204]: delete "[]"
   [junit4]   1> IFD 103 [2016-02-07T16:44:44.311Z; Thread-1204]: 0 msec to 
checkpoint
   [junit4]   1> IW 103 [2016-02-07T16:44:44.313Z; Thread-1204]: init: 
create=false
   [junit4]   1> IW 103 [2016-02-07T16:44:44.313Z; Thread-1204]: 
   [junit4]   1> 
dir=MockDirectoryWrapper(NIOFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\core\test\J1\temp\lucene.store.TestNativeFSLockFactory_3BBE714C9113ACE6-001\tempDir-005
 lockFactory=org.apache.lucene.store.NativeFSLockFactory@5a8347e2)
   [junit4]   1> index=_0(6.0.0):c1 _1(6.0.0):c1
   [junit4]   1> version=6.0.0
   [junit4]   1> analyzer=org.apache.lucene.analysis.MockAnalyzer
   [junit4]   1> ramBufferSizeMB=16.0
   [junit4]   1> maxBufferedDocs=-1
   [junit4]   1> maxBufferedDeleteTerms=-1
   [junit4]   1> mergedSegmentWarmer=null
   [junit4]   1> 
delPolicy=org.apache.lucene.index.KeepOnlyLastCommitDeletionPolicy
   [junit4]   1> commit=null
   [junit4]   1> openMode=APPEND
   [junit4]   1> similarity=org.apache.lucene.search.similarities.BM25Similarity
   [junit4]   1> mergeScheduler=ConcurrentMergeScheduler: maxThreadCount=-1, 
maxMergeCount=-1, ioThrottle=true
   [junit4]   1> codec=Asserting(Lucene60): 
{content=PostingsFormat(name=Asserting)}, docValues:{}
   [junit4]   1> infoStream=org.apache.lucene.util.PrintStreamInfoStream
   [junit4]   1> mergePolicy=[TieredMergePolicy: maxMergeAtOnce=10, 
maxMergeAtOnceExplicit=30, maxMergedSegmentMB=5120.0, floorSegmentMB=2.0, 
forceMergeDeletesPctAllowed=10.0, segmentsPerTier=10.0, 
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1
   [junit4]   1> 
indexerThreadPool=org.apache.lucene.index.DocumentsWriterPerThreadPool@37570396
   [junit4]   1> readerPooling=false
   [junit4]   1> perThreadHardLimitMB=1945
   [junit4]   1> useCompoundFile=true
   [junit4]   1> commitOnClose=true
   [junit4]   1> writer=org.apache.lucene.index.IndexWriter@16612eff
   [junit4]   1> 
   [junit4]   1> IW 103 [2016-02-07T16:44:44.313Z; Thread-1204]: 
MMapDirectory.UNMAP_SUPPORTED=true
   [junit4]   1> IW 103 [2016-02-07T16:44:44.314Z; Thread-1204]: now flush at 
close
   [junit4]   1> IW 103 [2016-02-07T16:44:44.314Z; Thread-1204]:   start flush: 
applyAllDeletes=true
   [junit4]   1> IW 103 [2016-02-07T16:44:44.314Z; Thread-1204]:   index before 
flush _0(6.0.0):c1 _1(6.0.0):c1
   [junit4]   1> DW 103 [2016-02-07T16:44:44.314Z; Thread-1204]: startFullFlush
   [junit4]   1> DW 103 [2016-02-07T16:44:44.314Z; Thread-1204]: anyChanges? 
numDocsInRam=1 deletes=false hasTickets:false pendingChangesInFullFlush: false
   [junit4]   1> DWFC 103 [2016-02-07T16:44:44.314Z; Thread-1204]: 
addFlushableState DocumentsWriterPerThread [pendingDeletes=gen=0, segment=_2, 
aborted=false, numDocsInRAM=1, deleteQueue=DWDQ: [ generation: 0 ]]
   [junit4]   1> DWPT 103 [2016-02-07T16:44:44.314Z; Thread-1204]: flush 
postings as segment _2 numDocs=1
   [junit4]   1> IW 103 [2016-02-07T16:44:44.315Z; Thread-1204]: 1 msec to 
write norms
   [junit4]   1> IW 103 [2016-02-07T16:44:44.315Z; Thread-1204]: 0 msec to 
write docValues
   [junit4]   1> IW 103 [2016-02-07T16:44:44.315Z; Thread-1204]: 0 msec to 
write points
   [junit4]   1> IW 103 [2016-02-07T16:44:44.315Z; Thread-1204]: 0 msec to 
finish stored fields
   [junit4]   1> IW 103 [2016-02-07T16:44:44.320Z; Thread-1204]: 4 msec to 
write postings and finish vectors
   [junit4]   1> IW 103 [2016-02-07T16:44:44.321Z; Thread-1204]: 0 msec to 
write fieldInfos
   [junit4]   1> DWPT 103 [2016-02-07T16:44:44.321Z; Thread-1204]: new segment 
has 0 deleted docs
   [junit4]   1> DWPT 103 [2016-02-07T16:44:44.321Z; Thread-1204]: new segment 
has no vectors; norms; no docValues; prox; freqs
   [junit4]   1> DWPT 103 [2016-02-07T16:44:44.321Z; Thread-1204]: 
flushedFiles=[_2_Asserting_0.pos, _2.nvd, _2.fdt, _2_Asserting_0.tip, _2.nvm, 
_2.fnm, _2.fdx, _2_Asserting_0.doc, _2_Asserting_0.tim]
   [junit4]   1> DWPT 103 [2016-02-07T16:44:44.321Z; Thread-1204]: flushed 
codec=Asserting(Lucene60): {content=PostingsFormat(name=Asserting)}, 
docValues:{}
   [junit4]   1> DWPT 103 [2016-02-07T16:44:44.322Z; Thread-1204]: flushed: 
segment=_2 ramUsed=0.071 MB newFlushedSize=0.001 MB docs/MB=1,132.371
   [junit4]   1> IW 103 [2016-02-07T16:44:44.322Z; Thread-1204]: create 
compound file
   [junit4]   1> DWPT 103 [2016-02-07T16:44:44.326Z; Thread-1204]: flush time 
11.7754 msec
   [junit4]   1> DW 103 [2016-02-07T16:44:44.326Z; Thread-1204]: 
publishFlushedSegment seg-private updates=null
   [junit4]   1> IW 103 [2016-02-07T16:44:44.326Z; Thread-1204]: 
publishFlushedSegment
   [junit4]   1> IW 103 [2016-02-07T16:44:44.326Z; Thread-1204]: publish sets 
newSegment delGen=1 seg=_2(6.0.0):c1
   [junit4]   1> IFD 103 [2016-02-07T16:44:44.326Z; Thread-1204]: now 
checkpoint "_0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1" [3 segments ; isCommit = 
false]
   [junit4]   1> IFD 103 [2016-02-07T16:44:44.326Z; Thread-1204]: delete "[]"
   [junit4]   1> IFD 103 [2016-02-07T16:44:44.326Z; Thread-1204]: 0 msec to 
checkpoint
   [junit4]   1> DW 103 [2016-02-07T16:44:44.326Z; Thread-1204]: Thread-1204 
finishFullFlush success=true
   [junit4]   1> IFD 103 [2016-02-07T16:44:44.326Z; Thread-1204]: will delete 
new file "_2_Asserting_0.pos"
   [junit4]   1> IFD 103 [2016-02-07T16:44:44.326Z; Thread-1204]: will delete 
new file "_2.nvd"
   [junit4]   1> IFD 103 [2016-02-07T16:44:44.326Z; Thread-1204]: will delete 
new file "_2.fdt"
   [junit4]   1> IFD 103 [2016-02-07T16:44:44.326Z; Thread-1204]: will delete 
new file "_2_Asserting_0.tip"
   [junit4]   1> IFD 103 [2016-02-07T16:44:44.326Z; Thread-1204]: will delete 
new file "_2.nvm"
   [junit4]   1> IFD 103 [2016-02-07T16:44:44.326Z; Thread-1204]: will delete 
new file "_2.fnm"
   [junit4]   1> IFD 103 [2016-02-07T16:44:44.326Z; Thread-1204]: will delete 
new file "_2.fdx"
   [junit4]   1> IFD 103 [2016-02-07T16:44:44.326Z; Thread-1204]: will delete 
new file "_2_Asserting_0.doc"
   [junit4]   1> IFD 103 [2016-02-07T16:44:44.326Z; Thread-1204]: will delete 
new file "_2_Asserting_0.tim"
   [junit4]   1> IFD 103 [2016-02-07T16:44:44.326Z; Thread-1204]: delete 
"[_2_Asserting_0.pos, _2.nvd, _2.fdt, _2_Asserting_0.tip, _2.nvm, _2.fnm, 
_2.fdx, _2_Asserting_0.doc, _2_Asserting_0.tim]"
   [junit4]   1> IW 103 [2016-02-07T16:44:44.328Z; Thread-1204]: apply all 
deletes during flush
   [junit4]   1> IW 103 [2016-02-07T16:44:44.328Z; Thread-1204]: now apply all 
deletes for all segments maxDoc=3
   [junit4]   1> BD 103 [2016-02-07T16:44:44.328Z; Thread-1204]: applyDeletes: 
open segment readers took 0 msec
   [junit4]   1> BD 103 [2016-02-07T16:44:44.328Z; Thread-1204]: applyDeletes: 
no segments; skipping
   [junit4]   1> BD 103 [2016-02-07T16:44:44.328Z; Thread-1204]: prune 
sis=segments_2: _0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1 minGen=0 packetCount=0
   [junit4]   1> TMP 103 [2016-02-07T16:44:44.328Z; Thread-1204]: findMerges: 3 
segments
   [junit4]   1> TMP 103 [2016-02-07T16:44:44.329Z; Thread-1204]:   
seg=_0(6.0.0):c1 size=0.001 MB [floored]
   [junit4]   1> TMP 103 [2016-02-07T16:44:44.329Z; Thread-1204]:   
seg=_1(6.0.0):c1 size=0.001 MB [floored]
   [junit4]   1> TMP 103 [2016-02-07T16:44:44.329Z; Thread-1204]:   
seg=_2(6.0.0):c1 size=0.001 MB [floored]
   [junit4]   1> TMP 103 [2016-02-07T16:44:44.329Z; Thread-1204]:   
allowedSegmentCount=1 vs count=3 (eligible count=3) tooBigCount=0
   [junit4]   1> MS 103 [2016-02-07T16:44:44.329Z; Thread-1204]: 
initDynamicDefaults spins=false maxThreadCount=1 maxMergeCount=6
   [junit4]   1> MS 103 [2016-02-07T16:44:44.329Z; Thread-1204]: now merge
   [junit4]   1> MS 103 [2016-02-07T16:44:44.329Z; Thread-1204]:   index: 
_0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1
   [junit4]   1> MS 103 [2016-02-07T16:44:44.329Z; Thread-1204]:   no more 
merges pending; now return
   [junit4]   1> MS 103 [2016-02-07T16:44:44.329Z; Thread-1204]: 
updateMergeThreads ioThrottle=true targetMBPerSec=10240.0 MB/sec
   [junit4]   1> MS 103 [2016-02-07T16:44:44.329Z; Thread-1204]: now merge
   [junit4]   1> MS 103 [2016-02-07T16:44:44.329Z; Thread-1204]:   index: 
_0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1
   [junit4]   1> MS 103 [2016-02-07T16:44:44.329Z; Thread-1204]:   no more 
merges pending; now return
   [junit4]   1> IW 103 [2016-02-07T16:44:44.329Z; Thread-1204]: waitForMerges
   [junit4]   1> IW 103 [2016-02-07T16:44:44.329Z; Thread-1204]: waitForMerges 
done
   [junit4]   1> IW 103 [2016-02-07T16:44:44.329Z; Thread-1204]: commit: start
   [junit4]   1> IW 103 [2016-02-07T16:44:44.329Z; Thread-1204]: commit: enter 
lock
   [junit4]   1> IW 103 [2016-02-07T16:44:44.329Z; Thread-1204]: commit: now 
prepare
   [junit4]   1> IW 103 [2016-02-07T16:44:44.329Z; Thread-1204]: prepareCommit: 
flush
   [junit4]   1> IW 103 [2016-02-07T16:44:44.329Z; Thread-1204]:   index before 
flush _0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1
   [junit4]   1> DW 103 [2016-02-07T16:44:44.329Z; Thread-1204]: startFullFlush
   [junit4]   1> IW 103 [2016-02-07T16:44:44.329Z; Thread-1204]: apply all 
deletes during flush
   [junit4]   1> IW 103 [2016-02-07T16:44:44.329Z; Thread-1204]: now apply all 
deletes for all segments maxDoc=3
   [junit4]   1> BD 103 [2016-02-07T16:44:44.329Z; Thread-1204]: applyDeletes: 
open segment readers took 0 msec
   [junit4]   1> BD 103 [2016-02-07T16:44:44.329Z; Thread-1204]: applyDeletes: 
no segments; skipping
   [junit4]   1> BD 103 [2016-02-07T16:44:44.329Z; Thread-1204]: prune 
sis=segments_2: _0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1 minGen=0 packetCount=0
   [junit4]   1> DW 103 [2016-02-07T16:44:44.329Z; Thread-1204]: Thread-1204 
finishFullFlush success=true
   [junit4]   1> IW 103 [2016-02-07T16:44:44.329Z; Thread-1204]: startCommit(): 
start
   [junit4]   1> IW 103 [2016-02-07T16:44:44.329Z; Thread-1204]: startCommit 
index=_0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1 changeCount=3
   [junit4]   1> IW 103 [2016-02-07T16:44:44.331Z; Thread-1204]: startCommit: 
wrote pending segments file "pending_segments_3"
   [junit4]   1> IW 103 [2016-02-07T16:44:44.332Z; Thread-1204]: done all 
syncs: [_1.cfs, _0.cfe, _0.si, _1.cfe, _1.si, _2.si, _0.cfs, _2.cfe, _2.cfs]
   [junit4]   1> IW 103 [2016-02-07T16:44:44.332Z; Thread-1204]: commit: 
pendingCommit != null
   [junit4]   1> IW 103 [2016-02-07T16:44:44.333Z; Thread-1204]: commit: done 
writing segments file "segments_3"
   [junit4]   1> IFD 103 [2016-02-07T16:44:44.333Z; Thread-1204]: now 
checkpoint "_0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1" [3 segments ; isCommit = 
true]
   [junit4]   1> IFD 103 [2016-02-07T16:44:44.333Z; Thread-1204]: 
deleteCommits: now decRef commit "segments_2"
   [junit4]   1> IFD 103 [2016-02-07T16:44:44.333Z; Thread-1204]: delete 
"[segments_2]"
   [junit4]   1> IFD 103 [2016-02-07T16:44:44.333Z; Thread-1204]: 0 msec to 
checkpoint
   [junit4]   1> IFD 103 [2016-02-07T16:44:44.333Z; Thread-1204]: delete "[]"
   [junit4]   1> IW 103 [2016-02-07T16:44:44.333Z; Thread-1204]: commit: took 
3.7 msec
   [junit4]   1> IW 103 [2016-02-07T16:44:44.333Z; Thread-1204]: commit: done
   [junit4]   1> IW 103 [2016-02-07T16:44:44.333Z; Thread-1204]: rollback
   [junit4]   1> IW 103 [2016-02-07T16:44:44.333Z; Thread-1204]: all running 
merges have aborted
   [junit4]   1> IW 103 [2016-02-07T16:44:44.333Z; Thread-1204]: rollback: done 
finish merges
   [junit4]   1> DW 103 [2016-02-07T16:44:44.333Z; Thread-1204]: abort
   [junit4]   1> DW 103 [2016-02-07T16:44:44.333Z; Thread-1204]: done abort 
success=true
   [junit4]   1> IW 103 [2016-02-07T16:44:44.333Z; Thread-1204]: rollback: 
infos=_0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1
   [junit4]   1> IFD 103 [2016-02-07T16:44:44.333Z; Thread-1204]: now 
checkpoint "_0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1" [3 segments ; isCommit = 
false]
   [junit4]   1> IFD 103 [2016-02-07T16:44:44.333Z; Thread-1204]: delete "[]"
   [junit4]   1> IFD 103 [2016-02-07T16:44:44.333Z; Thread-1204]: 0 msec to 
checkpoint
   [junit4]   1> IFD 103 [2016-02-07T16:44:44.333Z; Thread-1204]: delete "[]"
   [junit4]   1> IFD 103 [2016-02-07T16:44:44.333Z; Thread-1204]: delete "[]"
   [junit4]   1> 
   [junit4]   1> TEST: WriterThread iter=2
   [junit4]   1> IFD 104 [2016-02-07T16:44:44.339Z; Thread-1204]: init: current 
segments file is "segments_3"; 
deletionPolicy=org.apache.lucene.index.KeepOnlyLastCommitDeletionPolicy@4ea8d571
   [junit4]   1> IFD 104 [2016-02-07T16:44:44.339Z; Thread-1204]: init: load 
commit "segments_3"
   [junit4]   1> IFD 104 [2016-02-07T16:44:44.340Z; Thread-1204]: delete "[]"
   [junit4]   1> IFD 104 [2016-02-07T16:44:44.340Z; Thread-1204]: now 
checkpoint "_0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1" [3 segments ; isCommit = 
false]
   [junit4]   1> IFD 104 [2016-02-07T16:44:44.340Z; Thread-1204]: delete "[]"
   [junit4]   1> IFD 104 [2016-02-07T16:44:44.340Z; Thread-1204]: 0 msec to 
checkpoint
   [junit4]   1> IW 104 [2016-02-07T16:44:44.340Z; Thread-1204]: init: 
create=false
   [junit4]   1> IW 104 [2016-02-07T16:44:44.340Z; Thread-1204]: 
   [junit4]   1> 
dir=MockDirectoryWrapper(NIOFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\core\test\J1\temp\lucene.store.TestNativeFSLockFactory_3BBE714C9113ACE6-001\tempDir-005
 lockFactory=org.apache.lucene.store.NativeFSLockFactory@5a8347e2)
   [junit4]   1> index=_0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1
   [junit4]   1> version=6.0.0
   [junit4]   1> analyzer=org.apache.lucene.analysis.MockAnalyzer
   [junit4]   1> ramBufferSizeMB=16.0
   [junit4]   1> maxBufferedDocs=-1
   [junit4]   1> maxBufferedDeleteTerms=-1
   [junit4]   1> mergedSegmentWarmer=null
   [junit4]   1> 
delPolicy=org.apache.lucene.index.KeepOnlyLastCommitDeletionPolicy
   [junit4]   1> commit=null
   [junit4]   1> openMode=APPEND
   [junit4]   1> similarity=org.apache.lucene.search.similarities.BM25Similarity
   [junit4]   1> mergeScheduler=ConcurrentMergeScheduler: maxThreadCount=-1, 
maxMergeCount=-1, ioThrottle=true
   [junit4]   1> codec=Asserting(Lucene60): 
{content=PostingsFormat(name=Asserting)}, docValues:{}
   [junit4]   1> infoStream=org.apache.lucene.util.PrintStreamInfoStream
   [junit4]   1> mergePolicy=[TieredMergePolicy: maxMergeAtOnce=10, 
maxMergeAtOnceExplicit=30, maxMergedSegmentMB=5120.0, floorSegmentMB=2.0, 
forceMergeDeletesPctAllowed=10.0, segmentsPerTier=10.0, 
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1
   [junit4]   1> 
indexerThreadPool=org.apache.lucene.index.DocumentsWriterPerThreadPool@487fde1f
   [junit4]   1> readerPooling=false
   [junit4]   1> perThreadHardLimitMB=1945
   [junit4]   1> useCompoundFile=true
   [junit4]   1> commitOnClose=true
   [junit4]   1> writer=org.apache.lucene.index.IndexWriter@592c5f68
   [junit4]   1> 
   [junit4]   1> IW 104 [2016-02-07T16:44:44.340Z; Thread-1204]: 
MMapDirectory.UNMAP_SUPPORTED=true
   [junit4]   1> IW 104 [2016-02-07T16:44:44.341Z; Thread-1204]: now flush at 
close
   [junit4]   1> IW 104 [2016-02-07T16:44:44.341Z; Thread-1204]:   start flush: 
applyAllDeletes=true
   [junit4]   1> IW 104 [2016-02-07T16:44:44.341Z; Thread-1204]:   index before 
flush _0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1
   [junit4]   1> DW 104 [2016-02-07T16:44:44.341Z; Thread-1204]: startFullFlush
   [junit4]   1> DW 104 [2016-02-07T16:44:44.341Z; Thread-1204]: anyChanges? 
numDocsInRam=1 deletes=false hasTickets:false pendingChangesInFullFlush: false
   [junit4]   1> DWFC 104 [2016-02-07T16:44:44.341Z; Thread-1204]: 
addFlushableState DocumentsWriterPerThread [pendingDeletes=gen=0, segment=_3, 
aborted=false, numDocsInRAM=1, deleteQueue=DWDQ: [ generation: 0 ]]
   [junit4]   1> DWPT 104 [2016-02-07T16:44:44.341Z; Thread-1204]: flush 
postings as segment _3 numDocs=1
   [junit4]   1> IW 104 [2016-02-07T16:44:44.343Z; Thread-1204]: 2 msec to 
write norms
   [junit4]   1> IW 104 [2016-02-07T16:44:44.343Z; Thread-1204]: 0 msec to 
write docValues
   [junit4]   1> IW 104 [2016-02-07T16:44:44.343Z; Thread-1204]: 0 msec to 
write points
   [junit4]   1> IW 104 [2016-02-07T16:44:44.344Z; Thread-1204]: 0 msec to 
finish stored fields
   [junit4]   1> IW 104 [2016-02-07T16:44:44.347Z; Thread-1204]: 3 msec to 
write postings and finish vectors
   [junit4]   1> IW 104 [2016-02-07T16:44:44.349Z; Thread-1204]: 1 msec to 
write fieldInfos
   [junit4]   1> DWPT 104 [2016-02-07T16:44:44.349Z; Thread-1204]: new segment 
has 0 deleted docs
   [junit4]   1> DWPT 104 [2016-02-07T16:44:44.349Z; Thread-1204]: new segment 
has no vectors; norms; no docValues; prox; freqs
   [junit4]   1> DWPT 104 [2016-02-07T16:44:44.349Z; Thread-1204]: 
flushedFiles=[_3.fdx, _3.nvd, _3_Asserting_0.doc, _3_Asserting_0.pos, _3.nvm, 
_3.fnm, _3_Asserting_0.tim, _3.fdt, _3_Asserting_0.tip]
   [junit4]   1> DWPT 104 [2016-02-07T16:44:44.349Z; Thread-1204]: flushed 
codec=Asserting(Lucene60): {content=PostingsFormat(name=Asserting)}, 
docValues:{}
   [junit4]   1> DWPT 104 [2016-02-07T16:44:44.349Z; Thread-1204]: flushed: 
segment=_3 ramUsed=0.071 MB newFlushedSize=0.001 MB docs/MB=1,132.371
   [junit4]   1> IW 104 [2016-02-07T16:44:44.349Z; Thread-1204]: create 
compound file
   [junit4]   1> DWPT 104 [2016-02-07T16:44:44.353Z; Thread-1204]: flush time 
11.4196 msec
   [junit4]   1> DW 104 [2016-02-07T16:44:44.353Z; Thread-1204]: 
publishFlushedSegment seg-private updates=null
   [junit4]   1> IW 104 [2016-02-07T16:44:44.353Z; Thread-1204]: 
publishFlushedSegment
   [junit4]   1> IW 104 [2016-02-07T16:44:44.353Z; Thread-1204]: publish sets 
newSegment delGen=1 seg=_3(6.0.0):c1
   [junit4]   1> IFD 104 [2016-02-07T16:44:44.353Z; Thread-1204]: now 
checkpoint "_0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1 _3(6.0.0):c1" [4 segments ; 
isCommit = false]
   [junit4]   1> IFD 104 [2016-02-07T16:44:44.353Z; Thread-1204]: delete "[]"
   [junit4]   1> IFD 104 [2016-02-07T16:44:44.353Z; Thread-1204]: 0 msec to 
checkpoint
   [junit4]   1> DW 104 [2016-02-07T16:44:44.353Z; Thread-1204]: Thread-1204 
finishFullFlush success=true
   [junit4]   1> IFD 104 [2016-02-07T16:44:44.353Z; Thread-1204]: will delete 
new file "_3.fdx"
   [junit4]   1> IFD 104 [2016-02-07T16:44:44.353Z; Thread-1204]: will delete 
new file "_3.nvd"
   [junit4]   1> IFD 104 [2016-02-07T16:44:44.353Z; Thread-1204]: will delete 
new file "_3_Asserting_0.doc"
   [junit4]   1> IFD 104 [2016-02-07T16:44:44.353Z; Thread-1204]: will delete 
new file "_3_Asserting_0.pos"
   [junit4]   1> IFD 104 [2016-02-07T16:44:44.353Z; Thread-1204]: will delete 
new file "_3.nvm"
   [junit4]   1> IFD 104 [2016-02-07T16:44:44.353Z; Thread-1204]: will delete 
new file "_3.fnm"
   [junit4]   1> IFD 104 [2016-02-07T16:44:44.353Z; Thread-1204]: will delete 
new file "_3_Asserting_0.tim"
   [junit4]   1> IFD 104 [2016-02-07T16:44:44.353Z; Thread-1204]: will delete 
new file "_3.fdt"
   [junit4]   1> IFD 104 [2016-02-07T16:44:44.353Z; Thread-1204]: will delete 
new file "_3_Asserting_0.tip"
   [junit4]   1> IFD 104 [2016-02-07T16:44:44.353Z; Thread-1204]: delete 
"[_3.fdx, _3.nvd, _3_Asserting_0.doc, _3_Asserting_0.pos, _3.nvm, _3.fnm, 
_3_Asserting_0.tim, _3.fdt, _3_Asserting_0.tip]"
   [junit4]   1> IW 104 [2016-02-07T16:44:44.355Z; Thread-1204]: apply all 
deletes during flush
   [junit4]   1> IW 104 [2016-02-07T16:44:44.355Z; Thread-1204]: now apply all 
deletes for all segments maxDoc=4
   [junit4]   1> BD 104 [2016-02-07T16:44:44.355Z; Thread-1204]: applyDeletes: 
open segment readers took 0 msec
   [junit4]   1> BD 104 [2016-02-07T16:44:44.355Z; Thread-1204]: applyDeletes: 
no segments; skipping
   [junit4]   1> BD 104 [2016-02-07T16:44:44.355Z; Thread-1204]: prune 
sis=segments_3: _0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1 _3(6.0.0):c1 minGen=0 
packetCount=0
   [junit4]   1> TMP 104 [2016-02-07T16:44:44.355Z; Thread-1204]: findMerges: 4 
segments
   [junit4]   1> TMP 104 [2016-02-07T16:44:44.356Z; Thread-1204]:   
seg=_0(6.0.0):c1 size=0.001 MB [floored]
   [junit4]   1> TMP 104 [2016-02-07T16:44:44.356Z; Thread-1204]:   
seg=_1(6.0.0):c1 size=0.001 MB [floored]
   [junit4]   1> TMP 104 [2016-02-07T16:44:44.356Z; Thread-1204]:   
seg=_2(6.0.0):c1 size=0.001 MB [floored]
   [junit4]   1> TMP 104 [2016-02-07T16:44:44.356Z; Thread-1204]:   
seg=_3(6.0.0):c1 size=0.001 MB [floored]
   [junit4]   1> TMP 104 [2016-02-07T16:44:44.356Z; Thread-1204]:   
allowedSegmentCount=1 vs count=4 (eligible count=4) tooBigCount=0
   [junit4]   1> MS 104 [2016-02-07T16:44:44.356Z; Thread-1204]: 
initDynamicDefaults spins=false maxThreadCount=1 maxMergeCount=6
   [junit4]   1> MS 104 [2016-02-07T16:44:44.356Z; Thread-1204]: now merge
   [junit4]   1> MS 104 [2016-02-07T16:44:44.356Z; Thread-1204]:   index: 
_0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1 _3(6.0.0):c1
   [junit4]   1> MS 104 [2016-02-07T16:44:44.356Z; Thread-1204]:   no more 
merges pending; now return
   [junit4]   1> MS 104 [2016-02-07T16:44:44.356Z; Thread-1204]: 
updateMergeThreads ioThrottle=true targetMBPerSec=10240.0 MB/sec
   [junit4]   1> MS 104 [2016-02-07T16:44:44.356Z; Thread-1204]: now merge
   [junit4]   1> MS 104 [2016-02-07T16:44:44.356Z; Thread-1204]:   index: 
_0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1 _3(6.0.0):c1
   [junit4]   1> MS 104 [2016-02-07T16:44:44.356Z; Thread-1204]:   no more 
merges pending; now return
   [junit4]   1> IW 104 [2016-02-07T16:44:44.356Z; Thread-1204]: waitForMerges
   [junit4]   1> IW 104 [2016-02-07T16:44:44.356Z; Thread-1204]: waitForMerges 
done
   [junit4]   1> IW 104 [2016-02-07T16:44:44.356Z; Thread-1204]: commit: start
   [junit4]   1> IW 104 [2016-02-07T16:44:44.356Z; Thread-1204]: commit: enter 
lock
   [junit4]   1> IW 104 [2016-02-07T16:44:44.356Z; Thread-1204]: commit: now 
prepare
   [junit4]   1> IW 104 [2016-02-07T16:44:44.356Z; Thread-1204]: prepareCommit: 
flush
   [junit4]   1> IW 104 [2016-02-07T16:44:44.356Z; Thread-1204]:   index before 
flush _0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1 _3(6.0.0):c1
   [junit4]   1> DW 104 [2016-02-07T16:44:44.356Z; Thread-1204]: startFullFlush
   [junit4]   1> IW 104 [2016-02-07T16:44:44.356Z; Thread-1204]: apply all 
deletes during flush
   [junit4]   1> IW 104 [2016-02-07T16:44:44.356Z; Thread-1204]: now apply all 
deletes for all segments maxDoc=4
   [junit4]   1> BD 104 [2016-02-07T16:44:44.356Z; Thread-1204]: applyDeletes: 
open segment readers took 0 msec
   [junit4]   1> BD 104 [2016-02-07T16:44:44.356Z; Thread-1204]: applyDeletes: 
no segments; skipping
   [junit4]   1> BD 104 [2016-02-07T16:44:44.356Z; Thread-1204]: prune 
sis=segments_3: _0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1 _3(6.0.0):c1 minGen=0 
packetCount=0
   [junit4]   1> DW 104 [2016-02-07T16:44:44.356Z; Thread-1204]: Thread-1204 
finishFullFlush success=true
   [junit4]   1> IW 104 [2016-02-07T16:44:44.356Z; Thread-1204]: startCommit(): 
start
   [junit4]   1> IW 104 [2016-02-07T16:44:44.356Z; Thread-1204]: startCommit 
index=_0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1 _3(6.0.0):c1 changeCount=3
   [junit4]   1> IW 104 [2016-02-07T16:44:44.360Z; Thread-1204]: startCommit: 
wrote pending segments file "pending_segments_4"
   [junit4]   1> IW 104 [2016-02-07T16:44:44.361Z; Thread-1204]: done all 
syncs: [_1.cfs, _0.cfe, _0.si, _3.si, _1.cfe, _1.si, _2.si, _0.cfs, _2.cfe, 
_2.cfs, _3.cfs, _3.cfe]
   [junit4]   1> IW 104 [2016-02-07T16:44:44.361Z; Thread-1204]: commit: 
pendingCommit != null
   [junit4]   1> IW 104 [2016-02-07T16:44:44.362Z; Thread-1204]: commit: done 
writing segments file "segments_4"
   [junit4]   1> IFD 104 [2016-02-07T16:44:44.362Z; Thread-1204]: now 
checkpoint "_0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1 _3(6.0.0):c1" [4 segments ; 
isCommit = true]
   [junit4]   1> IFD 104 [2016-02-07T16:44:44.362Z; Thread-1204]: 
deleteCommits: now decRef commit "segments_3"
   [junit4]   1> IFD 104 [2016-02-07T16:44:44.362Z; Thread-1204]: delete 
"[segments_3]"
   [junit4]   1> IFD 104 [2016-02-07T16:44:44.362Z; Thread-1204]: 0 msec to 
checkpoint
   [junit4]   1> IFD 104 [2016-02-07T16:44:44.362Z; Thread-1204]: delete "[]"
   [junit4]   1> IW 104 [2016-02-07T16:44:44.362Z; Thread-1204]: commit: took 
5.0 msec
   [junit4]   1> IW 104 [2016-02-07T16:44:44.362Z; Thread-1204]: commit: done
   [junit4]   1> IW 104 [2016-02-07T16:44:44.362Z; Thread-1204]: rollback
   [junit4]   1> IW 104 [2016-02-07T16:44:44.362Z; Thread-1204]: all running 
merges have aborted
   [junit4]   1> IW 104 [2016-02-07T16:44:44.362Z; Thread-1204]: rollback: done 
finish merges
   [junit4]   1> DW 104 [2016-02-07T16:44:44.362Z; Thread-1204]: abort
   [junit4]   1> DW 104 [2016-02-07T16:44:44.362Z; Thread-1204]: done abort 
success=true
   [junit4]   1> IW 104 [2016-02-07T16:44:44.362Z; Thread-1204]: rollback: 
infos=_0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1 _3(6.0.0):c1
   [junit4]   1> IFD 104 [2016-02-07T16:44:44.362Z; Thread-1204]: now 
checkpoint "_0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1 _3(6.0.0):c1" [4 segments ; 
isCommit = false]
   [junit4]   1> IFD 104 [2016-02-07T16:44:44.362Z; Thread-1204]: delete "[]"
   [junit4]   1> IFD 104 [2016-02-07T16:44:44.362Z; Thread-1204]: 0 msec to 
checkpoint
   [junit4]   1> IFD 104 [2016-02-07T16:44:44.363Z; Thread-1204]: delete "[]"
   [junit4]   1> IFD 104 [2016-02-07T16:44:44.363Z; Thread-1204]: delete "[]"
   [junit4]   1> 
   [junit4]   1> TEST: WriterThread iter=3
   [junit4]   1> IFD 105 [2016-02-07T16:44:44.366Z; Thread-1204]: init: current 
segments file is "segments_4"; 
deletionPolicy=org.apache.lucene.index.KeepOnlyLastCommitDeletionPolicy@37e0582d
   [junit4]   1> IFD 105 [2016-02-07T16:44:44.366Z; Thread-1204]: init: load 
commit "segments_4"
   [junit4]   1> IFD 105 [2016-02-07T16:44:44.367Z; Thread-1204]: delete "[]"
   [junit4]   1> IFD 105 [2016-02-07T16:44:44.367Z; Thread-1204]: now 
checkpoint "_0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1 _3(6.0.0):c1" [4 segments ; 
isCommit = false]
   [junit4]   1> IFD 105 [2016-02-07T16:44:44.367Z; Thread-1204]: delete "[]"
   [junit4]   1> IFD 105 [2016-02-07T16:44:44.367Z; Thread-1204]: 0 msec to 
checkpoint
   [junit4]   1> IW 105 [2016-02-07T16:44:44.369Z; Thread-1204]: init: 
create=false
   [junit4]   1> IW 105 [2016-02-07T16:44:44.369Z; Thread-1204]: 
   [junit4]   1> 
dir=MockDirectoryWrapper(NIOFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\core\test\J1\temp\lucene.store.TestNativeFSLockFactory_3BBE714C9113ACE6-001\tempDir-005
 lockFactory=org.apache.lucene.store.NativeFSLockFactory@5a8347e2)
   [junit4]   1> index=_0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1 _3(6.0.0):c1
   [junit4]   1> version=6.0.0
   [junit4]   1> analyzer=org.apache.lucene.analysis.MockAnalyzer
   [junit4]   1> ramBufferSizeMB=16.0
   [junit4]   1> maxBufferedDocs=-1
   [junit4]   1> maxBufferedDeleteTerms=-1
   [junit4]   1> mergedSegmentWarmer=null
   [junit4]   1> 
delPolicy=org.apache.lucene.index.KeepOnlyLastCommitDeletionPolicy
   [junit4]   1> commit=null
   [junit4]   1> openMode=APPEND
   [junit4]   1> similarity=org.apache.lucene.search.similarities.BM25Similarity
   [junit4]   1> mergeScheduler=ConcurrentMergeScheduler: maxThreadCount=-1, 
maxMergeCount=-1, ioThrottle=true
   [junit4]   1> codec=Asserting(Lucene60): 
{content=PostingsFormat(name=Asserting)}, docValues:{}
   [junit4]   1> infoStream=org.apache.lucene.util.PrintStreamInfoStream
   [junit4]   1> mergePolicy=[TieredMergePolicy: maxMergeAtOnce=10, 
maxMergeAtOnceExplicit=30, maxMergedSegmentMB=5120.0, floorSegmentMB=2.0, 
forceMergeDeletesPctAllowed=10.0, segmentsPerTier=10.0, 
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1
   [junit4]   1> 
indexerThreadPool=org.apache.lucene.index.DocumentsWriterPerThreadPool@270fa2b3
   [junit4]   1> readerPooling=false
   [junit4]   1> perThreadHardLimitMB=1945
   [junit4]   1> useCompoundFile=true
   [junit4]   1> commitOnClose=true
   [junit4]   1> writer=org.apache.lucene.index.IndexWriter@3aa64279
   [junit4]   1> 
   [junit4]   1> IW 105 [2016-02-07T16:44:44.369Z; Thread-1204]: 
MMapDirectory.UNMAP_SUPPORTED=true
   [junit4]   1> IW 105 [2016-02-07T16:44:44.370Z; Thread-1204]: now flush at 
close
   [junit4]   1> IW 105 [2016-02-07T16:44:44.370Z; Thread-1204]:   start flush: 
applyAllDeletes=true
   [junit4]   1> IW 105 [2016-02-07T16:44:44.370Z; Thread-1204]:   index before 
flush _0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1 _3(6.0.0):c1
   [junit4]   1> DW 105 [2016-02-07T16:44:44.370Z; Thread-1204]: startFullFlush
   [junit4]   1> DW 105 [2016-02-07T16:44:44.370Z; Thread-1204]: anyChanges? 
numDocsInRam=1 deletes=false hasTickets:false pendingChangesInFullFlush: false
   [junit4]   1> DWFC 105 [2016-02-07T16:44:44.370Z; Thread-1204]: 
addFlushableState DocumentsWriterPerThread [pendingDeletes=gen=0, segment=_4, 
aborted=false, numDocsInRAM=1, deleteQueue=DWDQ: [ generation: 0 ]]
   [junit4]   1> DWPT 105 [2016-02-07T16:44:44.370Z; Thread-1204]: flush 
postings as segment _4 numDocs=1
   [junit4]   1> IW 105 [2016-02-07T16:44:44.372Z; Thread-1204]: 1 msec to 
write norms
   [junit4]   1> IW 105 [2016-02-07T16:44:44.372Z; Thread-1204]: 0 msec to 
write docValues
   [junit4]   1> IW 105 [2016-02-07T16:44:44.372Z; Thread-1204]: 0 msec to 
write points
   [junit4]   1> IW 105 [2016-02-07T16:44:44.372Z; Thread-1204]: 0 msec to 
finish stored fields
   [junit4]   1> IW 105 [2016-02-07T16:44:44.376Z; Thread-1204]: 4 msec to 
write postings and finish vectors
   [junit4]   1> IW 105 [2016-02-07T16:44:44.377Z; Thread-1204]: 0 msec to 
write fieldInfos
   [junit4]   1> DWPT 105 [2016-02-07T16:44:44.377Z; Thread-1204]: new segment 
has 0 deleted docs
   [junit4]   1> DWPT 105 [2016-02-07T16:44:44.377Z; Thread-1204]: new segment 
has no vectors; norms; no docValues; prox; freqs
   [junit4]   1> DWPT 105 [2016-02-07T16:44:44.377Z; Thread-1204]: 
flushedFiles=[_4_Asserting_0.pos, _4.fdt, _4.nvm, _4.fnm, _4_Asserting_0.doc, 
_4_Asserting_0.tim, _4_Asserting_0.tip, _4.nvd, _4.fdx]
   [junit4]   1> DWPT 105 [2016-02-07T16:44:44.377Z; Thread-1204]: flushed 
codec=Asserting(Lucene60): {content=PostingsFormat(name=Asserting)}, 
docValues:{}
   [junit4]   1> DWPT 105 [2016-02-07T16:44:44.377Z; Thread-1204]: flushed: 
segment=_4 ramUsed=0.071 MB newFlushedSize=0.001 MB docs/MB=1,132.371
   [junit4]   1> IW 105 [2016-02-07T16:44:44.377Z; Thread-1204]: create 
compound file
   [junit4]   1> DWPT 105 [2016-02-07T16:44:44.382Z; Thread-1204]: flush time 
11.4987 msec
   [junit4]   1> DW 105 [2016-02-07T16:44:44.382Z; Thread-1204]: 
publishFlushedSegment seg-private updates=null
   [junit4]   1> IW 105 [2016-02-07T16:44:44.382Z; Thread-1204]: 
publishFlushedSegment
   [junit4]   1> IW 105 [2016-02-07T16:44:44.382Z; Thread-1204]: publish sets 
newSegment delGen=1 seg=_4(6.0.0):c1
   [junit4]   1> IFD 105 [2016-02-07T16:44:44.382Z; Thread-1204]: now 
checkpoint "_0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1 _3(6.0.0):c1 _4(6.0.0):c1" 
[5 segments ; isCommit = false]
   [junit4]   1> IFD 105 [2016-02-07T16:44:44.382Z; Thread-1204]: delete "[]"
   [junit4]   1> IFD 105 [2016-02-07T16:44:44.382Z; Thread-1204]: 0 msec to 
checkpoint
   [junit4]   1> DW 105 [2016-02-07T16:44:44.382Z; Thread-1204]: Thread-1204 
finishFullFlush success=true
   [junit4]   1> IFD 105 [2016-02-07T16:44:44.382Z; Thread-1204]: will delete 
new file "_4_Asserting_0.pos"
   [junit4]   1> IFD 105 [2016-02-07T16:44:44.382Z; Thread-1204]: will delete 
new file "_4.fdt"
   [junit4]   1> IFD 105 [2016-02-07T16:44:44.382Z; Thread-1204]: will delete 
new file "_4.nvm"
   [junit4]   1> IFD 105 [2016-02-07T16:44:44.382Z; Thread-1204]: will delete 
new file "_4.fnm"
   [junit4]   1> IFD 105 [2016-02-07T16:44:44.382Z; Thread-1204]: will delete 
new file "_4_Asserting_0.doc"
   [junit4]   1> IFD 105 [2016-02-07T16:44:44.382Z; Thread-1204]: will delete 
new file "_4_Asserting_0.tim"
   [junit4]   1> IFD 105 [2016-02-07T16:44:44.382Z; Thread-1204]: will delete 
new file "_4_Asserting_0.tip"
   [junit4]   1> IFD 105 [2016-02-07T16:44:44.382Z; Thread-1204]: will delete 
new file "_4.nvd"
   [junit4]   1> IFD 105 [2016-02-07T16:44:44.382Z; Thread-1204]: will delete 
new file "_4.fdx"
   [junit4]   1> IFD 105 [2016-02-07T16:44:44.382Z; Thread-1204]: delete 
"[_4_Asserting_0.pos, _4.fdt, _4.nvm, _4.fnm, _4_Asserting_0.doc, 
_4_Asserting_0.tim, _4_Asserting_0.tip, _4.nvd, _4.fdx]"
   [junit4]   1> IW 105 [2016-02-07T16:44:44.382Z; Thread-1204]: apply all 
deletes during flush
   [junit4]   1> IW 105 [2016-02-07T16:44:44.382Z; Thread-1204]: now apply all 
deletes for all segments maxDoc=5
   [junit4]   1> BD 105 [2016-02-07T16:44:44.382Z; Thread-1204]: applyDeletes: 
open segment readers took 0 msec
   [junit4]   1> BD 105 [2016-02-07T16:44:44.382Z; Thread-1204]: applyDeletes: 
no segments; skipping
   [junit4]   1> BD 105 [2016-02-07T16:44:44.382Z; Thread-1204]: prune 
sis=segments_4: _0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1 _3(6.0.0):c1 
_4(6.0.0):c1 minGen=0 packetCount=0
   [junit4]   1> TMP 105 [2016-02-07T16:44:44.382Z; Thread-1204]: findMerges: 5 
segments
   [junit4]   1> TMP 105 [2016-02-07T16:44:44.385Z; Thread-1204]:   
seg=_0(6.0.0):c1 size=0.001 MB [floored]
   [junit4]   1> TMP 105 [2016-02-07T16:44:44.385Z; Thread-1204]:   
seg=_1(6.0.0):c1 size=0.001 MB [floored]
   [junit4]   1> TMP 105 [2016-02-07T16:44:44.385Z; Thread-1204]:   
seg=_2(6.0.0):c1 size=0.001 MB [floored]
   [junit4]   1> TMP 105 [2016-02-07T16:44:44.385Z; Thread-1204]:   
seg=_3(6.0.0):c1 size=0.001 MB [floored]
   [junit4]   1> TMP 105 [2016-02-07T16:44:44.385Z; Thread-1204]:   
seg=_4(6.0.0):c1 size=0.001 MB [floored]
   [junit4]   1> TMP 105 [2016-02-07T16:44:44.385Z; Thread-1204]:   
allowedSegmentCount=1 vs count=5 (eligible count=5) tooBigCount=0
   [junit4]   1> MS 105 [2016-02-07T16:44:44.386Z; Thread-1204]: 
initDynamicDefaults spins=false maxThreadCount=1 maxMergeCount=6
   [junit4]   1> MS 105 [2016-02-07T16:44:44.386Z; Thread-1204]: now merge
   [junit4]   1> MS 105 [2016-02-07T16:44:44.386Z; Thread-1204]:   index: 
_0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1 _3(6.0.0):c1 _4(6.0.0):c1
   [junit4]   1> MS 105 [2016-02-07T16:44:44.386Z; Thread-1204]:   no more 
merges pending; now return
   [junit4]   1> MS 105 [2016-02-07T16:44:44.386Z; Thread-1204]: 
updateMergeThreads ioThrottle=true targetMBPerSec=10240.0 MB/sec
   [junit4]   1> MS 105 [2016-02-07T16:44:44.386Z; Thread-1204]: now merge
   [junit4]   1> MS 105 [2016-02-07T16:44:44.386Z; Thread-1204]:   index: 
_0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1 _3(6.0.0):c1 _4(6.0.0):c1
   [junit4]   1> MS 105 [2016-02-07T16:44:44.386Z; Thread-1204]:   no more 
merges pending; now return
   [junit4]   1> IW 105 [2016-02-07T16:44:44.386Z; Thread-1204]: waitForMerges
   [junit4]   1> IW 105 [2016-02-07T16:44:44.386Z; Thread-1204]: waitForMerges 
done
   [junit4]   1> IW 105 [2016-02-07T16:44:44.386Z; Thread-1204]: commit: start
   [junit4]   1> IW 105 [2016-02-07T16:44:44.386Z; Thread-1204]: commit: enter 
lock
   [junit4]   1> IW 105 [2016-02-07T16:44:44.386Z; Thread-1204]: commit: now 
prepare
   [junit4]   1> IW 105 [2016-02-07T16:44:44.386Z; Thread-1204]: prepareCommit: 
flush
   [junit4]   1> IW 105 [2016-02-07T16:44:44.386Z; Thread-1204]:   index before 
flush _0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1 _3(6.0.0):c1 _4(6.0.0):c1
   [junit4]   1> DW 105 [2016-02-07T16:44:44.386Z; Thread-1204]: startFullFlush
   [junit4]   1> IW 105 [2016-02-07T16:44:44.386Z; Thread-1204]: apply all 
deletes during flush
   [junit4]   1> IW 105 [2016-02-07T16:44:44.386Z; Thread-1204]: now apply all 
deletes for all segments maxDoc=5
   [junit4]   1> BD 105 [2016-02-07T16:44:44.386Z; Thread-1204]: applyDeletes: 
open segment readers took 0 msec
   [junit4]   1> BD 105 [2016-02-07T16:44:44.386Z; Thread-1204]: applyDeletes: 
no segments; skipping
   [junit4]   1> BD 105 [2016-02-07T16:44:44.386Z; Thread-1204]: prune 
sis=segments_4: _0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1 _3(6.0.0):c1 
_4(6.0.0):c1 minGen=0 packetCount=0
   [junit4]   1> DW 105 [2016-02-07T16:44:44.386Z; Thread-1204]: Thread-1204 
finishFullFlush success=true
   [junit4]   1> IW 105 [2016-02-07T16:44:44.386Z; Thread-1204]: startCommit(): 
start
   [junit4]   1> IW 105 [2016-02-07T16:44:44.386Z; Thread-1204]: startCommit 
index=_0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1 _3(6.0.0):c1 _4(6.0.0):c1 
changeCount=3
   [junit4]   1> IW 105 [2016-02-07T16:44:44.390Z; Thread-1204]: startCommit: 
wrote pending segments file "pending_segments_5"
   [junit4]   1> IW 105 [2016-02-07T16:44:44.390Z; Thread-1204]: done all 
syncs: [_0.si, _3.si, _1.cfe, _1.si, _2.si, _0.cfs, _2.cfs, _4.cfe, _3.cfe, 
_1.cfs, _0.cfe, _2.cfe, _3.cfs, _4.cfs, _4.si]
   [junit4]   1> IW 105 [2016-02-07T16:44:44.390Z; Thread-1204]: commit: 
pendingCommit != null
   [junit4]   1> IW 105 [2016-02-07T16:44:44.391Z; Thread-1204]: commit: done 
writing segments file "segments_5"
   [junit4]   1> IFD 105 [2016-02-07T16:44:44.391Z; Thread-1204]: now 
checkpoint "_0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1 _3(6.0.0):c1 _4(6.0.0):c1" 
[5 segments ; isCommit = true]
   [junit4]   1> IFD 105 [2016-02-07T16:44:44.391Z; Thread-1204]: 
deleteCommits: now decRef commit "segments_4"
   [junit4]   1> IFD 105 [2016-02-07T16:44:44.391Z; Thread-1204]: delete 
"[segments_4]"
   [junit4]   1> IFD 105 [2016-02-07T16:44:44.392Z; Thread-1204]: 0 msec to 
checkpoint
   [junit4]   1> IFD 105 [2016-02-07T16:44:44.392Z; Thread-1204]: delete "[]"
   [junit4]   1> IW 105 [2016-02-07T16:44:44.392Z; Thread-1204]: commit: took 
5.7 msec
   [junit4]   1> IW 105 [2016-02-07T16:44:44.392Z; Thread-1204]: commit: done
   [junit4]   1> IW 105 [2016-02-07T16:44:44.392Z; Thread-1204]: rollback
   [junit4]   1> IW 105 [2016-02-07T16:44:44.392Z; Thread-1204]: all running 
merges have aborted
   [junit4]   1> IW 105 [2016-02-07T16:44:44.392Z; Thread-1204]: rollback: done 
finish merges
   [junit4]   1> DW 105 [2016-02-07T16:44:44.392Z; Thread-1204]: abort
   [junit4]   1> DW 105 [2016-02-07T16:44:44.392Z; Thread-1204]: done abort 
success=true
   [junit4]   1> IW 105 [2016-02-07T16:44:44.392Z; Thread-1204]: rollback: 
infos=_0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1 _3(6.0.0):c1 _4(6.0.0):c1
   [junit4]   1> IFD 105 [2016-02-07T16:44:44.392Z; Thread-1204]: now 
checkpoint "_0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1 _3(6.0.0):c1 _4(6.0.0):c1" 
[5 segments ; isCommit = false]
   [junit4]   1> IFD 105 [2016-02-07T16:44:44.392Z; Thread-1204]: delete "[]"
   [junit4]   1> IFD 105 [2016-02-07T16:44:44.392Z; Thread-1204]: 0 msec to 
checkpoint
   [junit4]   1> IFD 105 [2016-02-07T16:44:44.392Z; Thread-1204]: delete "[]"
   [junit4]   1> IFD 105 [2016-02-07T16:44:44.392Z; Thread-1204]: delete "[]"
   [junit4]   1> 
   [junit4]   1> TEST: WriterThread iter=4
   [junit4]   1> IFD 106 [2016-02-07T16:44:44.397Z; Thread-1204]: init: current 
segments file is "segments_5"; 
deletionPolicy=org.apache.lucene.index.KeepOnlyLastCommitDeletionPolicy@29b7b667
   [junit4]   1> IFD 106 [2016-02-07T16:44:44.397Z; Thread-1204]: init: load 
commit "segments_5"
   [junit4]   1> IFD 106 [2016-02-07T16:44:44.398Z; Thread-1204]: delete "[]"
   [junit4]   1> IFD 106 [2016-02-07T16:44:44.398Z; Thread-1204]: now 
checkpoint "_0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1 _3(6.0.0):c1 _4(6.0.0):c1" 
[5 segments ; isCommit = false]
   [junit4]   1> IFD 106 [2016-02-07T16:44:44.398Z; Thread-1204]: delete "[]"
   [junit4]   1> IFD 106 [2016-02-07T16:44:44.398Z; Thread-1204]: 0 msec to 
checkpoint
   [junit4]   1> IW 106 [2016-02-07T16:44:44.400Z; Thread-1204]: init: 
create=false
   [junit4]   1> IW 106 [2016-02-07T16:44:44.400Z; Thread-1204]: 
   [junit4]   1> 
dir=MockDirectoryWrapper(NIOFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\core\test\J1\temp\lucene.store.TestNativeFSLockFactory_3BBE714C9113ACE6-001\tempDir-005
 lockFactory=org.apache.lucene.store.NativeFSLockFactory@5a8347e2)
   [junit4]   1> index=_0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1 _3(6.0.0):c1 
_4(6.0.0):c1
   [junit4]   1> version=6.0.0
   [junit4]   1> analyzer=org.apache.lucene.analysis.MockAnalyzer
   [junit4]   1> ramBufferSizeMB=16.0
   [junit4]   1> maxBufferedDocs=-1
   [junit4]   1> maxBufferedDeleteTerms=-1
   [junit4]   1> mergedSegmentWarmer=null
   [junit4]   1> 
delPolicy=org.apache.lucene.index.KeepOnlyLastCommitDeletionPolicy
   [junit4]   1> commit=null
   [junit4]   1> openMode=APPEND
   [junit4]   1> similarity=org.apache.lucene.search.similarities.BM25Similarity
   [junit4]   1> mergeScheduler=ConcurrentMergeScheduler: maxThreadCount=-1, 
maxMergeCount=-1, ioThrottle=true
   [junit4]   1> codec=Asserting(Lucene60): 
{content=PostingsFormat(name=Asserting)}, docValues:{}
   [junit4]   1> infoStream=org.apache.lucene.util.PrintStreamInfoStream
   [junit4]   1> mergePolicy=[TieredMergePolicy: maxMergeAtOnce=10, 
maxMergeAtOnceExplicit=30, maxMergedSegmentMB=5120.0, floorSegmentMB=2.0, 
forceMergeDeletesPctAllowed=10.0, segmentsPerTier=10.0, 
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1
   [junit4]   1> 
indexerThreadPool=org.apache.lucene.index.DocumentsWriterPerThreadPool@68cb785f
   [junit4]   1> readerPooling=false
   [junit4]   1> perThreadHardLimitMB=1945
   [junit4]   1> useCompoundFile=true
   [junit4]   1> commitOnClose=true
   [junit4]   1> writer=org.apache.lucene.index.IndexWriter@2afaaefc
   [junit4]   1> 
   [junit4]   1> IW 106 [2016-02-07T16:44:44.400Z; Thread-1204]: 
MMapDirectory.UNMAP_SUPPORTED=true
   [junit4]   1> IW 106 [2016-02-07T16:44:44.401Z; Thread-1204]: now flush at 
close
   [junit4]   1> IW 106 [2016-02-07T16:44:44.401Z; Thread-1204]:   start flush: 
applyAllDeletes=true
   [junit4]   1> IW 106 [2016-02-07T16:44:44.401Z; Thread-1204]:   index before 
flush _0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1 _3(6.0.0):c1 _4(6.0.0):c1
   [junit4]   1> DW 106 [2016-02-07T16:44:44.401Z; Thread-1204]: startFullFlush
   [junit4]   1> DW 106 [2016-02-07T16:44:44.401Z; Thread-1204]: anyChanges? 
numDocsInRam=1 deletes=false hasTickets:false pendingChangesInFullFlush: false
   [junit4]   1> DWFC 106 [2016-02-07T16:44:44.401Z; Thread-1204]: 
addFlushableState DocumentsWriterPerThread [pendingDeletes=gen=0, segment=_5, 
aborted=false, numDocsInRAM=1, deleteQueue=DWDQ: [ generation: 0 ]]
   [junit4]   1> DWPT 106 [2016-02-07T16:44:44.401Z; Thread-1204]: flush 
postings as segment _5 numDocs=1
   [junit4]   1> IW 106 [2016-02-07T16:44:44.403Z; Thread-1204]: 1 msec to 
write norms
   [junit4]   1> IW 106 [2016-02-07T16:44:44.403Z; Thread-1204]: 0 msec to 
write docValues
   [junit4]   1> IW 106 [2016-02-07T16:44:44.403Z; Thread-1204]: 0 msec to 
write points
   [junit4]   1> IW 106 [2016-02-07T16:44:44.403Z; Thread-1204]: 0 msec to 
finish stored fields
   [junit4]   1> IW 106 [2016-02-07T16:44:44.407Z; Thread-1204]: 3 msec to 
write postings and finish vectors
   [junit4]   1> IW 106 [2016-02-07T16:44:44.407Z; Thread-1204]: 0 msec to 
write fieldInfos
   [junit4]   1> DWPT 106 [2016-02-07T16:44:44.407Z; Thread-1204]: new segment 
has 0 deleted docs
   [junit4]   1> DWPT 106 [2016-02-07T16:44:44.407Z; Thread-1204]: new segment 
has no vectors; norms; no docValues; prox; freqs
   [junit4]   1> DWPT 106 [2016-02-07T16:44:44.407Z; Thread-1204]: 
flushedFiles=[_5.nvm, _5.fnm, _5_Asserting_0.tip, _5.fdt, _5.nvd, 
_5_Asserting_0.pos, _5_Asserting_0.doc, _5_Asserting_0.tim, _5.fdx]
   [junit4]   1> DWPT 106 [2016-02-07T16:44:44.407Z; Thread-1204]: flushed 
codec=Asserting(Lucene60): {content=PostingsFormat(name=Asserting)}, 
docValues:{}
   [junit4]   1> DWPT 106 [2016-02-07T16:44:44.407Z; Thread-1204]: flushed: 
segment=_5 ramUsed=0.071 MB newFlushedSize=0.001 MB docs/MB=1,132.371
   [junit4]   1> IW 106 [2016-02-07T16:44:44.407Z; Thread-1204]: create 
compound file
   [junit4]   1> DWPT 106 [2016-02-07T16:44:44.411Z; Thread-1204]: flush time 
9.1549 msec
   [junit4]   1> DW 106 [2016-02-07T16:44:44.411Z; Thread-1204]: 
publishFlushedSegment seg-private updates=null
   [junit4]   1> IW 106 [2016-02-07T16:44:44.411Z; Thread-1204]: 
publishFlushedSegment
   [junit4]   1> IW 106 [2016-02-07T16:44:44.411Z; Thread-1204]: publish sets 
newSegment delGen=1 seg=_5(6.0.0):c1
   [junit4]   1> IFD 106 [2016-02-07T16:44:44.411Z; Thread-1204]: now 
checkpoint "_0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1 _3(6.0.0):c1 _4(6.0.0):c1 
_5(6.0.0):c1" [6 segments ; isCommit = false]
   [junit4]   1> IFD 106 [2016-02-07T16:44:44.411Z; Thread-1204]: delete "[]"
   [junit4]   1> IFD 106 [2016-02-07T16:44:44.411Z; Thread-1204]: 0 msec to 
checkpoint
   [junit4]   1> DW 106 [2016-02-07T16:44:44.411Z; Thread-1204]: Thread-1204 
finishFullFlush success=true
   [junit4]   1> IFD 106 [2016-02-07T16:44:44.411Z; Thread-1204]: will delete 
new file "_5.nvm"
   [junit4]   1> IFD 106 [2016-02-07T16:44:44.411Z; Thread-1204]: will delete 
new file "_5.fnm"
   [junit4]   1> IFD 106 [2016-02-07T16:44:44.411Z; Thread-1204]: will delete 
new file "_5_Asserting_0.tip"
   [junit4]   1> IFD 106 [2016-02-07T16:44:44.411Z; Thread-1204]: will delete 
new file "_5.fdt"
   [junit4]   1> IFD 106 [2016-02-07T16:44:44.411Z; Thread-1204]: will delete 
new file "_5.nvd"
   [junit4]   1> IFD 106 [2016-02-07T16:44:44.411Z; Thread-1204]: will delete 
new file "_5_Asserting_0.pos"
   [junit4]   1> IFD 106 [2016-02-07T16:44:44.411Z; Thread-1204]: will delete 
new file "_5_Asserting_0.doc"
   [junit4]   1> IFD 106 [2016-02-07T16:44:44.411Z; Thread-1204]: will delete 
new file "_5_Asserting_0.tim"
   [junit4]   1> IFD 106 [2016-02-07T16:44:44.411Z; Thread-1204]: will delete 
new file "_5.fdx"
   [junit4]   1> IFD 106 [2016-02-07T16:44:44.411Z; Thread-1204]: delete 
"[_5.nvm, _5.fnm, _5_Asserting_0.tip, _5.fdt, _5.nvd, _5_Asserting_0.pos, 
_5_Asserting_0.doc, _5_Asserting_0.tim, _5.fdx]"
   [junit4]   1> IW 106 [2016-02-07T16:44:44.413Z; Thread-1204]: apply all 
deletes during flush
   [junit4]   1> IW 106 [2016-02-07T16:44:44.413Z; Thread-1204]: now apply all 
deletes for all segments maxDoc=6
   [junit4]   1> BD 106 [2016-02-07T16:44:44.413Z; Thread-1204]: applyDeletes: 
open segment readers took 0 msec
   [junit4]   1> BD 106 [2016-02-07T16:44:44.413Z; Thread-1204]: applyDeletes: 
no segments; skipping
   [junit4]   1> BD 106 [2016-02-07T16:44:44.413Z; Thread-1204]: prune 
sis=segments_5: _0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1 _3(6.0.0):c1 
_4(6.0.0):c1 _5(6.0.0):c1 minGen=0 packetCount=0
   [junit4]   1> TMP 106 [2016-02-07T16:44:44.413Z; Thread-1204]: findMerges: 6 
segments
   [junit4]   1> TMP 106 [2016-02-07T16:44:44.414Z; Thread-1204]:   
seg=_0(6.0.0):c1 size=0.001 MB [floored]
   [junit4]   1> TMP 106 [2016-02-07T16:44:44.414Z; Thread-1204]:   
seg=_1(6.0.0):c1 size=0.001 MB [floored]
   [junit4]   1> TMP 106 [2016-02-07T16:44:44.414Z; Thread-1204]:   
seg=_2(6.0.0):c1 size=0.001 MB [floored]
   [junit4]   1> TMP 106 [2016-02-07T16:44:44.414Z; Thread-1204]:   
seg=_3(6.0.0):c1 size=0.001 MB [floored]
   [junit4]   1> TMP 106 [2016-02-07T16:44:44.414Z; Thread-1204]:   
seg=_4(6.0.0):c1 size=0.001 MB [floored]
   [junit4]   1> TMP 106 [2016-02-07T16:44:44.414Z; Thread-1204]:   
seg=_5(6.0.0):c1 size=0.001 MB [floored]
   [junit4]   1> TMP 106 [2016-02-07T16:44:44.414Z; Thread-1204]:   
allowedSegmentCount=1 vs count=6 (eligible count=6) tooBigCount=0
   [junit4]   1> MS 106 [2016-02-07T16:44:44.414Z; Thread-1204]: 
initDynamicDefaults spins=false maxThreadCount=1 maxMergeCount=6
   [junit4]   1> MS 106 [2016-02-07T16:44:44.414Z; Thread-1204]: now merge
   [junit4]   1> MS 106 [2016-02-07T16:44:44.414Z; Thread-1204]:   index: 
_0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1 _3(6.0.0):c1 _4(6.0.0):c1 _5(6.0.0):c1
   [junit4]   1> MS 106 [2016-02-07T16:44:44.414Z; Thread-1204]:   no more 
merges pending; now return
   [junit4]   1> MS 106 [2016-02-07T16:44:44.414Z; Thread-1204]: 
updateMergeThreads ioThrottle=true targetMBPerSec=10240.0 MB/sec
   [junit4]   1> MS 106 [2016-02-07T16:44:44.414Z; Thread-1204]: now merge
   [junit4]   1> MS 106 [2016-02-07T16:44:44.414Z; Thread-1204]:   index: 
_0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1 _3(6.0.0):c1 _4(6.0.0):c1 _5(6.0.0):c1
   [junit4]   1> MS 106 [2016-02-07T16:44:44.414Z; Thread-1204]:   no more 
merges pending; now return
   [junit4]   1> IW 106 [2016-02-07T16:44:44.414Z; Thread-1204]: waitForMerges
   [junit4]   1> IW 106 [2016-02-07T16:44:44.414Z; Thread-1204]: waitForMerges 
done
   [junit4]   1> IW 106 [2016-02-07T16:44:44.414Z; Thread-1204]: commit: start
   [junit4]   1> IW 106 [2016-02-07T16:44:44.414Z; Thread-1204]: commit: enter 
lock
   [junit4]   1> IW 106 [2016-02-07T16:44:44.414Z; Thread-1204]: commit: now 
prepare
   [junit4]   1> IW 106 [2016-02-07T16:44:44.414Z; Thread-1204]: prepareCommit: 
flush
   [junit4]   1> IW 106 [2016-02-07T16:44:44.414Z; Thread-1204]:   index before 
flush _0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1 _3(6.0.0):c1 _4(6.0.0):c1 
_5(6.0.0):c1
   [junit4]   1> DW 106 [2016-02-07T16:44:44.414Z; Thread-1204]: startFullFlush
   [junit4]   1> IW 106 [2016-02-07T16:44:44.414Z; Thread-1204]: apply all 
deletes during flush
   [junit4]   1> IW 106 [2016-02-07T16:44:44.414Z; Thread-1204]: now apply all 
deletes for all segments maxDoc=6
   [junit4]   1> BD 106 [2016-02-07T16:44:44.414Z; Thread-1204]: applyDeletes: 
open segment readers took 0 msec
   [junit4]   1> BD 106 [2016-02-07T16:44:44.414Z; Thread-1204]: applyDeletes: 
no segments; skipping
   [junit4]   1> BD 106 [2016-02-07T16:44:44.414Z; Thread-1204]: prune 
sis=segments_5: _0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1 _3(6.0.0):c1 
_4(6.0.0):c1 _5(6.0.0):c1 minGen=0 packetCount=0
   [junit4]   1> DW 106 [2016-02-07T16:44:44.414Z; Thread-1204]: Thread-1204 
finishFullFlush success=true
   [junit4]   1> IW 106 [2016-02-07T16:44:44.414Z; Thread-1204]: startCommit(): 
start
   [junit4]   1> IW 106 [2016-02-07T16:44:44.414Z; Thread-1204]: startCommit 
index=_0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1 _3(6.0.0):c1 _4(6.0.0):c1 
_5(6.0.0):c1 changeCount=3
   [junit4]   1> IW 106 [2016-02-07T16:44:44.418Z; Thread-1204]: startCommit: 
wrote pending segments file "pending_segments_6"
   [junit4]   1> IW 106 [2016-02-07T16:44:44.418Z; Thread-1204]: done all 
syncs: [_0.si, _3.si, _1.cfe, _1.si, _2.si, _0.cfs, _5.cfs, _2.cfs, _4.cfe, 
_3.cfe, _1.cfs, _0.cfe, _2.cfe, _3.cfs, _5.cfe, _4.cfs, _4.si, _5.si]
   [junit4]   1> IW 106 [2016-02-07T16:44:44.418Z; Thread-1204]: commit: 
pendingCommit != null
   [junit4]   1> IW 106 [2016-02-07T16:44:44.419Z; Thread-1204]: commit: done 
writing segments file "segments_6"
   [junit4]   1> IFD 106 [2016-02-07T16:44:44.419Z; Thread-1204]: now 
checkpoint "_0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1 _3(6.0.0):c1 _4(6.0.0):c1 
_5(6.0.0):c1" [6 segments ; isCommit = true]
   [junit4]   1> IFD 106 [2016-02-07T16:44:44.419Z; Thread-1204]: 
deleteCommits: now decRef commit "segments_5"
   [junit4]   1> IFD 106 [2016-02-07T16:44:44.419Z; Thread-1204]: delete 
"[segments_5]"
   [junit4]   1> IFD 106 [2016-02-07T16:44:44.420Z; Thread-1204]: 0 msec to 
checkpoint
   [junit4]   1> IFD 106 [2016-02-07T16:44:44.420Z; Thread-1204]: delete "[]"
   [junit4]   1> IW 106 [2016-02-07T16:44:44.420Z; Thread-1204]: commit: took 
5.4 msec
   [junit4]   1> IW 106 [2016-02-07T16:44:44.420Z; Thread-1204]: commit: done
   [junit4]   1> IW 106 [2016-02-07T16:44:44.420Z; Thread-1204]: rollback
   [junit4]   1> IW 106 [2016-02-07T16:44:44.420Z; Thread-1204]: all running 
merges have aborted
   [junit4]   1> IW 106 [2016-02-07T16:44:44.420Z; Thread-1204]: rollback: done 
finish merges
   [junit4]   1> DW 106 [2016-02-07T16:44:44.420Z; Thread-1204]: abort
   [junit4]   1> DW 106 [2016-02-07T16:44:44.420Z; Thread-1204]: done abort 
success=true
   [junit4]   1> IW 106 [2016-02-07T16:44:44.420Z; Thread-1204]: rollback: 
infos=_0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1 _3(6.0.0):c1 _4(6.0.0):c1 
_5(6.0.0):c1
   [junit4]   1> IFD 106 [2016-02-07T16:44:44.420Z; Thread-1204]: now 
checkpoint "_0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1 _3(6.0.0):c1 _4(6.0.0):c1 
_5(6.0.0):c1" [6 segments ; isCommit = false]
   [junit4]   1> IFD 106 [2016-02-07T16:44:44.420Z; Thread-1204]: delete "[]"
   [junit4]   1> IFD 106 [2016-02-07T16:44:44.420Z; Thread-1204]: 0 msec to 
checkpoint
   [junit4]   1> IFD 106 [2016-02-07T16:44:44.420Z; Thread-1204]: delete "[]"
   [junit4]   1> IFD 106 [2016-02-07T16:44:44.420Z; Thread-1204]: delete "[]"
   [junit4]   1> 
   [junit4]   1> TEST: WriterThread iter=5
   [junit4]   1> IFD 107 [2016-02-07T16:44:44.424Z; Thread-1204]: init: current 
segments file is "segments_6"; 
deletionPolicy=org.apache.lucene.index.KeepOnlyLastCommitDeletionPolicy@6887e1fe
   [junit4]   1> IFD 107 [2016-02-07T16:44:44.424Z; Thread-1204]: init: load 
commit "segments_6"
   [junit4]   1> IFD 107 [2016-02-07T16:44:44.425Z; Thread-1204]: delete "[]"
   [junit4]   1> IFD 107 [2016-02-07T16:44:44.425Z; Thread-1204]: now 
checkpoint "_0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1 _3(6.0.0):c1 _4(6.0.0):c1 
_5(6.0.0):c1" [6 segments ; isCommit = false]
   [junit4]   1> IFD 107 [2016-02-07T16:44:44.425Z; Thread-1204]: delete "[]"
   [junit4]   1> IFD 107 [2016-02-07T16:44:44.425Z; Thread-1204]: 0 msec to 
checkpoint
   [junit4]   1> IW 107 [2016-02-07T16:44:44.428Z; Thread-1204]: init: 
create=false
   [junit4]   1> IW 107 [2016-02-07T16:44:44.428Z; Thread-1204]: 
   [junit4]   1> 
dir=MockDirectoryWrapper(NIOFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\core\test\J1\temp\lucene.store.TestNativeFSLockFactory_3BBE714C9113ACE6-001\tempDir-005
 lockFactory=org.apache.lucene.store.NativeFSLockFactory@5a8347e2)
   [junit4]   1> index=_0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1 _3(6.0.0):c1 
_4(6.0.0):c1 _5(6.0.0):c1
   [junit4]   1> version=6.0.0
   [junit4]   1> analyzer=org.apache.lucene.analysis.MockAnalyzer
   [junit4]   1> ramBufferSizeMB=16.0
   [junit4]   1> maxBufferedDocs=-1
   [junit4]   1> maxBufferedDeleteTerms=-1
   [junit4]   1> mergedSegmentWarmer=null
   [junit4]   1> 
delPolicy=org.apache.lucene.index.KeepOnlyLastCommitDeletionPolicy
   [junit4]   1> commit=null
   [junit4]   1> openMode=APPEND
   [junit4]   1> similarity=org.apache.lucene.search.similarities.BM25Similarity
   [junit4]   1> mergeScheduler=ConcurrentMergeScheduler: maxThreadCount=-1, 
maxMergeCount=-1, ioThrottle=true
   [junit4]   1> codec=Asserting(Lucene60): 
{content=PostingsFormat(name=Asserting)}, docValues:{}
   [junit4]   1> infoStream=org.apache.lucene.util.PrintStreamInfoStream
   [junit4]   1> mergePolicy=[TieredMergePolicy: maxMergeAtOnce=10, 
maxMergeAtOnceExplicit=30, maxMergedSegmentMB=5120.0, floorSegmentMB=2.0, 
forceMergeDeletesPctAllowed=10.0, segmentsPerTier=10.0, 
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1
   [junit4]   1> 
indexerThreadPool=org.apache.lucene.index.DocumentsWriterPerThreadPool@48116e4d
   [junit4]   1> readerPooling=false
   [junit4]   1> perThreadHardLimitMB=1945
   [junit4]   1> useCompoundFile=true
   [junit4]   1> commitOnClose=true
   [junit4]   1> writer=org.apache.lucene.index.IndexWriter@35c0c4dc
   [junit4]   1> 
   [junit4]   1> IW 107 [2016-02-07T16:44:44.428Z; Thread-1204]: 
MMapDirectory.UNMAP_SUPPORTED=true
   [junit4]   1> IW 107 [2016-02-07T16:44:44.429Z; Thread-1204]: now flush at 
close
   [junit4]   1> IW 107 [2016-02-07T16:44:44.429Z; Thread-1204]:   start flush: 
applyAllDeletes=true
   [junit4]   1> IW 107 [2016-02-07T16:44:44.429Z; Thread-1204]:   index before 
flush _0(6.0.0):c1 _1(6.0.0):c1 _2(6.0.0):c1 _3(6.0.0):c1 _4(6.0.0):c1 
_5(6.0.0):c1
   [junit4]   1> DW 107 [2016-02-07T16:44:44.429Z; Thread-1204]: startFullFlush
   [junit4]   1> DW 107 [2016-02-07T16:44:44.429Z; Thread-1204]: anyChanges? 
numDocsInRam=1 deletes=false hasTickets:false pendingChangesInFullFlush: false
   [junit4]   1> DWFC 107 [2016-02-07T16:44:44.429Z; Thread-1204]: 
addFlushableState DocumentsWriterPerThread [pendingDeletes=gen=0, segment=_6, 
aborted=false, numDocsInRAM=1, deleteQueue=DWDQ: [ generation: 0 ]]
   [junit4]   1> DWPT 107 [2016-02-07T16:44:44.429Z; Thread-1204]: flush 
postings as segment _6 numDocs=1
   [junit4]   1> IW 107 [2016-02-07T16:44:44.431Z; Thread-1204]: 1 msec to 
write 

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

Values:{}
   [junit4]   1> infoStream=org.apache.lucene.util.PrintStreamInfoStream
   [junit4]   1> mergePolicy=[TieredMergePolicy: maxMergeAtOnce=10, 
maxMergeAtOnceExplicit=30, maxMergedSegmentMB=5120.0, floorSegmentMB=2.0, 
forceMergeDeletesPctAllowed=10.0, segmentsPerTier=10.0, 
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1
   [junit4]   1> 
indexerThreadPool=org.apache.lucene.index.DocumentsWriterPerThreadPool@33c4b902
   [junit4]   1> readerPooling=false
   [junit4]   1> perThreadHardLimitMB=1945
   [junit4]   1> useCompoundFile=true
   [junit4]   1> commitOnClose=true
   [junit4]   1> writer=org.apache.lucene.index.IndexWriter@557aa67f
   [junit4]   1> 
   [junit4]   1> IW 111 [2016-02-07T16:44:44.591Z; Thread-1204]: 
MMapDirectory.UNMAP_SUPPORTED=true
   [junit4]   1> IW 111 [2016-02-07T16:44:44.592Z; Thread-1204]: now flush at 
close
   [junit4]   1> IW 111 [2016-02-07T16:44:44.592Z; Thread-1204]:   start flush: 
applyAllDeletes=true
   [junit4]   1> IW 111 [2016-02-07T16:44:44.592Z; Thread-1204]:   index before 
flush _a(6.0.0):c10
   [junit4]   1> DW 111 [2016-02-07T16:44:44.592Z; Thread-1204]: startFullFlush
   [junit4]   1> DW 111 [2016-02-07T16:44:44.592Z; Thread-1204]: anyChanges? 
numDocsInRam=1 deletes=false hasTickets:false pendingChangesInFullFlush: false
   [junit4]   1> DWFC 111 [2016-02-07T16:44:44.592Z; Thread-1204]: 
addFlushableState DocumentsWriterPerThread [pendingDeletes=gen=0, segment=_b, 
aborted=false, numDocsInRAM=1, deleteQueue=DWDQ: [ generation: 0 ]]
   [junit4]   1> DWPT 111 [2016-02-07T16:44:44.592Z; Thread-1204]: flush 
postings as segment _b numDocs=1
   [junit4]   1> IW 111 [2016-02-07T16:44:44.593Z; Thread-1204]: 1 msec to 
write norms
   [junit4]   1> IW 111 [2016-02-07T16:44:44.593Z; Thread-1204]: 0 msec to 
write docValues
   [junit4]   1> IW 111 [2016-02-07T16:44:44.593Z; Thread-1204]: 0 msec to 
write points
   [junit4]   1> IW 111 [2016-02-07T16:44:44.594Z; Thread-1204]: 0 msec to 
finish stored fields
   [junit4]   1> DWPT 111 [2016-02-07T16:44:44.594Z; Thread-1204]: now abort
   [junit4]   1> DWPT 111 [2016-02-07T16:44:44.594Z; Thread-1204]: done abort
   [junit4]   1> DW 111 [2016-02-07T16:44:44.594Z; Thread-1204]: Thread-1204 
finishFullFlush success=false
   [junit4]   1> IW 111 [2016-02-07T16:44:44.594Z; Thread-1204]: hit tragic 
NoSuchFileException inside doFlush
   [junit4]   1> IW 111 [2016-02-07T16:44:44.594Z; Thread-1204]: hit exception 
during flush
   [junit4]   1> IW 111 [2016-02-07T16:44:44.594Z; Thread-1204]: rollback
   [junit4]   1> IW 111 [2016-02-07T16:44:44.594Z; Thread-1204]: all running 
merges have aborted
   [junit4]   1> IW 111 [2016-02-07T16:44:44.594Z; Thread-1204]: rollback: done 
finish merges
   [junit4]   1> DW 111 [2016-02-07T16:44:44.594Z; Thread-1204]: abort
   [junit4]   1> DW 111 [2016-02-07T16:44:44.594Z; Thread-1204]: done abort 
success=true
   [junit4]   1> IW 111 [2016-02-07T16:44:44.594Z; Thread-1204]: rollback: 
infos=_a(6.0.0):c10
   [junit4]   1> 
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestNativeFSLockFactory -Dtests.method=testStressLocks 
-Dtests.seed=3BBE714C9113ACE6 -Dtests.slow=true -Dtests.locale=it-CH 
-Dtests.timezone=Africa/Accra -Dtests.asserts=true 
-Dtests.file.encoding=ISO-8859-1
   [junit4] FAILURE 1.04s J1 | TestNativeFSLockFactory.testStressLocks <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: IndexWriter hit 
unexpected exceptions
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([3BBE714C9113ACE6:658F3FB18DBF6480]:0)
   [junit4]    >        at 
org.apache.lucene.store.BaseLockFactoryTestCase.testStressLocks(BaseLockFactoryTestCase.java:180)
   [junit4]    >        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> NOTE: leaving temporary files on disk at: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\core\test\J1\temp\lucene.store.TestNativeFSLockFactory_3BBE714C9113ACE6-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene60): 
{content=PostingsFormat(name=Asserting)}, docValues:{}, 
sim=RandomSimilarity(queryNorm=true,coord=no): {content=IB SPL-L3(800.0)}, 
locale=it-CH, timezone=Africa/Accra
   [junit4]   2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.8.0_72 
(64-bit)/cpus=3,threads=1,free=204488648,total=234319872
   [junit4]   2> NOTE: All tests run in this JVM: [TestStressDeletes, 
TestBoostQuery, TestMultiTermConstantScore, TestBlockPostingsFormat3, 
TestStringHelper, TestWildcardRandom, TestSpansEnum, TestMergeRateLimiter, 
TestApproximationSearchEquivalence, TestStressIndexing2, 
TestMergeSchedulerExternal, TestAllFilesHaveChecksumFooter, 
TestSearchForDuplicates, LimitedFiniteStringsIteratorTest, 
TestMaxTermFrequency, TestSameScoresWithThreads, TestFastDecompressionMode, 
TestIndexWriterMergePolicy, TestCustomNorms, TestIndexWriterFromReader, 
TestSleepingLockWrapper, TestSpanFirstQuery, TestBlockPostingsFormat, 
TestIndexableField, TestSetOnce, TestByteArrayDataInput, TestByteBlockPool, 
TestIndexWriterUnicode, TestIndexFileDeleter, TestIndexWriterOnJRECrash, 
TestNRTCachingDirectory, TestDeterminism, TestFilterDirectoryReader, TestCrash, 
TestMaxPosition, TestMultiPhraseQuery, TestDocumentsWriterStallControl, 
TestIndexInput, TestReaderWrapperDVTypeCheck, TestTieredMergePolicy, 
TestDuelingCodecsAtNight, TestPhraseQuery, TestBytesRefAttImpl, 
TestAttributeSource, TestSpanNearQuery, TestFieldMaskingSpanQuery, 
TestLongPostings, Test2BSortedDocValuesFixedSorted, TestTermVectorsReader, 
TestDocumentWriter, TestPayloads, TestMixedCodecs, 
TestIndexWriterDeleteByQuery, TestSentinelIntSet, TestAllFilesCheckIndexHeader, 
TestIndexSearcher, TestQueryBuilder, TestDirectoryReader, TestPointValues, 
TestNumericRangeQuery64, TestBKD, TestMultiThreadTermVectors, TestAddIndexes, 
TestLucene50SegmentInfoFormat, TestDocIdSetBuilder, TestIndexWriterCommit, 
TestRecyclingIntBlockAllocator, TestNewestSegment, TestOmitTf, 
TestNumericRangeQuery32, TestSortedSetSelector, TestLogMergePolicy, 
TestBM25Similarity, TestConjunctions, TestNotDocIdSet, TestMultiCollector, 
TestNumericDocValuesUpdates, TestDirectMonotonic, TestTerm, TestPointQueries, 
TestBooleanQueryVisitSubscorers, TestPrefixRandom, TestMultiMMap, 
TestNIOFSDirectory, TestBytesRefArray, TestDateSort, TestLegacyNumericUtils, 
TestStressAdvance, TestReaderClosed, TestSpanOrQuery, TestHighCompressionMode, 
TestMatchAllDocsQuery, TestNRTThreads, TestTopFieldCollector, 
TestParallelLeafReader, TestDocBoost, TestPositionIncrement, TestRegexpRandom, 
TestLucene60PointFormat, TestDocCount, TestAutomatonQuery, 
TestMatchNoDocsQuery, TestIndexWriter, TestPerFieldDocValuesFormat, 
TestIndexWriterMerging, TestPackedInts, TestBooleanOr, TestSloppyPhraseQuery, 
TestPersistentSnapshotDeletionPolicy, TestSearchWithThreads, TestSearchAfter, 
TestConcurrentMergeScheduler, TestTopDocsMerge, TestStressIndexing, 
TestSnapshotDeletionPolicy, TestArrayUtil, TestLevenshteinAutomata, 
TestConsistentFieldNumbers, TestWeakIdentityMap, TestSimpleExplanations, 
TestLockFactory, TestSegmentMerger, TestBagOfPositions, TestOmitNorms, 
TestFixedBitSet, TestSegmentReader, TestIndexWriterNRTIsCurrent, 
TestSegmentTermDocs, TestSort, TestFieldsReader, TestDocValuesIndexing, 
TestHugeRamFile, TestDeterminizeLexicon, TestTransactionRollback, 
TestSpanExplanations, TestFastCompressionMode, TestCodecs, TestOmitPositions, 
TestDisjunctionMaxQuery, TestNearSpansOrdered, TestDocumentsWriterDeleteQueue, 
TestForTooMuchCloning, TestSpanSearchEquivalence, TestSimilarity2, 
TestSimpleExplanationsOfNonMatches, TestMultiValuedNumericRangeQuery, 
TestTopDocsCollector, TestComplexExplanationsOfNonMatches, TestSortRandom, 
TestDocument, TestFileSwitchDirectory, TestParallelTermEnum, 
TestMultiTermQueryRewrites, TestSpanExplanationsOfNonMatches, 
TestRollingBuffer, TestFilterLeafReader, TestCachingTokenFilter, TestCharsRef, 
Test2BPostings, TestCachingCollector, TestAutomatonQueryUnicode, 
TestBooleanScorer, TestDemo, TestRollback, TestCloseableThreadLocal, 
TestCodecHoldsOpenFiles, TestSimpleAttributeImpl, 
TestPositiveScoresOnlyCollector, Test2BTerms, TestReusableStringReader, 
TestGrowableByteArrayDataOutput, TestLucene50FieldInfoFormat, 
TestLucene50StoredFieldsFormatHighCompression, TestLucene50TermVectorsFormat, 
TestDocInverterPerFieldErrorInfo, TestExceedMaxTermLength, 
TestIndexReaderClose, TestIndexWriterExceptions2, 
TestIndexWriterThreadsToSegments, TestMergePolicyWrapper, TestMultiTermsEnum, 
TestNRTReaderCleanup, TestTermVectors, TestTryDelete, 
FuzzyTermOnShortTermsTest, TestBooleanCoord, 
TestControlledRealTimeReopenThread, TestDocValuesRewriteMethod, 
TestEarlyTermination, TestLRUQueryCache, TestSpanBoostQuery, 
TestSpanContainQuery, TestBufferedChecksum, TestNativeFSLockFactory]
   [junit4] Completed [395/412 (2!)] on J1 in 10.94s, 10 tests, 1 failure <<< 
FAILURES!

[...truncated 64 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:740: The 
following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:684: The 
following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:59: The 
following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build.xml:50: 
The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1457:
 The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1014:
 There were test failures: 412 suites (1 ignored), 3384 tests, 1 error, 1 
failure, 65 ignored (61 assumptions) [seed: 3BBE714C9113ACE6]

Total time: 4 minutes 46 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any



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