Doesn’t reproduce for me on Linux or macOS. -- Steve www.lucidworks.com
> On Jan 23, 2017, at 2:21 PM, Policeman Jenkins Server <jenk...@thetaphi.de> > wrote: > > Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/3793/ > Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC > > 1 tests failed. > FAILED: > org.apache.lucene.TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler > > Error Message: > > > Stack Trace: > java.lang.AssertionError > at > __randomizedtesting.SeedInfo.seed([B6EBCEBC661DDA5A:316A7311623DA05E]: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: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:1713) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957) > 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:368) > at > com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) > at > com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) > at > com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) > 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:368) > at java.lang.Thread.run(Thread.java:745) > > > > > Build Log: > [...truncated 568 lines...] > [junit4] Suite: org.apache.lucene.TestMergeSchedulerExternal > [junit4] 1> TEST FAILED; IW infoStream output: > [junit4] 1> IFD 0 [2017-01-23T17:34:48.331Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > init: current segments file is "segments"; > deletionPolicy=org.apache.lucene.index.KeepOnlyLastCommitDeletionPolicy@709f179e > [junit4] 1> IFD 0 [2017-01-23T17:34:48.348Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > delete [] > [junit4] 1> IFD 0 [2017-01-23T17:34:48.349Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > now checkpoint "" [0 segments ; isCommit = false] > [junit4] 1> IFD 0 [2017-01-23T17:34:48.349Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > delete [] > [junit4] 1> IFD 0 [2017-01-23T17:34:48.349Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 0 msec to checkpoint > [junit4] 1> IW 0 [2017-01-23T17:34:48.349Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > init: create=true > [junit4] 1> IW 0 [2017-01-23T17:34:48.349Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > > [junit4] 1> dir=MockDirectoryWrapper(RAMDirectory@4e5c323d > lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@35fbe4a2) > [junit4] 1> index= > [junit4] 1> version=7.0.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> codec=Asserting(Lucene70): {}, docValues:{}, > maxPointsInLeafNode=586, maxMBSortInHeap=7.926156065328051 > [junit4] 1> infoStream=org.apache.lucene.util.PrintStreamInfoStream > [junit4] 1> mergePolicy=[LogByteSizeMergePolicy: minMergeSize=1677721, > mergeFactor=37, maxMergeSize=2147483648, > maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, > maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, > noCFSRatio=0.3522412660268801] > [junit4] 1> > indexerThreadPool=org.apache.lucene.index.DocumentsWriterPerThreadPool@656f9de2 > [junit4] 1> readerPooling=false > [junit4] 1> perThreadHardLimitMB=1945 > [junit4] 1> useCompoundFile=true > [junit4] 1> commitOnClose=true > [junit4] 1> indexSort=null > [junit4] 1> writer=org.apache.lucene.index.IndexWriter@27e66036 > [junit4] 1> > [junit4] 1> IW 0 [2017-01-23T17:34:48.349Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > MMapDirectory.UNMAP_SUPPORTED=true > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.354Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flush postings as segment _0 numDocs=2 > [junit4] 1> IW 0 [2017-01-23T17:34:48.354Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 0 msec to write norms > [junit4] 1> IW 0 [2017-01-23T17:34:48.354Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 0 msec to write docValues > [junit4] 1> IW 0 [2017-01-23T17:34:48.354Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 0 msec to write points > [junit4] 1> IW 0 [2017-01-23T17:34:48.362Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 7 msec to finish stored fields > [junit4] 1> IW 0 [2017-01-23T17:34:48.383Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 20 msec to write postings and finish vectors > [junit4] 1> IW 0 [2017-01-23T17:34:48.387Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 4 msec to write fieldInfos > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.387Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > new segment has 0 deleted docs > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.388Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > new segment has no vectors; no norms; no docValues; no prox; no freqs > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.388Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flushedFiles=[_0_LuceneFixedGap_0.tib, _0.fdx, _0_LuceneFixedGap_0.doc, > _0.fdt, _0_LuceneFixedGap_0.tii, _0.fnm] > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.388Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flushed codec=Asserting(Lucene70): {id=PostingsFormat(name=LuceneFixedGap)}, > docValues:{}, maxPointsInLeafNode=586, maxMBSortInHeap=7.926156065328051 > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.390Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flushed: segment=_0 ramUsed=0.071 MB newFlushedSize=0.001 MB > docs/MB=2,904.643 > [junit4] 1> IW 0 [2017-01-23T17:34:48.390Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > create compound file > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.429Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flush time 74.368493 msec > [junit4] 1> IFD 0 [2017-01-23T17:34:48.429Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_0_LuceneFixedGap_0.tib" > [junit4] 1> IFD 0 [2017-01-23T17:34:48.429Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_0.fdx" > [junit4] 1> IFD 0 [2017-01-23T17:34:48.429Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_0_LuceneFixedGap_0.doc" > [junit4] 1> IFD 0 [2017-01-23T17:34:48.429Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_0.fdt" > [junit4] 1> IFD 0 [2017-01-23T17:34:48.429Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_0_LuceneFixedGap_0.tii" > [junit4] 1> IFD 0 [2017-01-23T17:34:48.429Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_0.fnm" > [junit4] 1> IFD 0 [2017-01-23T17:34:48.429Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > delete [_0_LuceneFixedGap_0.tib, _0.fdx, _0_LuceneFixedGap_0.doc, _0.fdt, > _0_LuceneFixedGap_0.tii, _0.fnm] > [junit4] 1> DW 0 [2017-01-23T17:34:48.429Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > publishFlushedSegment seg-private updates=null > [junit4] 1> IW 0 [2017-01-23T17:34:48.429Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > publishFlushedSegment > [junit4] 1> IW 0 [2017-01-23T17:34:48.429Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > publish sets newSegment delGen=1 seg=_0(7.0.0):c2 > [junit4] 1> IFD 0 [2017-01-23T17:34:48.429Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > now checkpoint "_0(7.0.0):c2" [1 segments ; isCommit = false] > [junit4] 1> IFD 0 [2017-01-23T17:34:48.429Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > delete [] > [junit4] 1> IFD 0 [2017-01-23T17:34:48.429Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 0 msec to checkpoint > [junit4] 1> LMP 0 [2017-01-23T17:34:48.429Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > findMerges: 1 segments > [junit4] 1> LMP 0 [2017-01-23T17:34:48.441Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_0(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:48.441Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > level -1.0 to 3.143639: 1 segments > [junit4] 1> MS 0 [2017-01-23T17:34:48.441Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > initDynamicDefaults spins=false maxThreadCount=2 maxMergeCount=7 > [junit4] 1> MS 0 [2017-01-23T17:34:48.441Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > now merge > [junit4] 1> MS 0 [2017-01-23T17:34:48.441Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > index: _0(7.0.0):c2 > [junit4] 1> MS 0 [2017-01-23T17:34:48.441Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > no more merges pending; now return > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.450Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flush postings as segment _1 numDocs=2 > [junit4] 1> IW 0 [2017-01-23T17:34:48.450Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 0 msec to write norms > [junit4] 1> IW 0 [2017-01-23T17:34:48.450Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 0 msec to write docValues > [junit4] 1> IW 0 [2017-01-23T17:34:48.450Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 0 msec to write points > [junit4] 1> IW 0 [2017-01-23T17:34:48.454Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 4 msec to finish stored fields > [junit4] 1> IW 0 [2017-01-23T17:34:48.486Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 31 msec to write postings and finish vectors > [junit4] 1> IW 0 [2017-01-23T17:34:48.490Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 4 msec to write fieldInfos > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.490Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > new segment has 0 deleted docs > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.490Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > new segment has no vectors; no norms; no docValues; no prox; no freqs > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.490Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flushedFiles=[_1_LuceneFixedGap_0.doc, _1_LuceneFixedGap_0.tii, _1.fdx, > _1.fnm, _1_LuceneFixedGap_0.tib, _1.fdt] > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.490Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flushed codec=Asserting(Lucene70): {id=PostingsFormat(name=LuceneFixedGap)}, > docValues:{}, maxPointsInLeafNode=586, maxMBSortInHeap=7.926156065328051 > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.490Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flushed: segment=_1 ramUsed=0.071 MB newFlushedSize=0.001 MB > docs/MB=2,904.643 > [junit4] 1> IW 0 [2017-01-23T17:34:48.491Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > create compound file > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.526Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flush time 76.063712 msec > [junit4] 1> IFD 0 [2017-01-23T17:34:48.526Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_1_LuceneFixedGap_0.doc" > [junit4] 1> IFD 0 [2017-01-23T17:34:48.526Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_1_LuceneFixedGap_0.tii" > [junit4] 1> IFD 0 [2017-01-23T17:34:48.526Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_1.fdx" > [junit4] 1> IFD 0 [2017-01-23T17:34:48.526Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_1.fnm" > [junit4] 1> IFD 0 [2017-01-23T17:34:48.526Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_1_LuceneFixedGap_0.tib" > [junit4] 1> IFD 0 [2017-01-23T17:34:48.526Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_1.fdt" > [junit4] 1> IFD 0 [2017-01-23T17:34:48.526Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > delete [_1_LuceneFixedGap_0.doc, _1_LuceneFixedGap_0.tii, _1.fdx, _1.fnm, > _1_LuceneFixedGap_0.tib, _1.fdt] > [junit4] 1> DW 0 [2017-01-23T17:34:48.527Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > publishFlushedSegment seg-private updates=null > [junit4] 1> IW 0 [2017-01-23T17:34:48.527Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > publishFlushedSegment > [junit4] 1> IW 0 [2017-01-23T17:34:48.527Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > publish sets newSegment delGen=2 seg=_1(7.0.0):c2 > [junit4] 1> IFD 0 [2017-01-23T17:34:48.527Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > now checkpoint "_0(7.0.0):c2 _1(7.0.0):c2" [2 segments ; isCommit = false] > [junit4] 1> IFD 0 [2017-01-23T17:34:48.527Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > delete [] > [junit4] 1> IFD 0 [2017-01-23T17:34:48.527Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 0 msec to checkpoint > [junit4] 1> LMP 0 [2017-01-23T17:34:48.527Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > findMerges: 2 segments > [junit4] 1> LMP 0 [2017-01-23T17:34:48.527Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_0(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:48.527Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_1(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:48.527Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > level -1.0 to 3.143639: 2 segments > [junit4] 1> MS 0 [2017-01-23T17:34:48.527Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > now merge > [junit4] 1> MS 0 [2017-01-23T17:34:48.527Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > index: _0(7.0.0):c2 _1(7.0.0):c2 > [junit4] 1> MS 0 [2017-01-23T17:34:48.527Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > no more merges pending; now return > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.531Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flush postings as segment _2 numDocs=2 > [junit4] 1> IW 0 [2017-01-23T17:34:48.531Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 0 msec to write norms > [junit4] 1> IW 0 [2017-01-23T17:34:48.531Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 0 msec to write docValues > [junit4] 1> IW 0 [2017-01-23T17:34:48.531Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 0 msec to write points > [junit4] 1> IW 0 [2017-01-23T17:34:48.536Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 4 msec to finish stored fields > [junit4] 1> IW 0 [2017-01-23T17:34:48.560Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 24 msec to write postings and finish vectors > [junit4] 1> IW 0 [2017-01-23T17:34:48.568Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 6 msec to write fieldInfos > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.568Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > new segment has 0 deleted docs > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.568Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > new segment has no vectors; no norms; no docValues; no prox; no freqs > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.568Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flushedFiles=[_2_LuceneFixedGap_0.tib, _2_LuceneFixedGap_0.tii, _2.fdt, > _2.fnm, _2_LuceneFixedGap_0.doc, _2.fdx] > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.568Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flushed codec=Asserting(Lucene70): {id=PostingsFormat(name=LuceneFixedGap)}, > docValues:{}, maxPointsInLeafNode=586, maxMBSortInHeap=7.926156065328051 > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.568Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flushed: segment=_2 ramUsed=0.071 MB newFlushedSize=0.001 MB > docs/MB=2,904.643 > [junit4] 1> IW 0 [2017-01-23T17:34:48.568Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > create compound file > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.623Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flush time 92.027308 msec > [junit4] 1> IFD 0 [2017-01-23T17:34:48.623Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_2_LuceneFixedGap_0.tib" > [junit4] 1> IFD 0 [2017-01-23T17:34:48.623Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_2_LuceneFixedGap_0.tii" > [junit4] 1> IFD 0 [2017-01-23T17:34:48.623Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_2.fdt" > [junit4] 1> IFD 0 [2017-01-23T17:34:48.623Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_2.fnm" > [junit4] 1> IFD 0 [2017-01-23T17:34:48.623Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_2_LuceneFixedGap_0.doc" > [junit4] 1> IFD 0 [2017-01-23T17:34:48.623Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_2.fdx" > [junit4] 1> IFD 0 [2017-01-23T17:34:48.623Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > delete [_2_LuceneFixedGap_0.tib, _2_LuceneFixedGap_0.tii, _2.fdt, _2.fnm, > _2_LuceneFixedGap_0.doc, _2.fdx] > [junit4] 1> DW 0 [2017-01-23T17:34:48.623Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > publishFlushedSegment seg-private updates=null > [junit4] 1> IW 0 [2017-01-23T17:34:48.623Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > publishFlushedSegment > [junit4] 1> IW 0 [2017-01-23T17:34:48.623Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > publish sets newSegment delGen=3 seg=_2(7.0.0):c2 > [junit4] 1> IFD 0 [2017-01-23T17:34:48.623Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > now checkpoint "_0(7.0.0):c2 _1(7.0.0):c2 _2(7.0.0):c2" [3 segments ; > isCommit = false] > [junit4] 1> IFD 0 [2017-01-23T17:34:48.623Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > delete [] > [junit4] 1> IFD 0 [2017-01-23T17:34:48.624Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 0 msec to checkpoint > [junit4] 1> LMP 0 [2017-01-23T17:34:48.624Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > findMerges: 3 segments > [junit4] 1> LMP 0 [2017-01-23T17:34:48.624Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_0(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:48.624Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_1(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:48.625Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_2(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:48.625Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > level -1.0 to 3.143639: 3 segments > [junit4] 1> MS 0 [2017-01-23T17:34:48.625Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > now merge > [junit4] 1> MS 0 [2017-01-23T17:34:48.625Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > index: _0(7.0.0):c2 _1(7.0.0):c2 _2(7.0.0):c2 > [junit4] 1> MS 0 [2017-01-23T17:34:48.625Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > no more merges pending; now return > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.630Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flush postings as segment _3 numDocs=2 > [junit4] 1> IW 0 [2017-01-23T17:34:48.630Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 0 msec to write norms > [junit4] 1> IW 0 [2017-01-23T17:34:48.630Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 0 msec to write docValues > [junit4] 1> IW 0 [2017-01-23T17:34:48.631Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 0 msec to write points > [junit4] 1> IW 0 [2017-01-23T17:34:48.638Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 7 msec to finish stored fields > [junit4] 1> IW 0 [2017-01-23T17:34:48.660Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 22 msec to write postings and finish vectors > [junit4] 1> IW 0 [2017-01-23T17:34:48.665Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 4 msec to write fieldInfos > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.665Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > new segment has 0 deleted docs > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.665Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > new segment has no vectors; no norms; no docValues; no prox; no freqs > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.665Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flushedFiles=[_3.fdx, _3_LuceneFixedGap_0.tii, _3_LuceneFixedGap_0.tib, > _3.fnm, _3.fdt, _3_LuceneFixedGap_0.doc] > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.665Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flushed codec=Asserting(Lucene70): {id=PostingsFormat(name=LuceneFixedGap)}, > docValues:{}, maxPointsInLeafNode=586, maxMBSortInHeap=7.926156065328051 > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.665Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flushed: segment=_3 ramUsed=0.071 MB newFlushedSize=0.001 MB > docs/MB=2,904.643 > [junit4] 1> IW 0 [2017-01-23T17:34:48.665Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > create compound file > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.698Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flush time 67.521381 msec > [junit4] 1> IFD 0 [2017-01-23T17:34:48.698Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_3.fdx" > [junit4] 1> IFD 0 [2017-01-23T17:34:48.698Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_3_LuceneFixedGap_0.tii" > [junit4] 1> IFD 0 [2017-01-23T17:34:48.698Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_3_LuceneFixedGap_0.tib" > [junit4] 1> IFD 0 [2017-01-23T17:34:48.698Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_3.fnm" > [junit4] 1> IFD 0 [2017-01-23T17:34:48.698Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_3.fdt" > [junit4] 1> IFD 0 [2017-01-23T17:34:48.698Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_3_LuceneFixedGap_0.doc" > [junit4] 1> IFD 0 [2017-01-23T17:34:48.698Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > delete [_3.fdx, _3_LuceneFixedGap_0.tii, _3_LuceneFixedGap_0.tib, _3.fnm, > _3.fdt, _3_LuceneFixedGap_0.doc] > [junit4] 1> DW 0 [2017-01-23T17:34:48.701Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > publishFlushedSegment seg-private updates=null > [junit4] 1> IW 0 [2017-01-23T17:34:48.701Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > publishFlushedSegment > [junit4] 1> IW 0 [2017-01-23T17:34:48.701Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > publish sets newSegment delGen=4 seg=_3(7.0.0):c2 > [junit4] 1> IFD 0 [2017-01-23T17:34:48.701Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > now checkpoint "_0(7.0.0):c2 _1(7.0.0):c2 _2(7.0.0):c2 _3(7.0.0):c2" [4 > segments ; isCommit = false] > [junit4] 1> IFD 0 [2017-01-23T17:34:48.701Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > delete [] > [junit4] 1> IFD 0 [2017-01-23T17:34:48.701Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 0 msec to checkpoint > [junit4] 1> LMP 0 [2017-01-23T17:34:48.701Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > findMerges: 4 segments > [junit4] 1> LMP 0 [2017-01-23T17:34:48.701Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_0(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:48.701Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_1(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:48.701Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_2(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:48.701Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_3(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:48.701Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > level -1.0 to 3.143639: 4 segments > [junit4] 1> MS 0 [2017-01-23T17:34:48.701Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > now merge > [junit4] 1> MS 0 [2017-01-23T17:34:48.701Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > index: _0(7.0.0):c2 _1(7.0.0):c2 _2(7.0.0):c2 _3(7.0.0):c2 > [junit4] 1> MS 0 [2017-01-23T17:34:48.701Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > no more merges pending; now return > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.707Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flush postings as segment _4 numDocs=2 > [junit4] 1> IW 0 [2017-01-23T17:34:48.707Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 0 msec to write norms > [junit4] 1> IW 0 [2017-01-23T17:34:48.707Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 0 msec to write docValues > [junit4] 1> IW 0 [2017-01-23T17:34:48.707Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 0 msec to write points > [junit4] 1> IW 0 [2017-01-23T17:34:48.715Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 7 msec to finish stored fields > [junit4] 1> IW 0 [2017-01-23T17:34:48.741Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 26 msec to write postings and finish vectors > [junit4] 1> IW 0 [2017-01-23T17:34:48.749Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 7 msec to write fieldInfos > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.749Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > new segment has 0 deleted docs > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.749Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > new segment has no vectors; no norms; no docValues; no prox; no freqs > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.749Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flushedFiles=[_4.fdt, _4_LuceneFixedGap_0.tib, _4.fnm, > _4_LuceneFixedGap_0.doc, _4_LuceneFixedGap_0.tii, _4.fdx] > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.749Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flushed codec=Asserting(Lucene70): {id=PostingsFormat(name=LuceneFixedGap)}, > docValues:{}, maxPointsInLeafNode=586, maxMBSortInHeap=7.926156065328051 > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.749Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flushed: segment=_4 ramUsed=0.071 MB newFlushedSize=0.001 MB > docs/MB=2,904.643 > [junit4] 1> IW 0 [2017-01-23T17:34:48.749Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > create compound file > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.808Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flush time 101.106793 msec > [junit4] 1> IFD 0 [2017-01-23T17:34:48.809Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_4.fdt" > [junit4] 1> IFD 0 [2017-01-23T17:34:48.809Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_4_LuceneFixedGap_0.tib" > [junit4] 1> IFD 0 [2017-01-23T17:34:48.809Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_4.fnm" > [junit4] 1> IFD 0 [2017-01-23T17:34:48.809Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_4_LuceneFixedGap_0.doc" > [junit4] 1> IFD 0 [2017-01-23T17:34:48.809Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_4_LuceneFixedGap_0.tii" > [junit4] 1> IFD 0 [2017-01-23T17:34:48.809Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_4.fdx" > [junit4] 1> IFD 0 [2017-01-23T17:34:48.809Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > delete [_4.fdt, _4_LuceneFixedGap_0.tib, _4.fnm, _4_LuceneFixedGap_0.doc, > _4_LuceneFixedGap_0.tii, _4.fdx] > [junit4] 1> DW 0 [2017-01-23T17:34:48.810Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > publishFlushedSegment seg-private updates=null > [junit4] 1> IW 0 [2017-01-23T17:34:48.810Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > publishFlushedSegment > [junit4] 1> IW 0 [2017-01-23T17:34:48.810Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > publish sets newSegment delGen=5 seg=_4(7.0.0):c2 > [junit4] 1> IFD 0 [2017-01-23T17:34:48.810Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > now checkpoint "_0(7.0.0):c2 _1(7.0.0):c2 _2(7.0.0):c2 _3(7.0.0):c2 > _4(7.0.0):c2" [5 segments ; isCommit = false] > [junit4] 1> IFD 0 [2017-01-23T17:34:48.810Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > delete [] > [junit4] 1> IFD 0 [2017-01-23T17:34:48.810Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 0 msec to checkpoint > [junit4] 1> LMP 0 [2017-01-23T17:34:48.810Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > findMerges: 5 segments > [junit4] 1> LMP 0 [2017-01-23T17:34:48.810Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_0(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:48.810Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_1(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:48.810Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_2(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:48.810Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_3(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:48.810Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_4(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:48.810Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > level -1.0 to 3.143639: 5 segments > [junit4] 1> MS 0 [2017-01-23T17:34:48.810Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > now merge > [junit4] 1> MS 0 [2017-01-23T17:34:48.810Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > index: _0(7.0.0):c2 _1(7.0.0):c2 _2(7.0.0):c2 _3(7.0.0):c2 _4(7.0.0):c2 > [junit4] 1> MS 0 [2017-01-23T17:34:48.810Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > no more merges pending; now return > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.815Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flush postings as segment _5 numDocs=2 > [junit4] 1> IW 0 [2017-01-23T17:34:48.815Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 0 msec to write norms > [junit4] 1> IW 0 [2017-01-23T17:34:48.815Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 0 msec to write docValues > [junit4] 1> IW 0 [2017-01-23T17:34:48.815Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 0 msec to write points > [junit4] 1> IW 0 [2017-01-23T17:34:48.823Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 7 msec to finish stored fields > [junit4] 1> IW 0 [2017-01-23T17:34:48.846Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 22 msec to write postings and finish vectors > [junit4] 1> IW 0 [2017-01-23T17:34:48.850Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 3 msec to write fieldInfos > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.850Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > new segment has 0 deleted docs > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.850Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > new segment has no vectors; no norms; no docValues; no prox; no freqs > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.850Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flushedFiles=[_5.fnm, _5.fdt, _5_LuceneFixedGap_0.tii, > _5_LuceneFixedGap_0.doc, _5_LuceneFixedGap_0.tib, _5.fdx] > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.850Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flushed codec=Asserting(Lucene70): {id=PostingsFormat(name=LuceneFixedGap)}, > docValues:{}, maxPointsInLeafNode=586, maxMBSortInHeap=7.926156065328051 > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.850Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flushed: segment=_5 ramUsed=0.071 MB newFlushedSize=0.001 MB > docs/MB=2,904.643 > [junit4] 1> IW 0 [2017-01-23T17:34:48.850Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > create compound file > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.882Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flush time 67.001219 msec > [junit4] 1> IFD 0 [2017-01-23T17:34:48.882Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_5.fnm" > [junit4] 1> IFD 0 [2017-01-23T17:34:48.882Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_5.fdt" > [junit4] 1> IFD 0 [2017-01-23T17:34:48.882Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_5_LuceneFixedGap_0.tii" > [junit4] 1> IFD 0 [2017-01-23T17:34:48.882Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_5_LuceneFixedGap_0.doc" > [junit4] 1> IFD 0 [2017-01-23T17:34:48.882Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_5_LuceneFixedGap_0.tib" > [junit4] 1> IFD 0 [2017-01-23T17:34:48.882Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_5.fdx" > [junit4] 1> IFD 0 [2017-01-23T17:34:48.882Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > delete [_5.fnm, _5.fdt, _5_LuceneFixedGap_0.tii, _5_LuceneFixedGap_0.doc, > _5_LuceneFixedGap_0.tib, _5.fdx] > [junit4] 1> DW 0 [2017-01-23T17:34:48.883Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > publishFlushedSegment seg-private updates=null > [junit4] 1> IW 0 [2017-01-23T17:34:48.883Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > publishFlushedSegment > [junit4] 1> IW 0 [2017-01-23T17:34:48.883Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > publish sets newSegment delGen=6 seg=_5(7.0.0):c2 > [junit4] 1> IFD 0 [2017-01-23T17:34:48.883Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > now checkpoint "_0(7.0.0):c2 _1(7.0.0):c2 _2(7.0.0):c2 _3(7.0.0):c2 > _4(7.0.0):c2 _5(7.0.0):c2" [6 segments ; isCommit = false] > [junit4] 1> IFD 0 [2017-01-23T17:34:48.883Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > delete [] > [junit4] 1> IFD 0 [2017-01-23T17:34:48.883Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 0 msec to checkpoint > [junit4] 1> LMP 0 [2017-01-23T17:34:48.883Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > findMerges: 6 segments > [junit4] 1> LMP 0 [2017-01-23T17:34:48.883Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_0(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:48.883Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_1(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:48.883Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_2(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:48.883Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_3(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:48.883Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_4(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:48.884Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_5(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:48.884Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > level -1.0 to 3.143639: 6 segments > [junit4] 1> MS 0 [2017-01-23T17:34:48.884Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > now merge > [junit4] 1> MS 0 [2017-01-23T17:34:48.884Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > index: _0(7.0.0):c2 _1(7.0.0):c2 _2(7.0.0):c2 _3(7.0.0):c2 _4(7.0.0):c2 > _5(7.0.0):c2 > [junit4] 1> MS 0 [2017-01-23T17:34:48.884Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > no more merges pending; now return > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.889Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flush postings as segment _6 numDocs=2 > [junit4] 1> IW 0 [2017-01-23T17:34:48.889Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 0 msec to write norms > [junit4] 1> IW 0 [2017-01-23T17:34:48.889Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 0 msec to write docValues > [junit4] 1> IW 0 [2017-01-23T17:34:48.889Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 0 msec to write points > [junit4] 1> IW 0 [2017-01-23T17:34:48.897Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 7 msec to finish stored fields > [junit4] 1> IW 0 [2017-01-23T17:34:48.922Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 25 msec to write postings and finish vectors > [junit4] 1> IW 0 [2017-01-23T17:34:48.930Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 8 msec to write fieldInfos > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.930Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > new segment has 0 deleted docs > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.931Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > new segment has no vectors; no norms; no docValues; no prox; no freqs > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.931Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flushedFiles=[_6.fdt, _6_LuceneFixedGap_0.tib, _6.fnm, _6.fdx, > _6_LuceneFixedGap_0.tii, _6_LuceneFixedGap_0.doc] > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.931Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flushed codec=Asserting(Lucene70): {id=PostingsFormat(name=LuceneFixedGap)}, > docValues:{}, maxPointsInLeafNode=586, maxMBSortInHeap=7.926156065328051 > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.931Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flushed: segment=_6 ramUsed=0.071 MB newFlushedSize=0.001 MB > docs/MB=2,904.643 > [junit4] 1> IW 0 [2017-01-23T17:34:48.931Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > create compound file > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.975Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flush time 86.659369 msec > [junit4] 1> IFD 0 [2017-01-23T17:34:48.977Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_6.fdt" > [junit4] 1> IFD 0 [2017-01-23T17:34:48.979Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_6_LuceneFixedGap_0.tib" > [junit4] 1> IFD 0 [2017-01-23T17:34:48.980Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_6.fnm" > [junit4] 1> IFD 0 [2017-01-23T17:34:48.980Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_6.fdx" > [junit4] 1> IFD 0 [2017-01-23T17:34:48.980Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_6_LuceneFixedGap_0.tii" > [junit4] 1> IFD 0 [2017-01-23T17:34:48.980Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_6_LuceneFixedGap_0.doc" > [junit4] 1> IFD 0 [2017-01-23T17:34:48.980Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > delete [_6.fdt, _6_LuceneFixedGap_0.tib, _6.fnm, _6.fdx, > _6_LuceneFixedGap_0.tii, _6_LuceneFixedGap_0.doc] > [junit4] 1> DW 0 [2017-01-23T17:34:48.980Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > publishFlushedSegment seg-private updates=null > [junit4] 1> IW 0 [2017-01-23T17:34:48.981Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > publishFlushedSegment > [junit4] 1> IW 0 [2017-01-23T17:34:48.981Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > publish sets newSegment delGen=7 seg=_6(7.0.0):c2 > [junit4] 1> IFD 0 [2017-01-23T17:34:48.981Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > now checkpoint "_0(7.0.0):c2 _1(7.0.0):c2 _2(7.0.0):c2 _3(7.0.0):c2 > _4(7.0.0):c2 _5(7.0.0):c2 _6(7.0.0):c2" [7 segments ; isCommit = false] > [junit4] 1> IFD 0 [2017-01-23T17:34:48.981Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > delete [] > [junit4] 1> IFD 0 [2017-01-23T17:34:48.981Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 0 msec to checkpoint > [junit4] 1> LMP 0 [2017-01-23T17:34:48.981Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > findMerges: 7 segments > [junit4] 1> LMP 0 [2017-01-23T17:34:48.981Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_0(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:48.981Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_1(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:48.981Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_2(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:48.981Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_3(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:48.981Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_4(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:48.981Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_5(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:48.981Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_6(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:48.981Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > level -1.0 to 3.143639: 7 segments > [junit4] 1> MS 0 [2017-01-23T17:34:48.981Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > now merge > [junit4] 1> MS 0 [2017-01-23T17:34:48.981Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > index: _0(7.0.0):c2 _1(7.0.0):c2 _2(7.0.0):c2 _3(7.0.0):c2 _4(7.0.0):c2 > _5(7.0.0):c2 _6(7.0.0):c2 > [junit4] 1> MS 0 [2017-01-23T17:34:48.981Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > no more merges pending; now return > [junit4] 1> DWPT 0 [2017-01-23T17:34:48.983Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flush postings as segment _7 numDocs=2 > [junit4] 1> IW 0 [2017-01-23T17:34:48.983Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 0 msec to write norms > [junit4] 1> IW 0 [2017-01-23T17:34:48.983Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 0 msec to write docValues > [junit4] 1> IW 0 [2017-01-23T17:34:48.983Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 0 msec to write points > [junit4] 1> IW 0 [2017-01-23T17:34:48.989Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 5 msec to finish stored fields > [junit4] 1> IW 0 [2017-01-23T17:34:49.005Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 16 msec to write postings and finish vectors > [junit4] 1> IW 0 [2017-01-23T17:34:49.009Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 4 msec to write fieldInfos > [junit4] 1> DWPT 0 [2017-01-23T17:34:49.009Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > new segment has 0 deleted docs > [junit4] 1> DWPT 0 [2017-01-23T17:34:49.009Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > new segment has no vectors; no norms; no docValues; no prox; no freqs > [junit4] 1> DWPT 0 [2017-01-23T17:34:49.010Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flushedFiles=[_7_LuceneFixedGap_0.tib, _7.fnm, _7_LuceneFixedGap_0.doc, > _7.fdt, _7_LuceneFixedGap_0.tii, _7.fdx] > [junit4] 1> DWPT 0 [2017-01-23T17:34:49.010Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flushed codec=Asserting(Lucene70): {id=PostingsFormat(name=LuceneFixedGap)}, > docValues:{}, maxPointsInLeafNode=586, maxMBSortInHeap=7.926156065328051 > [junit4] 1> DWPT 0 [2017-01-23T17:34:49.010Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flushed: segment=_7 ramUsed=0.071 MB newFlushedSize=0.001 MB > docs/MB=2,904.643 > [junit4] 1> IW 0 [2017-01-23T17:34:49.010Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > create compound file > [junit4] 1> DWPT 0 [2017-01-23T17:34:49.043Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flush time 59.953643 msec > [junit4] 1> IFD 0 [2017-01-23T17:34:49.043Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_7_LuceneFixedGap_0.tib" > [junit4] 1> IFD 0 [2017-01-23T17:34:49.043Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_7.fnm" > [junit4] 1> IFD 0 [2017-01-23T17:34:49.043Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_7_LuceneFixedGap_0.doc" > [junit4] 1> IFD 0 [2017-01-23T17:34:49.043Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_7.fdt" > [junit4] 1> IFD 0 [2017-01-23T17:34:49.043Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_7_LuceneFixedGap_0.tii" > [junit4] 1> IFD 0 [2017-01-23T17:34:49.043Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_7.fdx" > [junit4] 1> IFD 0 [2017-01-23T17:34:49.043Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > delete [_7_LuceneFixedGap_0.tib, _7.fnm, _7_LuceneFixedGap_0.doc, _7.fdt, > _7_LuceneFixedGap_0.tii, _7.fdx] > [junit4] 1> DW 0 [2017-01-23T17:34:49.044Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > publishFlushedSegment seg-private updates=null > [junit4] 1> IW 0 [2017-01-23T17:34:49.044Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > publishFlushedSegment > [junit4] 1> IW 0 [2017-01-23T17:34:49.044Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > publish sets newSegment delGen=8 seg=_7(7.0.0):c2 > [junit4] 1> IFD 0 [2017-01-23T17:34:49.044Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > now checkpoint "_0(7.0.0):c2 _1(7.0.0):c2 _2(7.0.0):c2 _3(7.0.0):c2 > _4(7.0.0):c2 _5(7.0.0):c2 _6(7.0.0):c2 _7(7.0.0):c2" [8 segments ; isCommit = > false] > [junit4] 1> IFD 0 [2017-01-23T17:34:49.044Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > delete [] > [junit4] 1> IFD 0 [2017-01-23T17:34:49.044Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 0 msec to checkpoint > [junit4] 1> LMP 0 [2017-01-23T17:34:49.044Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > findMerges: 8 segments > [junit4] 1> LMP 0 [2017-01-23T17:34:49.044Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_0(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:49.044Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_1(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:49.044Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_2(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:49.044Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_3(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:49.044Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_4(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:49.044Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_5(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:49.044Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_6(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:49.044Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_7(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:49.044Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > level -1.0 to 3.143639: 8 segments > [junit4] 1> MS 0 [2017-01-23T17:34:49.044Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > now merge > [junit4] 1> MS 0 [2017-01-23T17:34:49.044Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > index: _0(7.0.0):c2 _1(7.0.0):c2 _2(7.0.0):c2 _3(7.0.0):c2 _4(7.0.0):c2 > _5(7.0.0):c2 _6(7.0.0):c2 _7(7.0.0):c2 > [junit4] 1> MS 0 [2017-01-23T17:34:49.044Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > no more merges pending; now return > [junit4] 1> DWPT 0 [2017-01-23T17:34:49.047Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flush postings as segment _8 numDocs=2 > [junit4] 1> IW 0 [2017-01-23T17:34:49.047Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 0 msec to write norms > [junit4] 1> IW 0 [2017-01-23T17:34:49.047Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 0 msec to write docValues > [junit4] 1> IW 0 [2017-01-23T17:34:49.047Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 0 msec to write points > [junit4] 1> IW 0 [2017-01-23T17:34:49.052Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 4 msec to finish stored fields > [junit4] 1> IW 0 [2017-01-23T17:34:49.067Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 15 msec to write postings and finish vectors > [junit4] 1> IW 0 [2017-01-23T17:34:49.070Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 3 msec to write fieldInfos > [junit4] 1> DWPT 0 [2017-01-23T17:34:49.070Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > new segment has 0 deleted docs > [junit4] 1> DWPT 0 [2017-01-23T17:34:49.070Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > new segment has no vectors; no norms; no docValues; no prox; no freqs > [junit4] 1> DWPT 0 [2017-01-23T17:34:49.070Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flushedFiles=[_8_LuceneFixedGap_0.doc, _8.fdx, _8_LuceneFixedGap_0.tii, > _8.fdt, _8.fnm, _8_LuceneFixedGap_0.tib] > [junit4] 1> DWPT 0 [2017-01-23T17:34:49.070Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flushed codec=Asserting(Lucene70): {id=PostingsFormat(name=LuceneFixedGap)}, > docValues:{}, maxPointsInLeafNode=586, maxMBSortInHeap=7.926156065328051 > [junit4] 1> DWPT 0 [2017-01-23T17:34:49.070Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flushed: segment=_8 ramUsed=0.071 MB newFlushedSize=0.001 MB > docs/MB=2,904.643 > [junit4] 1> IW 0 [2017-01-23T17:34:49.070Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > create compound file > [junit4] 1> DWPT 0 [2017-01-23T17:34:49.104Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flush time 56.504489 msec > [junit4] 1> IFD 0 [2017-01-23T17:34:49.104Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_8_LuceneFixedGap_0.doc" > [junit4] 1> IFD 0 [2017-01-23T17:34:49.104Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_8.fdx" > [junit4] 1> IFD 0 [2017-01-23T17:34:49.104Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_8_LuceneFixedGap_0.tii" > [junit4] 1> IFD 0 [2017-01-23T17:34:49.104Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_8.fdt" > [junit4] 1> IFD 0 [2017-01-23T17:34:49.104Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_8.fnm" > [junit4] 1> IFD 0 [2017-01-23T17:34:49.104Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_8_LuceneFixedGap_0.tib" > [junit4] 1> IFD 0 [2017-01-23T17:34:49.104Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > delete [_8_LuceneFixedGap_0.doc, _8.fdx, _8_LuceneFixedGap_0.tii, _8.fdt, > _8.fnm, _8_LuceneFixedGap_0.tib] > [junit4] 1> DW 0 [2017-01-23T17:34:49.104Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > publishFlushedSegment seg-private updates=null > [junit4] 1> IW 0 [2017-01-23T17:34:49.104Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > publishFlushedSegment > [junit4] 1> IW 0 [2017-01-23T17:34:49.104Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > publish sets newSegment delGen=9 seg=_8(7.0.0):c2 > [junit4] 1> IFD 0 [2017-01-23T17:34:49.104Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > now checkpoint "_0(7.0.0):c2 _1(7.0.0):c2 _2(7.0.0):c2 _3(7.0.0):c2 > _4(7.0.0):c2 _5(7.0.0):c2 _6(7.0.0):c2 _7(7.0.0):c2 _8(7.0.0):c2" [9 segments > ; isCommit = false] > [junit4] 1> IFD 0 [2017-01-23T17:34:49.104Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > delete [] > [junit4] 1> IFD 0 [2017-01-23T17:34:49.104Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 0 msec to checkpoint > [junit4] 1> LMP 0 [2017-01-23T17:34:49.104Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > findMerges: 9 segments > [junit4] 1> LMP 0 [2017-01-23T17:34:49.104Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_0(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:49.104Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_1(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:49.104Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_2(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:49.104Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_3(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:49.104Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_4(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:49.104Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_5(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:49.104Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_6(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:49.104Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_7(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:49.105Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_8(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:49.105Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > level -1.0 to 3.143639: 9 segments > [junit4] 1> MS 0 [2017-01-23T17:34:49.105Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > now merge > [junit4] 1> MS 0 [2017-01-23T17:34:49.105Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > index: _0(7.0.0):c2 _1(7.0.0):c2 _2(7.0.0):c2 _3(7.0.0):c2 _4(7.0.0):c2 > _5(7.0.0):c2 _6(7.0.0):c2 _7(7.0.0):c2 _8(7.0.0):c2 > [junit4] 1> MS 0 [2017-01-23T17:34:49.105Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > no more merges pending; now return > [junit4] 1> DWPT 0 [2017-01-23T17:34:49.109Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flush postings as segment _9 numDocs=2 > [junit4] 1> IW 0 [2017-01-23T17:34:49.109Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 0 msec to write norms > [junit4] 1> IW 0 [2017-01-23T17:34:49.109Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 0 msec to write docValues > [junit4] 1> IW 0 [2017-01-23T17:34:49.109Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 0 msec to write points > [junit4] 1> IW 0 [2017-01-23T17:34:49.112Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 3 msec to finish stored fields > [junit4] 1> IW 0 [2017-01-23T17:34:49.128Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 14 msec to write postings and finish vectors > [junit4] 1> IW 0 [2017-01-23T17:34:49.139Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 11 msec to write fieldInfos > [junit4] 1> DWPT 0 [2017-01-23T17:34:49.139Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > new segment has 0 deleted docs > [junit4] 1> DWPT 0 [2017-01-23T17:34:49.139Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > new segment has no vectors; no norms; no docValues; no prox; no freqs > [junit4] 1> DWPT 0 [2017-01-23T17:34:49.139Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flushedFiles=[_9.fdx, _9_LuceneFixedGap_0.tib, _9.fdt, _9.fnm, > _9_LuceneFixedGap_0.tii, _9_LuceneFixedGap_0.doc] > [junit4] 1> DWPT 0 [2017-01-23T17:34:49.139Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flushed codec=Asserting(Lucene70): {id=PostingsFormat(name=LuceneFixedGap)}, > docValues:{}, maxPointsInLeafNode=586, maxMBSortInHeap=7.926156065328051 > [junit4] 1> DWPT 0 [2017-01-23T17:34:49.139Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flushed: segment=_9 ramUsed=0.071 MB newFlushedSize=0.001 MB > docs/MB=2,904.643 > [junit4] 1> IW 0 [2017-01-23T17:34:49.139Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > create compound file > [junit4] 1> DWPT 0 [2017-01-23T17:34:49.197Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > flush time 88.539554 msec > [junit4] 1> IFD 0 [2017-01-23T17:34:49.197Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_9.fdx" > [junit4] 1> IFD 0 [2017-01-23T17:34:49.197Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_9_LuceneFixedGap_0.tib" > [junit4] 1> IFD 0 [2017-01-23T17:34:49.197Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_9.fdt" > [junit4] 1> IFD 0 [2017-01-23T17:34:49.197Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_9.fnm" > [junit4] 1> IFD 0 [2017-01-23T17:34:49.197Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_9_LuceneFixedGap_0.tii" > [junit4] 1> IFD 0 [2017-01-23T17:34:49.197Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > will delete new file "_9_LuceneFixedGap_0.doc" > [junit4] 1> IFD 0 [2017-01-23T17:34:49.197Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > delete [_9.fdx, _9_LuceneFixedGap_0.tib, _9.fdt, _9.fnm, > _9_LuceneFixedGap_0.tii, _9_LuceneFixedGap_0.doc] > [junit4] 1> DW 0 [2017-01-23T17:34:49.199Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > publishFlushedSegment seg-private updates=null > [junit4] 1> IW 0 [2017-01-23T17:34:49.199Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > publishFlushedSegment > [junit4] 1> IW 0 [2017-01-23T17:34:49.199Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > publish sets newSegment delGen=10 seg=_9(7.0.0):c2 > [junit4] 1> IFD 0 [2017-01-23T17:34:49.199Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > now checkpoint "_0(7.0.0):c2 _1(7.0.0):c2 _2(7.0.0):c2 _3(7.0.0):c2 > _4(7.0.0):c2 _5(7.0.0):c2 _6(7.0.0):c2 _7(7.0.0):c2 _8(7.0.0):c2 > _9(7.0.0):c2" [10 segments ; isCommit = false] > [junit4] 1> IFD 0 [2017-01-23T17:34:49.199Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > delete [] > [junit4] 1> IFD 0 [2017-01-23T17:34:49.199Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 0 msec to checkpoint > [junit4] 1> LMP 0 [2017-01-23T17:34:49.200Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > findMerges: 10 segments > [junit4] 1> LMP 0 [2017-01-23T17:34:49.200Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_0(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:49.200Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_1(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:49.200Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_2(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:49.200Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_3(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:49.200Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_4(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:49.200Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_5(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:49.200Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_6(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:49.200Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_7(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:49.200Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_8(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:49.200Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > seg=_9(7.0.0):c2 level=3.143639 size=0.001 MB > [junit4] 1> LMP 0 [2017-01-23T17:34:49.200Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > level -1.0 to 3.143639: 10 segments > [junit4] 1> LMP 0 [2017-01-23T17:34:49.200Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > add merge=_0(7.0.0):c2 _1(7.0.0):c2 _2(7.0.0):c2 _3(7.0.0):c2 _4(7.0.0):c2 > _5(7.0.0):c2 _6(7.0.0):c2 _7(7.0.0):c2 _8(7.0.0):c2 _9(7.0.0):c2 start=0 > end=10 > [junit4] 1> IW 0 [2017-01-23T17:34:49.200Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > add merge to pendingMerges: _0(7.0.0):c2 _1(7.0.0):c2 _2(7.0.0):c2 > _3(7.0.0):c2 _4(7.0.0):c2 _5(7.0.0):c2 _6(7.0.0):c2 _7(7.0.0):c2 _8(7.0.0):c2 > _9(7.0.0):c2 [total 1 pending] > [junit4] 1> IW 0 [2017-01-23T17:34:49.200Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > registerMerge merging= [] > [junit4] 1> IW 0 [2017-01-23T17:34:49.200Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > registerMerge info=_0(7.0.0):c2 > [junit4] 1> IW 0 [2017-01-23T17:34:49.200Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > registerMerge info=_1(7.0.0):c2 > [junit4] 1> IW 0 [2017-01-23T17:34:49.200Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > registerMerge info=_2(7.0.0):c2 > [junit4] 1> IW 0 [2017-01-23T17:34:49.200Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > registerMerge info=_3(7.0.0):c2 > [junit4] 1> IW 0 [2017-01-23T17:34:49.200Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > registerMerge info=_4(7.0.0):c2 > [junit4] 1> IW 0 [2017-01-23T17:34:49.200Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > registerMerge info=_5(7.0.0):c2 > [junit4] 1> IW 0 [2017-01-23T17:34:49.200Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > registerMerge info=_6(7.0.0):c2 > [junit4] 1> IW 0 [2017-01-23T17:34:49.200Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > registerMerge info=_7(7.0.0):c2 > [junit4] 1> IW 0 [2017-01-23T17:34:49.200Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > registerMerge info=_8(7.0.0):c2 > [junit4] 1> IW 0 [2017-01-23T17:34:49.200Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > registerMerge info=_9(7.0.0):c2 > [junit4] 1> MS 0 [2017-01-23T17:34:49.200Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > now merge > [junit4] 1> MS 0 [2017-01-23T17:34:49.200Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > index: _0(7.0.0):c2 _1(7.0.0):c2 _2(7.0.0):c2 _3(7.0.0):c2 _4(7.0.0):c2 > _5(7.0.0):c2 _6(7.0.0):c2 _7(7.0.0):c2 _8(7.0.0):c2 _9(7.0.0):c2 > [junit4] 1> MS 0 [2017-01-23T17:34:49.200Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > consider merge _0(7.0.0):c2 _1(7.0.0):c2 _2(7.0.0):c2 _3(7.0.0):c2 > _4(7.0.0):c2 _5(7.0.0):c2 _6(7.0.0):c2 _7(7.0.0):c2 _8(7.0.0):c2 _9(7.0.0):c2 > [junit4] 1> MS 0 [2017-01-23T17:34:49.201Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > launch new thread [MyMergeThread] > [junit4] 1> MS 0 [2017-01-23T17:34:49.201Z; MyMergeThread]: merge > thread: start > [junit4] 1> IW 0 [2017-01-23T17:34:49.203Z; MyMergeThread]: now apply > deletes for 10 merging segments > [junit4] 1> BD 0 [2017-01-23T17:34:49.203Z; MyMergeThread]: applyDeletes: > open segment readers took 0 msec > [junit4] 1> MS 0 [2017-01-23T17:34:49.204Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > 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> BD 0 [2017-01-23T17:34:49.204Z; MyMergeThread]: applyDeletes: > no segments; skipping > [junit4] 1> BD 0 [2017-01-23T17:34:49.204Z; MyMergeThread]: prune > sis=segments: _0(7.0.0):c2 _1(7.0.0):c2 _2(7.0.0):c2 _3(7.0.0):c2 > _4(7.0.0):c2 _5(7.0.0):c2 _6(7.0.0):c2 _7(7.0.0):c2 _8(7.0.0):c2 _9(7.0.0):c2 > minGen=1 packetCount=0 > [junit4] 1> IW 0 [2017-01-23T17:34:49.204Z; MyMergeThread]: merge seg=_a > _0(7.0.0):c2 _1(7.0.0):c2 _2(7.0.0):c2 _3(7.0.0):c2 _4(7.0.0):c2 _5(7.0.0):c2 > _6(7.0.0):c2 _7(7.0.0):c2 _8(7.0.0):c2 _9(7.0.0):c2 > [junit4] 1> IW 0 [2017-01-23T17:34:49.204Z; MyMergeThread]: now merge > [junit4] 1> merge=_0(7.0.0):c2 _1(7.0.0):c2 _2(7.0.0):c2 _3(7.0.0):c2 > _4(7.0.0):c2 _5(7.0.0):c2 _6(7.0.0):c2 _7(7.0.0):c2 _8(7.0.0):c2 _9(7.0.0):c2 > [junit4] 1> index=_0(7.0.0):c2 _1(7.0.0):c2 _2(7.0.0):c2 _3(7.0.0):c2 > _4(7.0.0):c2 _5(7.0.0):c2 _6(7.0.0):c2 _7(7.0.0):c2 _8(7.0.0):c2 _9(7.0.0):c2 > [junit4] 1> IW 0 [2017-01-23T17:34:49.204Z; MyMergeThread]: merging > _0(7.0.0):c2 _1(7.0.0):c2 _2(7.0.0):c2 _3(7.0.0):c2 _4(7.0.0):c2 _5(7.0.0):c2 > _6(7.0.0):c2 _7(7.0.0):c2 _8(7.0.0):c2 _9(7.0.0):c2 > [junit4] 1> IW 0 [2017-01-23T17:34:49.205Z; 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:95) > [junit4] 1> at > org.apache.lucene.store.MockDirectoryWrapper.maybeThrowDeterministicException(MockDirectoryWrapper.java:1022) > [junit4] 1> at > org.apache.lucene.store.MockDirectoryWrapper.openInput(MockDirectoryWrapper.java:743) > [junit4] 1> at > org.apache.lucene.store.Directory.openChecksumInput(Directory.java:119) > [junit4] 1> at > org.apache.lucene.store.MockDirectoryWrapper.openChecksumInput(MockDirectoryWrapper.java:1072) > [junit4] 1> at > org.apache.lucene.codecs.lucene50.Lucene50CompoundReader.readEntries(Lucene50CompoundReader.java:105) > [junit4] 1> at > org.apache.lucene.codecs.lucene50.Lucene50CompoundReader.<init>(Lucene50CompoundReader.java:69) > [junit4] 1> at > org.apache.lucene.codecs.lucene50.Lucene50CompoundFormat.getCompoundReader(Lucene50CompoundFormat.java:71) > [junit4] 1> at > org.apache.lucene.index.SegmentCoreReaders.<init>(SegmentCoreReaders.java:96) > [junit4] 1> at > org.apache.lucene.index.SegmentReader.<init>(SegmentReader.java:74) > [junit4] 1> at > org.apache.lucene.index.ReadersAndUpdates.getReader(ReadersAndUpdates.java:143) > [junit4] 1> at > org.apache.lucene.index.ReadersAndUpdates.getReaderForMerge(ReadersAndUpdates.java:681) > [junit4] 1> at > org.apache.lucene.index.IndexWriter.mergeMiddle(IndexWriter.java:4252) > [junit4] 1> at > org.apache.lucene.index.IndexWriter.merge(IndexWriter.java:3899) > [junit4] 1> at > org.apache.lucene.index.ConcurrentMergeScheduler.doMerge(ConcurrentMergeScheduler.java:588) > [junit4] 1> at > org.apache.lucene.TestMergeSchedulerExternal$MyMergeScheduler.doMerge(TestMergeSchedulerExternal.java:85) > [junit4] 1> at > org.apache.lucene.index.ConcurrentMergeScheduler$MergeThread.run(ConcurrentMergeScheduler.java:626) > [junit4] 1> > [junit4] 1> IW 0 [2017-01-23T17:34:49.205Z; MyMergeThread]: > handleMergeException: merge=_0(7.0.0):c2 _1(7.0.0):c2 _2(7.0.0):c2 > _3(7.0.0):c2 _4(7.0.0):c2 _5(7.0.0):c2 _6(7.0.0):c2 _7(7.0.0):c2 _8(7.0.0):c2 > _9(7.0.0):c2 exc=java.io.IOException: now failing during merge > [junit4] 1> IW 0 [2017-01-23T17:34:49.206Z; MyMergeThread]: hit exception > during merge > [junit4] 1> IW 0 [2017-01-23T17:34:49.206Z; MyMergeThread]: hit tragic > IOException inside merge > [junit4] 1> IW 0 [2017-01-23T17:34:49.206Z; MyMergeThread]: rollback > [junit4] 1> IW 0 [2017-01-23T17:34:49.206Z; MyMergeThread]: all running > merges have aborted > [junit4] 1> IW 0 [2017-01-23T17:34:49.206Z; MyMergeThread]: rollback: > done finish merges > [junit4] 1> MS 0 [2017-01-23T17:34:49.206Z; > TEST-TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler-seed#[B6EBCEBC661DDA5A]]: > no more merges pending; now return > [junit4] 1> DW 0 [2017-01-23T17:34:49.207Z; MyMergeThread]: abort > [junit4] 1> DW 0 [2017-01-23T17:34:49.207Z; MyMergeThread]: done abort > success=true > [junit4] 1> IW 0 [2017-01-23T17:34:49.207Z; MyMergeThread]: rollback: > infos= > [junit4] 1> IW 0 [2017-01-23T17:34:49.208Z; MyMergeThread]: TEST: now > handleMergeException > [junit4] 1> MS 0 [2017-01-23T17:34:49.208Z; 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=B6EBCEBC661DDA5A -Dtests.slow=true -Dtests.locale=mk-MK > -Dtests.timezone=America/Godthab -Dtests.asserts=true > -Dtests.file.encoding=UTF-8 > [junit4] FAILURE 0.93s J1 | > TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler <<< > [junit4] > Throwable #1: java.lang.AssertionError > [junit4] > at > __randomizedtesting.SeedInfo.seed([B6EBCEBC661DDA5A:316A7311623DA05E]:0) > [junit4] > at > org.apache.lucene.TestMergeSchedulerExternal.testSubclassConcurrentMergeScheduler(TestMergeSchedulerExternal.java:147) > [junit4] > at java.lang.Thread.run(Thread.java:745) > [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): > {id=PostingsFormat(name=LuceneFixedGap)}, docValues:{}, > maxPointsInLeafNode=586, maxMBSortInHeap=7.926156065328051, > sim=RandomSimilarity(queryNorm=false): {}, locale=mk-MK, > timezone=America/Godthab > [junit4] 2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 1.8.0_121 > (64-bit)/cpus=3,threads=1,free=65330152,total=127008768 > [junit4] 2> NOTE: All tests run in this JVM: > [TestUpgradeIndexMergePolicy, TestTermVectorsWriter, TestNIOFSDirectory, > TestMultiset, TestSortedSetSelector, TestOrdinalMap, TestMaxTermFrequency, > TestLucene62SegmentInfoFormat, TestAllFilesCheckIndexHeader, > TestSentinelIntSet, TestIndexWriterOnVMError, TestDirectMonotonic, > TestAutomatonQueryUnicode, TestFuzzyQuery, TestTimSorterWorstCase, > TestStressDeletes, TestPagedBytes, TestNRTCachingDirectory, TestDocIdsWriter, > TestTotalHitCountCollector, TestIsCurrent, TestSegmentMerger, > TestMultiDocValues, TestBasics, TestIndexReaderClose, TestPackedInts, > TestConcurrentMergeScheduler, TestDocumentWriter, TestIndexWriterOnDiskFull, > TestGraphTokenStreamFiniteStrings, TestSetOnce, TestBufferedChecksum, > TestDeletionPolicy, TestConjunctionDISI, TestDemoParallelLeafReader, > TestSpanTermQuery, TermInSetQueryTest, TestGeoUtils, TestSimpleAttributeImpl, > TestSizeBoundedForceMerge, TestNot, TestInPlaceMergeSorter, > TestNearSpansOrdered, TestCustomSearcherSort, TestFastCompressionMode, > TestMergePolicyWrapper, TestStopFilter, TestMergeSchedulerExternal] > [junit4] Completed [107/447 (1!)] on J1 in 0.95s, 2 tests, 1 failure <<< > FAILURES! > > [...truncated 74740 lines...] > > > --------------------------------------------------------------------- > To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org > For additional commands, e-mail: dev-h...@lucene.apache.org --------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org