Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-NightlyTests-main/645/
1 tests failed.
FAILED: org.apache.lucene.replicator.nrt.TestStressNRTReplication.test
Error Message:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught
exception in thread: Thread[id=69, name=start R2, state=RUNNABLE,
group=TGRP-TestStressNRTReplication]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught
exception in thread: Thread[id=69, name=start R2, state=RUNNABLE,
group=TGRP-TestStressNRTReplication]
at
__randomizedtesting.SeedInfo.seed([9411BAC8B658A371:1C45851218A4CE89]:0)
Caused by: java.lang.RuntimeException: java.lang.RuntimeException: replica R2
failed to start
at __randomizedtesting.SeedInfo.seed([9411BAC8B658A371]:0)
at
org.apache.lucene.replicator.nrt.TestStressNRTReplication$RestartThread$1.run(TestStressNRTReplication.java:960)
Caused by: java.lang.RuntimeException: replica R2 failed to start
at
org.apache.lucene.replicator.nrt.TestStressNRTReplication.startNode(TestStressNRTReplication.java:690)
at
org.apache.lucene.replicator.nrt.TestStressNRTReplication$RestartThread$1.run(TestStressNRTReplication.java:955)
Build Log:
[...truncated 4354 lines...]
1> 19.275s 12.7s: parent [ pump4] # A fatal error has been detected
by the Java Runtime Environment:
1> 19.275s 12.7s: parent [ pump4] #
1> 19.275s 12.7s: parent [ pump4] # SIGSEGV (0xb) at
pc=0x00007fc0ab7bfc8c, pid=7727, tid=7899
1> 19.275s 12.7s: parent [ pump4] #
1> 19.275s 12.7s: parent [ pump4] # JRE version: Java(TM) SE
Runtime Environment (17.0.1+12) (build 17.0.1+12-LTS-39)
1> 19.275s 12.7s: parent [ pump4] # Java VM: Java HotSpot(TM)
64-Bit Server VM (17.0.1+12-LTS-39, mixed mode, sharing, tiered, compressed
oops, compressed class ptrs, g1 gc, linux-amd64)
1> 19.275s 12.7s: parent [ pump4] # Problematic frame:
1> 19.277s 12.7s: parent [ pump4] # V [libjvm.so+0xdbec8c]
Unsafe_PutLong+0x13c
1> 19.277s 12.7s: parent [ pump4] #
1> 19.277s 12.7s: parent [ pump4] # Core dump will be written.
Default location: Core dumps may be processed with "/usr/share/apport/apport
-p%p -s%s -c%c -d%d -P%P -u%u -g%g -- %E" (or dumping to
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-NightlyTests-main/checkout/lucene/replicator/build/tmp/tests-tmp/lucene.replicator.nrt.TestStressNRTReplication_9411BAC8B658A371-001/child-001/core.7727)
1> 19.278s 12.7s: parent [ pump4] #
1> 19.278s 12.7s: parent [ pump4] # An error report file with more
information is saved as:
1> 19.278s 12.7s: parent [ pump4] #
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-NightlyTests-main/checkout/lucene/replicator/build/tmp/tests-tmp/lucene.replicator.nrt.TestStressNRTReplication_9411BAC8B658A371-001/child-001/hs_err_pid7727.log
1> 19.299s 12.7s: parent [ pump4] #
1> 19.299s 12.7s: parent [ pump4] # If you would like to submit a
bug report, please visit:
1> 19.299s 12.7s: parent [ pump4] #
https://bugreport.java.com/bugreport/crash.jsp
1> 19.300s 12.7s: parent [ pump4] #
1> 19.311s : parent [ start R0] JUnit version 4.13.1
1> 19.448s : parent [ pump4] now wait for process
Process[pid=7727, exitValue="not exited"]
1> 19.448s : parent [ pump4] done wait for process
Process[pid=7727, exitValue=134]
1> 19.449s : parent [ pump4] exit value=134 willCrash=true
1> 19.449s : parent [ pump4] top: R4 tcpPort=33171: replica
process finished
1> 19.449s : parent [ pump4] top: send 2 replicas to primary
1> 19.450s 17.6s: idle P1 [ set repls] top: set replicasIDs=[2, 3]
tcpPorts=[45851, 44027]
1> 19.623s : parent [ restarter] node status s0(0.5s) p1(19.3s)
2(15.3s) 3(12.7s) x4(0.2s)
1> 19.623s : parent [ restarter] downNodes=[0, 4]
1> 19.817s : parent [ main] top: now flush primary; at least
marker count=82
1> 19.828s 17.9s: idle P1 [ flush] now flush; 2 replicas
1> 19.828s 17.9s: idle P1 [ flush] top: now flushAndRefresh
1> 19.856s : parent [ restarter] node status s0(0.7s) p1(19.5s)
2(15.6s) 3(12.9s) x4(0.4s)
1> 19.856s : parent [ restarter] downNodes=[0, 4]
1> 19.856s : parent [ restarter] node 0 still starting
1> 19.876s 18.0s: idle P1 [ flush] top: switch to infos=segments_1:
_8(10.0.0):c251/25:[diagnostics={os.arch=amd64, source=merge,
java.vendor=Oracle Corporation, os.version=4.15.0-147-generic,
mergeMaxNumSegments=-1, java.version=17.0.1, java.vm.version=17.0.1+12-LTS-39,
lucene.version=10.0.0, timestamp=1653199396000, os=Linux,
java.runtime.version=17.0.1+12-LTS-39, mergeFactor=3}]:delGen=3
:id=cva6u9luajl9wwsjka944lwuy _b(10.0.0):c144/14:[diagnostics={os.arch=amd64,
source=merge, java.vendor=Oracle Corporation, os.version=4.15.0-147-generic,
mergeMaxNumSegments=-1, java.version=17.0.1, java.vm.version=17.0.1+12-LTS-39,
lucene.version=10.0.0, timestamp=1653199397423, os=Linux,
java.runtime.version=17.0.1+12-LTS-39, mergeFactor=3}]:delGen=2
:id=cva6u9luajl9wwsjka944lwuz
_a(10.0.0):C326/8:[diagnostics={lucene.version=10.0.0, source=flush,
os.arch=amd64, java.runtime.version=17.0.1+12-LTS-39,
os.version=4.15.0-147-generic, os=Linux, java.vendor=Oracle Corporation,
timestamp=1653199400523, java.version=17.0.1,
java.vm.version=17.0.1+12-LTS-39}]:delGen=2 :id=cva6u9luajl9wwsjka944lwv0
_c(10.0.0):C39:[diagnostics={lucene.version=10.0.0, source=flush,
os.arch=amd64, java.runtime.version=17.0.1+12-LTS-39,
os.version=4.15.0-147-generic, os=Linux, java.vendor=Oracle Corporation,
timestamp=1653199402071, java.version=17.0.1,
java.vm.version=17.0.1+12-LTS-39}] :id=cva6u9luajl9wwsjka944lwux version=38
1> 19.876s 18.0s: idle P1 [ flush] file _8_3.liv has length=91 b
1> 19.878s 18.0s: idle P1 [ flush] file _b.cfe has length=415 b
1> 19.879s 18.0s: idle P1 [ flush] file _b.cfs has length=326.1 KB
1> 19.879s 18.0s: idle P1 [ flush] file _b_2.liv has length=83 b
1> 19.880s 18.0s: idle P1 [ flush] file _b.si has length=386 b
1> 19.880s 18.0s: idle P1 [ flush] file _a_Lucene90_0.tip has
length=4.8 KB
1> 19.881s 18.0s: idle P1 [ flush] file _a.fdt has length=219.6 KB
1> 19.883s 18.0s: idle P1 [ flush] file _a.tvx has length=146 b
1> 19.883s 18.0s: idle P1 [ flush] file _a_Lucene90_0.doc has
length=22.2 KB
1> 19.884s 18.0s: idle P1 [ flush] file _a.si has length=488 b
1> 19.884s 18.0s: idle P1 [ flush] file _a.fdx has length=157 b
1> 19.885s : parent [Searcher node=P1 tcpPort=38439] top: searcher:
record search hitCount version=38 hitCount=440 node=P1 tcpPort=38439
1> 19.885s : parent [Searcher node=P1 tcpPort=38439] top: searcher:
set lastPrimaryVersion=30 vs 38
1> 19.885s 18.0s: idle P1 [ flush] file _a_Lucene90_0.tim has
length=212.9 KB
1> 19.886s 18.0s: idle P1 [ flush] file _a.fdm has length=156 b
1> 19.887s 18.0s: idle P1 [ flush] file _a.nvm has length=139 b
1> 19.887s 18.0s: idle P1 [ flush] file _a.fnm has length=535 b
1> 19.888s 18.0s: idle P1 [ flush] file _a_Lucene90_0.tmd has
length=526 b
1> 19.893s 18.0s: idle P1 [ flush] file _a.tvm has length=162 b
1> 19.894s 18.0s: idle P1 [ flush] file _a.nvd has length=1.8 KB
1> 19.894s 18.0s: idle P1 [ flush] file _a_Lucene90_0.pos has
length=54.7 KB
1> 19.894s 18.0s: idle P1 [ flush] file _a.tvd has length=292.8 KB
1> 19.894s 18.0s: idle P1 [ flush] file _a_2.liv has length=107 b
1> 19.895s 18.0s: idle P1 [ flush] file _c.fdm has length=156 b
1> 19.895s 18.0s: idle P1 [ flush] file _c.tvm has length=162 b
1> 19.896s 18.0s: idle P1 [ flush] file _c.si has length=488 b
1> 19.896s 18.0s: idle P1 [ flush] file _c.fdt has length=33.3 KB
1> 19.896s 18.0s: idle P1 [ flush] file _c.tvx has length=80 b
1> 19.896s 18.0s: idle P1 [ flush] file _c_Lucene90_0.tip has
length=890 b
1> 19.897s 18.0s: idle P1 [ flush] file _c_Lucene90_0.pos has
length=9.6 KB
1> 19.897s 18.0s: idle P1 [ flush] file _c.nvd has length=295 b
1> 19.898s 18.0s: idle P1 [ flush] file _c.fdx has length=79 b
1> 19.898s 18.0s: idle P1 [ flush] file _c_Lucene90_0.doc has
length=1.8 KB
1> 19.898s 18.0s: idle P1 [ flush] file _c_Lucene90_0.tim has
length=39.2 KB
1> 19.898s 18.0s: idle P1 [ flush] file _c_Lucene90_0.tmd has
length=495 b
1> 19.899s 18.0s: idle P1 [ flush] file _c.nvm has length=139 b
1> 19.899s 18.0s: idle P1 [ flush] file _c.tvd has length=44.7 KB
1> 19.915s 18.0s: idle P1 [ flush] file _c.fnm has length=535 b
1> 19.916s 18.0s: idle P1 [ flush] top: set copyState primaryGen=0
version=38 files=[_b.cfe, _c.fdm, _a_Lucene90_0.doc, _a.si, _c.tvm, _c.si,
_c.fdt, _c.tvx, _c_Lucene90_0.tip, _a.nvm, _a.fnm, _c_Lucene90_0.pos, _c.fdx,
_a.tvm, _c_Lucene90_0.tim, _a.nvd, _c_Lucene90_0.tmd, _a.tvd,
_a_Lucene90_0.tip, _a.fdt, _a.tvx, _b_2.liv, _a.fdx, _a_Lucene90_0.tim, _b.si,
_8.cfe, _a.fdm, _b.cfs, _a_Lucene90_0.tmd, _c.nvd, _8_3.liv, _c_Lucene90_0.doc,
_a_Lucene90_0.pos, _8.cfs, _c.nvm, _c.tvd, _c.fnm, _8.si, _a_2.liv]
1> 19.916s 18.0s: idle P1 [ flush] top: opened NRT reader version=38
1> 19.917s 18.0s: idle P1 [ flush] flushAndRefresh: version=38
completedMergeFiles=[_b.cfe, _b.si, _b.cfs] finishedMergedFiles=[]
1> 19.917s 18.0s: idle P1 [ flush] send flushed version=38
1> 19.917s : parent [ main] top: flush finished with changed;
new primary version=38
1> 19.917s : parent [ main] top: record transLogLoc:
version=38 loc=920161
1> 19.918s : parent [ main] top: record marker count:
version=38 count=82
1> 19.918s : parent [ main] PG=0 3 (of 5) nodes running: P1
R2 R3
1> 19.918s 18.0s: idle P1 [ flush] send NEW_NRT_POINT to R2 at
tcpPort=45851
1> 19.919s 18.0s: idle P1 [ flush] send NEW_NRT_POINT to R3 at
tcpPort=44027
1> 19.925s 8.5s: idle R3 [ recv-38] newNRTPoint primaryTCPPort=38439
version=38 newPrimaryGen=0
1> 19.925s 8.5s: idle R3 [ recv-38] top: keep current lastPrimaryGen=0
1> 19.926s 8.5s: syncing R3 [ recv-38] top: start sync sis.version=38
1> 19.926s 8.5s: syncing R3 [ recv-38] top: newNRTPoint
1> 19.929s 12.2s: idle R2 [ recv-38] newNRTPoint primaryTCPPort=38439
version=38 newPrimaryGen=0
1> 19.932s 18.1s: idle P1 [ send] top: start fetch for R3
socket=Socket[addr=/127.0.0.1,port=49146,localport=38439]
1> 19.947s 8.5s: syncing R3 [ recv-38] file _b.cfe has length=415 b
1> 19.948s 8.5s: syncing R3 [ recv-38] file _c.fdm: will copy [file does
not exist]
1> 19.948s 8.5s: syncing R3 [ recv-38] file _a_Lucene90_0.doc: will copy
[file does not exist]
1> 19.950s 8.5s: syncing R3 [ recv-38] file _a.si: will copy [file does
not exist]
1> 19.950s 8.5s: syncing R3 [ recv-38] file _c.tvm: will copy [file does
not exist]
1> 19.951s 8.5s: syncing R3 [ recv-38] file _c.si: will copy [file does
not exist]
1> 19.951s 8.5s: syncing R3 [ recv-38] file _c.fdt: will copy [file does
not exist]
1> 19.952s 8.5s: syncing R3 [ recv-38] file _c.tvx: will copy [file does
not exist]
1> 19.952s 8.5s: syncing R3 [ recv-38] file _c_Lucene90_0.tip: will copy
[file does not exist]
1> 19.929s 12.2s: idle R2 [ recv-38] top: keep current lastPrimaryGen=0
1> 19.953s 12.2s: syncing R2 [ recv-38] top: start sync sis.version=38
1> 19.954s 12.2s: syncing R2 [ recv-38] top: newNRTPoint
1> 19.954s 8.5s: syncing R3 [ recv-38] file _a.nvm: will copy [file does
not exist]
1> 19.954s 8.5s: syncing R3 [ recv-38] file _a.fnm: will copy [file does
not exist]
1> 19.955s 8.5s: syncing R3 [ recv-38] file _c_Lucene90_0.pos: will copy
[file does not exist]
1> 19.960s 8.5s: syncing R3 [ recv-38] file _c.fdx: will copy [file does
not exist]
1> 19.960s 8.5s: syncing R3 [ recv-38] file _a.tvm: will copy [file does
not exist]
1> 19.961s 8.5s: syncing R3 [ recv-38] file _c_Lucene90_0.tim: will copy
[file does not exist]
1> 19.961s 8.5s: syncing R3 [ recv-38] file _a.nvd: will copy [file does
not exist]
1> 19.961s 8.5s: syncing R3 [ recv-38] file _c_Lucene90_0.tmd: will copy
[file does not exist]
1> 19.962s 8.5s: syncing R3 [ recv-38] file _a.tvd: will copy [file does
not exist]
1> 19.962s 8.5s: syncing R3 [ recv-38] file _a_Lucene90_0.tip: will copy
[file does not exist]
1> 19.965s 18.1s: idle P1 [ send] top: start fetch for R2
socket=Socket[addr=/127.0.0.1,port=49148,localport=38439]
1> 19.966s 8.5s: syncing R3 [ recv-38] file _a.fdt: will copy [file does
not exist]
1> 19.966s 8.5s: syncing R3 [ recv-38] file _a.tvx: will copy [file does
not exist]
1> 19.966s 8.5s: syncing R3 [ recv-38] file _b_2.liv: will copy [file
does not exist]
1> 19.967s 12.2s: syncing R2 [ recv-38] file _b.cfe has length=415 b
1> 19.967s 12.2s: syncing R2 [ recv-38] file _c.fdm: will copy [file does
not exist]
1> 19.967s 12.2s: syncing R2 [ recv-38] file _a_Lucene90_0.doc: will copy
[file does not exist]
1> 19.967s 12.2s: syncing R2 [ recv-38] file _a.si: will copy [file does
not exist]
1> 19.967s 8.5s: syncing R3 [ recv-38] file _a.fdx: will copy [file does
not exist]
1> 19.967s 12.2s: syncing R2 [ recv-38] file _c.tvm: will copy [file does
not exist]
1> 19.967s 12.2s: syncing R2 [ recv-38] file _c.si: will copy [file does
not exist]
1> 19.967s 12.2s: syncing R2 [ recv-38] file _c.fdt: will copy [file does
not exist]
1> 19.968s 12.2s: syncing R2 [ recv-38] file _c.tvx: will copy [file does
not exist]
1> 19.968s 8.5s: syncing R3 [ recv-38] file _a_Lucene90_0.tim: will copy
[file does not exist]
1> 19.968s 12.2s: syncing R2 [ recv-38] file _c_Lucene90_0.tip: will copy
[file does not exist]
1> 19.968s 12.2s: syncing R2 [ recv-38] file _a.nvm: will copy [file does
not exist]
1> 19.968s 12.2s: syncing R2 [ recv-38] file _a.fnm: will copy [file does
not exist]
1> 19.968s 12.2s: syncing R2 [ recv-38] file _c_Lucene90_0.pos: will copy
[file does not exist]
1> 19.968s 12.2s: syncing R2 [ recv-38] file _c.fdx: will copy [file does
not exist]
1> 19.968s 12.2s: syncing R2 [ recv-38] file _a.tvm: will copy [file does
not exist]
1> 19.968s 12.2s: syncing R2 [ recv-38] file _c_Lucene90_0.tim: will copy
[file does not exist]
1> 19.968s 12.2s: syncing R2 [ recv-38] file _a.nvd: will copy [file does
not exist]
1> 19.968s 12.2s: syncing R2 [ recv-38] file _c_Lucene90_0.tmd: will copy
[file does not exist]
1> 19.968s 12.2s: syncing R2 [ recv-38] file _a.tvd: will copy [file does
not exist]
1> 19.968s 12.2s: syncing R2 [ recv-38] file _a_Lucene90_0.tip: will copy
[file does not exist]
1> 19.969s 8.5s: syncing R3 [ recv-38] file _b.si has length=386 b
1> 19.969s 12.2s: syncing R2 [ recv-38] file _a.fdt: will copy [file does
not exist]
1> 19.969s 12.2s: syncing R2 [ recv-38] file _a.tvx: will copy [file does
not exist]
1> 19.970s 8.5s: syncing R3 [ recv-38] file _a.fdm: will copy [file does
not exist]
1> 19.970s 12.2s: syncing R2 [ recv-38] file _b_2.liv: will copy [file
does not exist]
1> 19.970s 12.2s: syncing R2 [ recv-38] file _a.fdx: will copy [file does
not exist]
1> 19.970s 12.2s: syncing R2 [ recv-38] file _a_Lucene90_0.tim: will copy
[file does not exist]
1> 19.970s 12.2s: syncing R2 [ recv-38] file _b.si has length=386 b
1> 19.970s 8.5s: syncing R3 [ recv-38] file _b.cfs has length=326.1 KB
1> 19.970s 12.2s: syncing R2 [ recv-38] file _a.fdm: will copy [file does
not exist]
1> 19.970s 8.5s: syncing R3 [ recv-38] file _a_Lucene90_0.tmd: will copy
[file does not exist]
1> 19.971s 12.2s: syncing R2 [ recv-38] file _b.cfs has length=326.1 KB
1> 19.971s 8.5s: syncing R3 [ recv-38] file _c.nvd: will copy [file does
not exist]
1> 19.972s 12.2s: syncing R2 [ recv-38] file _a_Lucene90_0.tmd: will copy
[file does not exist]
1> 19.972s 8.5s: syncing R3 [ recv-38] file _8_3.liv: will copy [file
does not exist]
1> 19.972s 8.5s: syncing R3 [ recv-38] file _c_Lucene90_0.doc: will copy
[file does not exist]
1> 19.973s 12.2s: syncing R2 [ recv-38] file _c.nvd: will copy [file does
not exist]
1> 19.973s 12.2s: syncing R2 [ recv-38] file _8_3.liv: will copy [file
does not exist]
1> 19.974s 12.2s: syncing R2 [ recv-38] file _c_Lucene90_0.doc: will copy
[file does not exist]
1> 19.974s 12.2s: syncing R2 [ recv-38] file _a_Lucene90_0.pos: will copy
[file does not exist]
1> 19.974s 12.2s: syncing R2 [ recv-38] file _c.nvm: will copy [file does
not exist]
1> 19.974s 12.2s: syncing R2 [ recv-38] file _c.tvd: will copy [file does
not exist]
1> 19.974s 12.2s: syncing R2 [ recv-38] file _c.fnm: will copy [file does
not exist]
1> 19.974s 8.5s: syncing R3 [ recv-38] file _a_Lucene90_0.pos: will copy
[file does not exist]
1> 19.974s 12.2s: syncing R2 [ recv-38] file _a_2.liv: will copy [file
does not exist]
1> 19.974s 12.2s: syncing R2 [ recv-38] create SimpleCopyJob o10
1> 19.974s 12.2s: syncing R2 [ recv-38] top: newNRTPoint: job
files=[_b.cfe, _c.fdm, _a_Lucene90_0.doc, _a.si, _c.tvm, _c.si, _c.fdt, _c.tvx,
_c_Lucene90_0.tip, _a.nvm, _a.fnm, _c_Lucene90_0.pos, _c.fdx, _a.tvm,
_c_Lucene90_0.tim, _a.nvd, _c_Lucene90_0.tmd, _a.tvd, _a_Lucene90_0.tip,
_a.fdt, _a.tvx, _b_2.liv, _a.fdx, _a_Lucene90_0.tim, _b.si, _8.cfe, _a.fdm,
_b.cfs, _a_Lucene90_0.tmd, _c.nvd, _8_3.liv, _c_Lucene90_0.doc,
_a_Lucene90_0.pos, _8.cfs, _c.nvm, _c.tvd, _c.fnm, _8.si, _a_2.liv]
1> 19.974s 12.2s: syncing R2 [ recv-38] SimpleCopyJob.init: done start
files count=33 totBytes=965778
1> 19.974s 8.5s: syncing R3 [ recv-38] file _c.nvm: will copy [file does
not exist]
1> 19.975s 8.5s: syncing R3 [ recv-38] file _c.tvd: will copy [file does
not exist]
1> 19.975s 8.5s: syncing R3 [ recv-38] file _c.fnm: will copy [file does
not exist]
1> 19.982s 12.2s: syncing R2 [ jobs o10] file _c.fdm: start copying to tmp
file _c.fdm_copy_41.tmp length=156
1> 19.982s 8.5s: syncing R3 [ recv-38] file _a_2.liv: will copy [file
does not exist]
1> 19.982s 8.5s: syncing R3 [ recv-38] create SimpleCopyJob o7
1> 19.982s 12.2s: syncing R2 [ jobs o10] file _c.fdm: done copying [156 b,
0.380ms]
1> 19.982s 12.2s: syncing R2 [ jobs o10] file _a_Lucene90_0.doc: start
copying to tmp file _a_Lucene90_0.doc_copy_42.tmp length=22747
1> 19.983s 8.5s: syncing R3 [ recv-38] top: newNRTPoint: job
files=[_b.cfe, _c.fdm, _a_Lucene90_0.doc, _a.si, _c.tvm, _c.si, _c.fdt, _c.tvx,
_c_Lucene90_0.tip, _a.nvm, _a.fnm, _c_Lucene90_0.pos, _c.fdx, _a.tvm,
_c_Lucene90_0.tim, _a.nvd, _c_Lucene90_0.tmd, _a.tvd, _a_Lucene90_0.tip,
_a.fdt, _a.tvx, _b_2.liv, _a.fdx, _a_Lucene90_0.tim, _b.si, _8.cfe, _a.fdm,
_b.cfs, _a_Lucene90_0.tmd, _c.nvd, _8_3.liv, _c_Lucene90_0.doc,
_a_Lucene90_0.pos, _8.cfs, _c.nvm, _c.tvd, _c.fnm, _8.si, _a_2.liv]
1> 19.983s 8.5s: syncing R3 [ recv-38] SimpleCopyJob.init: done start
files count=33 totBytes=965778
1> 19.984s 12.2s: syncing R2 [ jobs o10] file _a_Lucene90_0.doc: done
copying [22.2 KB, 1.097ms]
1> 19.984s 12.2s: syncing R2 [ jobs o10] file _a.si: start copying to tmp
file _a.si_copy_43.tmp length=488
1> 19.984s 8.5s: syncing R3 [ jobs o7] file _c.fdm: start copying to tmp
file _c.fdm_copy_2l.tmp length=156
1> 19.985s 8.5s: syncing R3 [ jobs o7] file _c.fdm: done copying [156 b,
0.110ms]
1> 19.984s 12.2s: syncing R2 [ jobs o10] file _a.si: done copying [488 b,
0.267ms]
1> 19.985s 12.2s: syncing R2 [ jobs o10] file _c.tvm: start copying to tmp
file _c.tvm_copy_44.tmp length=162
1> 19.985s 12.2s: syncing R2 [ jobs o10] file _c.tvm: done copying [162 b,
0.053ms]
1> 19.985s 12.2s: syncing R2 [ jobs o10] file _c.si: start copying to tmp
file _c.si_copy_45.tmp length=488
1> 19.985s 12.2s: syncing R2 [ jobs o10] file _c.si: done copying [488 b,
0.058ms]
1> 19.985s 12.2s: syncing R2 [ jobs o10] file _c.fdt: start copying to tmp
file _c.fdt_copy_46.tmp length=34084
1> 19.985s 8.5s: syncing R3 [ jobs o7] file _a_Lucene90_0.doc: start
copying to tmp file _a_Lucene90_0.doc_copy_2m.tmp length=22747
1> 19.986s 8.5s: syncing R3 [ jobs o7] file _a_Lucene90_0.doc: done
copying [22.2 KB, 0.123ms]
1> 19.986s 8.5s: syncing R3 [ jobs o7] file _a.si: start copying to tmp
file _a.si_copy_2n.tmp length=488
1> 19.986s 8.5s: syncing R3 [ jobs o7] file _a.si: done copying [488 b,
0.083ms]
1> 19.986s 18.1s: idle P1 [ send-R3-38] top: done fetch files for R3:
sent 33 files; sent 965778 bytes
1> 19.986s 18.1s: idle P1 [ send-R3-38] top: fetch: now release CopyState
1> 19.986s 8.5s: syncing R3 [ jobs o7] file _c.tvm: start copying to tmp
file _c.tvm_copy_2o.tmp length=162
1> 19.986s 8.5s: syncing R3 [ jobs o7] file _c.tvm: done copying [162 b,
0.060ms]
1> 19.986s 8.5s: syncing R3 [ jobs o7] file _c.si: start copying to tmp
file _c.si_copy_2p.tmp length=488
1> 19.987s 8.5s: syncing R3 [ jobs o7] file _c.si: done copying [488 b,
0.077ms]
1> 19.987s 8.5s: syncing R3 [ jobs o7] file _c.fdt: start copying to tmp
file _c.fdt_copy_2q.tmp length=34084
1> 19.987s 8.5s: syncing R3 [ jobs o7] file _c.fdt: done copying [33.3
KB, 0.148ms]
1> 19.987s 8.5s: syncing R3 [ jobs o7] file _c.tvx: start copying to tmp
file _c.tvx_copy_2r.tmp length=80
1> 19.987s 8.5s: syncing R3 [ jobs o7] file _c.tvx: done copying [80 b,
0.063ms]
1> 19.987s 8.5s: syncing R3 [ jobs o7] file _c_Lucene90_0.tip: start
copying to tmp file _c_Lucene90_0.tip_copy_2s.tmp length=890
1> 19.987s 8.5s: syncing R3 [ jobs o7] file _c_Lucene90_0.tip: done
copying [890 b, 0.066ms]
1> 19.988s 12.2s: syncing R2 [ jobs o10] file _c.fdt: done copying [33.3
KB, 2.464ms]
1> 19.988s 12.2s: syncing R2 [ jobs o10] file _c.tvx: start copying to tmp
file _c.tvx_copy_47.tmp length=80
1> 19.988s 12.2s: syncing R2 [ jobs o10] file _c.tvx: done copying [80 b,
0.056ms]
1> 19.988s 12.2s: syncing R2 [ jobs o10] file _c_Lucene90_0.tip: start
copying to tmp file _c_Lucene90_0.tip_copy_48.tmp length=890
1> 19.988s 12.2s: syncing R2 [ jobs o10] file _c_Lucene90_0.tip: done
copying [890 b, 0.072ms]
1> 19.988s 12.2s: syncing R2 [ jobs o10] file _a.nvm: start copying to tmp
file _a.nvm_copy_49.tmp length=139
1> 19.988s 12.2s: syncing R2 [ jobs o10] file _a.nvm: done copying [139 b,
0.048ms]
1> 19.989s 12.2s: syncing R2 [ jobs o10] file _a.fnm: start copying to tmp
file _a.fnm_copy_4a.tmp length=535
1> 19.989s 12.2s: syncing R2 [ jobs o10] file _a.fnm: done copying [535 b,
0.044ms]
1> 19.989s 12.2s: syncing R2 [ jobs o10] file _c_Lucene90_0.pos: start
copying to tmp file _c_Lucene90_0.pos_copy_4b.tmp length=9785
1> 19.989s 12.2s: syncing R2 [ jobs o10] file _c_Lucene90_0.pos: done
copying [9.6 KB, 0.073ms]
1> 19.989s 12.2s: syncing R2 [ jobs o10] file _c.fdx: start copying to tmp
file _c.fdx_copy_4c.tmp length=79
1> 19.989s 12.2s: syncing R2 [ jobs o10] file _c.fdx: done copying [79 b,
0.047ms]
1> 19.989s 12.2s: syncing R2 [ jobs o10] file _a.tvm: start copying to tmp
file _a.tvm_copy_4d.tmp length=162
1> 19.989s 12.2s: syncing R2 [ jobs o10] file _a.tvm: done copying [162 b,
0.044ms]
1> 19.989s 8.5s: syncing R3 [ jobs o7] file _a.nvm: start copying to tmp
file _a.nvm_copy_2t.tmp length=139
1> 19.990s 18.1s: idle P1 [ send-R2-38] top: done fetch files for R2:
sent 33 files; sent 965778 bytes
1> 19.990s 18.1s: idle P1 [ send-R2-38] top: fetch: now release CopyState
1> 19.990s 8.5s: syncing R3 [ jobs o7] file _a.nvm: done copying [139 b,
0.084ms]
1> 19.990s 8.5s: syncing R3 [ jobs o7] file _a.fnm: start copying to tmp
file _a.fnm_copy_2u.tmp length=535
1> 19.990s 8.5s: syncing R3 [ jobs o7] file _a.fnm: done copying [535 b,
0.059ms]
1> 19.990s 12.2s: syncing R2 [ jobs o10] file _c_Lucene90_0.tim: start
copying to tmp file _c_Lucene90_0.tim_copy_4e.tmp length=40122
1> 19.990s 12.2s: syncing R2 [ jobs o10] file _c_Lucene90_0.tim: done
copying [39.2 KB, 0.181ms]
1> 19.991s 12.2s: syncing R2 [ jobs o10] file _a.nvd: start copying to tmp
file _a.nvd_copy_4f.tmp length=1837
1> 19.991s 12.2s: syncing R2 [ jobs o10] file _a.nvd: done copying [1.8
KB, 0.051ms]
1> 19.991s 12.2s: syncing R2 [ jobs o10] file _c_Lucene90_0.tmd: start
copying to tmp file _c_Lucene90_0.tmd_copy_4g.tmp length=495
1> 19.991s 12.2s: syncing R2 [ jobs o10] file _c_Lucene90_0.tmd: done
copying [495 b, 0.042ms]
1> 19.991s 8.5s: syncing R3 [ jobs o7] file _c_Lucene90_0.pos: start
copying to tmp file _c_Lucene90_0.pos_copy_2v.tmp length=9785
1> 19.991s 8.5s: syncing R3 [ jobs o7] file _c_Lucene90_0.pos: done
copying [9.6 KB, 0.095ms]
1> 19.991s 12.2s: syncing R2 [ jobs o10] file _a.tvd: start copying to tmp
file _a.tvd_copy_4h.tmp length=299864
1> 19.992s 8.5s: syncing R3 [ jobs o7] file _c.fdx: start copying to tmp
file _c.fdx_copy_2w.tmp length=79
1> 19.992s 8.5s: syncing R3 [ jobs o7] file _c.fdx: done copying [79 b,
0.071ms]
1> 19.992s 8.5s: syncing R3 [ jobs o7] file _a.tvm: start copying to tmp
file _a.tvm_copy_2x.tmp length=162
1> 19.993s 8.5s: syncing R3 [ jobs o7] file _a.tvm: done copying [162 b,
0.093ms]
1> 19.993s 8.5s: syncing R3 [ jobs o7] file _c_Lucene90_0.tim: start
copying to tmp file _c_Lucene90_0.tim_copy_2y.tmp length=40122
1> 19.993s 8.5s: syncing R3 [ jobs o7] file _c_Lucene90_0.tim: done
copying [39.2 KB, 0.133ms]
1> 19.993s 8.5s: syncing R3 [ jobs o7] file _a.nvd: start copying to tmp
file _a.nvd_copy_2z.tmp length=1837
1> 19.993s 8.5s: syncing R3 [ jobs o7] file _a.nvd: done copying [1.8
KB, 0.067ms]
1> 19.993s 8.5s: syncing R3 [ jobs o7] file _c_Lucene90_0.tmd: start
copying to tmp file _c_Lucene90_0.tmd_copy_30.tmp length=495
1> 19.994s 8.5s: syncing R3 [ jobs o7] file _c_Lucene90_0.tmd: done
copying [495 b, 0.126ms]
1> 19.994s 8.5s: syncing R3 [ jobs o7] file _a.tvd: start copying to tmp
file _a.tvd_copy_31.tmp length=299864
1> 20.000s 12.2s: syncing R2 [ jobs o10] file _a.tvd: done copying [292.8
KB, 8.834ms]
1> 20.000s 12.2s: syncing R2 [ jobs o10] file _a_Lucene90_0.tip: start
copying to tmp file _a_Lucene90_0.tip_copy_4i.tmp length=4907
1> 20.001s 12.2s: syncing R2 [ jobs o10] file _a_Lucene90_0.tip: done
copying [4.8 KB, 0.084ms]
1> 20.001s 12.2s: syncing R2 [ jobs o10] file _a.fdt: start copying to tmp
file _a.fdt_copy_4j.tmp length=224839
1> 20.013s 12.2s: syncing R2 [ jobs o10] file _a.fdt: done copying [219.6
KB, 12.678ms]
1> 20.014s 12.2s: syncing R2 [ jobs o10] file _a.tvx: start copying to tmp
file _a.tvx_copy_4k.tmp length=146
1> 20.014s 12.2s: syncing R2 [ jobs o10] file _a.tvx: done copying [146 b,
0.061ms]
1> 20.014s 12.2s: syncing R2 [ jobs o10] file _b_2.liv: start copying to
tmp file _b_2.liv_copy_4l.tmp length=83
1> 20.014s 12.2s: syncing R2 [ jobs o10] file _b_2.liv: done copying [83
b, 0.051ms]
1> 20.014s 12.2s: syncing R2 [ jobs o10] file _a.fdx: start copying to tmp
file _a.fdx_copy_4m.tmp length=157
1> 20.014s 12.2s: syncing R2 [ jobs o10] file _a.fdx: done copying [157 b,
0.046ms]
1> 20.015s 12.2s: syncing R2 [ jobs o10] file _a_Lucene90_0.tim: start
copying to tmp file _a_Lucene90_0.tim_copy_4n.tmp length=218050
1> 20.027s 12.3s: syncing R2 [ jobs o10] file _a_Lucene90_0.tim: done
copying [212.9 KB, 12.664ms]
1> 20.028s 12.3s: syncing R2 [ jobs o10] file _a.fdm: start copying to tmp
file _a.fdm_copy_4o.tmp length=156
1> 20.028s 12.3s: syncing R2 [ jobs o10] file _a.fdm: done copying [156 b,
0.059ms]
1> 20.028s 12.3s: syncing R2 [ jobs o10] file _a_Lucene90_0.tmd: start
copying to tmp file _a_Lucene90_0.tmd_copy_4p.tmp length=526
1> 20.028s 12.3s: syncing R2 [ jobs o10] file _a_Lucene90_0.tmd: done
copying [526 b, 0.051ms]
1> 20.028s 12.3s: syncing R2 [ jobs o10] file _c.nvd: start copying to tmp
file _c.nvd_copy_4q.tmp length=295
1> 20.028s 12.3s: syncing R2 [ jobs o10] file _c.nvd: done copying [295 b,
0.056ms]
1> 20.028s 12.3s: syncing R2 [ jobs o10] file _8_3.liv: start copying to
tmp file _8_3.liv_copy_4r.tmp length=91
1> 20.028s 12.3s: syncing R2 [ jobs o10] file _8_3.liv: done copying [91
b, 0.047ms]
1> 20.029s 12.3s: syncing R2 [ jobs o10] file _c_Lucene90_0.doc: start
copying to tmp file _c_Lucene90_0.doc_copy_4s.tmp length=1803
1> 20.029s 12.3s: syncing R2 [ jobs o10] file _c_Lucene90_0.doc: done
copying [1.8 KB, 0.055ms]
1> 20.029s 12.3s: syncing R2 [ jobs o10] file _a_Lucene90_0.pos: start
copying to tmp file _a_Lucene90_0.pos_copy_4t.tmp length=56037
1> 20.029s 12.3s: syncing R2 [ jobs o10] file _a_Lucene90_0.pos: done
copying [54.7 KB, 0.136ms]
1> 20.029s 12.3s: syncing R2 [ jobs o10] file _c.nvm: start copying to tmp
file _c.nvm_copy_4u.tmp length=139
1> 20.029s 12.3s: syncing R2 [ jobs o10] file _c.nvm: done copying [139 b,
0.046ms]
1> 20.029s 12.3s: syncing R2 [ jobs o10] file _c.tvd: start copying to tmp
file _c.tvd_copy_4v.tmp length=45794
1> 20.029s 12.3s: syncing R2 [ jobs o10] file _c.tvd: done copying [44.7
KB, 0.116ms]
1> 20.030s 12.3s: syncing R2 [ jobs o10] file _c.fnm: start copying to tmp
file _c.fnm_copy_4w.tmp length=535
1> 20.036s 8.6s: syncing R3 [ jobs o7] file _a.tvd: done copying [292.8
KB, 42.513ms]
1> 20.037s 8.6s: syncing R3 [ jobs o7] file _a_Lucene90_0.tip: start
copying to tmp file _a_Lucene90_0.tip_copy_32.tmp length=4907
1> 20.037s 8.6s: syncing R3 [ jobs o7] file _a_Lucene90_0.tip: done
copying [4.8 KB, 0.104ms]
1> 20.037s 8.6s: syncing R3 [ jobs o7] file _a.fdt: start copying to tmp
file _a.fdt_copy_33.tmp length=224839
1> 20.043s 12.3s: syncing R2 [ jobs o10] file _c.fnm: done copying [535 b,
13.569ms]
1> 20.043s 12.3s: syncing R2 [ jobs o10] file _a_2.liv: start copying to
tmp file _a_2.liv_copy_4x.tmp length=107
1> 20.044s 12.3s: syncing R2 [ jobs o10] file _a_2.liv: done copying [107
b, 0.091ms]
1> 20.044s 12.3s: syncing R2 [ jobs o10] top: finishNRTCopy: version=38
job=SimpleCopyJob(ord=10 NRT point sync version=38 highPriority=true files
count=39 bytesCopied=965778 (of 965778) filesCopied=33)
1> 20.044s 12.3s: idle R2 [ jobs o10] top: now clear curNRTCopy;
job=SimpleCopyJob(ord=10 NRT point sync version=38 highPriority=true files
count=39 bytesCopied=965778 (of 965778) filesCopied=33)
1> 20.044s 12.3s: idle R2 [ jobs o10] top: file copy done; took 77.1
msec to copy 965778 bytes; now rename 33 tmp files
1> 20.044s 12.3s: idle R2 [ jobs o10] rename file _c.fdm_copy_41.tmp to
_c.fdm
1> 20.044s 12.3s: idle R2 [ jobs o10] rename file
_a_Lucene90_0.doc_copy_42.tmp to _a_Lucene90_0.doc
1> 20.044s 12.3s: idle R2 [ jobs o10] rename file _a.si_copy_43.tmp to
_a.si
1> 20.044s 12.3s: idle R2 [ jobs o10] rename file _c.tvm_copy_44.tmp to
_c.tvm
1> 20.044s 12.3s: idle R2 [ jobs o10] rename file _c.si_copy_45.tmp to
_c.si
1> 20.044s 12.3s: idle R2 [ jobs o10] rename file _c.fdt_copy_46.tmp to
_c.fdt
1> 20.044s 12.3s: idle R2 [ jobs o10] rename file _c.tvx_copy_47.tmp to
_c.tvx
1> 20.044s 12.3s: idle R2 [ jobs o10] rename file
_c_Lucene90_0.tip_copy_48.tmp to _c_Lucene90_0.tip
1> 20.044s 12.3s: idle R2 [ jobs o10] rename file _a.nvm_copy_49.tmp to
_a.nvm
1> 20.044s 12.3s: idle R2 [ jobs o10] rename file _a.fnm_copy_4a.tmp to
_a.fnm
1> 20.044s 12.3s: idle R2 [ jobs o10] rename file
_c_Lucene90_0.pos_copy_4b.tmp to _c_Lucene90_0.pos
1> 20.044s 12.3s: idle R2 [ jobs o10] rename file _c.fdx_copy_4c.tmp to
_c.fdx
1> 20.044s 12.3s: idle R2 [ jobs o10] rename file _a.tvm_copy_4d.tmp to
_a.tvm
1> 20.045s 12.3s: idle R2 [ jobs o10] rename file
_c_Lucene90_0.tim_copy_4e.tmp to _c_Lucene90_0.tim
1> 20.045s 12.3s: idle R2 [ jobs o10] rename file _a.nvd_copy_4f.tmp to
_a.nvd
1> 20.045s 12.3s: idle R2 [ jobs o10] rename file
_c_Lucene90_0.tmd_copy_4g.tmp to _c_Lucene90_0.tmd
1> 20.045s 12.3s: idle R2 [ jobs o10] rename file _a.tvd_copy_4h.tmp to
_a.tvd
1> 20.045s 12.3s: idle R2 [ jobs o10] rename file
_a_Lucene90_0.tip_copy_4i.tmp to _a_Lucene90_0.tip
1> 20.045s 12.3s: idle R2 [ jobs o10] rename file _a.fdt_copy_4j.tmp to
_a.fdt
1> 20.045s 12.3s: idle R2 [ jobs o10] rename file _a.tvx_copy_4k.tmp to
_a.tvx
1> 20.045s 12.3s: idle R2 [ jobs o10] rename file _b_2.liv_copy_4l.tmp
to _b_2.liv
1> 20.045s 12.3s: idle R2 [ jobs o10] rename file _a.fdx_copy_4m.tmp to
_a.fdx
1> 20.045s 12.3s: idle R2 [ jobs o10] rename file
_a_Lucene90_0.tim_copy_4n.tmp to _a_Lucene90_0.tim
1> 20.045s 12.3s: idle R2 [ jobs o10] rename file _a.fdm_copy_4o.tmp to
_a.fdm
1> 20.045s 12.3s: idle R2 [ jobs o10] rename file
_a_Lucene90_0.tmd_copy_4p.tmp to _a_Lucene90_0.tmd
1> 20.045s 12.3s: idle R2 [ jobs o10] rename file _c.nvd_copy_4q.tmp to
_c.nvd
1> 20.045s 12.3s: idle R2 [ jobs o10] rename file _8_3.liv_copy_4r.tmp
to _8_3.liv
1> 20.045s 12.3s: idle R2 [ jobs o10] rename file
_c_Lucene90_0.doc_copy_4s.tmp to _c_Lucene90_0.doc
1> 20.045s 12.3s: idle R2 [ jobs o10] rename file
_a_Lucene90_0.pos_copy_4t.tmp to _a_Lucene90_0.pos
1> 20.046s 12.3s: idle R2 [ jobs o10] rename file _c.nvm_copy_4u.tmp to
_c.nvm
1> 20.046s 12.3s: idle R2 [ jobs o10] rename file _c.tvd_copy_4v.tmp to
_c.tvd
1> 20.046s 12.3s: idle R2 [ jobs o10] rename file _c.fnm_copy_4w.tmp to
_c.fnm
1> 20.046s 12.3s: idle R2 [ jobs o10] rename file _a_2.liv_copy_4x.tmp
to _a_2.liv
1> 20.047s 12.3s: idle R2 [ jobs o10] version=38 segments=segments_1:
_8(10.0.0):c251/25:[diagnostics={os=Linux, timestamp=1653199396000,
lucene.version=10.0.0, java.vm.version=17.0.1+12-LTS-39, java.version=17.0.1,
mergeMaxNumSegments=-1, os.version=4.15.0-147-generic, java.vendor=Oracle
Corporation, source=merge, os.arch=amd64, mergeFactor=3,
java.runtime.version=17.0.1+12-LTS-39}]:delGen=3 :id=cva6u9luajl9wwsjka944lwuy
_b(10.0.0):c144/14:[diagnostics={os=Linux, timestamp=1653199397423,
lucene.version=10.0.0, java.vm.version=17.0.1+12-LTS-39, java.version=17.0.1,
mergeMaxNumSegments=-1, os.version=4.15.0-147-generic, java.vendor=Oracle
Corporation, source=merge, os.arch=amd64, mergeFactor=3,
java.runtime.version=17.0.1+12-LTS-39}]:delGen=2 :id=cva6u9luajl9wwsjka944lwuz
_a(10.0.0):C326/8:[diagnostics={timestamp=1653199400523, os=Linux,
java.vendor=Oracle Corporation, os.version=4.15.0-147-generic,
java.runtime.version=17.0.1+12-LTS-39, os.arch=amd64, source=flush,
lucene.version=10.0.0, java.vm.version=17.0.1+12-LTS-39,
java.version=17.0.1}]:delGen=2 :id=cva6u9luajl9wwsjka944lwv0
_c(10.0.0):C39:[diagnostics={timestamp=1653199402071, os=Linux,
java.vendor=Oracle Corporation, os.version=4.15.0-147-generic,
java.runtime.version=17.0.1+12-LTS-39, os.arch=amd64, source=flush,
lucene.version=10.0.0, java.vm.version=17.0.1+12-LTS-39, java.version=17.0.1}]
:id=cva6u9luajl9wwsjka944lwux
1> 20.047s 12.3s: idle R2 [ jobs o10] mgr.setCurrentInfos: carry over
infos gen=segments
1> 20.051s 12.3s: idle R2 [ jobs o10] refreshed to version=38
r=StandardDirectoryReader(segments:38
_8(10.0.0):c251/25:[diagnostics={os=Linux, timestamp=1653199396000,
lucene.version=10.0.0, java.vm.version=17.0.1+12-LTS-39, java.version=17.0.1,
mergeMaxNumSegments=-1, os.version=4.15.0-147-generic, java.vendor=Oracle
Corporation, source=merge, os.arch=amd64, mergeFactor=3,
java.runtime.version=17.0.1+12-LTS-39}]:delGen=3 :id=cva6u9luajl9wwsjka944lwuy
_b(10.0.0):c144/14:[diagnostics={os=Linux, timestamp=1653199397423,
lucene.version=10.0.0, java.vm.version=17.0.1+12-LTS-39, java.version=17.0.1,
mergeMaxNumSegments=-1, os.version=4.15.0-147-generic, java.vendor=Oracle
Corporation, source=merge, os.arch=amd64, mergeFactor=3,
java.runtime.version=17.0.1+12-LTS-39}]:delGen=2 :id=cva6u9luajl9wwsjka944lwuz
_a(10.0.0):C326/8:[diagnostics={timestamp=1653199400523, os=Linux,
java.vendor=Oracle Corporation, os.version=4.15.0-147-generic,
java.runtime.version=17.0.1+12-LTS-39, os.arch=amd64, source=flush,
lucene.version=10.0.0, java.vm.version=17.0.1+12-LTS-39,
java.version=17.0.1}]:delGen=2 :id=cva6u9luajl9wwsjka944lwv0
_c(10.0.0):C39:[diagnostics={timestamp=1653199402071, os=Linux,
java.vendor=Oracle Corporation, os.version=4.15.0-147-generic,
java.runtime.version=17.0.1+12-LTS-39, os.arch=amd64, source=flush,
lucene.version=10.0.0, java.vm.version=17.0.1+12-LTS-39, java.version=17.0.1}]
:id=cva6u9luajl9wwsjka944lwux)
1> 20.052s 12.3s: idle R2 [ jobs o10] top: incRef newNRTFiles=[_b.cfe,
_c.fdm, _a_Lucene90_0.doc, _a.si, _c.tvm, _c.si, _c.fdt, _c.tvx,
_c_Lucene90_0.tip, _a.nvm, _a.fnm, _c_Lucene90_0.pos, _c.fdx, _a.tvm,
_c_Lucene90_0.tim, _a.nvd, _c_Lucene90_0.tmd, _a.tvd, _a_Lucene90_0.tip,
_a.fdt, _a.tvx, _b_2.liv, _a.fdx, _a_Lucene90_0.tim, _b.si, _8.cfe, _a.fdm,
_b.cfs, _a_Lucene90_0.tmd, _c.nvd, _8_3.liv, _c_Lucene90_0.doc,
_a_Lucene90_0.pos, _8.cfs, _c.nvm, _c.tvd, _c.fnm, _8.si, _a_2.liv]
1> 20.056s 12.3s: idle R2 [ jobs o10] top: after remove from pending
merges pendingMergeFiles=[]
1> 20.056s 12.3s: idle R2 [ jobs o10] top: decRef lastNRTFiles=[_7.fdm,
_4_3.liv, _8_1.liv, _4_Lucene90_0.pos, _9.fdx, _9.fdt, _7.si,
_9_Lucene90_0.pos, _9.tvm, _9.nvm, _9.fnm, _4_Lucene90_0.doc,
_7_Lucene90_0.pos, _4.fdt, _4_Lucene90_0.tip, _9.tvd, _4.fdm, _7.tvx, _9.nvd,
_4_Lucene90_0.tmd, _9_Lucene90_0.tmd, _4_Lucene90_0.tim, _4.fdx, _7.tvm,
_9_Lucene90_0.tim, _9.tvx, _7.nvm, _7.tvd, _7.fnm, _9_Lucene90_0.tip, _7.nvd,
_7_Lucene90_0.doc, _9.si, _4.nvm, _4.fnm, _4.tvd, _7_Lucene90_0.tmd, _8.cfe,
_4.nvd, _9.fdm, _4.tvx, _9_Lucene90_0.doc, _7.fdt, _7_Lucene90_0.tip, _8.cfs,
_4.si, _7_Lucene90_0.tim, _4.tvm, _7.fdx, _8.si]
1> 20.056s 12.3s: idle R2 [ jobs o10] now delete 47 files: [_7.fdm,
_4_3.liv, _4_Lucene90_0.tmd, _8_1.liv, _9_Lucene90_0.tmd, _4_Lucene90_0.pos,
_4_Lucene90_0.tim, _4.fdx, _7.tvm, _9.fdx, _9_Lucene90_0.tim, _9.fdt, _9.tvx,
_7.nvm, _7.tvd, _7.fnm, _9_Lucene90_0.tip, _7.si, _7.nvd, _7_Lucene90_0.doc,
_9_Lucene90_0.pos, _9.si, _9.tvm, _4.nvm, _4.fnm, _4.tvd, _9.nvm, _9.fnm,
_7_Lucene90_0.tmd, _4.nvd, _9.fdm, _4_Lucene90_0.doc, _7_Lucene90_0.pos,
_4.fdt, _4.tvx, _9_Lucene90_0.doc, _4_Lucene90_0.tip, _9.tvd, _4.fdm, _7.fdt,
_7_Lucene90_0.tip, _7.tvx, _4.si, _7_Lucene90_0.tim, _4.tvm, _7.fdx, _9.nvd]
1> 20.056s 12.3s: idle R2 [ jobs o10] file _7.fdm: now delete
1> 20.057s 12.3s: idle R2 [ jobs o10] file _4_3.liv: now delete
1> 20.057s 12.3s: idle R2 [ jobs o10] file _4_Lucene90_0.tmd: now delete
1> 20.057s 12.3s: idle R2 [ jobs o10] file _8_1.liv: now delete
1> 20.058s 12.3s: idle R2 [ jobs o10] file _9_Lucene90_0.tmd: now delete
1> 20.059s 12.3s: idle R2 [ jobs o10] file _4_Lucene90_0.pos: now delete
1> 20.061s : parent [ start R0] .
1> 20.061s : parent [ start R0] PORT: 43399
1> 20.065s 8.6s: syncing R3 [ jobs o7] file _a.fdt: done copying [219.6
KB, 27.457ms]
1> 20.065s 8.6s: syncing R3 [ jobs o7] file _a.tvx: start copying to tmp
file _a.tvx_copy_34.tmp length=146
1> 20.065s 8.6s: syncing R3 [ jobs o7] file _a.tvx: done copying [146 b,
0.091ms]
1> 20.065s 8.6s: syncing R3 [ jobs o7] file _b_2.liv: start copying to
tmp file _b_2.liv_copy_35.tmp length=83
1> 20.065s 8.6s: syncing R3 [ jobs o7] file _b_2.liv: done copying [83
b, 0.057ms]
1> 20.066s 12.3s: idle R2 [ jobs o10] file _4_Lucene90_0.tim: now delete
1> 20.066s 12.3s: idle R2 [ jobs o10] file _4.fdx: now delete
1> 20.066s 12.3s: idle R2 [ jobs o10] file _7.tvm: now delete
1> 20.067s 12.3s: idle R2 [ jobs o10] file _9.fdx: now delete
1> 20.067s 12.3s: idle R2 [ jobs o10] file _9_Lucene90_0.tim: now delete
1> 20.067s 12.3s: idle R2 [ jobs o10] file _9.fdt: now delete
1> 20.068s 12.3s: idle R2 [ jobs o10] file _9.tvx: now delete
1> 20.066s 8.6s: syncing R3 [ jobs o7] file _a.fdx: start copying to tmp
file _a.fdx_copy_36.tmp length=157
1> 20.066s 8.6s: syncing R3 [ jobs o7] file _a.fdx: done copying [157 b,
0.068ms]
1> 20.066s 8.6s: syncing R3 [ jobs o7] file _a_Lucene90_0.tim: start
copying to tmp file _a_Lucene90_0.tim_copy_37.tmp length=218050
1> 20.069s 12.3s: idle R2 [ jobs o10] file _7.nvm: now delete
1> 20.069s 12.3s: idle R2 [ jobs o10] file _7.tvd: now delete
1> 20.069s 12.3s: idle R2 [ jobs o10] file _7.fnm: now delete
1> 20.069s 12.3s: idle R2 [ jobs o10] file _9_Lucene90_0.tip: now delete
1> 20.069s 12.3s: idle R2 [ jobs o10] file _7.si: now delete
1> 20.069s 12.3s: idle R2 [ jobs o10] file _7.nvd: now delete
1> 20.071s 12.3s: idle R2 [ jobs o10] file _7_Lucene90_0.doc: now delete
1> 20.072s 12.3s: idle R2 [ jobs o10] file _9_Lucene90_0.pos: now delete
1> 20.072s 12.3s: idle R2 [ jobs o10] file _9.si: now delete
1> 20.072s 12.3s: idle R2 [ jobs o10] file _9.tvm: now delete
1> 20.072s 12.3s: idle R2 [ jobs o10] file _4.nvm: now delete
1> 20.072s 12.3s: idle R2 [ jobs o10] file _4.fnm: now delete
1> 20.072s 12.3s: idle R2 [ jobs o10] file _4.tvd: now delete
1> 20.073s 12.3s: idle R2 [ jobs o10] file _9.nvm: now delete
1> 20.073s 12.3s: idle R2 [ jobs o10] file _9.fnm: now delete
1> 20.073s 12.3s: idle R2 [ jobs o10] file _7_Lucene90_0.tmd: now delete
1> 20.076s 12.3s: idle R2 [ jobs o10] file _4.nvd: now delete
1> 20.076s 12.3s: idle R2 [ jobs o10] file _9.fdm: now delete
1> 20.076s 12.3s: idle R2 [ jobs o10] file _4_Lucene90_0.doc: now delete
1> 20.076s 12.3s: idle R2 [ jobs o10] file _7_Lucene90_0.pos: now delete
1> 20.076s 12.3s: idle R2 [ jobs o10] file _4.fdt: now delete
1> 20.077s 12.3s: idle R2 [ jobs o10] file _4.tvx: now delete
1> 20.077s 12.3s: idle R2 [ jobs o10] file _9_Lucene90_0.doc: now delete
1> 20.078s 12.3s: idle R2 [ jobs o10] file _4_Lucene90_0.tip: now delete
1> 20.078s 12.3s: idle R2 [ jobs o10] file _9.tvd: now delete
1> 20.078s 12.3s: idle R2 [ jobs o10] file _4.fdm: now delete
1> 20.078s 12.3s: idle R2 [ jobs o10] file _7.fdt: now delete
1> 20.078s 12.3s: idle R2 [ jobs o10] file _7_Lucene90_0.tip: now delete
1> 20.078s 12.3s: idle R2 [ jobs o10] file _7.tvx: now delete
1> 20.078s 12.3s: idle R2 [ jobs o10] file _4.si: now delete
1> 20.079s 12.3s: idle R2 [ jobs o10] file _7_Lucene90_0.tim: now delete
1> 20.079s 12.3s: idle R2 [ jobs o10] file _4.tvm: now delete
1> 20.079s 12.3s: idle R2 [ jobs o10] file _7.fdx: now delete
1> 20.079s 12.3s: idle R2 [ jobs o10] file _9.nvd: now delete
1> 20.079s 12.3s: idle R2 [ jobs o10] top: set
lastNRTFiles=[_a_Lucene90_0.doc, _a.si, _c.tvm, _c.tvx, _c_Lucene90_0.tip,
_a.nvm, _a.fnm, _a.tvm, _c_Lucene90_0.tim, _a.nvd, _c_Lucene90_0.tmd, _a.tvd,
_a.tvx, _b_2.liv, _b.si, _c.nvd, _8_3.liv, _a_Lucene90_0.pos, _c.nvm, _c.tvd,
_c.fnm, _b.cfe, _c.fdm, _c.si, _c.fdt, _c_Lucene90_0.pos, _c.fdx,
_a_Lucene90_0.tip, _a.fdt, _a.fdx, _a_Lucene90_0.tim, _8.cfe, _a.fdm, _b.cfs,
_a_Lucene90_0.tmd, _c_Lucene90_0.doc, _8.cfs, _8.si, _a_2.liv]
1> 20.079s 12.3s: idle R2 [ jobs o10] now remove-if-not-ref'd completed
merge files: [_b.cfe, _b.cfs, _b.si]
1> 20.080s 12.3s: idle R2 [ jobs o10] top: done sync: took 0.126s for
943.1 KB, opened NRT reader version=38 markerCount=82
1> 20.093s 8.6s: syncing R3 [ jobs o7] file _a_Lucene90_0.tim: done
copying [212.9 KB, 26.945ms]
1> 20.093s 8.6s: syncing R3 [ jobs o7] file _a.fdm: start copying to tmp
file _a.fdm_copy_38.tmp length=156
1> 20.093s 8.6s: syncing R3 [ jobs o7] file _a.fdm: done copying [156 b,
0.079ms]
1> 20.094s 8.6s: syncing R3 [ jobs o7] file _a_Lucene90_0.tmd: start
copying to tmp file _a_Lucene90_0.tmd_copy_39.tmp length=526
1> 20.094s 8.6s: syncing R3 [ jobs o7] file _a_Lucene90_0.tmd: done
copying [526 b, 0.062ms]
1> 20.094s 8.6s: syncing R3 [ jobs o7] file _c.nvd: start copying to tmp
file _c.nvd_copy_3a.tmp length=295
1> 20.094s 8.6s: syncing R3 [ jobs o7] file _c.nvd: done copying [295 b,
0.055ms]
1> 20.094s 8.6s: syncing R3 [ jobs o7] file _8_3.liv: start copying to
tmp file _8_3.liv_copy_3b.tmp length=91
1> 20.094s 8.6s: syncing R3 [ jobs o7] file _8_3.liv: done copying [91
b, 0.069ms]
1> 20.094s 8.6s: syncing R3 [ jobs o7] file _c_Lucene90_0.doc: start
copying to tmp file _c_Lucene90_0.doc_copy_3c.tmp length=1803
1> 20.094s 8.6s: syncing R3 [ jobs o7] file _c_Lucene90_0.doc: done
copying [1.8 KB, 0.082ms]
1> 20.095s 8.6s: syncing R3 [ jobs o7] file _a_Lucene90_0.pos: start
copying to tmp file _a_Lucene90_0.pos_copy_3d.tmp length=56037
1> 20.100s 8.6s: syncing R3 [ jobs o7] file _a_Lucene90_0.pos: done
copying [54.7 KB, 5.341ms]
1> 20.101s 8.6s: syncing R3 [ jobs o7] file _c.nvm: start copying to tmp
file _c.nvm_copy_3e.tmp length=139
1> 20.101s 8.6s: syncing R3 [ jobs o7] file _c.nvm: done copying [139 b,
0.064ms]
1> 20.101s 8.6s: syncing R3 [ jobs o7] file _c.tvd: start copying to tmp
file _c.tvd_copy_3f.tmp length=45794
1> 20.106s 8.6s: syncing R3 [ jobs o7] file _c.tvd: done copying [44.7
KB, 5.326ms]
1> 20.107s 8.6s: syncing R3 [ jobs o7] file _c.fnm: start copying to tmp
file _c.fnm_copy_3g.tmp length=535
1> 20.107s 8.6s: syncing R3 [ jobs o7] file _c.fnm: done copying [535 b,
0.062ms]
1> 20.107s 8.6s: syncing R3 [ jobs o7] file _a_2.liv: start copying to
tmp file _a_2.liv_copy_3h.tmp length=107
1> 20.107s 8.6s: syncing R3 [ jobs o7] file _a_2.liv: done copying [107
b, 0.061ms]
1> 20.107s 8.6s: syncing R3 [ jobs o7] top: finishNRTCopy: version=38
job=SimpleCopyJob(ord=7 NRT point sync version=38 highPriority=true files
count=39 bytesCopied=965778 (of 965778) filesCopied=33)
1> 20.107s 8.6s: idle R3 [ jobs o7] top: now clear curNRTCopy;
job=SimpleCopyJob(ord=7 NRT point sync version=38 highPriority=true files
count=39 bytesCopied=965778 (of 965778) filesCopied=33)
1> 20.107s 8.6s: idle R3 [ jobs o7] top: file copy done; took 164.0
msec to copy 965778 bytes; now rename 33 tmp files
1> 20.107s 8.6s: idle R3 [ jobs o7] rename file _c.fdm_copy_2l.tmp to
_c.fdm
1> 20.107s 8.6s: idle R3 [ jobs o7] rename file
_a_Lucene90_0.doc_copy_2m.tmp to _a_Lucene90_0.doc
1> 20.107s 8.6s: idle R3 [ jobs o7] rename file _a.si_copy_2n.tmp to
_a.si
1> 20.108s 8.6s: idle R3 [ jobs o7] rename file _c.tvm_copy_2o.tmp to
_c.tvm
1> 20.108s 8.6s: idle R3 [ jobs o7] rename file _c.si_copy_2p.tmp to
_c.si
1> 20.108s 8.6s: idle R3 [ jobs o7] rename file _c.fdt_copy_2q.tmp to
_c.fdt
1> 20.108s 8.6s: idle R3 [ jobs o7] rename file _c.tvx_copy_2r.tmp to
_c.tvx
1> 20.108s 8.6s: idle R3 [ jobs o7] rename file
_c_Lucene90_0.tip_copy_2s.tmp to _c_Lucene90_0.tip
1> 20.108s 8.6s: idle R3 [ jobs o7] rename file _a.nvm_copy_2t.tmp to
_a.nvm
1> 20.108s 8.6s: idle R3 [ jobs o7] rename file _a.fnm_copy_2u.tmp to
_a.fnm
1> 20.108s 8.6s: idle R3 [ jobs o7] rename file
_c_Lucene90_0.pos_copy_2v.tmp to _c_Lucene90_0.pos
1> 20.108s 8.6s: idle R3 [ jobs o7] rename file _c.fdx_copy_2w.tmp to
_c.fdx
1> 20.108s 8.6s: idle R3 [ jobs o7] rename file _a.tvm_copy_2x.tmp to
_a.tvm
1> 20.108s 8.6s: idle R3 [ jobs o7] rename file
_c_Lucene90_0.tim_copy_2y.tmp to _c_Lucene90_0.tim
1> 20.108s 8.6s: idle R3 [ jobs o7] rename file _a.nvd_copy_2z.tmp to
_a.nvd
1> 20.108s 8.6s: idle R3 [ jobs o7] rename file
_c_Lucene90_0.tmd_copy_30.tmp to _c_Lucene90_0.tmd
1> 20.108s 8.6s: idle R3 [ jobs o7] rename file _a.tvd_copy_31.tmp to
_a.tvd
1> 20.108s 8.6s: idle R3 [ jobs o7] rename file
_a_Lucene90_0.tip_copy_32.tmp to _a_Lucene90_0.tip
1> 20.108s 8.6s: idle R3 [ jobs o7] rename file _a.fdt_copy_33.tmp to
_a.fdt
1> 20.109s 8.6s: idle R3 [ jobs o7] rename file _a.tvx_copy_34.tmp to
_a.tvx
1> 20.109s 8.6s: idle R3 [ jobs o7] rename file _b_2.liv_copy_35.tmp
to _b_2.liv
1> 20.109s 8.6s: idle R3 [ jobs o7] rename file _a.fdx_copy_36.tmp to
_a.fdx
1> 20.109s 8.6s: idle R3 [ jobs o7] rename file
_a_Lucene90_0.tim_copy_37.tmp to _a_Lucene90_0.tim
1> 20.109s 8.6s: idle R3 [ jobs o7] rename file _a.fdm_copy_38.tmp to
_a.fdm
1> 20.109s 8.6s: idle R3 [ jobs o7] rename file
_a_Lucene90_0.tmd_copy_39.tmp to _a_Lucene90_0.tmd
1> 20.109s 8.6s: idle R3 [ jobs o7] rename file _c.nvd_copy_3a.tmp to
_c.nvd
1> 20.109s 8.6s: idle R3 [ jobs o7] rename file _8_3.liv_copy_3b.tmp
to _8_3.liv
1> 20.109s 8.6s: idle R3 [ jobs o7] rename file
_c_Lucene90_0.doc_copy_3c.tmp to _c_Lucene90_0.doc
1> 20.110s 8.6s: idle R3 [ jobs o7] rename file
_a_Lucene90_0.pos_copy_3d.tmp to _a_Lucene90_0.pos
1> 20.110s 8.6s: idle R3 [ jobs o7] rename file _c.nvm_copy_3e.tmp to
_c.nvm
1> 20.110s 8.6s: idle R3 [ jobs o7] rename file _c.tvd_copy_3f.tmp to
_c.tvd
1> 20.110s 8.6s: idle R3 [ jobs o7] rename file _c.fnm_copy_3g.tmp to
_c.fnm
1> 20.110s 8.6s: idle R3 [ jobs o7] rename file _a_2.liv_copy_3h.tmp
to _a_2.liv
1> 20.114s 8.6s: idle R3 [ jobs o7] version=38 segments=segments_1:
_8(10.0.0):c251/25:[diagnostics={java.runtime.version=17.0.1+12-LTS-39,
mergeFactor=3, os.arch=amd64, source=merge, java.vendor=Oracle Corporation,
os.version=4.15.0-147-generic, mergeMaxNumSegments=-1, java.version=17.0.1,
java.vm.version=17.0.1+12-LTS-39, lucene.version=10.0.0,
timestamp=1653199396000, os=Linux}]:delGen=3 :id=cva6u9luajl9wwsjka944lwuy
_b(10.0.0):c144/14:[diagnostics={java.runtime.version=17.0.1+12-LTS-39,
mergeFactor=3, os.arch=amd64, source=merge, java.vendor=Oracle Corporation,
os.version=4.15.0-147-generic, mergeMaxNumSegments=-1, java.version=17.0.1,
java.vm.version=17.0.1+12-LTS-39, lucene.version=10.0.0,
timestamp=1653199397423, os=Linux}]:delGen=2 :id=cva6u9luajl9wwsjka944lwuz
_a(10.0.0):C326/8:[diagnostics={java.vm.version=17.0.1+12-LTS-39,
lucene.version=10.0.0, source=flush, os.arch=amd64,
java.runtime.version=17.0.1+12-LTS-39, os.version=4.15.0-147-generic,
java.vendor=Oracle Corporation, os=Linux, timestamp=1653199400523,
java.version=17.0.1}]:delGen=2 :id=cva6u9luajl9wwsjka944lwv0
_c(10.0.0):C39:[diagnostics={java.vm.version=17.0.1+12-LTS-39,
lucene.version=10.0.0, source=flush, os.arch=amd64,
java.runtime.version=17.0.1+12-LTS-39, os.version=4.15.0-147-generic,
java.vendor=Oracle Corporation, os=Linux, timestamp=1653199402071,
java.version=17.0.1}] :id=cva6u9luajl9wwsjka944lwux
1> 20.115s 8.7s: idle R3 [ jobs o7] mgr.setCurrentInfos: carry over
infos gen=segments
1> 20.100s 0.1s: N0 [main child 0] top: corrupt unknown files
1> 20.126s : parent [ main] top: now flush primary; at least
marker count=82
1> 20.133s 0.1s: idle R0 [main child 0] top: init replica
dir=MockDirectoryWrapper(NIOFSDirectory@/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-NightlyTests-main/checkout/lucene/replicator/build/tmp/tests-tmp/lucene.replicator.nrt.TestStressNRTReplication_9411BAC8B658A371-001/index0-001
lockFactory=org.apache.lucene.store.NativeFSLockFactory@35196f0)
1> 20.136s 8.7s: idle R3 [ jobs o7] refreshed to version=38
r=StandardDirectoryReader(segments:38
_8(10.0.0):c251/25:[diagnostics={java.runtime.version=17.0.1+12-LTS-39,
mergeFactor=3, os.arch=amd64, source=merge, java.vendor=Oracle Corporation,
os.version=4.15.0-147-generic, mergeMaxNumSegments=-1, java.version=17.0.1,
java.vm.version=17.0.1+12-LTS-39, lucene.version=10.0.0,
timestamp=1653199396000, os=Linux}]:delGen=3 :id=cva6u9luajl9wwsjka944lwuy
_b(10.0.0):c144/14:[diagnostics={java.runtime.version=17.0.1+12-LTS-39,
mergeFactor=3, os.arch=amd64, source=merge, java.vendor=Oracle Corporation,
os.version=4.15.0-147-generic, mergeMaxNumSegments=-1, java.version=17.0.1,
java.vm.version=17.0.1+12-LTS-39, lucene.version=10.0.0,
timestamp=1653199397423, os=Linux}]:delGen=2 :id=cva6u9luajl9wwsjka944lwuz
_a(10.0.0):C326/8:[diagnostics={java.vm.version=17.0.1+12-LTS-39,
lucene.version=10.0.0, source=flush, os.arch=amd64,
java.runtime.version=17.0.1+12-LTS-39, os.version=4.15.0-147-generic,
java.vendor=Oracle Corporation, os=Linux, timestamp=1653199400523,
java.version=17.0.1}]:delGen=2 :id=cva6u9luajl9wwsjka944lwv0
_c(10.0.0):C39:[diagnostics={java.vm.version=17.0.1+12-LTS-39,
lucene.version=10.0.0, source=flush, os.arch=amd64,
java.runtime.version=17.0.1+12-LTS-39, os.version=4.15.0-147-generic,
java.vendor=Oracle Corporation, os=Linux, timestamp=1653199402071,
java.version=17.0.1}] :id=cva6u9luajl9wwsjka944lwux)
1> 20.137s 18.3s: idle P1 [ flush] now flush; 2 replicas
1> 20.137s 18.3s: idle P1 [ flush] top: now flushAndRefresh
1> 20.141s 8.7s: idle R3 [ jobs o7] top: incRef newNRTFiles=[_b.cfe,
_c.fdm, _a_Lucene90_0.doc, _a.si, _c.tvm, _c.si, _c.fdt, _c.tvx,
_c_Lucene90_0.tip, _a.nvm, _a.fnm, _c_Lucene90_0.pos, _c.fdx, _a.tvm,
_c_Lucene90_0.tim, _a.nvd, _c_Lucene90_0.tmd, _a.tvd, _a_Lucene90_0.tip,
_a.fdt, _a.tvx, _b_2.liv, _a.fdx, _a_Lucene90_0.tim, _b.si, _8.cfe, _a.fdm,
_b.cfs, _a_Lucene90_0.tmd, _c.nvd, _8_3.liv, _c_Lucene90_0.doc,
_a_Lucene90_0.pos, _8.cfs, _c.nvm, _c.tvd, _c.fnm, _8.si, _a_2.liv]
1> 20.138s 18.3s: idle P1 [ flush] top: switch to infos=segments_1:
_8(10.0.0):c251/25:[diagnostics={os.arch=amd64, source=merge,
java.vendor=Oracle Corporation, os.version=4.15.0-147-generic,
mergeMaxNumSegments=-1, java.version=17.0.1, java.vm.version=17.0.1+12-LTS-39,
lucene.version=10.0.0, timestamp=1653199396000, os=Linux,
java.runtime.version=17.0.1+12-LTS-39, mergeFactor=3}]:delGen=3
:id=cva6u9luajl9wwsjka944lwuy _b(10.0.0):c144/14:[diagnostics={os.arch=amd64,
source=merge, java.vendor=Oracle Corporation, os.version=4.15.0-147-generic,
mergeMaxNumSegments=-1, java.version=17.0.1, java.vm.version=17.0.1+12-LTS-39,
lucene.version=10.0.0, timestamp=1653199397423, os=Linux,
java.runtime.version=17.0.1+12-LTS-39, mergeFactor=3}]:delGen=2
:id=cva6u9luajl9wwsjka944lwuz
_a(10.0.0):C326/8:[diagnostics={lucene.version=10.0.0, source=flush,
os.arch=amd64, java.runtime.version=17.0.1+12-LTS-39,
os.version=4.15.0-147-generic, os=Linux, java.vendor=Oracle Corporation,
timestamp=1653199400523, java.version=17.0.1,
java.vm.version=17.0.1+12-LTS-39}]:delGen=2 :id=cva6u9luajl9wwsjka944lwv0
_c(10.0.0):C39:[diagnostics={lucene.version=10.0.0, source=flush,
os.arch=amd64, java.runtime.version=17.0.1+12-LTS-39,
os.version=4.15.0-147-generic, os=Linux, java.vendor=Oracle Corporation,
timestamp=1653199402071, java.version=17.0.1,
java.vm.version=17.0.1+12-LTS-39}] :id=cva6u9luajl9wwsjka944lwux version=39
1> 20.143s 18.3s: idle P1 [ flush] top: set copyState primaryGen=0
version=39 files=[_b.cfe, _c.fdm, _a_Lucene90_0.doc, _a.si, _c.tvm, _c.si,
_c.fdt, _c.tvx, _c_Lucene90_0.tip, _a.nvm, _a.fnm, _c_Lucene90_0.pos, _c.fdx,
_a.tvm, _c_Lucene90_0.tim, _a.nvd, _c_Lucene90_0.tmd, _a.tvd,
_a_Lucene90_0.tip, _a.fdt, _a.tvx, _b_2.liv, _a.fdx, _a_Lucene90_0.tim, _b.si,
_8.cfe, _a.fdm, _b.cfs, _a_Lucene90_0.tmd, _c.nvd, _8_3.liv, _c_Lucene90_0.doc,
_a_Lucene90_0.pos, _8.cfs, _c.nvm, _c.tvd, _c.fnm, _8.si, _a_2.liv]
1> 20.143s 18.3s: idle P1 [ flush] top: opened NRT reader version=39
1> 20.144s 18.3s: idle P1 [ flush] flushAndRefresh: version=39
completedMergeFiles=[] finishedMergedFiles=[]
1> 20.144s 18.3s: idle P1 [ flush] send flushed version=39
1> 20.144s : parent [ main] top: flush finished with changed;
new primary version=39
1> 20.144s : parent [ main] top: record transLogLoc:
version=39 loc=920161
1> 20.145s : parent [ main] top: record marker count:
version=39 count=82
1> 20.145s : parent [ main] PG=0 3 (of 5) nodes running: P1
R2 R3
1> 20.145s 18.3s: idle P1 [ flush] send NEW_NRT_POINT to R2 at
tcpPort=45851
1> 20.145s 18.3s: idle P1 [ flush] send NEW_NRT_POINT to R3 at
tcpPort=44027
1> 20.147s 0.1s: init R0 [main child 0] top: will rate limit file fetch
to 11.84 MB/sec
1> 20.150s 0.1s: init R0 [main child 0] top: now start
1> 20.152s 8.7s: idle R3 [ recv-39] newNRTPoint primaryTCPPort=38439
version=39 newPrimaryGen=0
1> 20.154s : parent [Searcher node=P1 tcpPort=38439] top: searcher:
record search hitCount version=39 hitCount=440 node=P1 tcpPort=38439
1> 20.154s 0.1s: init R0 [main child 0] top: init: no segments in index
1> 20.154s 0.1s: init R0 [main child 0] top: delete unknown files on
init: all files=[write.lock]
1> 20.155s 0.1s: init R0 [main child 0] now delete 0 files: []
1> 20.156s 0.1s: init R0 [main child 0] top: done delete unknown files
on init: all files=[write.lock]
1> 20.158s 0.1s: init R0 [main child 0] top: myPrimaryGen=-1
1> 20.159s 0.1s: init R0 [main child 0] top: same primary as before
1> 20.159s 0.1s: init R0 [main child 0] send new_replica to primary
tcpPort=38439
1> 20.163s 8.7s: idle R3 [ jobs o7] top: after remove from pending
merges pendingMergeFiles=[]
1> 20.163s 8.7s: idle R3 [ jobs o7] top: decRef lastNRTFiles=[_7.fdm,
_4_3.liv, _8_1.liv, _4_Lucene90_0.pos, _9.fdx, _9.fdt, _7.si,
_9_Lucene90_0.pos, _9.tvm, _9.nvm, _9.fnm, _4_Lucene90_0.doc,
_7_Lucene90_0.pos, _4.fdt, _4_Lucene90_0.tip, _9.tvd, _4.fdm, _7.tvx, _9.nvd,
_4_Lucene90_0.tmd, _9_Lucene90_0.tmd, _4_Lucene90_0.tim, _4.fdx, _7.tvm,
_9_Lucene90_0.tim, _9.tvx, _7.nvm, _7.tvd, _7.fnm, _9_Lucene90_0.tip, _7.nvd,
_7_Lucene90_0.doc, _9.si, _4.nvm, _4.fnm, _4.tvd, _7_Lucene90_0.tmd, _8.cfe,
_4.nvd, _9.fdm, _4.tvx, _9_Lucene90_0.doc, _7.fdt, _7_Lucene90_0.tip, _8.cfs,
_4.si, _7_Lucene90_0.tim, _4.tvm, _7.fdx, _8.si]
1> 20.163s 8.7s: idle R3 [ jobs o7] now delete 47 files: [_7.fdm,
_4_3.liv, _4_Lucene90_0.tmd, _8_1.liv, _9_Lucene90_0.tmd, _4_Lucene90_0.pos,
_4_Lucene90_0.tim, _4.fdx, _7.tvm, _9.fdx, _9_Lucene90_0.tim, _9.fdt, _9.tvx,
_7.nvm, _7.tvd, _7.fnm, _9_Lucene90_0.tip, _7.si, _7.nvd, _7_Lucene90_0.doc,
_9_Lucene90_0.pos, _9.si, _9.tvm, _4.nvm, _4.fnm, _4.tvd, _9.nvm, _9.fnm,
_7_Lucene90_0.tmd, _4.nvd, _9.fdm, _4_Lucene90_0.doc, _7_Lucene90_0.pos,
_4.fdt, _4.tvx, _9_Lucene90_0.doc, _4_Lucene90_0.tip, _9.tvd, _4.fdm, _7.fdt,
_7_Lucene90_0.tip, _7.tvx, _4.si, _7_Lucene90_0.tim, _4.tvm, _7.fdx, _9.nvd]
1> 20.163s 8.7s: idle R3 [ jobs o7] file _7.fdm: now delete
1> 20.163s 8.7s: idle R3 [ jobs o7] file _4_3.liv: now delete
1> 20.164s 0.2s: init R0 [main child 0]
SegmentInfosSearcherManager.init: use incoming infos=segments:
1> 20.164s 8.7s: idle R3 [ jobs o7] file _4_Lucene90_0.tmd: now delete
1> 20.164s 12.4s: idle R2 [ recv-39] newNRTPoint primaryTCPPort=38439
version=39 newPrimaryGen=0
1> 20.165s 12.4s: idle R2 [ recv-39] top: keep current lastPrimaryGen=0
1> 20.165s 12.4s: syncing R2 [ recv-39] top: start sync sis.version=39
1> 20.165s 12.4s: syncing R2 [ recv-39] top: newNRTPoint
1> 20.165s 8.7s: idle R3 [ jobs o7] file _8_1.liv: now delete
1> 20.165s 8.7s: idle R3 [ jobs o7] file _9_Lucene90_0.tmd: now delete
1> 20.165s 8.7s: idle R3 [ jobs o7] file _4_Lucene90_0.pos: now delete
1> 20.165s 8.7s: idle R3 [ jobs o7] file _4_Lucene90_0.tim: now delete
1> 20.165s 8.7s: idle R3 [ jobs o7] file _4.fdx: now delete
1> 20.166s 8.7s: idle R3 [ jobs o7] file _7.tvm: now delete
1> 20.166s 8.7s: idle R3 [ jobs o7] file _9.fdx: now delete
1> 20.166s 8.7s: idle R3 [ jobs o7] file _9_Lucene90_0.tim: now delete
1> 20.166s 8.7s: idle R3 [ jobs o7] file _9.fdt: now delete
1> 20.167s 0.2s: init R0 [main child 0] top: done start
1> 20.167s 8.7s: idle R3 [ jobs o7] file _9.tvx: now delete
1> 20.167s 8.7s: idle R3 [ jobs o7] file _7.nvm: now delete
1> 20.167s 8.7s: idle R3 [ jobs o7] file _7.tvd: now delete
1> 20.167s 8.7s: idle R3 [ jobs o7] file _7.fnm: now delete
1> 20.168s 8.7s: idle R3 [ jobs o7] file _9_Lucene90_0.tip: now delete
1> 20.168s 8.7s: idle R3 [ jobs o7] file _7.si: now delete
1> 20.168s 18.3s: idle P1 [ new repl] new replica: 0 current warming
merges
1> 20.169s : parent [ start R0]
1> 20.169s : parent [ start R0] INFOS VERSION: 0
1> 20.169s 8.7s: idle R3 [ jobs o7] file _7.nvd: now delete
1> 20.169s 8.7s: idle R3 [ jobs o7] file _7_Lucene90_0.doc: now delete
1> 20.170s 8.7s: idle R3 [ jobs o7] file _9_Lucene90_0.pos: now delete
1> 20.170s 0.2s: idle R0 [main child 0] top: will crash after 58.372
seconds
1> 20.170s 8.7s: idle R3 [ jobs o7] file _9.si: now delete
1> 20.170s 8.7s: idle R3 [ jobs o7] file _9.tvm: now delete
1> 20.170s 8.7s: idle R3 [ jobs o7] file _4.nvm: now delete
1> 20.171s 8.7s: idle R3 [ jobs o7] file _4.fnm: now delete
1> 20.171s 8.7s: idle R3 [ jobs o7] file _4.tvd: now delete
1> 20.171s 8.7s: idle R3 [ jobs o7] file _9.nvm: now delete
1> 20.172s 8.7s: idle R3 [ jobs o7] file _9.fnm: now delete
1> 20.172s 8.7s: idle R3 [ jobs o7] file _7_Lucene90_0.tmd: now delete
1> 20.172s 8.7s: idle R3 [ jobs o7] file _4.nvd: now delete
1> 20.172s 8.7s: idle R3 [ jobs o7] file _9.fdm: now delete
1> 20.172s 8.7s: idle R3 [ jobs o7] file _4_Lucene90_0.doc: now delete
1> 20.172s 8.7s: idle R3 [ jobs o7] file _7_Lucene90_0.pos: now delete
1> 20.175s 8.7s: idle R3 [ jobs o7] file _4.fdt: now delete
1> 20.175s 8.7s: idle R3 [ jobs o7] file _4.tvx: now delete
1> 20.175s 8.7s: idle R3 [ jobs o7] file _9_Lucene90_0.doc: now delete
1> 20.175s 8.7s: idle R3 [ jobs o7] file _4_Lucene90_0.tip: now delete
1> 20.175s 8.7s: idle R3 [ jobs o7] file _9.tvd: now delete
1> 20.175s 8.7s: idle R3 [ jobs o7] file _4.fdm: now delete
1> 20.175s 8.7s: idle R3 [ jobs o7] file _7.fdt: now delete
1> 20.176s 8.7s: idle R3 [ jobs o7] file _7_Lucene90_0.tip: now delete
1> 20.176s 8.7s: idle R3 [ jobs o7] file _7.tvx: now delete
1> 20.176s 8.7s: idle R3 [ jobs o7] file _4.si: now delete
1> 20.177s 8.7s: idle R3 [ jobs o7] file _7_Lucene90_0.tim: now delete
1> 20.177s 8.7s: idle R3 [ jobs o7] file _4.tvm: now delete
1> 20.178s 18.3s: idle P1 [ send] top: start fetch for R2
socket=Socket[addr=/127.0.0.1,port=49158,localport=38439]
1> 20.179s 12.4s: syncing R2 [ recv-39] create SimpleCopyJob o11
1> 20.179s 12.4s: syncing R2 [ recv-39] top: newNRTPoint: job
files=[_b.cfe, _c.fdm, _a_Lucene90_0.doc, _a.si, _c.tvm, _c.si, _c.fdt, _c.tvx,
_c_Lucene90_0.tip, _a.nvm, _a.fnm, _c_Lucene90_0.pos, _c.fdx, _a.tvm,
_c_Lucene90_0.tim, _a.nvd, _c_Lucene90_0.tmd, _a.tvd, _a_Lucene90_0.tip,
_a.fdt, _a.tvx, _b_2.liv, _a.fdx, _a_Lucene90_0.tim, _b.si, _8.cfe, _a.fdm,
_b.cfs, _a_Lucene90_0.tmd, _c.nvd, _8_3.liv, _c_Lucene90_0.doc,
_a_Lucene90_0.pos, _8.cfs, _c.nvm, _c.tvd, _c.fnm, _8.si, _a_2.liv]
1> 20.179s 12.4s: syncing R2 [ recv-39] SimpleCopyJob.init: done start
files count=0 totBytes=0
1> 20.179s 8.7s: idle R3 [ jobs o7] file _7.fdx: now delete
1> 20.179s 8.7s: idle R3 [ jobs o7] file _9.nvd: now delete
1> 20.179s 8.7s: idle R3 [ jobs o7] top: set
lastNRTFiles=[_a_Lucene90_0.doc, _a.si, _c.tvm, _c.tvx, _c_Lucene90_0.tip,
_a.nvm, _a.fnm, _a.tvm, _c_Lucene90_0.tim, _a.nvd, _c_Lucene90_0.tmd, _a.tvd,
_a.tvx, _b_2.liv, _b.si, _c.nvd, _8_3.liv, _a_Lucene90_0.pos, _c.nvm, _c.tvd,
_c.fnm, _b.cfe, _c.fdm, _c.si, _c.fdt, _c_Lucene90_0.pos, _c.fdx,
_a_Lucene90_0.tip, _a.fdt, _a.fdx, _a_Lucene90_0.tim, _8.cfe, _a.fdm, _b.cfs,
_a_Lucene90_0.tmd, _c_Lucene90_0.doc, _8.cfs, _8.si, _a_2.liv]
1> 20.179s 8.7s: idle R3 [ jobs o7] now remove-if-not-ref'd completed
merge files: [_b.cfe, _b.cfs, _b.si]
1> 20.179s 8.7s: idle R3 [ recv-39] top: keep current lastPrimaryGen=0
1> 20.179s 8.7s: syncing R3 [ recv-39] top: start sync sis.version=39
1> 20.180s 8.7s: syncing R3 [ recv-39] top: newNRTPoint
1> 20.180s 12.4s: syncing R2 [ jobs o11] top: finishNRTCopy: version=39
job=SimpleCopyJob(ord=11 NRT point sync version=39 highPriority=true files
count=39 bytesCopied=0 (of 0) filesCopied=0)
1> 20.180s 12.4s: idle R2 [ jobs o11] top: now clear curNRTCopy;
job=SimpleCopyJob(ord=11 NRT point sync version=39 highPriority=true files
count=39 bytesCopied=0 (of 0) filesCopied=0)
1> 20.180s 12.4s: idle R2 [ jobs o11] top: file copy done; took 0.6
msec to copy 0 bytes; now rename 0 tmp files
1> 20.182s 12.4s: idle R2 [ jobs o11] version=39 segments=segments_1:
_8(10.0.0):c251/25:[diagnostics={os=Linux, timestamp=1653199396000,
lucene.version=10.0.0, java.vm.version=17.0.1+12-LTS-39, java.version=17.0.1,
mergeMaxNumSegments=-1, os.version=4.15.0-147-generic, java.vendor=Oracle
Corporation, source=merge, os.arch=amd64, mergeFactor=3,
java.runtime.version=17.0.1+12-LTS-39}]:delGen=3 :id=cva6u9luajl9wwsjka944lwuy
_b(10.0.0):c144/14:[diagnostics={os=Linux, timestamp=1653199397423,
lucene.version=10.0.0, java.vm.version=17.0.1+12-LTS-39, java.version=17.0.1,
mergeMaxNumSegments=-1, os.version=4.15.0-147-generic, java.vendor=Oracle
Corporation, source=merge, os.arch=amd64, mergeFactor=3,
java.runtime.version=17.0.1+12-LTS-39}]:delGen=2 :id=cva6u9luajl9wwsjka944lwuz
_a(10.0.0):C326/8:[diagnostics={timestamp=1653199400523, os=Linux,
java.vendor=Oracle Corporation, os.version=4.15.0-147-generic,
java.runtime.version=17.0.1+12-LTS-39, os.arch=amd64, source=flush,
lucene.version=10.0.0, java.vm.version=17.0.1+12-LTS-39,
java.version=17.0.1}]:delGen=2 :id=cva6u9luajl9wwsjka944lwv0
_c(10.0.0):C39:[diagnostics={timestamp=1653199402071, os=Linux,
java.vendor=Oracle Corporation, os.version=4.15.0-147-generic,
java.runtime.version=17.0.1+12-LTS-39, os.arch=amd64, source=flush,
lucene.version=10.0.0, java.vm.version=17.0.1+12-LTS-39, java.version=17.0.1}]
:id=cva6u9luajl9wwsjka944lwux
1> 20.182s 12.4s: idle R2 [ jobs o11] mgr.setCurrentInfos: carry over
infos gen=segments
1> 20.182s 12.4s: idle R2 [ jobs o11] refreshed to version=39
r=StandardDirectoryReader(segments:39
_8(10.0.0):c251/25:[diagnostics={os=Linux, timestamp=1653199396000,
lucene.version=10.0.0, java.vm.version=17.0.1+12-LTS-39, java.version=17.0.1,
mergeMaxNumSegments=-1, os.version=4.15.0-147-generic, java.vendor=Oracle
Corporation, source=merge, os.arch=amd64, mergeFactor=3,
java.runtime.version=17.0.1+12-LTS-39}]:delGen=3 :id=cva6u9luajl9wwsjka944lwuy
_b(10.0.0):c144/14:[diagnostics={os=Linux, timestamp=1653199397423,
lucene.version=10.0.0, java.vm.version=17.0.1+12-LTS-39, java.version=17.0.1,
mergeMaxNumSegments=-1, os.version=4.15.0-147-generic, java.vendor=Oracle
Corporation, source=merge, os.arch=amd64, mergeFactor=3,
java.runtime.version=17.0.1+12-LTS-39}]:delGen=2 :id=cva6u9luajl9wwsjka944lwuz
_a(10.0.0):C326/8:[diagnostics={timestamp=1653199400523, os=Linux,
java.vendor=Oracle Corporation, os.version=4.15.0-147-generic,
java.runtime.version=17.0.1+12-LTS-39, os.arch=amd64, source=flush,
lucene.version=10.0.0, java.vm.version=17.0.1+12-LTS-39,
java.version=17.0.1}]:delGen=2 :id=cva6u9luajl9wwsjka944lwv0
_c(10.0.0):C39:[diagnostics={timestamp=1653199402071, os=Linux,
java.vendor=Oracle Corporation, os.version=4.15.0-147-generic,
java.runtime.version=17.0.1+12-LTS-39, os.arch=amd64, source=flush,
lucene.version=10.0.0, java.vm.version=17.0.1+12-LTS-39, java.version=17.0.1}]
:id=cva6u9luajl9wwsjka944lwux)
1> 20.182s 12.4s: idle R2 [ jobs o11] top: incRef newNRTFiles=[_b.cfe,
_c.fdm, _a_Lucene90_0.doc, _a.si, _c.tvm, _c.si, _c.fdt, _c.tvx,
_c_Lucene90_0.tip, _a.nvm, _a.fnm, _c_Lucene90_0.pos, _c.fdx, _a.tvm,
_c_Lucene90_0.tim, _a.nvd, _c_Lucene90_0.tmd, _a.tvd, _a_Lucene90_0.tip,
_a.fdt, _a.tvx, _b_2.liv, _a.fdx, _a_Lucene90_0.tim, _b.si, _8.cfe, _a.fdm,
_b.cfs, _a_Lucene90_0.tmd, _c.nvd, _8_3.liv, _c_Lucene90_0.doc,
_a_Lucene90_0.pos, _8.cfs, _c.nvm, _c.tvd, _c.fnm, _8.si, _a_2.liv]
1> 20.183s 12.4s: idle R2 [ jobs o11] top: after remove from pending
merges pendingMergeFiles=[]
1> 20.183s 12.4s: idle R2 [ jobs o11] top: decRef
lastNRTFiles=[_a_Lucene90_0.doc, _a.si, _c.tvm, _c.tvx, _c_Lucene90_0.tip,
_a.nvm, _a.fnm, _a.tvm, _c_Lucene90_0.tim, _a.nvd, _c_Lucene90_0.tmd, _a.tvd,
_a.tvx, _b_2.liv, _b.si, _c.nvd, _8_3.liv, _a_Lucene90_0.pos, _c.nvm, _c.tvd,
_c.fnm, _b.cfe, _c.fdm, _c.si, _c.fdt, _c_Lucene90_0.pos, _c.fdx,
_a_Lucene90_0.tip, _a.fdt, _a.fdx, _a_Lucene90_0.tim, _8.cfe, _a.fdm, _b.cfs,
_a_Lucene90_0.tmd, _c_Lucene90_0.doc, _8.cfs, _8.si, _a_2.liv]
1> 20.184s 12.4s: idle R2 [ jobs o11] now delete 0 files: []
1> 20.184s 12.4s: idle R2 [ jobs o11] top: set
lastNRTFiles=[_a_Lucene90_0.doc, _a.si, _c.tvm, _c.tvx, _c_Lucene90_0.tip,
_a.nvm, _a.fnm, _a.tvm, _c_Lucene90_0.tim, _a.nvd, _c_Lucene90_0.tmd, _a.tvd,
_a.tvx, _b_2.liv, _b.si, _c.nvd, _8_3.liv, _a_Lucene90_0.pos, _c.nvm, _c.tvd,
_c.fnm, _b.cfe, _c.fdm, _c.si, _c.fdt, _c_Lucene90_0.pos, _c.fdx,
_a_Lucene90_0.tip, _a.fdt, _a.fdx, _a_Lucene90_0.tim, _8.cfe, _a.fdm, _b.cfs,
_a_Lucene90_0.tmd, _c_Lucene90_0.doc, _8.cfs, _8.si, _a_2.liv]
1> 20.184s 12.4s: idle R2 [ jobs o11] top: done sync: took 0.019s for 0
b, opened NRT reader version=39 markerCount=82
1> 20.184s 8.7s: syncing R3 [ jobs o7] top: done sync: took 0.258s for
943.1 KB, opened NRT reader version=38 markerCount=82
1> 20.182s 18.3s: idle P1 [ send-R2-39] top: done fetch files for R2:
sent 0 files; sent 0 bytes
1> 20.189s 18.3s: idle P1 [ send-R2-39] top: fetch: now release CopyState
1> 20.199s 18.3s: idle P1 [ send] top: start fetch for R3
socket=Socket[addr=/127.0.0.1,port=49160,localport=38439]
1> 20.205s : parent [ start R0]
1> 20.205s : parent [ start R0] NODE STARTED
1> 20.205s 8.7s: syncing R3 [ recv-39] create SimpleCopyJob o8
1> 20.205s 8.7s: syncing R3 [ recv-39] top: newNRTPoint: job
files=[_b.cfe, _c.fdm, _a_Lucene90_0.doc, _a.si, _c.tvm, _c.si, _c.fdt, _c.tvx,
_c_Lucene90_0.tip, _a.nvm, _a.fnm, _c_Lucene90_0.pos, _c.fdx, _a.tvm,
_c_Lucene90_0.tim, _a.nvd, _c_Lucene90_0.tmd, _a.tvd, _a_Lucene90_0.tip,
_a.fdt, _a.tvx, _b_2.liv, _a.fdx, _a_Lucene90_0.tim, _b.si, _8.cfe, _a.fdm,
_b.cfs, _a_Lucene90_0.tmd, _c.nvd, _8_3.liv, _c_Lucene90_0.doc,
_a_Lucene90_0.pos, _8.cfs, _c.nvm, _c.tvd, _c.fnm, _8.si, _a_2.liv]
1> 20.205s 8.7s: syncing R3 [ recv-39] SimpleCopyJob.init: done start
files count=0 totBytes=0
1> 20.206s 18.3s: idle P1 [ send-R3-39] top: done fetch files for R3:
sent 0 files; sent 0 bytes
1> 20.208s 8.7s: syncing R3 [ jobs o8] top: finishNRTCopy: version=39
job=SimpleCopyJob(ord=8 NRT point sync version=39 highPriority=true files
count=39 bytesCopied=0 (of 0) filesCopied=0)
1> 20.208s 8.7s: idle R3 [ jobs o8] top: now clear curNRTCopy;
job=SimpleCopyJob(ord=8 NRT point sync version=39 highPriority=true files
count=39 bytesCopied=0 (of 0) filesCopied=0)
1> 20.208s 8.7s: idle R3 [ jobs o8] top: file copy done; took 3.2
msec to copy 0 bytes; now rename 0 tmp files
1> 20.206s 18.3s: idle P1 [ send-R3-39] top: fetch: now release CopyState
1> 20.210s 8.7s: idle R3 [ jobs o8] version=39 segments=segments_1:
_8(10.0.0):c251/25:[diagnostics={java.runtime.version=17.0.1+12-LTS-39,
mergeFactor=3, os.arch=amd64, source=merge, java.vendor=Oracle Corporation,
os.version=4.15.0-147-generic, mergeMaxNumSegments=-1, java.version=17.0.1,
java.vm.version=17.0.1+12-LTS-39, lucene.version=10.0.0,
timestamp=1653199396000, os=Linux}]:delGen=3 :id=cva6u9luajl9wwsjka944lwuy
_b(10.0.0):c144/14:[diagnostics={java.runtime.version=17.0.1+12-LTS-39,
mergeFactor=3, os.arch=amd64, source=merge, java.vendor=Oracle Corporation,
os.version=4.15.0-147-generic, mergeMaxNumSegments=-1, java.version=17.0.1,
java.vm.version=17.0.1+12-LTS-39, lucene.version=10.0.0,
timestamp=1653199397423, os=Linux}]:delGen=2 :id=cva6u9luajl9wwsjka944lwuz
_a(10.0.0):C326/8:[diagnostics={java.vm.version=17.0.1+12-LTS-39,
lucene.version=10.0.0, source=flush, os.arch=amd64,
java.runtime.version=17.0.1+12-LTS-39, os.version=4.15.0-147-generic,
java.vendor=Oracle Corporation, os=Linux, timestamp=1653199400523,
java.version=17.0.1}]:delGen=2 :id=cva6u9luajl9wwsjka944lwv0
_c(10.0.0):C39:[diagnostics={java.vm.version=17.0.1+12-LTS-39,
lucene.version=10.0.0, source=flush, os.arch=amd64,
java.runtime.version=17.0.1+12-LTS-39, os.version=4.15.0-147-generic,
java.vendor=Oracle Corporation, os=Linux, timestamp=1653199402071,
java.version=17.0.1}] :id=cva6u9luajl9wwsjka944lwux
1> 20.210s 8.7s: idle R3 [ jobs o8] mgr.setCurrentInfos: carry over
infos gen=segments
1> 20.210s 8.7s: idle R3 [ jobs o8] refreshed to version=39
r=StandardDirectoryReader(segments:39
_8(10.0.0):c251/25:[diagnostics={java.runtime.version=17.0.1+12-LTS-39,
mergeFactor=3, os.arch=amd64, source=merge, java.vendor=Oracle Corporation,
os.version=4.15.0-147-generic, mergeMaxNumSegments=-1, java.version=17.0.1,
java.vm.version=17.0.1+12-LTS-39, lucene.version=10.0.0,
timestamp=1653199396000, os=Linux}]:delGen=3 :id=cva6u9luajl9wwsjka944lwuy
_b(10.0.0):c144/14:[diagnostics={java.runtime.version=17.0.1+12-LTS-39,
mergeFactor=3, os.arch=amd64, source=merge, java.vendor=Oracle Corporation,
os.version=4.15.0-147-generic, mergeMaxNumSegments=-1, java.version=17.0.1,
java.vm.version=17.0.1+12-LTS-39, lucene.version=10.0.0,
timestamp=1653199397423, os=Linux}]:delGen=2 :id=cva6u9luajl9wwsjka944lwuz
_a(10.0.0):C326/8:[diagnostics={java.vm.version=17.0.1+12-LTS-39,
lucene.version=10.0.0, source=flush, os.arch=amd64,
java.runtime.version=17.0.1+12-LTS-39, os.version=4.15.0-147-generic,
java.vendor=Oracle Corporation, os=Linux, timestamp=1653199400523,
java.version=17.0.1}]:delGen=2 :id=cva6u9luajl9wwsjka944lwv0
_c(10.0.0):C39:[diagnostics={java.vm.version=17.0.1+12-LTS-39,
lucene.version=10.0.0, source=flush, os.arch=amd64,
java.runtime.version=17.0.1+12-LTS-39, os.version=4.15.0-147-generic,
java.vendor=Oracle Corporation, os=Linux, timestamp=1653199402071,
java.version=17.0.1}] :id=cva6u9luajl9wwsjka944lwux)
1> 20.211s 8.7s: idle R3 [ jobs o8] top: incRef newNRTFiles=[_b.cfe,
_c.fdm, _a_Lucene90_0.doc, _a.si, _c.tvm, _c.si, _c.fdt, _c.tvx,
_c_Lucene90_0.tip, _a.nvm, _a.fnm, _c_Lucene90_0.pos, _c.fdx, _a.tvm,
_c_Lucene90_0.tim, _a.nvd, _c_Lucene90_0.tmd, _a.tvd, _a_Lucene90_0.tip,
_a.fdt, _a.tvx, _b_2.liv, _a.fdx, _a_Lucene90_0.tim, _b.si, _8.cfe, _a.fdm,
_b.cfs, _a_Lucene90_0.tmd, _c.nvd, _8_3.liv, _c_Lucene90_0.doc,
_a_Lucene90_0.pos, _8.cfs, _c.nvm, _c.tvd, _c.fnm, _8.si, _a_2.liv]
1> 20.217s : parent [ start R0] top: node=0 started at
tcpPort=43399 initCommitVersion=-1 initInfosVersion=0
1> 20.217s : parent [ start R0] top: send 3 replicas to primary
1> 20.218s 8.8s: idle R3 [ jobs o8] top: after remove from pending
merges pendingMergeFiles=[]
1> 20.218s 8.8s: idle R3 [ jobs o8] top: decRef
lastNRTFiles=[_a_Lucene90_0.doc, _a.si, _c.tvm, _c.tvx, _c_Lucene90_0.tip,
_a.nvm, _a.fnm, _a.tvm, _c_Lucene90_0.tim, _a.nvd, _c_Lucene90_0.tmd, _a.tvd,
_a.tvx, _b_2.liv, _b.si, _c.nvd, _8_3.liv, _a_Lucene90_0.pos, _c.nvm, _c.tvd,
_c.fnm, _b.cfe, _c.fdm, _c.si, _c.fdt, _c_Lucene90_0.pos, _c.fdx,
_a_Lucene90_0.tip, _a.fdt, _a.fdx, _a_Lucene90_0.tim, _8.cfe, _a.fdm, _b.cfs,
_a_Lucene90_0.tmd, _c_Lucene90_0.doc, _8.cfs, _8.si, _a_2.liv]
1> 20.218s 8.8s: idle R3 [ jobs o8] now delete 0 files: []
1> 20.218s 8.8s: idle R3 [ jobs o8] top: set
lastNRTFiles=[_a_Lucene90_0.doc, _a.si, _c.tvm, _c.tvx, _c_Lucene90_0.tip,
_a.nvm, _a.fnm, _a.tvm, _c_Lucene90_0.tim, _a.nvd, _c_Lucene90_0.tmd, _a.tvd,
_a.tvx, _b_2.liv, _b.si, _c.nvd, _8_3.liv, _a_Lucene90_0.pos, _c.nvm, _c.tvd,
_c.fnm, _b.cfe, _c.fdm, _c.si, _c.fdt, _c_Lucene90_0.pos, _c.fdx,
_a_Lucene90_0.tip, _a.fdt, _a.fdx, _a_Lucene90_0.tim, _8.cfe, _a.fdm, _b.cfs,
_a_Lucene90_0.tmd, _c_Lucene90_0.doc, _8.cfs, _8.si, _a_2.liv]
1> 20.219s 8.8s: idle R3 [ jobs o8] top: done sync: took 0.039s for 0
b, opened NRT reader version=39 markerCount=82
1> 20.228s 18.3s: idle P1 [ set repls] top: set replicasIDs=[0, 2, 3]
tcpPorts=[43399, 45851, 44027]
1> 20.270s : parent [ restarter] top: full cluster crash
1> 20.271s : parent [ restarter] top: N0: top: now crash node
1> 20.375s : parent [ main] top: now flush primary; at least
marker count=82
1> 20.381s 18.5s: idle P1 [ flush] now flush; 3 replicas
1> 20.382s 18.5s: idle P1 [ flush] top: now flushAndRefresh
1> 20.383s 18.5s: idle P1 [ flush] top: skip switch to infos:
version=39 is unchanged: segments_1:
_8(10.0.0):c251/25:[diagnostics={os.arch=amd64, source=merge,
java.vendor=Oracle Corporation, os.version=4.15.0-147-generic,
mergeMaxNumSegments=-1, java.version=17.0.1, java.vm.version=17.0.1+12-LTS-39,
lucene.version=10.0.0, timestamp=1653199396000, os=Linux,
java.runtime.version=17.0.1+12-LTS-39, mergeFactor=3}]:delGen=3
:id=cva6u9luajl9wwsjka944lwuy _b(10.0.0):c144/14:[diagnostics={os.arch=amd64,
source=merge, java.vendor=Oracle Corporation, os.version=4.15.0-147-generic,
mergeMaxNumSegments=-1, java.version=17.0.1, java.vm.version=17.0.1+12-LTS-39,
lucene.version=10.0.0, timestamp=1653199397423, os=Linux,
java.runtime.version=17.0.1+12-LTS-39, mergeFactor=3}]:delGen=2
:id=cva6u9luajl9wwsjka944lwuz
_a(10.0.0):C326/8:[diagnostics={lucene.version=10.0.0, source=flush,
os.arch=amd64, java.runtime.version=17.0.1+12-LTS-39,
os.version=4.15.0-147-generic, os=Linux, java.vendor=Oracle Corporation,
timestamp=1653199400523, java.version=17.0.1,
java.vm.version=17.0.1+12-LTS-39}]:delGen=2 :id=cva6u9luajl9wwsjka944lwv0
_c(10.0.0):C39:[diagnostics={lucene.version=10.0.0, source=flush,
os.arch=amd64, java.runtime.version=17.0.1+12-LTS-39,
os.version=4.15.0-147-generic, os=Linux, java.vendor=Oracle Corporation,
timestamp=1653199402071, java.version=17.0.1,
java.vm.version=17.0.1+12-LTS-39}] :id=cva6u9luajl9wwsjka944lwux
1> 20.383s 18.5s: idle P1 [ flush] top: no changes in
flushAndRefresh; still version=39
1> 20.383s : parent [ main] PG=0 4 (of 5) nodes running: R0
P1 R2 R3
1> 20.533s 18.7s: idle P1 [Lucene Merge Thread #3] file _d.cfs has
length=964.8 KB
1> 20.534s 18.7s: idle P1 [Lucene Merge Thread #3] file _d.si has
length=386 b
1> 20.534s 18.7s: idle P1 [Lucene Merge Thread #3] file _d.cfe has
length=415 b
1> 20.534s 18.7s: idle P1 [Lucene Merge Thread #3] top: warm merge
_d(10.0.0):c395:[diagnostics={os.arch=amd64, source=merge, java.vendor=Oracle
Corporation, os.version=4.15.0-147-generic, mergeMaxNumSegments=-1,
java.version=17.0.1, java.vm.version=17.0.1+12-LTS-39, lucene.version=10.0.0,
timestamp=1653199402096, os=Linux, java.runtime.version=17.0.1+12-LTS-39,
mergeFactor=3}] :id=cva6u9luajl9wwsjka944lwv3 to 3 replicas; tcpPort=38439:
files=[_d.cfs, _d.si, _d.cfe]
1> 20.535s 18.7s: idle P1 [Lucene Merge Thread #3] warm connection
Socket[addr=localhost/127.0.0.1,port=43399,localport=48206]
1> 20.536s 18.7s: idle P1 [Lucene Merge Thread #3] warm connection
Socket[addr=localhost/127.0.0.1,port=45851,localport=35388]
1> 20.536s 18.7s: idle P1 [Lucene Merge Thread #3] warm connection
Socket[addr=localhost/127.0.0.1,port=44027,localport=34276]
1> 20.542s 12.8s: idle R2 [ merge copy] done reading files to copy
files=[_d.cfs, _d.si, _d.cfe]
1> 20.543s 12.8s: idle R2 [ merge copy] top: keep current lastPrimaryGen=0
1> 20.543s 12.8s: idle R2 [ merge copy] now pre-copy warm merge
files=[_d.cfs, _d.si, _d.cfe] primaryGen=0
1> 20.543s 12.8s: idle R2 [ merge copy] file _d.cfs: will copy [file does
not exist]
1> 20.542s 9.1s: idle R3 [ merge copy] done reading files to copy
files=[_d.cfs, _d.si, _d.cfe]
1> 20.542s 9.1s: idle R3 [ merge copy] top: keep current lastPrimaryGen=0
1> 20.542s 9.1s: idle R3 [ merge copy] now pre-copy warm merge
files=[_d.cfs, _d.si, _d.cfe] primaryGen=0
1> 20.544s 12.8s: idle R2 [ merge copy] file _d.si: will copy [file does
not exist]
1> 20.544s 12.8s: idle R2 [ merge copy] file _d.cfe: will copy [file does
not exist]
1> 20.545s 12.8s: idle R2 [ merge copy] create SimpleCopyJob o12
1> 20.545s 12.8s: idle R2 [ merge copy] SimpleCopyJob.init: done start
files count=3 totBytes=988710
1> 20.545s 12.8s: idle R2 [ merge copy] done launching copy job
files=[_d.cfs, _d.si, _d.cfe]
1> 20.550s : parent [ pump0] now wait for process
Process[pid=8276, exitValue="not exited"]
1> 20.552s 9.1s: idle R3 [ merge copy] file _d.cfs: will copy [file does
not exist]
1> 20.552s 9.1s: idle R3 [ merge copy] file _d.si: will copy [file does
not exist]
1> 20.552s 9.1s: idle R3 [ merge copy] file _d.cfe: will copy [file does
not exist]
1> 20.552s 9.1s: idle R3 [ merge copy] create SimpleCopyJob o9
1> 20.552s 9.1s: idle R3 [ merge copy] SimpleCopyJob.init: done start
files count=3 totBytes=988710
1> 20.552s 9.1s: idle R3 [ merge copy] done launching copy job
files=[_d.cfs, _d.si, _d.cfe]
1> 20.553s : parent [ pump0] done wait for process
Process[pid=8276, exitValue=143]
1> 20.553s : parent [ pump0] exit value=143 willCrash=true
1> 20.553s : parent [ pump0] top: R0 tcpPort=43399: replica
process finished
1> 20.553s : parent [ pump0] top: send 2 replicas to primary
1> 20.557s 18.7s: idle P1 [ send] top: start fetch for R3
socket=Socket[addr=/127.0.0.1,port=49172,localport=38439]
1> 20.559s 9.1s: idle R3 [ jobs o9] file _d.cfs: start copying to tmp
file _d.cfs_copy_3i.tmp length=987909
1> 20.563s 18.7s: idle P1 [ send] top: done fetch files for R3:
sent 3 files; sent 988710 bytes
1> 20.565s 18.7s: idle P1 [ send] top: start fetch for R2
socket=Socket[addr=/127.0.0.1,port=49174,localport=38439]
1> 20.571s 18.7s: idle P1 [ set repls] top: set replicasIDs=[2, 3]
tcpPorts=[45851, 44027]
1> 20.572s 12.8s: idle R2 [ jobs o12] file _d.cfs: start copying to tmp
file _d.cfs_copy_4y.tmp length=987909
1> 20.572s 18.7s: idle P1 [ send] top: done fetch files for R2:
sent 3 files; sent 988710 bytes
1> 20.573s : parent [ restarter] top: N0: top: done crash node
1> 20.573s : parent [ restarter] top: N1: top: now crash node
2> ignore IOExc reading from forked process pipe: java.io.IOException: Stream
closed
1> 20.589s : parent [ pump1] now wait for process
Process[pid=7453, exitValue="not exited"]
1> 20.620s 12.8s: idle R2 [ jobs o12] file _d.cfs: done copying [964.8
KB, 48.542ms]
1> 20.621s 12.8s: idle R2 [ jobs o12] file _d.si: start copying to tmp
file _d.si_copy_4z.tmp length=386
1> 20.621s 12.8s: idle R2 [ jobs o12] file _d.si: done copying [386 b,
0.059ms]
1> 20.621s 12.8s: idle R2 [ jobs o12] file _d.cfe: start copying to tmp
file _d.cfe_copy_50.tmp length=415
1> 20.621s 12.8s: idle R2 [ jobs o12] file _d.cfe: done copying [415 b,
0.056ms]
1> 20.621s 12.8s: idle R2 [ jobs o12] done warming merge [_d.cfs,
_d.si, _d.cfe] failed?=false
1> 20.621s 12.8s: idle R2 [ jobs o12] top: file copy done; took 78.4
msec to copy 988710 bytes; now rename 3 tmp files
1> 20.621s 12.8s: idle R2 [ jobs o12] rename file _d.cfs_copy_4y.tmp to
_d.cfs
1> 20.621s 12.8s: idle R2 [ jobs o12] rename file _d.si_copy_4z.tmp to
_d.si
1> 20.621s 12.8s: idle R2 [ jobs o12] rename file _d.cfe_copy_50.tmp to
_d.cfe
1> 20.621s 12.8s: idle R2 [ jobs o12] merge pre copy finished
files=[_d.cfs, _d.si, _d.cfe]
1> 20.621s 12.8s: idle R2 [ jobs o12] add file _d.cfs to
pendingMergeFiles
1> 20.621s 12.8s: idle R2 [ jobs o12] add file _d.si to
pendingMergeFiles
1> 20.621s 12.8s: idle R2 [ jobs o12] add file _d.cfe to
pendingMergeFiles
1> 20.627s 12.9s: idle R2 [ merge copy] done merge copy files=[_d.cfs,
_d.si, _d.cfe] success=true
1> 20.690s 9.2s: idle R3 [ jobs o9] file _d.cfs: done copying [964.8
KB, 131.570ms]
1> 20.691s 9.2s: idle R3 [ jobs o9] file _d.si: start copying to tmp
file _d.si_copy_3j.tmp length=386
1> 20.691s 9.2s: idle R3 [ jobs o9] file _d.si: done copying [386 b,
0.093ms]
1> 20.691s 9.2s: idle R3 [ jobs o9] file _d.cfe: start copying to tmp
file _d.cfe_copy_3k.tmp length=415
1> 20.691s 9.2s: idle R3 [ jobs o9] file _d.cfe: done copying [415 b,
0.086ms]
1> 20.691s 9.2s: idle R3 [ jobs o9] done warming merge [_d.cfs,
_d.si, _d.cfe] failed?=false
1> 20.691s 9.2s: idle R3 [ jobs o9] top: file copy done; took 147.2
msec to copy 988710 bytes; now rename 3 tmp files
1> 20.691s 9.2s: idle R3 [ jobs o9] rename file _d.cfs_copy_3i.tmp to
_d.cfs
1> 20.692s 9.2s: idle R3 [ jobs o9] rename file _d.si_copy_3j.tmp to
_d.si
1> 20.692s 9.2s: idle R3 [ jobs o9] rename file _d.cfe_copy_3k.tmp to
_d.cfe
1> 20.692s 9.2s: idle R3 [ jobs o9] merge pre copy finished
files=[_d.cfs, _d.si, _d.cfe]
1> 20.692s 9.2s: idle R3 [ jobs o9] add file _d.cfs to
pendingMergeFiles
1> 20.692s 9.2s: idle R3 [ jobs o9] add file _d.si to
pendingMergeFiles
1> 20.692s 9.2s: idle R3 [ jobs o9] add file _d.cfe to
pendingMergeFiles
1> 20.697s 9.2s: idle R3 [ merge copy] done merge copy files=[_d.cfs,
_d.si, _d.cfe] success=true
1> Indexer: done pause for a bit...
1> 20.933s : parent [ indexer p1] top: indexer lost connection to
primary
1> 20.933s : parent [ pump1] done wait for process
Process[pid=7453, exitValue=143]
1> 20.933s : parent [ pump1] exit value=143 willCrash=true
1> 20.934s : parent [ pump1] top: P1 tcpPort=38439: primary
process finished
1> 20.934s : parent [ restarter] top: N1: top: done crash node
1> 20.934s : parent [ restarter] top: N2: top: now crash node
1> 20.946s : parent [ main] PG=X 2 (of 5) nodes running: R2 R3
1> 21.262s : parent [ pump2] now wait for process
Process[pid=7819, exitValue="not exited"]
1> 21.263s : parent [ pump2] done wait for process
Process[pid=7819, exitValue=143]
1> 21.263s : parent [ pump2] exit value=143 willCrash=false
1> 21.263s : parent [ pump2] top: R2 tcpPort=45851: replica
process finished
1> 21.263s : parent [ restarter] top: N2: top: done crash node
1> 21.263s : parent [ restarter] top: N3: top: now crash node
1> 21.590s : parent [ pump3] now wait for process
Process[pid=7912, exitValue="not exited"]
1> 21.591s : parent [ pump3] done wait for process
Process[pid=7912, exitValue=143]
1> 21.591s : parent [ pump3] exit value=143 willCrash=true
1> 21.591s : parent [ pump3] top: R3 tcpPort=44027: replica
process finished
1> 21.591s : parent [ restarter] top: N3: top: done crash node
1> 21.591s : parent [ restarter] node status x0(1.0s) x1(0.7s)
x2(0.3s) x3(0.0s) x4(2.1s)
1> 21.591s : parent [ restarter] downNodes=[0, 1, 2, 3, 4]
1> 21.591s : parent [ restarter] top: primary crashed; now pick
replica to promote
1> 21.591s : parent [ restarter] top: no replicas running;
skipping primary promotion
1> 21.591s : parent [ restarter] N2: top: cold start as primary
1> 21.591s : parent [ restarter] 2: top: startPrimary
lastPrimaryVersion=39
1> 21.843s : parent [ main] PG=X 0 (of 5) nodes running:
1> 21.985s : parent [ restarter] JUnit version 4.13.1
1> 22.149s : parent [ main] PG=X 0 (of 5) nodes running:
1> 22.628s : parent [ main] PG=X 0 (of 5) nodes running:
1> 23.130s : parent [ main] PG=X 0 (of 5) nodes running:
1> 23.185s : parent [ restarter] .
1> 23.185s : parent [ restarter] PORT: 45383
1> 23.226s 0.0s: N2 [main child 2] top: corrupt unknown files
1> 23.557s 0.4s: idle P2 [main child 2] top: now init primary
1> 23.569s 0.4s: idle P2 [main child 2] IWC:
1> 23.570s : parent [ restarter]
analyzer=org.apache.lucene.tests.analysis.MockAnalyzer
1> 23.570s : parent [ restarter] ramBufferSizeMB=16.0
1> 23.570s : parent [ restarter] maxBufferedDocs=141
1
[...truncated too long message...]
will be removed in a future release
WARNING: A command line option has enabled the Security Manager
WARNING: The Security Manager is deprecated and will be removed in a future
release
WARNING: A command line option has enabled the Security Manager
WARNING: The Security Manager is deprecated and will be removed in a future
release
:lucene:analysis:nori:test (SUCCESS): 74 test(s), 1 skipped
> Task :lucene:analysis:nori:wipeTaskTemp
> Task :lucene:analysis:opennlp:compileTestJava
> Task :lucene:analysis:opennlp:copyTestResources NO-SOURCE
> Task :lucene:analysis:opennlp:processTestResources
> Task :lucene:analysis:opennlp:testClasses
> Task :lucene:analysis:opennlp:test
WARNING: A command line option has enabled the Security Manager
WARNING: The Security Manager is deprecated and will be removed in a future
release
WARNING: A command line option has enabled the Security Manager
WARNING: The Security Manager is deprecated and will be removed in a future
release
WARNING: A command line option has enabled the Security Manager
WARNING: The Security Manager is deprecated and will be removed in a future
release
WARNING: A command line option has enabled the Security Manager
WARNING: The Security Manager is deprecated and will be removed in a future
release
:lucene:analysis:opennlp:test (SUCCESS): 26 test(s)
> Task :lucene:analysis:opennlp:wipeTaskTemp
> Task :lucene:analysis:phonetic:compileTestJava
> Task :lucene:analysis:phonetic:copyTestResources NO-SOURCE
> Task :lucene:analysis:phonetic:processTestResources NO-SOURCE
> Task :lucene:analysis:phonetic:testClasses
> Task :lucene:analysis:phonetic:test
WARNING: A command line option has enabled the Security Manager
WARNING: The Security Manager is deprecated and will be removed in a future
release
WARNING: A command line option has enabled the Security Manager
WARNING: The Security Manager is deprecated and will be removed in a future
release
WARNING: A command line option has enabled the Security Manager
WARNING: The Security Manager is deprecated and will be removed in a future
release
WARNING: A command line option has enabled the Security Manager
WARNING: The Security Manager is deprecated and will be removed in a future
release
:lucene:analysis:phonetic:test (SUCCESS): 41 test(s)
> Task :lucene:analysis:phonetic:wipeTaskTemp
> Task :lucene:analysis:smartcn:compileTestJava
> Task :lucene:analysis:smartcn:copyTestResources NO-SOURCE
> Task :lucene:analysis:smartcn:processTestResources NO-SOURCE
> Task :lucene:analysis:smartcn:testClasses
> Task :lucene:analysis:smartcn:test
WARNING: A command line option has enabled the Security Manager
WARNING: The Security Manager is deprecated and will be removed in a future
release
WARNING: A command line option has enabled the Security Manager
WARNING: The Security Manager is deprecated and will be removed in a future
release
:lucene:analysis:smartcn:test (SUCCESS): 21 test(s)
> Task :lucene:analysis:smartcn:wipeTaskTemp
> Task :lucene:analysis:stempel:compileTestJava
> Task :lucene:analysis:stempel:copyTestResources
> Task :lucene:analysis:stempel:processTestResources NO-SOURCE
> Task :lucene:analysis:stempel:testClasses
> Task :lucene:analysis:stempel:test
WARNING: A command line option has enabled the Security Manager
WARNING: The Security Manager is deprecated and will be removed in a future
release
WARNING: A command line option has enabled the Security Manager
WARNING: The Security Manager is deprecated and will be removed in a future
release
WARNING: A command line option has enabled the Security Manager
WARNING: The Security Manager is deprecated and will be removed in a future
release
WARNING: A command line option has enabled the Security Manager
WARNING: The Security Manager is deprecated and will be removed in a future
release
:lucene:analysis:stempel:test (SUCCESS): 15 test(s)
> Task :lucene:analysis:stempel:wipeTaskTemp
The slowest tests (exceeding 500 ms) during this run:
1648.43s TestSearcherTaxonomyManager.testDirectory (:lucene:facet)
1618.10s Test2BPostings.test (:lucene:core)
759.26s TestLucene90DocValuesFormat.testNumericFieldJumpTables (:lucene:core)
756.50s TestLucene90DocValuesFormatMergeInstance.testNumericFieldJumpTables
(:lucene:core)
580.95s TestBestSpeedLucene80DocValuesFormat.testNumericFieldJumpTables
(:lucene:backward-codecs)
573.35s TestBestCompressionLucene80DocValuesFormat.testNumericFieldJumpTables
(:lucene:backward-codecs)
462.79s TestIndexWriterExceptions.testTooManyTokens (:lucene:core)
407.97s TestControlledRealTimeReopenThread.testControlledRealTimeReopenThread
(:lucene:core)
404.30s TestNRTThreads.testNRTThreads (:lucene:core)
402.19s
TestSimpleTextPostingsFormat.testDocsAndFreqsAndPositionsAndOffsetsAndPayloads
(:lucene:codecs)
The slowest suites (exceeding 1s) during this run:
1662.79s TestSearcherTaxonomyManager (:lucene:facet)
1618.16s Test2BPostings (:lucene:core)
1354.51s TestLucene90DocValuesFormat (:lucene:core)
1344.59s TestLucene90DocValuesFormatMergeInstance (:lucene:core)
956.53s TestBestSpeedLucene80DocValuesFormat (:lucene:backward-codecs)
940.13s TestBestCompressionLucene80DocValuesFormat (:lucene:backward-codecs)
905.30s TestSimpleTextPostingsFormat (:lucene:codecs)
510.94s TestIndexWriterExceptions (:lucene:core)
435.94s TestSearcherManager (:lucene:core)
408.57s TestControlledRealTimeReopenThread (:lucene:core)
ERROR: The following test(s) have failed:
- org.apache.lucene.replicator.nrt.TestStressNRTReplication.test
(:lucene:replicator)
Test output:
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-NightlyTests-main/checkout/lucene/replicator/build/test-results/test/outputs/OUTPUT-org.apache.lucene.replicator.nrt.TestStressNRTReplication.txt
Reproduce with: gradlew :lucene:replicator:test --tests
"org.apache.lucene.replicator.nrt.TestStressNRTReplication.test" -Ptests.jvms=4
-Ptests.haltonfailure=false -Ptests.jvmargs=-XX:TieredStopAtLevel=1
-Ptests.seed=9411BAC8B658A371 -Ptests.multiplier=2 -Ptests.nightly=true
-Ptests.badapples=false -Ptests.gui=true -Ptests.file.encoding=US-ASCII
-Ptests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-NightlyTests-main/test-data/enwiki.random.lines.txt
BUILD SUCCESSFUL in 2h 32m 3s
223 actionable tasks: 223 executed
Build step 'Invoke Gradle script' changed build result to SUCCESS
Archiving artifacts
java.lang.InterruptedException: no matches found within 10000
at hudson.FilePath$ValidateAntFileMask.hasMatch(FilePath.java:3079)
at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2958)
at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2939)
at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3329)
Also: hudson.remoting.Channel$CallSiteStackTrace: Remote call to lucene-solr-1
at
hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1797)
at
hudson.remoting.UserRequest$ExceptionResponse.retrieve(UserRequest.java:356)
at hudson.remoting.Channel.call(Channel.java:1001)
at hudson.FilePath.act(FilePath.java:1165)
at hudson.FilePath.act(FilePath.java:1154)
at hudson.FilePath.validateAntFileMask(FilePath.java:2937)
at
hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:268)
at
hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:78)
at
hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at
hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:806)
at
hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:755)
at hudson.model.Build$BuildExecution.post2(Build.java:178)
at
hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:699)
at hudson.model.Run.execute(Run.java:1913)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at
hudson.model.ResourceController.execute(ResourceController.java:99)
at hudson.model.Executor.run(Executor.java:432)
Caused: hudson.FilePath$TunneledInterruptedException
at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3331)
at hudson.remoting.UserRequest.perform(UserRequest.java:211)
at hudson.remoting.UserRequest.perform(UserRequest.java:54)
at hudson.remoting.Request$2.run(Request.java:376)
at
hudson.remoting.InterceptingExecutorService.lambda$wrap$0(InterceptingExecutorService.java:78)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
Caused: java.lang.InterruptedException: java.lang.InterruptedException: no
matches found within 10000
at hudson.FilePath.act(FilePath.java:1167)
at hudson.FilePath.act(FilePath.java:1154)
at hudson.FilePath.validateAntFileMask(FilePath.java:2937)
at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:268)
at
hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:78)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at
hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:806)
at
hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:755)
at hudson.model.Build$BuildExecution.post2(Build.java:178)
at
hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:699)
at hudson.model.Run.execute(Run.java:1913)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at hudson.model.ResourceController.execute(ResourceController.java:99)
at hudson.model.Executor.run(Executor.java:432)
No artifacts found that match the file pattern
"**/*.events,heapdumps/**,**/hs_err_pid*". Configuration error?
Recording test results
[Checks API] No suitable checks publisher found.
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
