lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@thetaphi.de>
Subject [JENKINS] Lucene-Solr-5.5-MacOSX (64bit/jdk1.8.0) - Build # 3 - Failure!
Date Mon, 22 Feb 2016 06:41:19 GMT
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.5-MacOSX/3/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseParallelGC

1 tests failed.
FAILED:  org.apache.lucene.TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler

Error Message:


Stack Trace:
java.lang.AssertionError
	at __randomizedtesting.SeedInfo.seed([A2BDF226D5BAFBA8:253C4F8BD19A81AC]:0)
	at org.junit.Assert.fail(Assert.java:92)
	at org.junit.Assert.assertTrue(Assert.java:43)
	at org.junit.Assert.assertTrue(Assert.java:54)
	at org.apache.lucene.TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler(TestMergeSchedulerExternal.java:144)
	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 1056 lines...]
   [junit4] Suite: org.apache.lucene.TestMergeSchedulerExternal
   [junit4]   1> TEST FAILED; IW infoStream output:
   [junit4]   1> IFD 100 [2016-02-22T06:39:21.348Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: init: current segments file is "segments"; deletionPolicy=org.apache.lucene.index.KeepOnlyLastCommitDeletionPolicy@162cfd3e
   [junit4]   1> IFD 100 [2016-02-22T06:39:21.348Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: now checkpoint "" [0 segments ; isCommit = false]
   [junit4]   1> IFD 100 [2016-02-22T06:39:21.348Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 0 msec to checkpoint
   [junit4]   1> IW 100 [2016-02-22T06:39:21.348Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: init: create=true
   [junit4]   1> IW 100 [2016-02-22T06:39:21.348Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 
   [junit4]   1> dir=MockDirectoryWrapper(RAMDirectory@8c7547a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1308524f)
   [junit4]   1> index=
   [junit4]   1> version=5.5.0
   [junit4]   1> analyzer=org.apache.lucene.analysis.MockAnalyzer
   [junit4]   1> ramBufferSizeMB=-1.0
   [junit4]   1> maxBufferedDocs=2
   [junit4]   1> maxBufferedDeleteTerms=-1
   [junit4]   1> mergedSegmentWarmer=null
   [junit4]   1> delPolicy=org.apache.lucene.index.KeepOnlyLastCommitDeletionPolicy
   [junit4]   1> commit=null
   [junit4]   1> openMode=CREATE_OR_APPEND
   [junit4]   1> similarity=org.apache.lucene.search.similarities.RandomSimilarity
   [junit4]   1> mergeScheduler=MyMergeScheduler: maxThreadCount=-1, maxMergeCount=-1, ioThrottle=true
   [junit4]   1> default WRITE_LOCK_TIMEOUT=0
   [junit4]   1> writeLockTimeout=0
   [junit4]   1> codec=Asserting(Lucene54): {}, docValues:{}
   [junit4]   1> infoStream=org.apache.lucene.util.PrintStreamInfoStream
   [junit4]   1> mergePolicy=[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=30, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=0.9658203125, noCFSRatio=1.0]
   [junit4]   1> indexerThreadPool=org.apache.lucene.index.DocumentsWriterPerThreadPool@585a706a
   [junit4]   1> readerPooling=true
   [junit4]   1> perThreadHardLimitMB=1945
   [junit4]   1> useCompoundFile=false
   [junit4]   1> commitOnClose=true
   [junit4]   1> writer=org.apache.lucene.index.IndexWriter@6a0c8b9f
   [junit4]   1> 
   [junit4]   1> IW 100 [2016-02-22T06:39:21.348Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: MMapDirectory.UNMAP_SUPPORTED=true
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.354Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: flush postings as segment _0 numDocs=2
   [junit4]   1> IW 100 [2016-02-22T06:39:21.354Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 0 msec to write norms
   [junit4]   1> IW 100 [2016-02-22T06:39:21.354Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 0 msec to write docValues
   [junit4]   1> IW 100 [2016-02-22T06:39:21.362Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 7 msec to finish stored fields
   [junit4]   1> IW 100 [2016-02-22T06:39:21.399Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 36 msec to write postings and finish vectors
   [junit4]   1> IW 100 [2016-02-22T06:39:21.407Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 8 msec to write fieldInfos
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.407Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: new segment has 0 deleted docs
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.407Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: new segment has no vectors; no norms; no docValues; no prox; no freqs
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.407Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: flushedFiles=[_0.fdx, _0_BlockTreeOrds_0.tio, _0_BlockTreeOrds_0.doc, _0.fdt, _0_BlockTreeOrds_0.tipo, _0.fnm]
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.407Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: flushed codec=Asserting(Lucene54): {id=BlockTreeOrds(blocksize=128)}, docValues:{}
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.413Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: flushed: segment=_0 ramUsed=0.071 MB newFlushedSize=0.001 MB docs/MB=2,884.666
   [junit4]   1> DW 100 [2016-02-22T06:39:21.447Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: publishFlushedSegment seg-private updates=null
   [junit4]   1> IW 100 [2016-02-22T06:39:21.447Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: publishFlushedSegment
   [junit4]   1> IW 100 [2016-02-22T06:39:21.447Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: publish sets newSegment delGen=1 seg=_0(5.5.0):C2
   [junit4]   1> IFD 100 [2016-02-22T06:39:21.447Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: now checkpoint "_0(5.5.0):C2" [1 segments ; isCommit = false]
   [junit4]   1> IFD 100 [2016-02-22T06:39:21.447Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 0 msec to checkpoint
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.447Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: findMerges: 1 segments
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.451Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_0(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.451Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]:   level -1.0 to 3.0659528: 1 segments
   [junit4]   1> MS 100 [2016-02-22T06:39:21.451Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: initDynamicDefaults spins=true maxThreadCount=1 maxMergeCount=6
   [junit4]   1> MS 100 [2016-02-22T06:39:21.451Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: now merge
   [junit4]   1> MS 100 [2016-02-22T06:39:21.451Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]:   index: _0(5.5.0):C2
   [junit4]   1> MS 100 [2016-02-22T06:39:21.451Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]:   no more merges pending; now return
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.459Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: flush postings as segment _1 numDocs=2
   [junit4]   1> IW 100 [2016-02-22T06:39:21.459Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 0 msec to write norms
   [junit4]   1> IW 100 [2016-02-22T06:39:21.459Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 0 msec to write docValues
   [junit4]   1> IW 100 [2016-02-22T06:39:21.468Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 8 msec to finish stored fields
   [junit4]   1> IW 100 [2016-02-22T06:39:21.509Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 41 msec to write postings and finish vectors
   [junit4]   1> IW 100 [2016-02-22T06:39:21.521Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 12 msec to write fieldInfos
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.521Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: new segment has 0 deleted docs
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.521Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: new segment has no vectors; no norms; no docValues; no prox; no freqs
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.521Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: flushedFiles=[_1_BlockTreeOrds_0.tipo, _1.fdx, _1_BlockTreeOrds_0.doc, _1.fnm, _1_BlockTreeOrds_0.tio, _1.fdt]
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.521Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: flushed codec=Asserting(Lucene54): {id=BlockTreeOrds(blocksize=128)}, docValues:{}
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.521Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: flushed: segment=_1 ramUsed=0.071 MB newFlushedSize=0.001 MB docs/MB=2,884.666
   [junit4]   1> DW 100 [2016-02-22T06:39:21.542Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: publishFlushedSegment seg-private updates=null
   [junit4]   1> IW 100 [2016-02-22T06:39:21.542Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: publishFlushedSegment
   [junit4]   1> IW 100 [2016-02-22T06:39:21.542Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: publish sets newSegment delGen=2 seg=_1(5.5.0):C2
   [junit4]   1> IFD 100 [2016-02-22T06:39:21.542Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: now checkpoint "_0(5.5.0):C2 _1(5.5.0):C2" [2 segments ; isCommit = false]
   [junit4]   1> IFD 100 [2016-02-22T06:39:21.542Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 0 msec to checkpoint
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.542Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: findMerges: 2 segments
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.542Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_0(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.543Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_1(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.543Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]:   level -1.0 to 3.0659528: 2 segments
   [junit4]   1> MS 100 [2016-02-22T06:39:21.543Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: now merge
   [junit4]   1> MS 100 [2016-02-22T06:39:21.543Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]:   index: _0(5.5.0):C2 _1(5.5.0):C2
   [junit4]   1> MS 100 [2016-02-22T06:39:21.543Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]:   no more merges pending; now return
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.548Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: flush postings as segment _2 numDocs=2
   [junit4]   1> IW 100 [2016-02-22T06:39:21.548Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 0 msec to write norms
   [junit4]   1> IW 100 [2016-02-22T06:39:21.548Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 0 msec to write docValues
   [junit4]   1> IW 100 [2016-02-22T06:39:21.556Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 7 msec to finish stored fields
   [junit4]   1> IW 100 [2016-02-22T06:39:21.586Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 29 msec to write postings and finish vectors
   [junit4]   1> IW 100 [2016-02-22T06:39:21.592Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 6 msec to write fieldInfos
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.592Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: new segment has 0 deleted docs
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.592Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: new segment has no vectors; no norms; no docValues; no prox; no freqs
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.592Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: flushedFiles=[_2_BlockTreeOrds_0.doc, _2_BlockTreeOrds_0.tio, _2.fdt, _2_BlockTreeOrds_0.tipo, _2.fnm, _2.fdx]
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.592Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: flushed codec=Asserting(Lucene54): {id=BlockTreeOrds(blocksize=128)}, docValues:{}
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.593Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: flushed: segment=_2 ramUsed=0.071 MB newFlushedSize=0.001 MB docs/MB=2,884.666
   [junit4]   1> DW 100 [2016-02-22T06:39:21.607Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: publishFlushedSegment seg-private updates=null
   [junit4]   1> IW 100 [2016-02-22T06:39:21.607Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: publishFlushedSegment
   [junit4]   1> IW 100 [2016-02-22T06:39:21.607Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: publish sets newSegment delGen=3 seg=_2(5.5.0):C2
   [junit4]   1> IFD 100 [2016-02-22T06:39:21.607Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: now checkpoint "_0(5.5.0):C2 _1(5.5.0):C2 _2(5.5.0):C2" [3 segments ; isCommit = false]
   [junit4]   1> IFD 100 [2016-02-22T06:39:21.607Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 0 msec to checkpoint
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.607Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: findMerges: 3 segments
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.607Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_0(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.607Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_1(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.609Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_2(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.609Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]:   level -1.0 to 3.0659528: 3 segments
   [junit4]   1> MS 100 [2016-02-22T06:39:21.609Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: now merge
   [junit4]   1> MS 100 [2016-02-22T06:39:21.609Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]:   index: _0(5.5.0):C2 _1(5.5.0):C2 _2(5.5.0):C2
   [junit4]   1> MS 100 [2016-02-22T06:39:21.609Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]:   no more merges pending; now return
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.614Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: flush postings as segment _3 numDocs=2
   [junit4]   1> IW 100 [2016-02-22T06:39:21.614Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 0 msec to write norms
   [junit4]   1> IW 100 [2016-02-22T06:39:21.614Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 0 msec to write docValues
   [junit4]   1> IW 100 [2016-02-22T06:39:21.622Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 7 msec to finish stored fields
   [junit4]   1> IW 100 [2016-02-22T06:39:21.652Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 29 msec to write postings and finish vectors
   [junit4]   1> IW 100 [2016-02-22T06:39:21.664Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 12 msec to write fieldInfos
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.664Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: new segment has 0 deleted docs
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.664Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: new segment has no vectors; no norms; no docValues; no prox; no freqs
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.664Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: flushedFiles=[_3.fdx, _3_BlockTreeOrds_0.tipo, _3.fnm, _3.fdt, _3_BlockTreeOrds_0.tio, _3_BlockTreeOrds_0.doc]
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.664Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: flushed codec=Asserting(Lucene54): {id=BlockTreeOrds(blocksize=128)}, docValues:{}
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.666Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: flushed: segment=_3 ramUsed=0.071 MB newFlushedSize=0.001 MB docs/MB=2,884.666
   [junit4]   1> DW 100 [2016-02-22T06:39:21.684Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: publishFlushedSegment seg-private updates=null
   [junit4]   1> IW 100 [2016-02-22T06:39:21.684Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: publishFlushedSegment
   [junit4]   1> IW 100 [2016-02-22T06:39:21.684Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: publish sets newSegment delGen=4 seg=_3(5.5.0):C2
   [junit4]   1> IFD 100 [2016-02-22T06:39:21.684Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: now checkpoint "_0(5.5.0):C2 _1(5.5.0):C2 _2(5.5.0):C2 _3(5.5.0):C2" [4 segments ; isCommit = false]
   [junit4]   1> IFD 100 [2016-02-22T06:39:21.684Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 0 msec to checkpoint
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.684Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: findMerges: 4 segments
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.685Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_0(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.685Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_1(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.685Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_2(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.69Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_3(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.69Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]:   level -1.0 to 3.0659528: 4 segments
   [junit4]   1> MS 100 [2016-02-22T06:39:21.69Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: now merge
   [junit4]   1> MS 100 [2016-02-22T06:39:21.69Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]:   index: _0(5.5.0):C2 _1(5.5.0):C2 _2(5.5.0):C2 _3(5.5.0):C2
   [junit4]   1> MS 100 [2016-02-22T06:39:21.69Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]:   no more merges pending; now return
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.696Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: flush postings as segment _4 numDocs=2
   [junit4]   1> IW 100 [2016-02-22T06:39:21.696Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 0 msec to write norms
   [junit4]   1> IW 100 [2016-02-22T06:39:21.696Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 0 msec to write docValues
   [junit4]   1> IW 100 [2016-02-22T06:39:21.703Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 7 msec to finish stored fields
   [junit4]   1> IW 100 [2016-02-22T06:39:21.738Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 30 msec to write postings and finish vectors
   [junit4]   1> IW 100 [2016-02-22T06:39:21.746Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 7 msec to write fieldInfos
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.746Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: new segment has 0 deleted docs
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.746Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: new segment has no vectors; no norms; no docValues; no prox; no freqs
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.746Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: flushedFiles=[_4.fdt, _4.fnm, _4_BlockTreeOrds_0.doc, _4_BlockTreeOrds_0.tipo, _4_BlockTreeOrds_0.tio, _4.fdx]
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.746Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: flushed codec=Asserting(Lucene54): {id=BlockTreeOrds(blocksize=128)}, docValues:{}
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.746Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: flushed: segment=_4 ramUsed=0.071 MB newFlushedSize=0.001 MB docs/MB=2,884.666
   [junit4]   1> DW 100 [2016-02-22T06:39:21.754Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: publishFlushedSegment seg-private updates=null
   [junit4]   1> IW 100 [2016-02-22T06:39:21.754Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: publishFlushedSegment
   [junit4]   1> IW 100 [2016-02-22T06:39:21.754Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: publish sets newSegment delGen=5 seg=_4(5.5.0):C2
   [junit4]   1> IFD 100 [2016-02-22T06:39:21.754Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: now checkpoint "_0(5.5.0):C2 _1(5.5.0):C2 _2(5.5.0):C2 _3(5.5.0):C2 _4(5.5.0):C2" [5 segments ; isCommit = false]
   [junit4]   1> IFD 100 [2016-02-22T06:39:21.755Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 0 msec to checkpoint
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.755Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: findMerges: 5 segments
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.755Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_0(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.755Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_1(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.755Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_2(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.755Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_3(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.762Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_4(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.762Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]:   level -1.0 to 3.0659528: 5 segments
   [junit4]   1> MS 100 [2016-02-22T06:39:21.762Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: now merge
   [junit4]   1> MS 100 [2016-02-22T06:39:21.762Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]:   index: _0(5.5.0):C2 _1(5.5.0):C2 _2(5.5.0):C2 _3(5.5.0):C2 _4(5.5.0):C2
   [junit4]   1> MS 100 [2016-02-22T06:39:21.762Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]:   no more merges pending; now return
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.766Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: flush postings as segment _5 numDocs=2
   [junit4]   1> IW 100 [2016-02-22T06:39:21.766Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 0 msec to write norms
   [junit4]   1> IW 100 [2016-02-22T06:39:21.766Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 0 msec to write docValues
   [junit4]   1> IW 100 [2016-02-22T06:39:21.77Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 4 msec to finish stored fields
   [junit4]   1> IW 100 [2016-02-22T06:39:21.79Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 19 msec to write postings and finish vectors
   [junit4]   1> IW 100 [2016-02-22T06:39:21.797Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 6 msec to write fieldInfos
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.797Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: new segment has 0 deleted docs
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.797Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: new segment has no vectors; no norms; no docValues; no prox; no freqs
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.797Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: flushedFiles=[_5.fnm, _5.fdt, _5_BlockTreeOrds_0.tio, _5_BlockTreeOrds_0.tipo, _5_BlockTreeOrds_0.doc, _5.fdx]
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.797Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: flushed codec=Asserting(Lucene54): {id=BlockTreeOrds(blocksize=128)}, docValues:{}
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.799Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: flushed: segment=_5 ramUsed=0.071 MB newFlushedSize=0.001 MB docs/MB=2,884.666
   [junit4]   1> DW 100 [2016-02-22T06:39:21.812Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: publishFlushedSegment seg-private updates=null
   [junit4]   1> IW 100 [2016-02-22T06:39:21.812Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: publishFlushedSegment
   [junit4]   1> IW 100 [2016-02-22T06:39:21.812Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: publish sets newSegment delGen=6 seg=_5(5.5.0):C2
   [junit4]   1> IFD 100 [2016-02-22T06:39:21.812Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: now checkpoint "_0(5.5.0):C2 _1(5.5.0):C2 _2(5.5.0):C2 _3(5.5.0):C2 _4(5.5.0):C2 _5(5.5.0):C2" [6 segments ; isCommit = false]
   [junit4]   1> IFD 100 [2016-02-22T06:39:21.813Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 0 msec to checkpoint
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.813Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: findMerges: 6 segments
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.813Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_0(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.813Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_1(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.813Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_2(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.813Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_3(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.813Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_4(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.815Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_5(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.815Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]:   level -1.0 to 3.0659528: 6 segments
   [junit4]   1> MS 100 [2016-02-22T06:39:21.815Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: now merge
   [junit4]   1> MS 100 [2016-02-22T06:39:21.815Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]:   index: _0(5.5.0):C2 _1(5.5.0):C2 _2(5.5.0):C2 _3(5.5.0):C2 _4(5.5.0):C2 _5(5.5.0):C2
   [junit4]   1> MS 100 [2016-02-22T06:39:21.815Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]:   no more merges pending; now return
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.821Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: flush postings as segment _6 numDocs=2
   [junit4]   1> IW 100 [2016-02-22T06:39:21.821Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 0 msec to write norms
   [junit4]   1> IW 100 [2016-02-22T06:39:21.821Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 0 msec to write docValues
   [junit4]   1> IW 100 [2016-02-22T06:39:21.828Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 7 msec to finish stored fields
   [junit4]   1> IW 100 [2016-02-22T06:39:21.858Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 29 msec to write postings and finish vectors
   [junit4]   1> IW 100 [2016-02-22T06:39:21.866Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 7 msec to write fieldInfos
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.866Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: new segment has 0 deleted docs
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.866Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: new segment has no vectors; no norms; no docValues; no prox; no freqs
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.866Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: flushedFiles=[_6.fdt, _6.fnm, _6.fdx, _6_BlockTreeOrds_0.tipo, _6_BlockTreeOrds_0.doc, _6_BlockTreeOrds_0.tio]
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.866Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: flushed codec=Asserting(Lucene54): {id=BlockTreeOrds(blocksize=128)}, docValues:{}
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.875Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: flushed: segment=_6 ramUsed=0.071 MB newFlushedSize=0.001 MB docs/MB=2,884.666
   [junit4]   1> DW 100 [2016-02-22T06:39:21.894Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: publishFlushedSegment seg-private updates=null
   [junit4]   1> IW 100 [2016-02-22T06:39:21.894Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: publishFlushedSegment
   [junit4]   1> IW 100 [2016-02-22T06:39:21.894Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: publish sets newSegment delGen=7 seg=_6(5.5.0):C2
   [junit4]   1> IFD 100 [2016-02-22T06:39:21.895Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: now checkpoint "_0(5.5.0):C2 _1(5.5.0):C2 _2(5.5.0):C2 _3(5.5.0):C2 _4(5.5.0):C2 _5(5.5.0):C2 _6(5.5.0):C2" [7 segments ; isCommit = false]
   [junit4]   1> IFD 100 [2016-02-22T06:39:21.895Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 0 msec to checkpoint
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.895Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: findMerges: 7 segments
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.895Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_0(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.895Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_1(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.895Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_2(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.895Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_3(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.895Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_4(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.895Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_5(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.895Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_6(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.895Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]:   level -1.0 to 3.0659528: 7 segments
   [junit4]   1> MS 100 [2016-02-22T06:39:21.895Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: now merge
   [junit4]   1> MS 100 [2016-02-22T06:39:21.895Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]:   index: _0(5.5.0):C2 _1(5.5.0):C2 _2(5.5.0):C2 _3(5.5.0):C2 _4(5.5.0):C2 _5(5.5.0):C2 _6(5.5.0):C2
   [junit4]   1> MS 100 [2016-02-22T06:39:21.895Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]:   no more merges pending; now return
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.898Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: flush postings as segment _7 numDocs=2
   [junit4]   1> IW 100 [2016-02-22T06:39:21.898Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 0 msec to write norms
   [junit4]   1> IW 100 [2016-02-22T06:39:21.898Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 0 msec to write docValues
   [junit4]   1> IW 100 [2016-02-22T06:39:21.907Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 8 msec to finish stored fields
   [junit4]   1> IW 100 [2016-02-22T06:39:21.932Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 25 msec to write postings and finish vectors
   [junit4]   1> IW 100 [2016-02-22T06:39:21.937Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 4 msec to write fieldInfos
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.937Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: new segment has 0 deleted docs
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.937Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: new segment has no vectors; no norms; no docValues; no prox; no freqs
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.937Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: flushedFiles=[_7_BlockTreeOrds_0.tio, _7.fnm, _7_BlockTreeOrds_0.doc, _7.fdt, _7.fdx, _7_BlockTreeOrds_0.tipo]
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.937Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: flushed codec=Asserting(Lucene54): {id=BlockTreeOrds(blocksize=128)}, docValues:{}
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.937Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: flushed: segment=_7 ramUsed=0.071 MB newFlushedSize=0.001 MB docs/MB=2,884.666
   [junit4]   1> DW 100 [2016-02-22T06:39:21.944Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: publishFlushedSegment seg-private updates=null
   [junit4]   1> IW 100 [2016-02-22T06:39:21.944Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: publishFlushedSegment
   [junit4]   1> IW 100 [2016-02-22T06:39:21.944Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: publish sets newSegment delGen=8 seg=_7(5.5.0):C2
   [junit4]   1> IFD 100 [2016-02-22T06:39:21.944Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: now checkpoint "_0(5.5.0):C2 _1(5.5.0):C2 _2(5.5.0):C2 _3(5.5.0):C2 _4(5.5.0):C2 _5(5.5.0):C2 _6(5.5.0):C2 _7(5.5.0):C2" [8 segments ; isCommit = false]
   [junit4]   1> IFD 100 [2016-02-22T06:39:21.944Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 0 msec to checkpoint
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.944Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: findMerges: 8 segments
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.944Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_0(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.944Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_1(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.944Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_2(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.944Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_3(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.944Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_4(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.944Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_5(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.944Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_6(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.944Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_7(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.944Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]:   level -1.0 to 3.0659528: 8 segments
   [junit4]   1> MS 100 [2016-02-22T06:39:21.944Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: now merge
   [junit4]   1> MS 100 [2016-02-22T06:39:21.944Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]:   index: _0(5.5.0):C2 _1(5.5.0):C2 _2(5.5.0):C2 _3(5.5.0):C2 _4(5.5.0):C2 _5(5.5.0):C2 _6(5.5.0):C2 _7(5.5.0):C2
   [junit4]   1> MS 100 [2016-02-22T06:39:21.944Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]:   no more merges pending; now return
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.948Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: flush postings as segment _8 numDocs=2
   [junit4]   1> IW 100 [2016-02-22T06:39:21.948Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 0 msec to write norms
   [junit4]   1> IW 100 [2016-02-22T06:39:21.948Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 0 msec to write docValues
   [junit4]   1> IW 100 [2016-02-22T06:39:21.952Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 4 msec to finish stored fields
   [junit4]   1> IW 100 [2016-02-22T06:39:21.969Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 16 msec to write postings and finish vectors
   [junit4]   1> IW 100 [2016-02-22T06:39:21.973Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 4 msec to write fieldInfos
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.973Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: new segment has 0 deleted docs
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.973Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: new segment has no vectors; no norms; no docValues; no prox; no freqs
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.973Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: flushedFiles=[_8_BlockTreeOrds_0.tipo, _8.fdx, _8.fdt, _8.fnm, _8_BlockTreeOrds_0.tio, _8_BlockTreeOrds_0.doc]
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.973Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: flushed codec=Asserting(Lucene54): {id=BlockTreeOrds(blocksize=128)}, docValues:{}
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.974Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: flushed: segment=_8 ramUsed=0.071 MB newFlushedSize=0.001 MB docs/MB=2,884.666
   [junit4]   1> DW 100 [2016-02-22T06:39:21.981Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: publishFlushedSegment seg-private updates=null
   [junit4]   1> IW 100 [2016-02-22T06:39:21.981Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: publishFlushedSegment
   [junit4]   1> IW 100 [2016-02-22T06:39:21.981Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: publish sets newSegment delGen=9 seg=_8(5.5.0):C2
   [junit4]   1> IFD 100 [2016-02-22T06:39:21.981Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: now checkpoint "_0(5.5.0):C2 _1(5.5.0):C2 _2(5.5.0):C2 _3(5.5.0):C2 _4(5.5.0):C2 _5(5.5.0):C2 _6(5.5.0):C2 _7(5.5.0):C2 _8(5.5.0):C2" [9 segments ; isCommit = false]
   [junit4]   1> IFD 100 [2016-02-22T06:39:21.981Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 0 msec to checkpoint
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.981Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: findMerges: 9 segments
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.981Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_0(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.981Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_1(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.981Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_2(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.981Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_3(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.981Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_4(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.981Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_5(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.981Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_6(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.981Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_7(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.982Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_8(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:21.982Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]:   level -1.0 to 3.0659528: 9 segments
   [junit4]   1> MS 100 [2016-02-22T06:39:21.982Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: now merge
   [junit4]   1> MS 100 [2016-02-22T06:39:21.982Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]:   index: _0(5.5.0):C2 _1(5.5.0):C2 _2(5.5.0):C2 _3(5.5.0):C2 _4(5.5.0):C2 _5(5.5.0):C2 _6(5.5.0):C2 _7(5.5.0):C2 _8(5.5.0):C2
   [junit4]   1> MS 100 [2016-02-22T06:39:21.982Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]:   no more merges pending; now return
   [junit4]   1> DWPT 100 [2016-02-22T06:39:21.985Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: flush postings as segment _9 numDocs=2
   [junit4]   1> IW 100 [2016-02-22T06:39:21.986Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 0 msec to write norms
   [junit4]   1> IW 100 [2016-02-22T06:39:21.986Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 0 msec to write docValues
   [junit4]   1> IW 100 [2016-02-22T06:39:21.99Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 4 msec to finish stored fields
   [junit4]   1> IW 100 [2016-02-22T06:39:22.008Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 18 msec to write postings and finish vectors
   [junit4]   1> IW 100 [2016-02-22T06:39:22.013Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 4 msec to write fieldInfos
   [junit4]   1> DWPT 100 [2016-02-22T06:39:22.013Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: new segment has 0 deleted docs
   [junit4]   1> DWPT 100 [2016-02-22T06:39:22.013Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: new segment has no vectors; no norms; no docValues; no prox; no freqs
   [junit4]   1> DWPT 100 [2016-02-22T06:39:22.013Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: flushedFiles=[_9.fdx, _9.fdt, _9.fnm, _9_BlockTreeOrds_0.tipo, _9_BlockTreeOrds_0.doc, _9_BlockTreeOrds_0.tio]
   [junit4]   1> DWPT 100 [2016-02-22T06:39:22.013Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: flushed codec=Asserting(Lucene54): {id=BlockTreeOrds(blocksize=128)}, docValues:{}
   [junit4]   1> DWPT 100 [2016-02-22T06:39:22.015Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: flushed: segment=_9 ramUsed=0.071 MB newFlushedSize=0.001 MB docs/MB=2,884.666
   [junit4]   1> DW 100 [2016-02-22T06:39:22.044Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: publishFlushedSegment seg-private updates=null
   [junit4]   1> IW 100 [2016-02-22T06:39:22.044Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: publishFlushedSegment
   [junit4]   1> IW 100 [2016-02-22T06:39:22.044Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: publish sets newSegment delGen=10 seg=_9(5.5.0):C2
   [junit4]   1> IFD 100 [2016-02-22T06:39:22.045Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: now checkpoint "_0(5.5.0):C2 _1(5.5.0):C2 _2(5.5.0):C2 _3(5.5.0):C2 _4(5.5.0):C2 _5(5.5.0):C2 _6(5.5.0):C2 _7(5.5.0):C2 _8(5.5.0):C2 _9(5.5.0):C2" [10 segments ; isCommit = false]
   [junit4]   1> IFD 100 [2016-02-22T06:39:22.045Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: 0 msec to checkpoint
   [junit4]   1> LMP 100 [2016-02-22T06:39:22.045Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: findMerges: 10 segments
   [junit4]   1> LMP 100 [2016-02-22T06:39:22.045Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_0(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:22.045Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_1(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:22.045Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_2(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:22.045Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_3(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:22.045Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_4(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:22.045Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_5(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:22.045Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_6(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:22.045Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_7(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:22.045Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_8(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:22.077Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: seg=_9(5.5.0):C2 level=3.0659528 size=0.001 MB
   [junit4]   1> LMP 100 [2016-02-22T06:39:22.077Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]:   level -1.0 to 3.0659528: 10 segments
   [junit4]   1> LMP 100 [2016-02-22T06:39:22.078Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]:   add merge=_0(5.5.0):C2 _1(5.5.0):C2 _2(5.5.0):C2 _3(5.5.0):C2 _4(5.5.0):C2 _5(5.5.0):C2 _6(5.5.0):C2 _7(5.5.0):C2 _8(5.5.0):C2 _9(5.5.0):C2 start=0 end=10
   [junit4]   1> IW 100 [2016-02-22T06:39:22.078Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: add merge to pendingMerges: _0(5.5.0):C2 _1(5.5.0):C2 _2(5.5.0):C2 _3(5.5.0):C2 _4(5.5.0):C2 _5(5.5.0):C2 _6(5.5.0):C2 _7(5.5.0):C2 _8(5.5.0):C2 _9(5.5.0):C2 [total 1 pending]
   [junit4]   1> IW 100 [2016-02-22T06:39:22.078Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: registerMerge merging= []
   [junit4]   1> IW 100 [2016-02-22T06:39:22.078Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: registerMerge info=_0(5.5.0):C2
   [junit4]   1> IW 100 [2016-02-22T06:39:22.078Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: registerMerge info=_1(5.5.0):C2
   [junit4]   1> IW 100 [2016-02-22T06:39:22.078Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: registerMerge info=_2(5.5.0):C2
   [junit4]   1> IW 100 [2016-02-22T06:39:22.078Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: registerMerge info=_3(5.5.0):C2
   [junit4]   1> IW 100 [2016-02-22T06:39:22.078Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: registerMerge info=_4(5.5.0):C2
   [junit4]   1> IW 100 [2016-02-22T06:39:22.078Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: registerMerge info=_5(5.5.0):C2
   [junit4]   1> IW 100 [2016-02-22T06:39:22.078Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: registerMerge info=_6(5.5.0):C2
   [junit4]   1> IW 100 [2016-02-22T06:39:22.078Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: registerMerge info=_7(5.5.0):C2
   [junit4]   1> IW 100 [2016-02-22T06:39:22.078Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: registerMerge info=_8(5.5.0):C2
   [junit4]   1> IW 100 [2016-02-22T06:39:22.078Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: registerMerge info=_9(5.5.0):C2
   [junit4]   1> MS 100 [2016-02-22T06:39:22.078Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: now merge
   [junit4]   1> MS 100 [2016-02-22T06:39:22.078Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]:   index: _0(5.5.0):C2 _1(5.5.0):C2 _2(5.5.0):C2 _3(5.5.0):C2 _4(5.5.0):C2 _5(5.5.0):C2 _6(5.5.0):C2 _7(5.5.0):C2 _8(5.5.0):C2 _9(5.5.0):C2
   [junit4]   1> MS 100 [2016-02-22T06:39:22.078Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]:   consider merge _0(5.5.0):C2 _1(5.5.0):C2 _2(5.5.0):C2 _3(5.5.0):C2 _4(5.5.0):C2 _5(5.5.0):C2 _6(5.5.0):C2 _7(5.5.0):C2 _8(5.5.0):C2 _9(5.5.0):C2
   [junit4]   1> MS 100 [2016-02-22T06:39:22.078Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]:     launch new thread [MyMergeThread]
   [junit4]   1> MS 100 [2016-02-22T06:39:22.088Z; MyMergeThread]:   merge thread: start
   [junit4]   1> IW 100 [2016-02-22T06:39:22.096Z; MyMergeThread]: now apply deletes for 10 merging segments
   [junit4]   1> BD 100 [2016-02-22T06:39:22.096Z; MyMergeThread]: applyDeletes: open segment readers took 0 msec
   [junit4]   1> BD 100 [2016-02-22T06:39:22.096Z; MyMergeThread]: applyDeletes: no segments; skipping
   [junit4]   1> BD 100 [2016-02-22T06:39:22.096Z; MyMergeThread]: prune sis=segments: _0(5.5.0):C2 _1(5.5.0):C2 _2(5.5.0):C2 _3(5.5.0):C2 _4(5.5.0):C2 _5(5.5.0):C2 _6(5.5.0):C2 _7(5.5.0):C2 _8(5.5.0):C2 _9(5.5.0):C2 minGen=1 packetCount=0
   [junit4]   1> IW 100 [2016-02-22T06:39:22.096Z; MyMergeThread]: merge seg=_a _0(5.5.0):C2 _1(5.5.0):C2 _2(5.5.0):C2 _3(5.5.0):C2 _4(5.5.0):C2 _5(5.5.0):C2 _6(5.5.0):C2 _7(5.5.0):C2 _8(5.5.0):C2 _9(5.5.0):C2
   [junit4]   1> IW 100 [2016-02-22T06:39:22.096Z; MyMergeThread]: now merge
   [junit4]   1>   merge=_0(5.5.0):C2 _1(5.5.0):C2 _2(5.5.0):C2 _3(5.5.0):C2 _4(5.5.0):C2 _5(5.5.0):C2 _6(5.5.0):C2 _7(5.5.0):C2 _8(5.5.0):C2 _9(5.5.0):C2
   [junit4]   1>   index=_0(5.5.0):C2 _1(5.5.0):C2 _2(5.5.0):C2 _3(5.5.0):C2 _4(5.5.0):C2 _5(5.5.0):C2 _6(5.5.0):C2 _7(5.5.0):C2 _8(5.5.0):C2 _9(5.5.0):C2
   [junit4]   1> IW 100 [2016-02-22T06:39:22.096Z; MyMergeThread]: merging _0(5.5.0):C2 _1(5.5.0):C2 _2(5.5.0):C2 _3(5.5.0):C2 _4(5.5.0):C2 _5(5.5.0):C2 _6(5.5.0):C2 _7(5.5.0):C2 _8(5.5.0):C2 _9(5.5.0):C2
   [junit4]   1> IW 100 [2016-02-22T06:39:22.096Z; MyMergeThread]: TEST: now throw exc:
   [junit4]   1> java.io.IOException: now failing during merge
   [junit4]   1> 	at org.apache.lucene.TestMergeSchedulerExternal$FailOnlyOnMerge.eval(TestMergeSchedulerExternal.java:92)
   [junit4]   1> 	at org.apache.lucene.store.MockDirectoryWrapper.maybeThrowDeterministicException(MockDirectoryWrapper.java:958)
   [junit4]   1> 	at org.apache.lucene.store.MockDirectoryWrapper.openInput(MockDirectoryWrapper.java:633)
   [junit4]   1> 	at org.apache.lucene.store.Directory.openChecksumInput(Directory.java:109)
   [junit4]   1> 	at org.apache.lucene.store.MockDirectoryWrapper.openChecksumInput(MockDirectoryWrapper.java:1009)
   [junit4]   1> 	at org.apache.lucene.codecs.lucene50.Lucene50FieldInfosFormat.read(Lucene50FieldInfosFormat.java:111)
   [junit4]   1> 	at org.apache.lucene.index.SegmentCoreReaders.<init>(SegmentCoreReaders.java:99)
   [junit4]   1> 	at org.apache.lucene.index.SegmentReader.<init>(SegmentReader.java:65)
   [junit4]   1> 	at org.apache.lucene.index.ReadersAndUpdates.getReader(ReadersAndUpdates.java:145)
   [junit4]   1> 	at org.apache.lucene.index.ReadersAndUpdates.getReaderForMerge(ReadersAndUpdates.java:617)
   [junit4]   1> 	at org.apache.lucene.index.IndexWriter.mergeMiddle(IndexWriter.java:4008)
   [junit4]   1> 	at org.apache.lucene.index.IndexWriter.merge(IndexWriter.java:3655)
   [junit4]   1> 	at org.apache.lucene.index.ConcurrentMergeScheduler.doMerge(ConcurrentMergeScheduler.java:588)
   [junit4]   1> 	at org.apache.lucene.TestMergeSchedulerExternal$MyMergeScheduler.doMerge(TestMergeSchedulerExternal.java:82)
   [junit4]   1> 	at org.apache.lucene.index.ConcurrentMergeScheduler$MergeThread.run(ConcurrentMergeScheduler.java:626)
   [junit4]   1> 
   [junit4]   1> IW 100 [2016-02-22T06:39:22.097Z; MyMergeThread]: handleMergeException: merge=_0(5.5.0):C2 _1(5.5.0):C2 _2(5.5.0):C2 _3(5.5.0):C2 _4(5.5.0):C2 _5(5.5.0):C2 _6(5.5.0):C2 _7(5.5.0):C2 _8(5.5.0):C2 _9(5.5.0):C2 exc=java.io.IOException: now failing during merge
   [junit4]   1> IW 100 [2016-02-22T06:39:22.097Z; MyMergeThread]: hit exception during merge
   [junit4]   1> IW 100 [2016-02-22T06:39:22.097Z; MyMergeThread]: hit tragic IOException inside merge
   [junit4]   1> IW 100 [2016-02-22T06:39:22.097Z; MyMergeThread]: rollback
   [junit4]   1> IW 100 [2016-02-22T06:39:22.097Z; MyMergeThread]: all running merges have aborted
   [junit4]   1> IW 100 [2016-02-22T06:39:22.097Z; MyMergeThread]: rollback: done finish merges
   [junit4]   1> MS 100 [2016-02-22T06:39:22.099Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]: updateMergeThreads ioThrottle=true targetMBPerSec=20.0 MB/sec
   [junit4]   1> merge thread MyMergeThread estSize=0.0 MB (written=0.0 MB) runTime=0.0s (stopped=0.0s, paused=0.0s) rate=unlimited
   [junit4]   1>   leave running at Infinity MB/sec
   [junit4]   1> MS 100 [2016-02-22T06:39:22.099Z; TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[A2BDF226D5BAFBA8]]:   no more merges pending; now return
   [junit4]   1> DW 100 [2016-02-22T06:39:22.099Z; MyMergeThread]: abort
   [junit4]   1> DW 100 [2016-02-22T06:39:22.099Z; MyMergeThread]: done abort success=true
   [junit4]   1> IW 100 [2016-02-22T06:39:22.099Z; MyMergeThread]: rollback: infos=
   [junit4]   1> MS 100 [2016-02-22T06:39:22.099Z; MyMergeThread]: updateMergeThreads ioThrottle=true targetMBPerSec=20.0 MB/sec
   [junit4]   1> 
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestMergeSchedulerExternal -Dtests.method=testSubclassConcurrentMergeScheduler -Dtests.seed=A2BDF226D5BAFBA8 -Dtests.slow=true -Dtests.locale=he -Dtests.timezone=America/Port-au-Prince -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
   [junit4] FAILURE 0.80s J0 | TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler <<<
   [junit4]    > Throwable #1: java.lang.AssertionError
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([A2BDF226D5BAFBA8:253C4F8BD19A81AC]:0)
   [junit4]    > 	at org.apache.lucene.TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler(TestMergeSchedulerExternal.java:144)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene54): {id=BlockTreeOrds(blocksize=128)}, docValues:{}, sim=RandomSimilarity(queryNorm=true,coord=yes): {}, locale=he, timezone=America/Port-au-Prince
   [junit4]   2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.8.0_72 (64-bit)/cpus=3,threads=1,free=120793000,total=258473984
   [junit4]   2> NOTE: All tests run in this JVM: [TestDeterminizeLexicon, TestIndexWriterDeleteByQuery, TestSameScoresWithThreads, TestSpanSearchEquivalence, TestReaderClosed, TestIndexWriterLockRelease, TestNativeFSLockFactory, TestUTF32ToUTF8, TestTransactionRollback, TestDirectoryReader, TestSimpleExplanationsOfNonMatches, TestIndexWriter, TestAllFilesCheckIndexHeader, TestDocIdSet, TestLogMergePolicy, TestPerFieldPostingsFormat, TestStressIndexing2, TestWildcardRandom, TestTermRangeFilter, TestDeletionPolicy, TestSloppyPhraseQuery2, TestPerFieldPostingsFormat2, TestNoMergeScheduler, TestSparseFixedBitDocIdSet, TestMinimize, TestAttributeSource, TestTermdocPerf, TestIndexWriterConfig, TestMergeRateLimiter, TestSimpleAttributeImpl, TestNorms, TestDirectPacked, TestLucene50TermVectorsFormat, TestBlockPostingsFormat2, TestFieldsReader, TestFuzzyQuery, TestRollingBuffer, TestPositiveScoresOnlyCollector, TestFilterLeafReader, TestOmitPositions, TestSpanNotQuery, TestFieldValueFilter, TestSpanTermQuery, TestIndexWriterExceptions, TestByteBlockPool, TestScoreCachingWrappingScorer, TestVersion, TestArrayUtil, TestRollback, TestMathUtil, TestIndexWriterThreadsToSegments, TestLazyProxSkipping, TestParallelTermEnum, TestTermsEnum2, TestComplexExplanations, TestSpanFirstQuery, TestBooleanScorer, TestIndexableField, TestBufferedIndexInput, TestCustomNorms, TestPrefixInBooleanQuery, TestMaxPosition, TestForceMergeForever, TestIntBlockPool, TestLucene50StoredFieldsFormat, TestTermScorer, TestDocumentsWriterDeleteQueue, TestConstantScoreQuery, TestIndexWriterOnVMError, TestCachingCollector, TestUniqueTermCount, TestMinShouldMatch2, TestBufferedChecksum, TestIndexSearcher, TestTransactions, TestPrefixCodedTerms, TestReadOnlyIndex, TestCachingWrapperQuery, FuzzyTermOnShortTermsTest, TestMultiThreadTermVectors, TestSwappedIndexFiles, TestNumericTokenStream, TestTrackingDirectoryWrapper, TestIndexWriterUnicode, TestHugeRamFile, TestIndexWriterMerging, TestRegexpQuery, TestIndexInput, TestBooleanRewrites, TestBytesRefArray, TestForUtil, TestIntArrayDocIdSet, TestIndexWriterWithThreads, TestOperations, TestIndexWriterOnJRECrash, TestSegmentInfos, TestComplexExplanationsOfNonMatches, TestDocIdSetBuilder, TestAtomicUpdate, TestCloseableThreadLocal, TestLiveFieldValues, TestShardSearching, TestPerSegmentDeletes, TestSpanContainQuery, TestWeakIdentityMap, TestFSTs, TestNumericRangeQuery64, TestBytesStore, TestNumericRangeQuery32, TestAddIndexes, TestBasics, TestFlushByRamOrCountsPolicy, TestIndexWriterDelete, TestIndexWriterMergePolicy, TestNRTThreads, TestDuelingCodecs, TestBoolean2, TestPersistentSnapshotDeletionPolicy, TestIndexWriterCommit, TestDirectoryReaderReopen, TestTimeLimitingCollector, TestSearchWithThreads, TestConcurrentMergeScheduler, TestDocumentsWriterStallControl, TestTieredMergePolicy, TestTermsEnum, TestNRTCachingDirectory, TestSegmentReader, TestFastDecompressionMode, TestSort, TestSimpleSearchEquivalence, TestDocValuesIndexing, TestSimilarityBase, TestPostingsOffsets, TestMixedCodecs, TestFastCompressionMode, TestMultiPhraseQuery, TestDocsAndPositions, TestMergeSchedulerExternal]
   [junit4] Completed [263/421 (1!)] on J0 in 0.84s, 2 tests, 1 failure <<< FAILURES!

[...truncated 522 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-5.5-MacOSX/build.xml:750: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.5-MacOSX/build.xml:694: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.5-MacOSX/build.xml:59: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.5-MacOSX/lucene/build.xml:50: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.5-MacOSX/lucene/common-build.xml:1477: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.5-MacOSX/lucene/common-build.xml:1033: There were test failures: 421 suites (1 ignored), 3386 tests, 1 failure, 48 ignored (44 assumptions) [seed: A2BDF226D5BAFBA8]

Total time: 6 minutes 44 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



Mime
View raw message