OPTS: 11:11:23.797348 - ./nfstest_delegation --nfsversion=4.0 -e /nfsroot --server 192.168.122.153 --client 192.168.122.152 --trcdelay 10 -v all --createlog --keeptraces --rexeclog recall22 OPTS: 11:11:23.797388 - basename = OPTS: 11:11:23.797406 - bugmsgs = None OPTS: 11:11:23.797419 - client = 192.168.122.152 OPTS: 11:11:23.797431 - client-ipaddr = None OPTS: 11:11:23.797444 - client-nfsvers = 4.0,4.1 OPTS: 11:11:23.797457 - createlog = True OPTS: 11:11:23.797469 - createtraces = False OPTS: 11:11:23.797481 - datadir = OPTS: 11:11:23.797492 - export = /nfsroot OPTS: 11:11:23.797503 - file = OPTS: 11:11:23.797514 - filesize = 64k OPTS: 11:11:23.797527 - interface = None OPTS: 11:11:23.797542 - iodelay = 0.1 OPTS: 11:11:23.797553 - iptables = /usr/sbin/iptables OPTS: 11:11:23.797564 - isatty = False OPTS: 11:11:23.797575 - keeptraces = True OPTS: 11:11:23.797586 - kill = /usr/bin/kill OPTS: 11:11:23.797597 - lock-len = 4096 OPTS: 11:11:23.797608 - lock-offset = 0 OPTS: 11:11:23.797620 - lock-poffset = 8192 OPTS: 11:11:23.797630 - messages = /var/log/messages OPTS: 11:11:23.797641 - mtopts = hard,rsize=4096,wsize=4096 OPTS: 11:11:23.797652 - mtpoint = /mnt/t OPTS: 11:11:23.797663 - nconnect = 1 OPTS: 11:11:23.797673 - nfiles = 2 OPTS: 11:11:23.797684 - nfsdebug = OPTS: 11:11:23.797695 - nfserrors = False OPTS: 11:11:23.797705 - nfsstat = /usr/sbin/nfsstat OPTS: 11:11:23.797726 - nfsstats = False OPTS: 11:11:23.797738 - nfsversion = 4.0 OPTS: 11:11:23.797748 - nocleanup = False OPTS: 11:11:23.797759 - nomount = False OPTS: 11:11:23.797770 - notimestamps = False OPTS: 11:11:23.797781 - notty = False OPTS: 11:11:23.797791 - offset-delta = 4k OPTS: 11:11:23.797802 - pktdisp = False OPTS: 11:11:23.797812 - port = 2049 OPTS: 11:11:23.797824 - proto = tcp OPTS: 11:11:23.797837 - rexeclog = True OPTS: 11:11:23.797847 - rmtraces = False OPTS: 11:11:23.797858 - rpcdebug = OPTS: 11:11:23.797868 - rsize = 4k OPTS: 11:11:23.797879 - runtest = recall22 OPTS: 11:11:23.797890 - sec = sys OPTS: 11:11:23.797901 - server = 192.168.122.153 OPTS: 11:11:23.797912 - setup-delay = 4.0 OPTS: 11:11:23.797923 - sudo = /usr/bin/sudo OPTS: 11:11:23.797934 - tag = OPTS: 11:11:23.797945 - tbsize = 192k OPTS: 11:11:23.797955 - tcpdump = /usr/sbin/tcpdump OPTS: 11:11:23.797966 - tmpdir = /tmp OPTS: 11:11:23.797976 - tracepoints = OPTS: 11:11:23.797988 - trcdelay = 10.0 OPTS: 11:11:23.797998 - trcevents = /sys/kernel/debug/tracing/events OPTS: 11:11:23.798009 - trcpipe = /sys/kernel/debug/tracing/trace_pipe OPTS: 11:11:23.798020 - truncate = False OPTS: 11:11:23.798030 - tverbose = 1 OPTS: 11:11:23.798041 - verbose = all OPTS: 11:11:23.798051 - warnings = False OPTS: 11:11:23.798062 - wsize = 4k OPTS: 11:11:23.798073 - xunit-report = False OPTS: 11:11:23.798085 - xunit-report-file = None INFO: 11:11:23.798101 - SYSTEM: Linux zj-rhel92-client1 5.14.0-244.el9.x86_64 #1 SMP PREEMPT_DYNAMIC Wed Jan 25 15:35:31 EST 2023 x86_64 DBG5: 11:11:23.798497 - Get routing info: /usr/sbin/ip route get 192.168.122.153 DBG2: 11:11:23.802100 - Start remote procedure server at 192.168.122.152 DBG7: 11:11:24.216221 - SETUP starts DBG5: 11:11:24.216353 - Sync all buffers to disk DBG2: 11:11:24.252951 - Unmount volume: /usr/bin/sudo umount -f /mnt/t DBG2: 11:11:24.264966 - Mount volume: /usr/bin/sudo mount -o vers=4.0,proto=tcp,sec=sys,hard,rsize=4096,wsize=4096 192.168.122.153:/nfsroot /mnt/t DBG5: 11:11:24.303836 - Get the actual NFS version of mount point: findmnt /mnt/t DBG6: 11:11:24.307553 - NFS version of mount point: 4.0 DBG2: 11:11:24.307638 - Creating file [/mnt/t/nfstest_delegation_20230426_111123_f_001] 65536@0 DBG2: 11:11:24.375956 - Creating file [/mnt/t/nfstest_delegation_20230426_111123_f_002] 65536@0 DBG2: 11:11:24.401048 - Creating file [/mnt/t/nfstest_delegation_20230426_111123_f_003] 65536@0 DBG5: 11:11:24.426116 - Sync all buffers to disk DBG2: 11:11:24.443417 - Unmount volume: /usr/bin/sudo umount -f /mnt/t DBG7: 11:11:24.473468 - SETUP done TIME: 4.684914s *** Recall WRITE delegation with RENAME (DST) with file lock TEST: Running test 'recall22' DBG5: 11:11:28.478358 - Sync all buffers to disk DBG2: 11:11:28.533355 - Unmount volume: /usr/bin/sudo umount -f /mnt/t DBG4: 11:11:28.546005 - Check if mount point directory exists: ssh -t -t 192.168.122.152 "test -e '/mnt/t'" DBG4: 11:11:28.727029 - Check if mount point is a directory: ssh -t -t 192.168.122.152 "test -d '/mnt/t'" DBG5: 11:11:28.907466 - Sync all buffers to disk DBG2: 11:11:28.932577 - Unmount volume: ssh -t -t 192.168.122.152 "/usr/bin/sudo umount -f /mnt/t" DBG2: 11:11:29.123644 - Trace start: /usr/bin/sudo /usr/sbin/tcpdump -i ens3 -n -B 196608 -s 0 -w /tmp/nfstest_delegation_20230426_111123_001.cap host 192.168.122.151 or 192.168.122.152 DBG2: 11:11:30.172102 - Mount volume: /usr/bin/sudo mount -o vers=4.0,proto=tcp,sec=sys,hard,rsize=4096,wsize=4096 192.168.122.153:/nfsroot /mnt/t DBG5: 11:11:30.202242 - Get the actual NFS version of mount point: findmnt /mnt/t DBG6: 11:11:30.205016 - NFS version of mount point: 4.0 DBG2: 11:11:30.205132 - Mount volume: ssh -t -t 192.168.122.152 "/usr/bin/sudo mount -o vers=4.1,proto=tcp,sec=sys,hard,rsize=4096,wsize=4096 192.168.122.153:/nfsroot /mnt/t" DBG5: 11:11:30.503255 - Get the actual NFS version of mount point: ssh -t -t 192.168.122.152 "findmnt /mnt/t" DBG6: 11:11:30.682155 - NFS version of mount point: 4.1 DBG4: 11:11:30.682246 - Open /mnt/t/nfstest_delegation_20230426_111123_f_001 so open owner sticks around DBG2: 11:11:30.682968 - Open file for WRITE [/mnt/t/nfstest_delegation_20230426_111123_f_002] PASS: Open file for WRITE should succeed DBG3: 11:11:30.683219 - Lock /mnt/t/nfstest_delegation_20230426_111123_f_002 (F_SETLK, F_WRLCK) start=0 len=4096 PASS: Lock file with F_WRLCK should succeed DBG3: 11:11:30.683424 - Write file on client holding delegation [/mnt/t/nfstest_delegation_20230426_111123_f_002] PASS: Write file on client holding delegation should succeed DBG2: 11:11:30.783744 - Rename into the file (DST) from another client to recall delegation [nfstest_delegation_20230426_111123_f_003 -> nfstest_delegation_20230426_111123_f_002] PASS: Rename into the file (DST) from another client should succeed