This is https://issues.apache.org/jira/browse/LUCENE-6750
Long standing mysterious failure only on OS X! Mike McCandless http://blog.mikemccandless.com On Mon, Jan 23, 2017 at 2:31 PM, Steve Rowe <sar...@gmail.com> wrote: > 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 > --------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org
