C:\workspaces\workspaceecm5\LuceneIndexUpgrade\lb>java -cp lucene-core-6.6.6.jar;lucene-backward-codecs-6.6.6.jar org.apache.lucene.index.IndexUpgrader -delete-prior-commits -verbose "V:\\LuceneMigration\\5"
IFD 0 [2022-01-12T14:02:35.479Z; main]: init: current segments file is "segments_8z"; deletionPolicy=org.apache.lucene.index.KeepOnlyLastCommitDeletionPolicy@6833ce2c
IFD 0 [2022-01-12T14:02:35.491Z; main]: init: load commit "segments_8z"
IFD 0 [2022-01-12T14:02:35.494Z; main]: delete []
IFD 0 [2022-01-12T14:02:35.495Z; main]: now checkpoint "_gg(5.5.5):C98089 _gu(5.5.5):C52875 _h4(5.5.5):c11834" [3 segments ; isCommit = false]
IFD 0 [2022-01-12T14:02:35.495Z; main]: delete []
IFD 0 [2022-01-12T14:02:35.496Z; main]: 1 msec to checkpoint
IW 0 [2022-01-12T14:02:35.496Z; main]: init: create=false
IW 0 [2022-01-12T14:02:35.498Z; main]:
dir=MMapDirectory@V:\LuceneMigration\5 lockFactory=org.apache.lucene.store.NativeFSLockFactory@7d417077
index=_gg(5.5.5):C98089 _gu(5.5.5):C52875 _h4(5.5.5):c11834
version=6.6.6
analyzer=null
ramBufferSizeMB=16.0
maxBufferedDocs=-1
maxBufferedDeleteTerms=-1
mergedSegmentWarmer=null
delPolicy=org.apache.lucene.index.KeepOnlyLastCommitDeletionPolicy
commit=null
openMode=CREATE_OR_APPEND
similarity=org.apache.lucene.search.similarities.BM25Similarity
mergeScheduler=ConcurrentMergeScheduler: maxThreadCount=-1, maxMergeCount=-1, ioThrottle=true
codec=Lucene62
infoStream=org.apache.lucene.util.PrintStreamInfoStream
mergePolicy=UpgradeIndexMergePolicy([.TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=5120.0, floorSegmentMB=2.0, forceMergeDeletesPctAllowed=10.0, segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1)
indexerThreadPool=org.apache.lucene.index.DocumentsWriterPerThreadPool@7dc36524
readerPooling=false
perThreadHardLimitMB=1945
useCompoundFile=true
commitOnClose=true
indexSort=null
writer=org.apache.lucene.index.IndexWriter@35bbe5e8
IW 0 [2022-01-12T14:02:35.499Z; main]: MMapDirectory.UNMAP_SUPPORTED=true
IndexUpgrader 0 [2022-01-12T14:02:35.499Z; main]: Upgrading all pre-6.6.6 segments of index directory 'MMapDirectory@V:\LuceneMigration\5 lockFactory=org.apache.lucene.store.NativeFSLockFactory@7d417077' to version 6.6.6...
IW 0 [2022-01-12T14:02:35.500Z; main]: forceMerge: index now _gg(5.5.5):C98089 _gu(5.5.5):C52875 _h4(5.5.5):c11834
IW 0 [2022-01-12T14:02:35.501Z; main]: now flush at forceMerge
IW 0 [2022-01-12T14:02:35.501Z; main]: start flush: applyAllDeletes=true
IW 0 [2022-01-12T14:02:35.502Z; main]: index before flush _gg(5.5.5):C98089 _gu(5.5.5):C52875 _h4(5.5.5):c11834
DW 0 [2022-01-12T14:02:35.502Z; main]: startFullFlush
DW 0 [2022-01-12T14:02:35.503Z; main]: main finishFullFlush success=true
IW 0 [2022-01-12T14:02:35.503Z; main]: apply all deletes during flush
IW 0 [2022-01-12T14:02:35.503Z; main]: now apply all deletes for all segments maxDoc=162798
BD 0 [2022-01-12T14:02:35.509Z; main]: applyDeletes: open segment readers took 0 msec
BD 0 [2022-01-12T14:02:35.510Z; main]: applyDeletes: no segments; skipping
BD 0 [2022-01-12T14:02:35.510Z; main]: prune sis=segments_8z: _gg(5.5.5):C98089 _gu(5.5.5):C52875 _h4(5.5.5):c11834 minGen=0 packetCount=0
UPGMP 0 [2022-01-12T14:02:35.511Z; main]: findForcedMerges: segmentsToUpgrade={_gg(5.5.5):C98089=true, _gu(5.5.5):C52875=true, _h4(5.5.5):c11834=true}
TMP 0 [2022-01-12T14:02:35.511Z; main]: findForcedMerges maxSegmentCount=1 infos=_gg(5.5.5):C98089 _gu(5.5.5):C52875 _h4(5.5.5):c11834 segmentsToMerge={_gg(5.5.5):C98089=true, _gu(5.5.5):C52875=true, _h4(5.5.5):c11834=true}
TMP 0 [2022-01-12T14:02:35.514Z; main]: eligible=[_gg(5.5.5):C98089, _gu(5.5.5):C52875, _h4(5.5.5):c11834]
TMP 0 [2022-01-12T14:02:35.514Z; main]: forceMergeRunning=false
TMP 0 [2022-01-12T14:02:35.516Z; main]: add final merge=_gg(5.5.5):C98089 _gu(5.5.5):C52875 _h4(5.5.5):c11834
IW 0 [2022-01-12T14:02:35.517Z; main]: add merge to pendingMerges: _gg(5.5.5):C98089 _gu(5.5.5):C52875 _h4(5.5.5):c11834 [total 1 pending]
IW 0 [2022-01-12T14:02:35.517Z; main]: registerMerge merging= []
IW 0 [2022-01-12T14:02:35.518Z; main]: registerMerge info=_gg(5.5.5):C98089
IW 0 [2022-01-12T14:02:35.518Z; main]: registerMerge info=_gu(5.5.5):C52875
IW 0 [2022-01-12T14:02:35.519Z; main]: registerMerge info=_h4(5.5.5):c11834
MS 0 [2022-01-12T14:02:35.520Z; main]: initDynamicDefaults spins=true maxThreadCount=1 maxMergeCount=6
MS 0 [2022-01-12T14:02:35.522Z; main]: now merge
MS 0 [2022-01-12T14:02:35.522Z; main]: index: _gg(5.5.5):C98089 _gu(5.5.5):C52875 _h4(5.5.5):c11834
MS 0 [2022-01-12T14:02:35.522Z; main]: consider merge _gg(5.5.5):C98089 _gu(5.5.5):C52875 _h4(5.5.5):c11834
MS 0 [2022-01-12T14:02:35.523Z; main]: launch new thread [Lucene Merge Thread #0]
MS 0 [2022-01-12T14:02:35.524Z; Lucene Merge Thread #0]: merge thread: start
IW 0 [2022-01-12T14:02:35.524Z; Lucene Merge Thread #0]: now apply deletes for 3 merging segments
BD 0 [2022-01-12T14:02:35.524Z; Lucene Merge Thread #0]: applyDeletes: open segment readers took 0 msec
BD 0 [2022-01-12T14:02:35.524Z; Lucene Merge Thread #0]: applyDeletes: no segments; skipping
BD 0 [2022-01-12T14:02:35.526Z; Lucene Merge Thread #0]: prune sis=segments_8z: _gg(5.5.5):C98089 _gu(5.5.5):C52875 _h4(5.5.5):c11834 minGen=0 packetCount=0
IW 0 [2022-01-12T14:02:35.527Z; Lucene Merge Thread #0]: merge seg=_h5 _gg(5.5.5):C98089 _gu(5.5.5):C52875 _h4(5.5.5):c11834
IW 0 [2022-01-12T14:02:35.527Z; Lucene Merge Thread #0]: now merge
merge=_gg(5.5.5):C98089 _gu(5.5.5):C52875 _h4(5.5.5):c11834
index=_gg(5.5.5):C98089 _gu(5.5.5):C52875 _h4(5.5.5):c11834
IW 0 [2022-01-12T14:02:35.529Z; Lucene Merge Thread #0]: merging _gg(5.5.5):C98089 _gu(5.5.5):C52875 _h4(5.5.5):c11834
MS 0 [2022-01-12T14:02:35.530Z; main]: updateMergeThreads ioThrottle=true targetMBPerSec=20.0 MB/sec
merge thread Lucene Merge Thread #0 estSize=27.5 MB (written=0.0 MB) runTime=0.0s (stopped=0.0s, paused=0.0s) rate=unlimited
leave running at Infinity MB/sec
IW 0 [2022-01-12T14:02:35.609Z; Lucene Merge Thread #0]: seg=_gg(5.5.5):C98089 no deletes
IW 0 [2022-01-12T14:02:35.654Z; Lucene Merge Thread #0]: seg=_gu(5.5.5):C52875 no deletes
IW 0 [2022-01-12T14:02:35.658Z; Lucene Merge Thread #0]: seg=_h4(5.5.5):c11834 no deletes
MS 0 [2022-01-12T14:02:35.658Z; main]: no more merges pending; now return
SM 0 [2022-01-12T14:02:35.668Z; Lucene Merge Thread #0]: merge store matchedCount=3 vs 3
SM 0 [2022-01-12T14:02:35.734Z; Lucene Merge Thread #0]: 70 msec to merge stored fields [162798 docs]
SM 0 [2022-01-12T14:02:37.129Z; Lucene Merge Thread #0]: 1394 msec to merge postings [162798 docs]
SM 0 [2022-01-12T14:02:37.286Z; Lucene Merge Thread #0]: 156 msec to merge doc values [162798 docs]
SM 0 [2022-01-12T14:02:37.287Z; Lucene Merge Thread #0]: 0 msec to merge points [162798 docs]
SM 0 [2022-01-12T14:02:37.874Z; Lucene Merge Thread #0]: 585 msec to merge norms [162798 docs]
SM 0 [2022-01-12T14:02:37.877Z; Lucene Merge Thread #0]: 3 msec to write field infos [162798 docs]
IW 0 [2022-01-12T14:02:37.881Z; Lucene Merge Thread #0]: merge codec=Lucene62 maxDoc=162798; merged segment has no vectors; norms; docValues; prox; freqs; no points; 2.2 sec to merge segment [28.78 MB, 12.97 MB/sec]
IW 0 [2022-01-12T14:02:37.883Z; Lucene Merge Thread #0]: merged segment size=28.782 MB vs estimate=27.451 MB
IW 0 [2022-01-12T14:02:37.883Z; Lucene Merge Thread #0]: commitMerge: _gg(5.5.5):C98089 _gu(5.5.5):C52875 _h4(5.5.5):c11834 index=_gg(5.5.5):C98089 _gu(5.5.5):C52875 _h4(5.5.5):c11834
IW 0 [2022-01-12T14:02:37.885Z; Lucene Merge Thread #0]: commitMergeDeletes _gg(5.5.5):C98089 _gu(5.5.5):C52875 _h4(5.5.5):c11834
IW 0 [2022-01-12T14:02:37.887Z; Lucene Merge Thread #0]: no new deletes or field updates since merge started
IFD 0 [2022-01-12T14:02:37.891Z; Lucene Merge Thread #0]: now checkpoint "_h5(6.6.6):C162798" [1 segments ; isCommit = false]
IFD 0 [2022-01-12T14:02:37.891Z; Lucene Merge Thread #0]: delete []
IFD 0 [2022-01-12T14:02:37.891Z; Lucene Merge Thread #0]: 0 msec to checkpoint
IW 0 [2022-01-12T14:02:37.891Z; Lucene Merge Thread #0]: after commitMerge: _h5(6.6.6):C162798
UPGMP 0 [2022-01-12T14:02:37.892Z; Lucene Merge Thread #0]: findForcedMerges: segmentsToUpgrade={}
IW 0 [2022-01-12T14:02:37.892Z; Lucene Merge Thread #0]: merge time 2368 msec for 162798 docs
MS 0 [2022-01-12T14:02:37.893Z; Lucene Merge Thread #0]: merge thread: done
MS 0 [2022-01-12T14:02:37.893Z; Lucene Merge Thread #0]: now merge
MS 0 [2022-01-12T14:02:37.893Z; Lucene Merge Thread #0]: index: _h5(6.6.6):C162798
MS 0 [2022-01-12T14:02:37.894Z; Lucene Merge Thread #0]: no more merges pending; now return
IndexUpgrader 0 [2022-01-12T14:02:37.892Z; main]: All segments upgraded to version 6.6.6
IndexUpgrader 0 [2022-01-12T14:02:37.894Z; main]: Enforcing commit to rewrite all index metadata...
MS 0 [2022-01-12T14:02:37.894Z; Lucene Merge Thread #0]: updateMergeThreads ioThrottle=true targetMBPerSec=20.0 MB/sec
IW 0 [2022-01-12T14:02:37.895Z; main]: commit: start
IW 0 [2022-01-12T14:02:37.895Z; main]: commit: enter lock
IW 0 [2022-01-12T14:02:37.895Z; main]: commit: now prepare
IW 0 [2022-01-12T14:02:37.896Z; main]: prepareCommit: flush
IW 0 [2022-01-12T14:02:37.896Z; main]: index before flush _h5(6.6.6):C162798
DW 0 [2022-01-12T14:02:37.896Z; main]: startFullFlush
IW 0 [2022-01-12T14:02:37.896Z; main]: apply all deletes during flush
IW 0 [2022-01-12T14:02:37.897Z; main]: now apply all deletes for all segments maxDoc=162798
BD 0 [2022-01-12T14:02:37.897Z; main]: applyDeletes: open segment readers took 0 msec
BD 0 [2022-01-12T14:02:37.897Z; main]: applyDeletes: no segments; skipping
BD 0 [2022-01-12T14:02:37.898Z; main]: prune sis=segments_8z: _h5(6.6.6):C162798 minGen=0 packetCount=0
DW 0 [2022-01-12T14:02:37.898Z; main]: main finishFullFlush success=true
IW 0 [2022-01-12T14:02:37.898Z; main]: startCommit(): start
IW 0 [2022-01-12T14:02:37.898Z; main]: startCommit index=_h5(6.6.6):C162798 changeCount=4
IW 0 [2022-01-12T14:02:37.903Z; main]: startCommit: wrote pending segments file "pending_segments_90"
IW 0 [2022-01-12T14:02:37.942Z; main]: done all syncs: [._h5_Lucene50_0.tim, _h5_Lucene54_0.dvm, _h5.fdx, _h5_Lucene50_0.doc, _h5.nvd, _h5_Lucene50_0.tip, _h5.si, _h5.nvm, _h5.fnm, _h5_Lucene54_0.dvd, _h5.fdt, _h5_Lucene50_0.pos]
IW 0 [2022-01-12T14:02:37.942Z; main]: commit: pendingCommit != null
IW 0 [2022-01-12T14:02:37.944Z; main]: commit: done writing segments file "segments_90"
IFD 0 [2022-01-12T14:02:37.944Z; main]: now checkpoint "_h5(6.6.6):C162798" [1 segments ; isCommit = true]
IFD 0 [2022-01-12T14:02:37.944Z; main]: deleteCommits: now decRef commit "segments_8z"
IFD 0 [2022-01-12T14:02:37.945Z; main]: delete [.segments_8z, _gu.nvd, _gu_Lucene50_0.tim, _gg.nvm, _gg.fnm, _h4.cfe, _gu_Lucene50_0.tip, _gu_Lucene50_0.pos, _gg.si, _gg_Lucene50_0.tip, _gg_Lucene50_0.tim, _h4.si, _gg.nvd, _gg_Lucene50_0.pos, _gu_Lucene50_0.doc, _gu.fdx, _gu_Lucene54_0.dvd, _gu.si, _gu.fdt, _gu_Lucene54_0.dvm, _gg_Lucene54_0.dvd, _gg.fdt, _gg_Lucene50_0.doc, _h4.cfs, _gg_Lucene54_0.dvm, _gg.fdx, _gu.nvm, _gu.fnm]
IFD 0 [2022-01-12T14:02:37.956Z; main]: 12 msec to checkpoint
IFD 0 [2022-01-12T14:02:37.956Z; main]: delete []
IW 0 [2022-01-12T14:02:37.957Z; main]: commit: took 61.1 msec
IW 0 [2022-01-12T14:02:37.957Z; main]: commit: done
IndexUpgrader 0 [2022-01-12T14:02:37.957Z; main]: Committed upgraded metadata to index.
IW 0 [2022-01-12T14:02:37.957Z; main]: now flush at close
IW 0 [2022-01-12T14:02:37.958Z; main]: start flush: applyAllDeletes=true
IW 0 [2022-01-12T14:02:37.958Z; main]: index before flush _h5(6.6.6):C162798
DW 0 [2022-01-12T14:02:37.959Z; main]: startFullFlush
DW 0 [2022-01-12T14:02:37.959Z; main]: main finishFullFlush success=true
IW 0 [2022-01-12T14:02:37.959Z; main]: apply all deletes during flush
IW 0 [2022-01-12T14:02:37.960Z; main]: now apply all deletes for all segments maxDoc=162798
BD 0 [2022-01-12T14:02:37.960Z; main]: applyDeletes: open segment readers took 0 msec
BD 0 [2022-01-12T14:02:37.960Z; main]: applyDeletes: no segments; skipping
BD 0 [2022-01-12T14:02:37.961Z; main]: prune sis=segments_90: _h5(6.6.6):C162798 minGen=0 packetCount=0
MS 0 [2022-01-12T14:02:37.961Z; main]: updateMergeThreads ioThrottle=true targetMBPerSec=10240.0 MB/sec
MS 0 [2022-01-12T14:02:37.961Z; main]: now merge
MS 0 [2022-01-12T14:02:37.963Z; main]: index: _h5(6.6.6):C162798
MS 0 [2022-01-12T14:02:37.964Z; main]: no more merges pending; now return
IW 0 [2022-01-12T14:02:37.964Z; main]: waitForMerges
IW 0 [2022-01-12T14:02:37.964Z; main]: waitForMerges done
IW 0 [2022-01-12T14:02:37.964Z; main]: commit: start
IW 0 [2022-01-12T14:02:37.965Z; main]: commit: enter lock
IW 0 [2022-01-12T14:02:37.965Z; main]: commit: now prepare
IW 0 [2022-01-12T14:02:37.965Z; main]: prepareCommit: flush
IW 0 [2022-01-12T14:02:37.965Z; main]: index before flush _h5(6.6.6):C162798
DW 0 [2022-01-12T14:02:37.966Z; main]: startFullFlush
IW 0 [2022-01-12T14:02:37.966Z; main]: apply all deletes during flush
IW 0 [2022-01-12T14:02:37.966Z; main]: now apply all deletes for all segments maxDoc=162798
BD 0 [2022-01-12T14:02:37.967Z; main]: applyDeletes: open segment readers took 0 msec
BD 0 [2022-01-12T14:02:37.967Z; main]: applyDeletes: no segments; skipping
BD 0 [2022-01-12T14:02:37.967Z; main]: prune sis=segments_90: _h5(6.6.6):C162798 minGen=0 packetCount=0
DW 0 [2022-01-12T14:02:37.967Z; main]: main finishFullFlush success=true
IW 0 [2022-01-12T14:02:37.968Z; main]: startCommit(): start
IW 0 [2022-01-12T14:02:37.968Z; main]: skip startCommit(): no changes pending
IFD 0 [2022-01-12T14:02:37.968Z; main]: delete []
IW 0 [2022-01-12T14:02:37.969Z; main]: commit: pendingCommit == null; skip
IW 0 [2022-01-12T14:02:37.969Z; main]: commit: took 3.8 msec
IW 0 [2022-01-12T14:02:37.969Z; main]: commit: done
IW 0 [2022-01-12T14:02:37.969Z; main]: rollback
IW 0 [2022-01-12T14:02:37.970Z; main]: all running merges have aborted
IW 0 [2022-01-12T14:02:37.970Z; main]: rollback: done finish merges
DW 0 [2022-01-12T14:02:37.970Z; main]: abort
DW 0 [2022-01-12T14:02:37.971Z; main]: done abort success=true
IW 0 [2022-01-12T14:02:37.971Z; main]: rollback: infos=_h5(6.6.6):C162798
IFD 0 [2022-01-12T14:02:37.971Z; main]: now checkpoint "_h5(6.6.6):C162798" [1 segments ; isCommit = false]
IFD 0 [2022-01-12T14:02:37.971Z; main]: delete []
IFD 0 [2022-01-12T14:02:37.972Z; main]: 0 msec to checkpoint
IFD 0 [2022-01-12T14:02:37.972Z; main]: delete []
IFD 0 [2022-01-12T14:02:37.972Z; main]: delete []
C:\workspaces\workspaceecm5\LuceneIndexUpgrade\lb>java -cp lucene-core-7.7.3.jar;lucene-backward-codecs-7.7.3.jar org.apache.lucene.index.IndexUpgrader -delete-prior-commits -verbose "V:\\LuceneMigration\\5"
IFD 0 [2022-01-12T14:03:35.765Z; main]: init: current segments file is "segments_90"; deletionPolicy=org.apache.lucene.index.KeepOnlyLastCommitDeletionPolicy@4ec6a292
IFD 0 [2022-01-12T14:03:35.778Z; main]: init: load commit "segments_90"
IFD 0 [2022-01-12T14:03:35.785Z; main]: now checkpoint "_h5(6.6.6):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=6.6.6, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=3, os.version=10.0, timestamp=1641996155526}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]" [1 segments ; isCommit = false]
IFD 0 [2022-01-12T14:03:35.786Z; main]: 5 msec to checkpoint
IW 0 [2022-01-12T14:03:35.786Z; main]: init: create=false reader=null
IW 0 [2022-01-12T14:03:35.788Z; main]:
dir=MMapDirectory@V:\LuceneMigration\5 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3ac3fd8b
index=_h5(6.6.6):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=6.6.6, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=3, os.version=10.0, timestamp=1641996155526}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
version=7.7.3
analyzer=null
ramBufferSizeMB=16.0
maxBufferedDocs=-1
mergedSegmentWarmer=null
delPolicy=org.apache.lucene.index.KeepOnlyLastCommitDeletionPolicy
commit=null
openMode=CREATE_OR_APPEND
similarity=org.apache.lucene.search.similarities.BM25Similarity
mergeScheduler=ConcurrentMergeScheduler: maxThreadCount=-1, maxMergeCount=-1, ioThrottle=true
codec=Lucene70
infoStream=org.apache.lucene.util.PrintStreamInfoStream
mergePolicy=UpgradeIndexMergePolicy([.TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=5120.0, floorSegmentMB=2.0, forceMergeDeletesPctAllowed=10.0, segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1, deletesPctAllowed=33.0)
indexerThreadPool=org.apache.lucene.index.DocumentsWriterPerThreadPool@5594a1b5
readerPooling=true
perThreadHardLimitMB=1945
useCompoundFile=true
commitOnClose=true
indexSort=null
checkPendingFlushOnUpdate=true
softDeletesField=null
writer=org.apache.lucene.index.IndexWriter@6a5fc7f7
IW 0 [2022-01-12T14:03:35.789Z; main]: MMapDirectory.UNMAP_SUPPORTED=true
IndexUpgrader 0 [2022-01-12T14:03:35.790Z; main]: Upgrading all pre-7.7.3 segments of index directory 'MMapDirectory@V:\LuceneMigration\5 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3ac3fd8b' to version 7.7.3...
IW 0 [2022-01-12T14:03:35.791Z; main]: forceMerge: index now _h5(6.6.6):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=6.6.6, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=3, os.version=10.0, timestamp=1641996155526}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
IW 0 [2022-01-12T14:03:35.791Z; main]: now flush at forceMerge
IW 0 [2022-01-12T14:03:35.792Z; main]: start flush: applyAllDeletes=true
IW 0 [2022-01-12T14:03:35.792Z; main]: index before flush _h5(6.6.6):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=6.6.6, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=3, os.version=10.0, timestamp=1641996155526}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
DW 0 [2022-01-12T14:03:35.793Z; main]: startFullFlush
DW 0 [2022-01-12T14:03:35.795Z; main]: main finishFullFlush success=true
IW 0 [2022-01-12T14:03:35.795Z; main]: now apply all deletes for all segments buffered updates bytesUsed=0 reader pool bytesUsed=0
BD 0 [2022-01-12T14:03:35.796Z; main]: waitApply: no deletes to apply
MP 0 [2022-01-12T14:03:35.797Z; main]: findForcedMerges: segmentsToUpgrade={_h5(6.6.6):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=6.6.6, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=3, os.version=10.0, timestamp=1641996155526}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]=true}
MP 0 [2022-01-12T14:03:35.801Z; main]: findForcedMerges maxSegmentCount=1 infos=_h5(6.6.6):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=6.6.6, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=3, os.version=10.0, timestamp=1641996155526}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}] segmentsToMerge={_h5(6.6.6):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=6.6.6, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=3, os.version=10.0, timestamp=1641996155526}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]=true}
MP 0 [2022-01-12T14:03:35.803Z; main]: already merged
MP 0 [2022-01-12T14:03:35.803Z; main]: findForcedMerges: TieredMergePolicy does not want to merge all old segments, merge remaining ones into new segment: {_h5(6.6.6):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=6.6.6, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=3, os.version=10.0, timestamp=1641996155526}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]=true}
IW 0 [2022-01-12T14:03:35.806Z; main]: add merge to pendingMerges: _h5(6.6.6):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=6.6.6, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=3, os.version=10.0, timestamp=1641996155526}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}] [total 1 pending]
IW 0 [2022-01-12T14:03:35.806Z; main]: registerMerge merging= []
IW 0 [2022-01-12T14:03:35.807Z; main]: registerMerge info=_h5(6.6.6):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=6.6.6, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=3, os.version=10.0, timestamp=1641996155526}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
MS 0 [2022-01-12T14:03:35.808Z; main]: initDynamicDefaults spins=true maxThreadCount=1 maxMergeCount=6
MS 0 [2022-01-12T14:03:35.808Z; main]: now merge
MS 0 [2022-01-12T14:03:35.808Z; main]: index: _h5(6.6.6):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=6.6.6, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=3, os.version=10.0, timestamp=1641996155526}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
MS 0 [2022-01-12T14:03:35.809Z; main]: consider merge _h5(6.6.6):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=6.6.6, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=3, os.version=10.0, timestamp=1641996155526}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
MS 0 [2022-01-12T14:03:35.810Z; main]: launch new thread [Lucene Merge Thread #0]
MS 0 [2022-01-12T14:03:35.811Z; Lucene Merge Thread #0]: merge thread: start
BD 0 [2022-01-12T14:03:35.811Z; Lucene Merge Thread #0]: waitApplyForMerge: 0 packets, 1 merging segments
BD 0 [2022-01-12T14:03:35.812Z; Lucene Merge Thread #0]: waitApply: no deletes to apply
IW 0 [2022-01-12T14:03:35.813Z; Lucene Merge Thread #0]: now apply deletes for 1 merging segments
IW 0 [2022-01-12T14:03:35.816Z; Lucene Merge Thread #0]: merge seg=_h6 _h5(6.6.6):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=6.6.6, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=3, os.version=10.0, timestamp=1641996155526}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
IW 0 [2022-01-12T14:03:35.816Z; Lucene Merge Thread #0]: now merge
merge=_h5(6.6.6):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=6.6.6, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=3, os.version=10.0, timestamp=1641996155526}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
index=_h5(6.6.6):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=6.6.6, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=3, os.version=10.0, timestamp=1641996155526}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
IW 0 [2022-01-12T14:03:35.818Z; Lucene Merge Thread #0]: merging _h5(6.6.6):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=6.6.6, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=3, os.version=10.0, timestamp=1641996155526}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
MS 0 [2022-01-12T14:03:35.820Z; main]: updateMergeThreads ioThrottle=true targetMBPerSec=20.0 MB/sec
merge thread Lucene Merge Thread #0 estSize=28.8 MB (written=0.0 MB) runTime=0.0s (stopped=0.0s, paused=0.0s) rate=unlimited
leave running at Infinity MB/sec
MS 0 [2022-01-12T14:03:35.820Z; main]: no more merges pending; now return
IW 0 [2022-01-12T14:03:35.907Z; Lucene Merge Thread #0]: seg=_h5(6.6.6):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=6.6.6, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=3, os.version=10.0, timestamp=1641996155526}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}] reader=_h5(6.6.6):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=6.6.6, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=3, os.version=10.0, timestamp=1641996155526}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
SM 0 [2022-01-12T14:03:35.917Z; Lucene Merge Thread #0]: merge store matchedCount=1 vs 1
SM 0 [2022-01-12T14:03:35.942Z; Lucene Merge Thread #0]: 28 msec to merge stored fields [162798 docs]
SM 0 [2022-01-12T14:03:37.243Z; Lucene Merge Thread #0]: 1301 msec to merge postings [162798 docs]
SM 0 [2022-01-12T14:03:37.347Z; Lucene Merge Thread #0]: 103 msec to merge doc values [162798 docs]
SM 0 [2022-01-12T14:03:37.347Z; Lucene Merge Thread #0]: 0 msec to merge points [162798 docs]
SM 0 [2022-01-12T14:03:38.100Z; Lucene Merge Thread #0]: 751 msec to merge norms [162798 docs]
SM 0 [2022-01-12T14:03:38.103Z; Lucene Merge Thread #0]: 2 msec to write field infos [162798 docs]
IW 0 [2022-01-12T14:03:38.105Z; Lucene Merge Thread #0]: merge codec=Lucene70 maxDoc=162798; merged segment has no vectors; norms; docValues; prox; freqs; no points; 2.2 sec to merge segment [28.66 MB, 13.06 MB/sec]
IW 0 [2022-01-12T14:03:38.107Z; Lucene Merge Thread #0]: merged segment size=28.657 MB vs estimate=28.782 MB
IW 0 [2022-01-12T14:03:38.107Z; Lucene Merge Thread #0]: commitMerge: _h5(6.6.6):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=6.6.6, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=3, os.version=10.0, timestamp=1641996155526}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}] index=_h5(6.6.6):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=6.6.6, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=3, os.version=10.0, timestamp=1641996155526}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
IW 0 [2022-01-12T14:03:38.109Z; Lucene Merge Thread #0]: commitMergeDeletes _h5(6.6.6):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=6.6.6, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=3, os.version=10.0, timestamp=1641996155526}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
IW 0 [2022-01-12T14:03:38.110Z; Lucene Merge Thread #0]: 0 new deletes since merge started
IFD 0 [2022-01-12T14:03:38.120Z; Lucene Merge Thread #0]: now checkpoint "_h6(7.7.3):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=7.7.3, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=1, os.version=10.0, timestamp=1641996215816}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]" [1 segments ; isCommit = false]
IFD 0 [2022-01-12T14:03:38.120Z; Lucene Merge Thread #0]: 0 msec to checkpoint
IW 0 [2022-01-12T14:03:38.121Z; Lucene Merge Thread #0]: after commitMerge: _h6(7.7.3):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=7.7.3, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=1, os.version=10.0, timestamp=1641996215816}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
MP 0 [2022-01-12T14:03:38.122Z; Lucene Merge Thread #0]: findForcedMerges: segmentsToUpgrade={}
IW 0 [2022-01-12T14:03:38.122Z; Lucene Merge Thread #0]: merge time 2311 msec for 162798 docs
MS 0 [2022-01-12T14:03:38.123Z; Lucene Merge Thread #0]: merge thread: done
MS 0 [2022-01-12T14:03:38.123Z; Lucene Merge Thread #0]: now merge
IndexUpgrader 0 [2022-01-12T14:03:38.122Z; main]: All segments upgraded to version 7.7.3
MS 0 [2022-01-12T14:03:38.124Z; Lucene Merge Thread #0]: index: _h6(7.7.3):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=7.7.3, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=1, os.version=10.0, timestamp=1641996215816}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
IndexUpgrader 0 [2022-01-12T14:03:38.124Z; main]: Enforcing commit to rewrite all index metadata...
MS 0 [2022-01-12T14:03:38.125Z; Lucene Merge Thread #0]: no more merges pending; now return
IW 0 [2022-01-12T14:03:38.126Z; main]: commit: start
MS 0 [2022-01-12T14:03:38.126Z; Lucene Merge Thread #0]: updateMergeThreads ioThrottle=true targetMBPerSec=20.0 MB/sec
IW 0 [2022-01-12T14:03:38.126Z; main]: commit: enter lock
IW 0 [2022-01-12T14:03:38.127Z; main]: commit: now prepare
IW 0 [2022-01-12T14:03:38.128Z; main]: prepareCommit: flush
IW 0 [2022-01-12T14:03:38.128Z; main]: index before flush _h6(7.7.3):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=7.7.3, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=1, os.version=10.0, timestamp=1641996215816}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
DW 0 [2022-01-12T14:03:38.130Z; main]: startFullFlush
IW 0 [2022-01-12T14:03:38.131Z; main]: now apply all deletes for all segments buffered updates bytesUsed=0 reader pool bytesUsed=0
BD 0 [2022-01-12T14:03:38.132Z; main]: waitApply: no deletes to apply
DW 0 [2022-01-12T14:03:38.133Z; main]: main finishFullFlush success=true
IW 0 [2022-01-12T14:03:38.133Z; main]: startCommit(): start
IW 0 [2022-01-12T14:03:38.134Z; main]: startCommit index=_h6(7.7.3):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=7.7.3, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=1, os.version=10.0, timestamp=1641996215816}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}] changeCount=4
IW 0 [2022-01-12T14:03:38.140Z; main]: startCommit: wrote pending segments file "pending_segments_91"
IW 0 [2022-01-12T14:03:38.177Z; main]: done all syncs: [._h6_Lucene50_0.tip, _h6.fdt, _h6.nvm, _h6.fnm, _h6_Lucene70_0.dvm, _h6_Lucene50_0.doc, _h6_Lucene50_0.tim, _h6.si, _h6_Lucene50_0.pos, _h6.nvd, _h6_Lucene70_0.dvd, _h6.fdx]
IW 0 [2022-01-12T14:03:38.179Z; main]: commit: pendingCommit != null
IW 0 [2022-01-12T14:03:38.180Z; main]: commit: done writing segments file "segments_91"
IFD 0 [2022-01-12T14:03:38.180Z; main]: now checkpoint "_h6(7.7.3):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=7.7.3, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=1, os.version=10.0, timestamp=1641996215816}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]" [1 segments ; isCommit = true]
IFD 0 [2022-01-12T14:03:38.181Z; main]: deleteCommits: now decRef commit "segments_90"
IFD 0 [2022-01-12T14:03:38.182Z; main]: delete [._h5_Lucene54_0.dvm, _h5.nvm, _h5.fnm, _h5_Lucene50_0.tim, _h5.fdx, _h5_Lucene50_0.doc, _h5.nvd, _h5_Lucene50_0.tip, _h5.si, segments_90, _h5_Lucene54_0.dvd, _h5.fdt, _h5_Lucene50_0.pos]
IFD 0 [2022-01-12T14:03:38.189Z; main]: 7 msec to checkpoint
IW 0 [2022-01-12T14:03:38.189Z; main]: commit: took 60.5 msec
IW 0 [2022-01-12T14:03:38.189Z; main]: commit: done
IndexUpgrader 0 [2022-01-12T14:03:38.190Z; main]: Committed upgraded metadata to index.
IW 0 [2022-01-12T14:03:38.190Z; main]: now flush at close
IW 0 [2022-01-12T14:03:38.191Z; main]: start flush: applyAllDeletes=true
IW 0 [2022-01-12T14:03:38.191Z; main]: index before flush _h6(7.7.3):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=7.7.3, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=1, os.version=10.0, timestamp=1641996215816}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
DW 0 [2022-01-12T14:03:38.192Z; main]: startFullFlush
DW 0 [2022-01-12T14:03:38.193Z; main]: main finishFullFlush success=true
IW 0 [2022-01-12T14:03:38.195Z; main]: now apply all deletes for all segments buffered updates bytesUsed=0 reader pool bytesUsed=0
BD 0 [2022-01-12T14:03:38.195Z; main]: waitApply: no deletes to apply
MS 0 [2022-01-12T14:03:38.195Z; main]: updateMergeThreads ioThrottle=true targetMBPerSec=10240.0 MB/sec
MS 0 [2022-01-12T14:03:38.196Z; main]: now merge
MS 0 [2022-01-12T14:03:38.196Z; main]: index: _h6(7.7.3):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=7.7.3, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=1, os.version=10.0, timestamp=1641996215816}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
MS 0 [2022-01-12T14:03:38.196Z; main]: no more merges pending; now return
IW 0 [2022-01-12T14:03:38.197Z; main]: waitForMerges
IW 0 [2022-01-12T14:03:38.197Z; main]: waitForMerges done
IW 0 [2022-01-12T14:03:38.198Z; main]: commit: start
IW 0 [2022-01-12T14:03:38.198Z; main]: commit: enter lock
IW 0 [2022-01-12T14:03:38.199Z; main]: commit: now prepare
IW 0 [2022-01-12T14:03:38.199Z; main]: prepareCommit: flush
IW 0 [2022-01-12T14:03:38.199Z; main]: index before flush _h6(7.7.3):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=7.7.3, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=1, os.version=10.0, timestamp=1641996215816}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
DW 0 [2022-01-12T14:03:38.200Z; main]: startFullFlush
IW 0 [2022-01-12T14:03:38.200Z; main]: now apply all deletes for all segments buffered updates bytesUsed=0 reader pool bytesUsed=0
BD 0 [2022-01-12T14:03:38.200Z; main]: waitApply: no deletes to apply
DW 0 [2022-01-12T14:03:38.201Z; main]: main finishFullFlush success=true
IW 0 [2022-01-12T14:03:38.201Z; main]: startCommit(): start
IW 0 [2022-01-12T14:03:38.201Z; main]: skip startCommit(): no changes pending
IW 0 [2022-01-12T14:03:38.201Z; main]: commit: pendingCommit == null; skip
IW 0 [2022-01-12T14:03:38.202Z; main]: commit: took 2.9 msec
IW 0 [2022-01-12T14:03:38.202Z; main]: commit: done
IW 0 [2022-01-12T14:03:38.202Z; main]: rollback
IW 0 [2022-01-12T14:03:38.203Z; main]: all running merges have aborted
IW 0 [2022-01-12T14:03:38.203Z; main]: rollback: done finish merges
DW 0 [2022-01-12T14:03:38.203Z; main]: abort
DW 0 [2022-01-12T14:03:38.203Z; main]: done abort success=true
IW 0 [2022-01-12T14:03:38.204Z; main]: rollback: infos=_h6(7.7.3):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=7.7.3, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=1, os.version=10.0, timestamp=1641996215816}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
IFD 0 [2022-01-12T14:03:38.204Z; main]: now checkpoint "_h6(7.7.3):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=7.7.3, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=1, os.version=10.0, timestamp=1641996215816}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]" [1 segments ; isCommit = false]
IFD 0 [2022-01-12T14:03:38.205Z; main]: 0 msec to checkpoint
C:\workspaces\workspaceecm5\LuceneIndexUpgrade\lb>java -cp lucene-core-8.11.1.jar;lucene-backward-codecs-8.11.1.jar org.apache.lucene.index.IndexUpgrader -delete-prior-commits -verbose "V:\\LuceneMigration\\5"
MS 0 [2022-01-12T14:04:24.248Z; main]: initDynamicDefaults spins=true maxThreadCount=1 maxMergeCount=6
IW 0 [2022-01-12T14:04:24.275Z; main]: init: hit exception on init; releasing write lock
Exception in thread "main" org.apache.lucene.index.IndexFormatTooOldException: Format version is not supported (resource BufferedChecksumIndexInput(MMapIndexInput(path="V:\LuceneMigration\5\segments_91"))): This index was initially created with Lucene 6.x while the current version is 8.11.1 and Lucene only supports reading the current and previous major versions.. This version of Lucene only supports indexes created with release 7.0 and later.
at org.apache.lucene.index.SegmentInfos.readCommit(SegmentInfos.java:322)
at org.apache.lucene.index.SegmentInfos.readCommit(SegmentInfos.java:291)
at org.apache.lucene.index.IndexWriter.<init>(IndexWriter.java:1037)
at org.apache.lucene.index.IndexUpgrader.upgrade(IndexUpgrader.java:167)
at org.apache.lucene.index.IndexUpgrader.main(IndexUpgrader.java:78)
Suppressed: org.apache.lucene.index.CorruptIndexException: checksum passed (7268b2f2). possibly transient resource issue, or a Lucene or JVM bug (resource=BufferedChecksumIndexInput(MMapIndexInput(path="V:\LuceneMigration\5\segments_91")))
at org.apache.lucene.codecs.CodecUtil.checkFooter(CodecUtil.java:466)
at org.apache.lucene.index.SegmentInfos.readCommit(SegmentInfos.java:434)
... 4 more
IFD 0 [2022-01-12T14:02:35.479Z; main]: init: current segments file is "segments_8z"; deletionPolicy=org.apache.lucene.index.KeepOnlyLastCommitDeletionPolicy@6833ce2c
IFD 0 [2022-01-12T14:02:35.491Z; main]: init: load commit "segments_8z"
IFD 0 [2022-01-12T14:02:35.494Z; main]: delete []
IFD 0 [2022-01-12T14:02:35.495Z; main]: now checkpoint "_gg(5.5.5):C98089 _gu(5.5.5):C52875 _h4(5.5.5):c11834" [3 segments ; isCommit = false]
IFD 0 [2022-01-12T14:02:35.495Z; main]: delete []
IFD 0 [2022-01-12T14:02:35.496Z; main]: 1 msec to checkpoint
IW 0 [2022-01-12T14:02:35.496Z; main]: init: create=false
IW 0 [2022-01-12T14:02:35.498Z; main]:
dir=MMapDirectory@V:\LuceneMigration\5 lockFactory=org.apache.lucene.store.NativeFSLockFactory@7d417077
index=_gg(5.5.5):C98089 _gu(5.5.5):C52875 _h4(5.5.5):c11834
version=6.6.6
analyzer=null
ramBufferSizeMB=16.0
maxBufferedDocs=-1
maxBufferedDeleteTerms=-1
mergedSegmentWarmer=null
delPolicy=org.apache.lucene.index.KeepOnlyLastCommitDeletionPolicy
commit=null
openMode=CREATE_OR_APPEND
similarity=org.apache.lucene.search.similarities.BM25Similarity
mergeScheduler=ConcurrentMergeScheduler: maxThreadCount=-1, maxMergeCount=-1, ioThrottle=true
codec=Lucene62
infoStream=org.apache.lucene.util.PrintStreamInfoStream
mergePolicy=UpgradeIndexMergePolicy([.TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=5120.0, floorSegmentMB=2.0, forceMergeDeletesPctAllowed=10.0, segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1)
indexerThreadPool=org.apache.lucene.index.DocumentsWriterPerThreadPool@7dc36524
readerPooling=false
perThreadHardLimitMB=1945
useCompoundFile=true
commitOnClose=true
indexSort=null
writer=org.apache.lucene.index.IndexWriter@35bbe5e8
IW 0 [2022-01-12T14:02:35.499Z; main]: MMapDirectory.UNMAP_SUPPORTED=true
IndexUpgrader 0 [2022-01-12T14:02:35.499Z; main]: Upgrading all pre-6.6.6 segments of index directory 'MMapDirectory@V:\LuceneMigration\5 lockFactory=org.apache.lucene.store.NativeFSLockFactory@7d417077' to version 6.6.6...
IW 0 [2022-01-12T14:02:35.500Z; main]: forceMerge: index now _gg(5.5.5):C98089 _gu(5.5.5):C52875 _h4(5.5.5):c11834
IW 0 [2022-01-12T14:02:35.501Z; main]: now flush at forceMerge
IW 0 [2022-01-12T14:02:35.501Z; main]: start flush: applyAllDeletes=true
IW 0 [2022-01-12T14:02:35.502Z; main]: index before flush _gg(5.5.5):C98089 _gu(5.5.5):C52875 _h4(5.5.5):c11834
DW 0 [2022-01-12T14:02:35.502Z; main]: startFullFlush
DW 0 [2022-01-12T14:02:35.503Z; main]: main finishFullFlush success=true
IW 0 [2022-01-12T14:02:35.503Z; main]: apply all deletes during flush
IW 0 [2022-01-12T14:02:35.503Z; main]: now apply all deletes for all segments maxDoc=162798
BD 0 [2022-01-12T14:02:35.509Z; main]: applyDeletes: open segment readers took 0 msec
BD 0 [2022-01-12T14:02:35.510Z; main]: applyDeletes: no segments; skipping
BD 0 [2022-01-12T14:02:35.510Z; main]: prune sis=segments_8z: _gg(5.5.5):C98089 _gu(5.5.5):C52875 _h4(5.5.5):c11834 minGen=0 packetCount=0
UPGMP 0 [2022-01-12T14:02:35.511Z; main]: findForcedMerges: segmentsToUpgrade={_gg(5.5.5):C98089=true, _gu(5.5.5):C52875=true, _h4(5.5.5):c11834=true}
TMP 0 [2022-01-12T14:02:35.511Z; main]: findForcedMerges maxSegmentCount=1 infos=_gg(5.5.5):C98089 _gu(5.5.5):C52875 _h4(5.5.5):c11834 segmentsToMerge={_gg(5.5.5):C98089=true, _gu(5.5.5):C52875=true, _h4(5.5.5):c11834=true}
TMP 0 [2022-01-12T14:02:35.514Z; main]: eligible=[_gg(5.5.5):C98089, _gu(5.5.5):C52875, _h4(5.5.5):c11834]
TMP 0 [2022-01-12T14:02:35.514Z; main]: forceMergeRunning=false
TMP 0 [2022-01-12T14:02:35.516Z; main]: add final merge=_gg(5.5.5):C98089 _gu(5.5.5):C52875 _h4(5.5.5):c11834
IW 0 [2022-01-12T14:02:35.517Z; main]: add merge to pendingMerges: _gg(5.5.5):C98089 _gu(5.5.5):C52875 _h4(5.5.5):c11834 [total 1 pending]
IW 0 [2022-01-12T14:02:35.517Z; main]: registerMerge merging= []
IW 0 [2022-01-12T14:02:35.518Z; main]: registerMerge info=_gg(5.5.5):C98089
IW 0 [2022-01-12T14:02:35.518Z; main]: registerMerge info=_gu(5.5.5):C52875
IW 0 [2022-01-12T14:02:35.519Z; main]: registerMerge info=_h4(5.5.5):c11834
MS 0 [2022-01-12T14:02:35.520Z; main]: initDynamicDefaults spins=true maxThreadCount=1 maxMergeCount=6
MS 0 [2022-01-12T14:02:35.522Z; main]: now merge
MS 0 [2022-01-12T14:02:35.522Z; main]: index: _gg(5.5.5):C98089 _gu(5.5.5):C52875 _h4(5.5.5):c11834
MS 0 [2022-01-12T14:02:35.522Z; main]: consider merge _gg(5.5.5):C98089 _gu(5.5.5):C52875 _h4(5.5.5):c11834
MS 0 [2022-01-12T14:02:35.523Z; main]: launch new thread [Lucene Merge Thread #0]
MS 0 [2022-01-12T14:02:35.524Z; Lucene Merge Thread #0]: merge thread: start
IW 0 [2022-01-12T14:02:35.524Z; Lucene Merge Thread #0]: now apply deletes for 3 merging segments
BD 0 [2022-01-12T14:02:35.524Z; Lucene Merge Thread #0]: applyDeletes: open segment readers took 0 msec
BD 0 [2022-01-12T14:02:35.524Z; Lucene Merge Thread #0]: applyDeletes: no segments; skipping
BD 0 [2022-01-12T14:02:35.526Z; Lucene Merge Thread #0]: prune sis=segments_8z: _gg(5.5.5):C98089 _gu(5.5.5):C52875 _h4(5.5.5):c11834 minGen=0 packetCount=0
IW 0 [2022-01-12T14:02:35.527Z; Lucene Merge Thread #0]: merge seg=_h5 _gg(5.5.5):C98089 _gu(5.5.5):C52875 _h4(5.5.5):c11834
IW 0 [2022-01-12T14:02:35.527Z; Lucene Merge Thread #0]: now merge
merge=_gg(5.5.5):C98089 _gu(5.5.5):C52875 _h4(5.5.5):c11834
index=_gg(5.5.5):C98089 _gu(5.5.5):C52875 _h4(5.5.5):c11834
IW 0 [2022-01-12T14:02:35.529Z; Lucene Merge Thread #0]: merging _gg(5.5.5):C98089 _gu(5.5.5):C52875 _h4(5.5.5):c11834
MS 0 [2022-01-12T14:02:35.530Z; main]: updateMergeThreads ioThrottle=true targetMBPerSec=20.0 MB/sec
merge thread Lucene Merge Thread #0 estSize=27.5 MB (written=0.0 MB) runTime=0.0s (stopped=0.0s, paused=0.0s) rate=unlimited
leave running at Infinity MB/sec
IW 0 [2022-01-12T14:02:35.609Z; Lucene Merge Thread #0]: seg=_gg(5.5.5):C98089 no deletes
IW 0 [2022-01-12T14:02:35.654Z; Lucene Merge Thread #0]: seg=_gu(5.5.5):C52875 no deletes
IW 0 [2022-01-12T14:02:35.658Z; Lucene Merge Thread #0]: seg=_h4(5.5.5):c11834 no deletes
MS 0 [2022-01-12T14:02:35.658Z; main]: no more merges pending; now return
SM 0 [2022-01-12T14:02:35.668Z; Lucene Merge Thread #0]: merge store matchedCount=3 vs 3
SM 0 [2022-01-12T14:02:35.734Z; Lucene Merge Thread #0]: 70 msec to merge stored fields [162798 docs]
SM 0 [2022-01-12T14:02:37.129Z; Lucene Merge Thread #0]: 1394 msec to merge postings [162798 docs]
SM 0 [2022-01-12T14:02:37.286Z; Lucene Merge Thread #0]: 156 msec to merge doc values [162798 docs]
SM 0 [2022-01-12T14:02:37.287Z; Lucene Merge Thread #0]: 0 msec to merge points [162798 docs]
SM 0 [2022-01-12T14:02:37.874Z; Lucene Merge Thread #0]: 585 msec to merge norms [162798 docs]
SM 0 [2022-01-12T14:02:37.877Z; Lucene Merge Thread #0]: 3 msec to write field infos [162798 docs]
IW 0 [2022-01-12T14:02:37.881Z; Lucene Merge Thread #0]: merge codec=Lucene62 maxDoc=162798; merged segment has no vectors; norms; docValues; prox; freqs; no points; 2.2 sec to merge segment [28.78 MB, 12.97 MB/sec]
IW 0 [2022-01-12T14:02:37.883Z; Lucene Merge Thread #0]: merged segment size=28.782 MB vs estimate=27.451 MB
IW 0 [2022-01-12T14:02:37.883Z; Lucene Merge Thread #0]: commitMerge: _gg(5.5.5):C98089 _gu(5.5.5):C52875 _h4(5.5.5):c11834 index=_gg(5.5.5):C98089 _gu(5.5.5):C52875 _h4(5.5.5):c11834
IW 0 [2022-01-12T14:02:37.885Z; Lucene Merge Thread #0]: commitMergeDeletes _gg(5.5.5):C98089 _gu(5.5.5):C52875 _h4(5.5.5):c11834
IW 0 [2022-01-12T14:02:37.887Z; Lucene Merge Thread #0]: no new deletes or field updates since merge started
IFD 0 [2022-01-12T14:02:37.891Z; Lucene Merge Thread #0]: now checkpoint "_h5(6.6.6):C162798" [1 segments ; isCommit = false]
IFD 0 [2022-01-12T14:02:37.891Z; Lucene Merge Thread #0]: delete []
IFD 0 [2022-01-12T14:02:37.891Z; Lucene Merge Thread #0]: 0 msec to checkpoint
IW 0 [2022-01-12T14:02:37.891Z; Lucene Merge Thread #0]: after commitMerge: _h5(6.6.6):C162798
UPGMP 0 [2022-01-12T14:02:37.892Z; Lucene Merge Thread #0]: findForcedMerges: segmentsToUpgrade={}
IW 0 [2022-01-12T14:02:37.892Z; Lucene Merge Thread #0]: merge time 2368 msec for 162798 docs
MS 0 [2022-01-12T14:02:37.893Z; Lucene Merge Thread #0]: merge thread: done
MS 0 [2022-01-12T14:02:37.893Z; Lucene Merge Thread #0]: now merge
MS 0 [2022-01-12T14:02:37.893Z; Lucene Merge Thread #0]: index: _h5(6.6.6):C162798
MS 0 [2022-01-12T14:02:37.894Z; Lucene Merge Thread #0]: no more merges pending; now return
IndexUpgrader 0 [2022-01-12T14:02:37.892Z; main]: All segments upgraded to version 6.6.6
IndexUpgrader 0 [2022-01-12T14:02:37.894Z; main]: Enforcing commit to rewrite all index metadata...
MS 0 [2022-01-12T14:02:37.894Z; Lucene Merge Thread #0]: updateMergeThreads ioThrottle=true targetMBPerSec=20.0 MB/sec
IW 0 [2022-01-12T14:02:37.895Z; main]: commit: start
IW 0 [2022-01-12T14:02:37.895Z; main]: commit: enter lock
IW 0 [2022-01-12T14:02:37.895Z; main]: commit: now prepare
IW 0 [2022-01-12T14:02:37.896Z; main]: prepareCommit: flush
IW 0 [2022-01-12T14:02:37.896Z; main]: index before flush _h5(6.6.6):C162798
DW 0 [2022-01-12T14:02:37.896Z; main]: startFullFlush
IW 0 [2022-01-12T14:02:37.896Z; main]: apply all deletes during flush
IW 0 [2022-01-12T14:02:37.897Z; main]: now apply all deletes for all segments maxDoc=162798
BD 0 [2022-01-12T14:02:37.897Z; main]: applyDeletes: open segment readers took 0 msec
BD 0 [2022-01-12T14:02:37.897Z; main]: applyDeletes: no segments; skipping
BD 0 [2022-01-12T14:02:37.898Z; main]: prune sis=segments_8z: _h5(6.6.6):C162798 minGen=0 packetCount=0
DW 0 [2022-01-12T14:02:37.898Z; main]: main finishFullFlush success=true
IW 0 [2022-01-12T14:02:37.898Z; main]: startCommit(): start
IW 0 [2022-01-12T14:02:37.898Z; main]: startCommit index=_h5(6.6.6):C162798 changeCount=4
IW 0 [2022-01-12T14:02:37.903Z; main]: startCommit: wrote pending segments file "pending_segments_90"
IW 0 [2022-01-12T14:02:37.942Z; main]: done all syncs: [._h5_Lucene50_0.tim, _h5_Lucene54_0.dvm, _h5.fdx, _h5_Lucene50_0.doc, _h5.nvd, _h5_Lucene50_0.tip, _h5.si, _h5.nvm, _h5.fnm, _h5_Lucene54_0.dvd, _h5.fdt, _h5_Lucene50_0.pos]
IW 0 [2022-01-12T14:02:37.942Z; main]: commit: pendingCommit != null
IW 0 [2022-01-12T14:02:37.944Z; main]: commit: done writing segments file "segments_90"
IFD 0 [2022-01-12T14:02:37.944Z; main]: now checkpoint "_h5(6.6.6):C162798" [1 segments ; isCommit = true]
IFD 0 [2022-01-12T14:02:37.944Z; main]: deleteCommits: now decRef commit "segments_8z"
IFD 0 [2022-01-12T14:02:37.945Z; main]: delete [.segments_8z, _gu.nvd, _gu_Lucene50_0.tim, _gg.nvm, _gg.fnm, _h4.cfe, _gu_Lucene50_0.tip, _gu_Lucene50_0.pos, _gg.si, _gg_Lucene50_0.tip, _gg_Lucene50_0.tim, _h4.si, _gg.nvd, _gg_Lucene50_0.pos, _gu_Lucene50_0.doc, _gu.fdx, _gu_Lucene54_0.dvd, _gu.si, _gu.fdt, _gu_Lucene54_0.dvm, _gg_Lucene54_0.dvd, _gg.fdt, _gg_Lucene50_0.doc, _h4.cfs, _gg_Lucene54_0.dvm, _gg.fdx, _gu.nvm, _gu.fnm]
IFD 0 [2022-01-12T14:02:37.956Z; main]: 12 msec to checkpoint
IFD 0 [2022-01-12T14:02:37.956Z; main]: delete []
IW 0 [2022-01-12T14:02:37.957Z; main]: commit: took 61.1 msec
IW 0 [2022-01-12T14:02:37.957Z; main]: commit: done
IndexUpgrader 0 [2022-01-12T14:02:37.957Z; main]: Committed upgraded metadata to index.
IW 0 [2022-01-12T14:02:37.957Z; main]: now flush at close
IW 0 [2022-01-12T14:02:37.958Z; main]: start flush: applyAllDeletes=true
IW 0 [2022-01-12T14:02:37.958Z; main]: index before flush _h5(6.6.6):C162798
DW 0 [2022-01-12T14:02:37.959Z; main]: startFullFlush
DW 0 [2022-01-12T14:02:37.959Z; main]: main finishFullFlush success=true
IW 0 [2022-01-12T14:02:37.959Z; main]: apply all deletes during flush
IW 0 [2022-01-12T14:02:37.960Z; main]: now apply all deletes for all segments maxDoc=162798
BD 0 [2022-01-12T14:02:37.960Z; main]: applyDeletes: open segment readers took 0 msec
BD 0 [2022-01-12T14:02:37.960Z; main]: applyDeletes: no segments; skipping
BD 0 [2022-01-12T14:02:37.961Z; main]: prune sis=segments_90: _h5(6.6.6):C162798 minGen=0 packetCount=0
MS 0 [2022-01-12T14:02:37.961Z; main]: updateMergeThreads ioThrottle=true targetMBPerSec=10240.0 MB/sec
MS 0 [2022-01-12T14:02:37.961Z; main]: now merge
MS 0 [2022-01-12T14:02:37.963Z; main]: index: _h5(6.6.6):C162798
MS 0 [2022-01-12T14:02:37.964Z; main]: no more merges pending; now return
IW 0 [2022-01-12T14:02:37.964Z; main]: waitForMerges
IW 0 [2022-01-12T14:02:37.964Z; main]: waitForMerges done
IW 0 [2022-01-12T14:02:37.964Z; main]: commit: start
IW 0 [2022-01-12T14:02:37.965Z; main]: commit: enter lock
IW 0 [2022-01-12T14:02:37.965Z; main]: commit: now prepare
IW 0 [2022-01-12T14:02:37.965Z; main]: prepareCommit: flush
IW 0 [2022-01-12T14:02:37.965Z; main]: index before flush _h5(6.6.6):C162798
DW 0 [2022-01-12T14:02:37.966Z; main]: startFullFlush
IW 0 [2022-01-12T14:02:37.966Z; main]: apply all deletes during flush
IW 0 [2022-01-12T14:02:37.966Z; main]: now apply all deletes for all segments maxDoc=162798
BD 0 [2022-01-12T14:02:37.967Z; main]: applyDeletes: open segment readers took 0 msec
BD 0 [2022-01-12T14:02:37.967Z; main]: applyDeletes: no segments; skipping
BD 0 [2022-01-12T14:02:37.967Z; main]: prune sis=segments_90: _h5(6.6.6):C162798 minGen=0 packetCount=0
DW 0 [2022-01-12T14:02:37.967Z; main]: main finishFullFlush success=true
IW 0 [2022-01-12T14:02:37.968Z; main]: startCommit(): start
IW 0 [2022-01-12T14:02:37.968Z; main]: skip startCommit(): no changes pending
IFD 0 [2022-01-12T14:02:37.968Z; main]: delete []
IW 0 [2022-01-12T14:02:37.969Z; main]: commit: pendingCommit == null; skip
IW 0 [2022-01-12T14:02:37.969Z; main]: commit: took 3.8 msec
IW 0 [2022-01-12T14:02:37.969Z; main]: commit: done
IW 0 [2022-01-12T14:02:37.969Z; main]: rollback
IW 0 [2022-01-12T14:02:37.970Z; main]: all running merges have aborted
IW 0 [2022-01-12T14:02:37.970Z; main]: rollback: done finish merges
DW 0 [2022-01-12T14:02:37.970Z; main]: abort
DW 0 [2022-01-12T14:02:37.971Z; main]: done abort success=true
IW 0 [2022-01-12T14:02:37.971Z; main]: rollback: infos=_h5(6.6.6):C162798
IFD 0 [2022-01-12T14:02:37.971Z; main]: now checkpoint "_h5(6.6.6):C162798" [1 segments ; isCommit = false]
IFD 0 [2022-01-12T14:02:37.971Z; main]: delete []
IFD 0 [2022-01-12T14:02:37.972Z; main]: 0 msec to checkpoint
IFD 0 [2022-01-12T14:02:37.972Z; main]: delete []
IFD 0 [2022-01-12T14:02:37.972Z; main]: delete []
C:\workspaces\workspaceecm5\LuceneIndexUpgrade\lb>java -cp lucene-core-7.7.3.jar;lucene-backward-codecs-7.7.3.jar org.apache.lucene.index.IndexUpgrader -delete-prior-commits -verbose "V:\\LuceneMigration\\5"
IFD 0 [2022-01-12T14:03:35.765Z; main]: init: current segments file is "segments_90"; deletionPolicy=org.apache.lucene.index.KeepOnlyLastCommitDeletionPolicy@4ec6a292
IFD 0 [2022-01-12T14:03:35.778Z; main]: init: load commit "segments_90"
IFD 0 [2022-01-12T14:03:35.785Z; main]: now checkpoint "_h5(6.6.6):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=6.6.6, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=3, os.version=10.0, timestamp=1641996155526}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]" [1 segments ; isCommit = false]
IFD 0 [2022-01-12T14:03:35.786Z; main]: 5 msec to checkpoint
IW 0 [2022-01-12T14:03:35.786Z; main]: init: create=false reader=null
IW 0 [2022-01-12T14:03:35.788Z; main]:
dir=MMapDirectory@V:\LuceneMigration\5 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3ac3fd8b
index=_h5(6.6.6):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=6.6.6, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=3, os.version=10.0, timestamp=1641996155526}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
version=7.7.3
analyzer=null
ramBufferSizeMB=16.0
maxBufferedDocs=-1
mergedSegmentWarmer=null
delPolicy=org.apache.lucene.index.KeepOnlyLastCommitDeletionPolicy
commit=null
openMode=CREATE_OR_APPEND
similarity=org.apache.lucene.search.similarities.BM25Similarity
mergeScheduler=ConcurrentMergeScheduler: maxThreadCount=-1, maxMergeCount=-1, ioThrottle=true
codec=Lucene70
infoStream=org.apache.lucene.util.PrintStreamInfoStream
mergePolicy=UpgradeIndexMergePolicy([.TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=5120.0, floorSegmentMB=2.0, forceMergeDeletesPctAllowed=10.0, segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1, deletesPctAllowed=33.0)
indexerThreadPool=org.apache.lucene.index.DocumentsWriterPerThreadPool@5594a1b5
readerPooling=true
perThreadHardLimitMB=1945
useCompoundFile=true
commitOnClose=true
indexSort=null
checkPendingFlushOnUpdate=true
softDeletesField=null
writer=org.apache.lucene.index.IndexWriter@6a5fc7f7
IW 0 [2022-01-12T14:03:35.789Z; main]: MMapDirectory.UNMAP_SUPPORTED=true
IndexUpgrader 0 [2022-01-12T14:03:35.790Z; main]: Upgrading all pre-7.7.3 segments of index directory 'MMapDirectory@V:\LuceneMigration\5 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3ac3fd8b' to version 7.7.3...
IW 0 [2022-01-12T14:03:35.791Z; main]: forceMerge: index now _h5(6.6.6):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=6.6.6, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=3, os.version=10.0, timestamp=1641996155526}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
IW 0 [2022-01-12T14:03:35.791Z; main]: now flush at forceMerge
IW 0 [2022-01-12T14:03:35.792Z; main]: start flush: applyAllDeletes=true
IW 0 [2022-01-12T14:03:35.792Z; main]: index before flush _h5(6.6.6):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=6.6.6, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=3, os.version=10.0, timestamp=1641996155526}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
DW 0 [2022-01-12T14:03:35.793Z; main]: startFullFlush
DW 0 [2022-01-12T14:03:35.795Z; main]: main finishFullFlush success=true
IW 0 [2022-01-12T14:03:35.795Z; main]: now apply all deletes for all segments buffered updates bytesUsed=0 reader pool bytesUsed=0
BD 0 [2022-01-12T14:03:35.796Z; main]: waitApply: no deletes to apply
MP 0 [2022-01-12T14:03:35.797Z; main]: findForcedMerges: segmentsToUpgrade={_h5(6.6.6):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=6.6.6, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=3, os.version=10.0, timestamp=1641996155526}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]=true}
MP 0 [2022-01-12T14:03:35.801Z; main]: findForcedMerges maxSegmentCount=1 infos=_h5(6.6.6):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=6.6.6, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=3, os.version=10.0, timestamp=1641996155526}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}] segmentsToMerge={_h5(6.6.6):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=6.6.6, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=3, os.version=10.0, timestamp=1641996155526}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]=true}
MP 0 [2022-01-12T14:03:35.803Z; main]: already merged
MP 0 [2022-01-12T14:03:35.803Z; main]: findForcedMerges: TieredMergePolicy does not want to merge all old segments, merge remaining ones into new segment: {_h5(6.6.6):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=6.6.6, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=3, os.version=10.0, timestamp=1641996155526}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]=true}
IW 0 [2022-01-12T14:03:35.806Z; main]: add merge to pendingMerges: _h5(6.6.6):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=6.6.6, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=3, os.version=10.0, timestamp=1641996155526}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}] [total 1 pending]
IW 0 [2022-01-12T14:03:35.806Z; main]: registerMerge merging= []
IW 0 [2022-01-12T14:03:35.807Z; main]: registerMerge info=_h5(6.6.6):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=6.6.6, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=3, os.version=10.0, timestamp=1641996155526}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
MS 0 [2022-01-12T14:03:35.808Z; main]: initDynamicDefaults spins=true maxThreadCount=1 maxMergeCount=6
MS 0 [2022-01-12T14:03:35.808Z; main]: now merge
MS 0 [2022-01-12T14:03:35.808Z; main]: index: _h5(6.6.6):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=6.6.6, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=3, os.version=10.0, timestamp=1641996155526}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
MS 0 [2022-01-12T14:03:35.809Z; main]: consider merge _h5(6.6.6):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=6.6.6, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=3, os.version=10.0, timestamp=1641996155526}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
MS 0 [2022-01-12T14:03:35.810Z; main]: launch new thread [Lucene Merge Thread #0]
MS 0 [2022-01-12T14:03:35.811Z; Lucene Merge Thread #0]: merge thread: start
BD 0 [2022-01-12T14:03:35.811Z; Lucene Merge Thread #0]: waitApplyForMerge: 0 packets, 1 merging segments
BD 0 [2022-01-12T14:03:35.812Z; Lucene Merge Thread #0]: waitApply: no deletes to apply
IW 0 [2022-01-12T14:03:35.813Z; Lucene Merge Thread #0]: now apply deletes for 1 merging segments
IW 0 [2022-01-12T14:03:35.816Z; Lucene Merge Thread #0]: merge seg=_h6 _h5(6.6.6):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=6.6.6, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=3, os.version=10.0, timestamp=1641996155526}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
IW 0 [2022-01-12T14:03:35.816Z; Lucene Merge Thread #0]: now merge
merge=_h5(6.6.6):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=6.6.6, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=3, os.version=10.0, timestamp=1641996155526}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
index=_h5(6.6.6):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=6.6.6, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=3, os.version=10.0, timestamp=1641996155526}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
IW 0 [2022-01-12T14:03:35.818Z; Lucene Merge Thread #0]: merging _h5(6.6.6):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=6.6.6, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=3, os.version=10.0, timestamp=1641996155526}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
MS 0 [2022-01-12T14:03:35.820Z; main]: updateMergeThreads ioThrottle=true targetMBPerSec=20.0 MB/sec
merge thread Lucene Merge Thread #0 estSize=28.8 MB (written=0.0 MB) runTime=0.0s (stopped=0.0s, paused=0.0s) rate=unlimited
leave running at Infinity MB/sec
MS 0 [2022-01-12T14:03:35.820Z; main]: no more merges pending; now return
IW 0 [2022-01-12T14:03:35.907Z; Lucene Merge Thread #0]: seg=_h5(6.6.6):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=6.6.6, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=3, os.version=10.0, timestamp=1641996155526}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}] reader=_h5(6.6.6):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=6.6.6, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=3, os.version=10.0, timestamp=1641996155526}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
SM 0 [2022-01-12T14:03:35.917Z; Lucene Merge Thread #0]: merge store matchedCount=1 vs 1
SM 0 [2022-01-12T14:03:35.942Z; Lucene Merge Thread #0]: 28 msec to merge stored fields [162798 docs]
SM 0 [2022-01-12T14:03:37.243Z; Lucene Merge Thread #0]: 1301 msec to merge postings [162798 docs]
SM 0 [2022-01-12T14:03:37.347Z; Lucene Merge Thread #0]: 103 msec to merge doc values [162798 docs]
SM 0 [2022-01-12T14:03:37.347Z; Lucene Merge Thread #0]: 0 msec to merge points [162798 docs]
SM 0 [2022-01-12T14:03:38.100Z; Lucene Merge Thread #0]: 751 msec to merge norms [162798 docs]
SM 0 [2022-01-12T14:03:38.103Z; Lucene Merge Thread #0]: 2 msec to write field infos [162798 docs]
IW 0 [2022-01-12T14:03:38.105Z; Lucene Merge Thread #0]: merge codec=Lucene70 maxDoc=162798; merged segment has no vectors; norms; docValues; prox; freqs; no points; 2.2 sec to merge segment [28.66 MB, 13.06 MB/sec]
IW 0 [2022-01-12T14:03:38.107Z; Lucene Merge Thread #0]: merged segment size=28.657 MB vs estimate=28.782 MB
IW 0 [2022-01-12T14:03:38.107Z; Lucene Merge Thread #0]: commitMerge: _h5(6.6.6):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=6.6.6, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=3, os.version=10.0, timestamp=1641996155526}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}] index=_h5(6.6.6):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=6.6.6, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=3, os.version=10.0, timestamp=1641996155526}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
IW 0 [2022-01-12T14:03:38.109Z; Lucene Merge Thread #0]: commitMergeDeletes _h5(6.6.6):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=6.6.6, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=3, os.version=10.0, timestamp=1641996155526}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
IW 0 [2022-01-12T14:03:38.110Z; Lucene Merge Thread #0]: 0 new deletes since merge started
IFD 0 [2022-01-12T14:03:38.120Z; Lucene Merge Thread #0]: now checkpoint "_h6(7.7.3):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=7.7.3, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=1, os.version=10.0, timestamp=1641996215816}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]" [1 segments ; isCommit = false]
IFD 0 [2022-01-12T14:03:38.120Z; Lucene Merge Thread #0]: 0 msec to checkpoint
IW 0 [2022-01-12T14:03:38.121Z; Lucene Merge Thread #0]: after commitMerge: _h6(7.7.3):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=7.7.3, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=1, os.version=10.0, timestamp=1641996215816}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
MP 0 [2022-01-12T14:03:38.122Z; Lucene Merge Thread #0]: findForcedMerges: segmentsToUpgrade={}
IW 0 [2022-01-12T14:03:38.122Z; Lucene Merge Thread #0]: merge time 2311 msec for 162798 docs
MS 0 [2022-01-12T14:03:38.123Z; Lucene Merge Thread #0]: merge thread: done
MS 0 [2022-01-12T14:03:38.123Z; Lucene Merge Thread #0]: now merge
IndexUpgrader 0 [2022-01-12T14:03:38.122Z; main]: All segments upgraded to version 7.7.3
MS 0 [2022-01-12T14:03:38.124Z; Lucene Merge Thread #0]: index: _h6(7.7.3):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=7.7.3, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=1, os.version=10.0, timestamp=1641996215816}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
IndexUpgrader 0 [2022-01-12T14:03:38.124Z; main]: Enforcing commit to rewrite all index metadata...
MS 0 [2022-01-12T14:03:38.125Z; Lucene Merge Thread #0]: no more merges pending; now return
IW 0 [2022-01-12T14:03:38.126Z; main]: commit: start
MS 0 [2022-01-12T14:03:38.126Z; Lucene Merge Thread #0]: updateMergeThreads ioThrottle=true targetMBPerSec=20.0 MB/sec
IW 0 [2022-01-12T14:03:38.126Z; main]: commit: enter lock
IW 0 [2022-01-12T14:03:38.127Z; main]: commit: now prepare
IW 0 [2022-01-12T14:03:38.128Z; main]: prepareCommit: flush
IW 0 [2022-01-12T14:03:38.128Z; main]: index before flush _h6(7.7.3):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=7.7.3, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=1, os.version=10.0, timestamp=1641996215816}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
DW 0 [2022-01-12T14:03:38.130Z; main]: startFullFlush
IW 0 [2022-01-12T14:03:38.131Z; main]: now apply all deletes for all segments buffered updates bytesUsed=0 reader pool bytesUsed=0
BD 0 [2022-01-12T14:03:38.132Z; main]: waitApply: no deletes to apply
DW 0 [2022-01-12T14:03:38.133Z; main]: main finishFullFlush success=true
IW 0 [2022-01-12T14:03:38.133Z; main]: startCommit(): start
IW 0 [2022-01-12T14:03:38.134Z; main]: startCommit index=_h6(7.7.3):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=7.7.3, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=1, os.version=10.0, timestamp=1641996215816}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}] changeCount=4
IW 0 [2022-01-12T14:03:38.140Z; main]: startCommit: wrote pending segments file "pending_segments_91"
IW 0 [2022-01-12T14:03:38.177Z; main]: done all syncs: [._h6_Lucene50_0.tip, _h6.fdt, _h6.nvm, _h6.fnm, _h6_Lucene70_0.dvm, _h6_Lucene50_0.doc, _h6_Lucene50_0.tim, _h6.si, _h6_Lucene50_0.pos, _h6.nvd, _h6_Lucene70_0.dvd, _h6.fdx]
IW 0 [2022-01-12T14:03:38.179Z; main]: commit: pendingCommit != null
IW 0 [2022-01-12T14:03:38.180Z; main]: commit: done writing segments file "segments_91"
IFD 0 [2022-01-12T14:03:38.180Z; main]: now checkpoint "_h6(7.7.3):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=7.7.3, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=1, os.version=10.0, timestamp=1641996215816}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]" [1 segments ; isCommit = true]
IFD 0 [2022-01-12T14:03:38.181Z; main]: deleteCommits: now decRef commit "segments_90"
IFD 0 [2022-01-12T14:03:38.182Z; main]: delete [._h5_Lucene54_0.dvm, _h5.nvm, _h5.fnm, _h5_Lucene50_0.tim, _h5.fdx, _h5_Lucene50_0.doc, _h5.nvd, _h5_Lucene50_0.tip, _h5.si, segments_90, _h5_Lucene54_0.dvd, _h5.fdt, _h5_Lucene50_0.pos]
IFD 0 [2022-01-12T14:03:38.189Z; main]: 7 msec to checkpoint
IW 0 [2022-01-12T14:03:38.189Z; main]: commit: took 60.5 msec
IW 0 [2022-01-12T14:03:38.189Z; main]: commit: done
IndexUpgrader 0 [2022-01-12T14:03:38.190Z; main]: Committed upgraded metadata to index.
IW 0 [2022-01-12T14:03:38.190Z; main]: now flush at close
IW 0 [2022-01-12T14:03:38.191Z; main]: start flush: applyAllDeletes=true
IW 0 [2022-01-12T14:03:38.191Z; main]: index before flush _h6(7.7.3):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=7.7.3, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=1, os.version=10.0, timestamp=1641996215816}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
DW 0 [2022-01-12T14:03:38.192Z; main]: startFullFlush
DW 0 [2022-01-12T14:03:38.193Z; main]: main finishFullFlush success=true
IW 0 [2022-01-12T14:03:38.195Z; main]: now apply all deletes for all segments buffered updates bytesUsed=0 reader pool bytesUsed=0
BD 0 [2022-01-12T14:03:38.195Z; main]: waitApply: no deletes to apply
MS 0 [2022-01-12T14:03:38.195Z; main]: updateMergeThreads ioThrottle=true targetMBPerSec=10240.0 MB/sec
MS 0 [2022-01-12T14:03:38.196Z; main]: now merge
MS 0 [2022-01-12T14:03:38.196Z; main]: index: _h6(7.7.3):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=7.7.3, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=1, os.version=10.0, timestamp=1641996215816}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
MS 0 [2022-01-12T14:03:38.196Z; main]: no more merges pending; now return
IW 0 [2022-01-12T14:03:38.197Z; main]: waitForMerges
IW 0 [2022-01-12T14:03:38.197Z; main]: waitForMerges done
IW 0 [2022-01-12T14:03:38.198Z; main]: commit: start
IW 0 [2022-01-12T14:03:38.198Z; main]: commit: enter lock
IW 0 [2022-01-12T14:03:38.199Z; main]: commit: now prepare
IW 0 [2022-01-12T14:03:38.199Z; main]: prepareCommit: flush
IW 0 [2022-01-12T14:03:38.199Z; main]: index before flush _h6(7.7.3):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=7.7.3, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=1, os.version=10.0, timestamp=1641996215816}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
DW 0 [2022-01-12T14:03:38.200Z; main]: startFullFlush
IW 0 [2022-01-12T14:03:38.200Z; main]: now apply all deletes for all segments buffered updates bytesUsed=0 reader pool bytesUsed=0
BD 0 [2022-01-12T14:03:38.200Z; main]: waitApply: no deletes to apply
DW 0 [2022-01-12T14:03:38.201Z; main]: main finishFullFlush success=true
IW 0 [2022-01-12T14:03:38.201Z; main]: startCommit(): start
IW 0 [2022-01-12T14:03:38.201Z; main]: skip startCommit(): no changes pending
IW 0 [2022-01-12T14:03:38.201Z; main]: commit: pendingCommit == null; skip
IW 0 [2022-01-12T14:03:38.202Z; main]: commit: took 2.9 msec
IW 0 [2022-01-12T14:03:38.202Z; main]: commit: done
IW 0 [2022-01-12T14:03:38.202Z; main]: rollback
IW 0 [2022-01-12T14:03:38.203Z; main]: all running merges have aborted
IW 0 [2022-01-12T14:03:38.203Z; main]: rollback: done finish merges
DW 0 [2022-01-12T14:03:38.203Z; main]: abort
DW 0 [2022-01-12T14:03:38.203Z; main]: done abort success=true
IW 0 [2022-01-12T14:03:38.204Z; main]: rollback: infos=_h6(7.7.3):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=7.7.3, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=1, os.version=10.0, timestamp=1641996215816}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
IFD 0 [2022-01-12T14:03:38.204Z; main]: now checkpoint "_h6(7.7.3):C162798:[.diagnostics={os=Windows 10, java.vendor=AdoptOpenJDK, java.version=1.8.0_282, java.vm.version=25.282-b08, lucene.version=7.7.3, mergeMaxNumSegments=1, os.arch=amd64, java.runtime.version=1.8.0_282-b08, source=merge, mergeFactor=1, os.version=10.0, timestamp=1641996215816}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]" [1 segments ; isCommit = false]
IFD 0 [2022-01-12T14:03:38.205Z; main]: 0 msec to checkpoint
C:\workspaces\workspaceecm5\LuceneIndexUpgrade\lb>java -cp lucene-core-8.11.1.jar;lucene-backward-codecs-8.11.1.jar org.apache.lucene.index.IndexUpgrader -delete-prior-commits -verbose "V:\\LuceneMigration\\5"
MS 0 [2022-01-12T14:04:24.248Z; main]: initDynamicDefaults spins=true maxThreadCount=1 maxMergeCount=6
IW 0 [2022-01-12T14:04:24.275Z; main]: init: hit exception on init; releasing write lock
Exception in thread "main" org.apache.lucene.index.IndexFormatTooOldException: Format version is not supported (resource BufferedChecksumIndexInput(MMapIndexInput(path="V:\LuceneMigration\5\segments_91"))): This index was initially created with Lucene 6.x while the current version is 8.11.1 and Lucene only supports reading the current and previous major versions.. This version of Lucene only supports indexes created with release 7.0 and later.
at org.apache.lucene.index.SegmentInfos.readCommit(SegmentInfos.java:322)
at org.apache.lucene.index.SegmentInfos.readCommit(SegmentInfos.java:291)
at org.apache.lucene.index.IndexWriter.<init>(IndexWriter.java:1037)
at org.apache.lucene.index.IndexUpgrader.upgrade(IndexUpgrader.java:167)
at org.apache.lucene.index.IndexUpgrader.main(IndexUpgrader.java:78)
Suppressed: org.apache.lucene.index.CorruptIndexException: checksum passed (7268b2f2). possibly transient resource issue, or a Lucene or JVM bug (resource=BufferedChecksumIndexInput(MMapIndexInput(path="V:\LuceneMigration\5\segments_91")))
at org.apache.lucene.codecs.CodecUtil.checkFooter(CodecUtil.java:466)
at org.apache.lucene.index.SegmentInfos.readCommit(SegmentInfos.java:434)
... 4 more