OPTS: 13:39:41.968621 - ./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: 13:39:41.968663 - basename = OPTS: 13:39:41.968681 - bugmsgs = None OPTS: 13:39:41.968695 - client = 192.168.122.152 OPTS: 13:39:41.968709 - client-ipaddr = None OPTS: 13:39:41.968721 - client-nfsvers = 4.0,4.1 OPTS: 13:39:41.968733 - createlog = True OPTS: 13:39:41.968745 - createtraces = False OPTS: 13:39:41.968755 - datadir = OPTS: 13:39:41.968766 - export = /nfsroot OPTS: 13:39:41.968778 - file = OPTS: 13:39:41.968791 - filesize = 64k OPTS: 13:39:41.968803 - interface = None OPTS: 13:39:41.968818 - iodelay = 0.1 OPTS: 13:39:41.968829 - iptables = /usr/sbin/iptables OPTS: 13:39:41.968840 - isatty = False OPTS: 13:39:41.968851 - keeptraces = True OPTS: 13:39:41.968862 - kill = /usr/bin/kill OPTS: 13:39:41.968873 - lock-len = 4096 OPTS: 13:39:41.968884 - lock-offset = 0 OPTS: 13:39:41.968896 - lock-poffset = 8192 OPTS: 13:39:41.968906 - messages = /var/log/messages OPTS: 13:39:41.968917 - mtopts = hard,rsize=4096,wsize=4096 OPTS: 13:39:41.968928 - mtpoint = /mnt/t OPTS: 13:39:41.968939 - nconnect = 1 OPTS: 13:39:41.968957 - nfiles = 2 OPTS: 13:39:41.968969 - nfsdebug = OPTS: 13:39:41.968980 - nfserrors = False OPTS: 13:39:41.968990 - nfsstat = /usr/sbin/nfsstat OPTS: 13:39:41.969001 - nfsstats = False OPTS: 13:39:41.969012 - nfsversion = 4.0 OPTS: 13:39:41.969022 - nocleanup = False OPTS: 13:39:41.969033 - nomount = False OPTS: 13:39:41.969043 - notimestamps = False OPTS: 13:39:41.969054 - notty = False OPTS: 13:39:41.969065 - offset-delta = 4k OPTS: 13:39:41.969076 - pktdisp = False OPTS: 13:39:41.969087 - port = 2049 OPTS: 13:39:41.969098 - proto = tcp OPTS: 13:39:41.969110 - rexeclog = True OPTS: 13:39:41.969121 - rmtraces = False OPTS: 13:39:41.969131 - rpcdebug = OPTS: 13:39:41.969142 - rsize = 4k OPTS: 13:39:41.969152 - runtest = recall22 OPTS: 13:39:41.969163 - sec = sys OPTS: 13:39:41.969173 - server = 192.168.122.153 OPTS: 13:39:41.969186 - setup-delay = 4.0 OPTS: 13:39:41.969197 - sudo = /usr/bin/sudo OPTS: 13:39:41.969207 - tag = OPTS: 13:39:41.969218 - tbsize = 192k OPTS: 13:39:41.969228 - tcpdump = /usr/sbin/tcpdump OPTS: 13:39:41.969239 - tmpdir = /tmp OPTS: 13:39:41.969250 - tracepoints = OPTS: 13:39:41.969261 - trcdelay = 10.0 OPTS: 13:39:41.969272 - trcevents = /sys/kernel/debug/tracing/events OPTS: 13:39:41.969283 - trcpipe = /sys/kernel/debug/tracing/trace_pipe OPTS: 13:39:41.969294 - truncate = False OPTS: 13:39:41.969304 - tverbose = 1 OPTS: 13:39:41.969315 - verbose = all OPTS: 13:39:41.969325 - warnings = False OPTS: 13:39:41.969336 - wsize = 4k OPTS: 13:39:41.969347 - xunit-report = False OPTS: 13:39:41.969359 - xunit-report-file = None INFO: 13:39:41.969376 - 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: 13:39:41.969769 - Get routing info: /usr/sbin/ip route get 192.168.122.153 DBG2: 13:39:41.975744 - Start remote procedure server at 192.168.122.152 DBG7: 13:39:42.390831 - SETUP starts DBG5: 13:39:42.391003 - Sync all buffers to disk DBG2: 13:39:42.435502 - Unmount volume: /usr/bin/sudo umount -f /mnt/t DBG2: 13:39:42.456351 - 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: 13:39:42.762404 - Get the actual NFS version of mount point: findmnt /mnt/t DBG6: 13:39:42.766005 - NFS version of mount point: 4.0 DBG2: 13:39:42.766116 - Creating file [/mnt/t/nfstest_delegation_20230426_133941_f_001] 65536@0 DBG2: 13:39:42.843108 - Creating file [/mnt/t/nfstest_delegation_20230426_133941_f_002] 65536@0 DBG2: 13:39:42.869074 - Creating file [/mnt/t/nfstest_delegation_20230426_133941_f_003] 65536@0 DBG5: 13:39:42.901989 - Sync all buffers to disk DBG2: 13:39:42.920251 - Unmount volume: /usr/bin/sudo umount -f /mnt/t DBG7: 13:39:42.943900 - SETUP done TIME: 4.985141s *** Recall WRITE delegation with RENAME (DST) with file lock TEST: Running test 'recall22' DBG5: 13:39:46.948755 - Sync all buffers to disk DBG2: 13:39:46.999247 - Unmount volume: /usr/bin/sudo umount -f /mnt/t DBG4: 13:39:47.009069 - Check if mount point directory exists: ssh -t -t 192.168.122.152 "test -e '/mnt/t'" DBG4: 13:39:47.187663 - Check if mount point is a directory: ssh -t -t 192.168.122.152 "test -d '/mnt/t'" DBG5: 13:39:47.365745 - Sync all buffers to disk DBG2: 13:39:47.384329 - Unmount volume: ssh -t -t 192.168.122.152 "/usr/bin/sudo umount -f /mnt/t" DBG2: 13:39:47.580512 - Trace start: /usr/bin/sudo /usr/sbin/tcpdump -i ens3 -n -B 196608 -s 0 -w /tmp/nfstest_delegation_20230426_133941_001.cap host 192.168.122.151 or 192.168.122.152 DBG2: 13:39:48.669627 - 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: 13:39:48.703165 - Get the actual NFS version of mount point: findmnt /mnt/t DBG6: 13:39:48.705938 - NFS version of mount point: 4.0 DBG2: 13:39:48.706064 - 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: 13:39:49.244565 - Get the actual NFS version of mount point: ssh -t -t 192.168.122.152 "findmnt /mnt/t" DBG6: 13:39:49.423739 - NFS version of mount point: 4.1 DBG4: 13:39:49.423846 - Open /mnt/t/nfstest_delegation_20230426_133941_f_001 so open owner sticks around DBG2: 13:39:49.424788 - Open file for WRITE [/mnt/t/nfstest_delegation_20230426_133941_f_002] PASS: Open file for WRITE should succeed DBG3: 13:39:49.425050 - Lock /mnt/t/nfstest_delegation_20230426_133941_f_002 (F_SETLK, F_WRLCK) start=0 len=4096 PASS: Lock file with F_WRLCK should succeed DBG3: 13:39:49.425266 - Write file on client holding delegation [/mnt/t/nfstest_delegation_20230426_133941_f_002] PASS: Write file on client holding delegation should succeed DBG2: 13:39:49.525732 - Rename into the file (DST) from another client to recall delegation [nfstest_delegation_20230426_133941_f_003 -> nfstest_delegation_20230426_133941_f_002] PASS: Rename into the file (DST) from another client should succeed