Hi all,
we are currently in the process of migrating our file server infrastructure to
NFS. In our test environments, the following problem has now occurred several
times in certain situations:
A previously very fast NFS file operation suddenly takes 5 seconds longer - per
file. This leads to applications running very slowly and severely delayed file
operations.
Here are the details:
NFS server:
OS: Ubuntu 22.04.1, all patches installed
Kernel: Ubuntu Mainline, Versions
6.1.7-060107-generic_6.1.7-060107.202301181200
6.1.8-060108_6.1.8-060108.202301240742
6.1.9-060109_6.1.9-060109.202302010835
Security options: all Kerberos security options are affected
(The bug does not seem to occur without Kerberos security.)
Output of exportfs -v:
/export
gss/krb5p(async,wdelay,hide,crossmnt,no_subtree_check,fsid=0,sec=sys,rw,secure,root_squash,no_all_squash)
/export
gss/krb5(async,wdelay,hide,crossmnt,no_subtree_check,fsid=0,sec=sys,rw,secure,root_squash,no_all_squash)
Client 1:
OS: Arch Linux, all patches installed
Kernel: 6.1.9-arch1-2, 6.1.9-arch1-1
Mount-Line: servername:/ on /nfs type nfs4
(rw,relatime,vers=4.2,rsize=1048576,wsize=1048576,namlen=255,hard,proto=tcp,timeo=600,retrans=2,sec=krb5p,clientaddr=XX.XX.XX.XX,local_lock=none,addr=YY.YY.YY.YY,_netdev)
krb5: 1.20.1-1
libevent: 2.1.12-4
nfs-utils: 2.6.2-1
util-linux: 2.38.1-1
Client 2:
OS: openSuSE 15.4, all patches installed
Kernel: 5.14.21-150400.24.41-default
Mount-Line: servername:/ on /nfs type nfs4
(rw,relatime,sync,vers=4.2,rsize=1048576,wsize=1048576,namlen=255,acregmin=0,acregmax=0,acdirmin=0,acdirmax=0,softerr,softreval,noac,noresvport,proto=tcp,timeo=1,retrans=2,sec=krb5,clientaddr=XX.XX.XX.XX,lookupcache=none,local_lock=none,addr=YY.YY.YY.YY)
libgssapi3: 7.8.0-bp154.2.4.1
libevent: 2.1.8-2.23
nfs-client: 2.1.1-150100.10.27.1
util-linux: 2.37.2-140400.8.14.1
The error occurs for example if a file is touched twice:
touch testfile && echo "done" && touch testfile && echo "and again"
However, touching a large number of files (about 10000) with (pairwise)
different filenames works fast.
Here is another example that triggers the error:
1st step: create many files (shell code in Z-shell syntax):
for i in {1..10000}; do
echo "test" > $i.txt
done
This is fast.
2nd step:
for i in {1..10000}; do
echo $i
cat $i.txt
done
This takes 5 seconds per cat(1) call.
After unmounting and mounting, the 2nd step also runs quickly at first. But
after executing the 2nd step several times in a row, the error occurs again
(quite soon, after the 2nd or 3rd execution).
We were not able to reproduce the error without a Kerberos security type set.
Attached are a log from the server and from the client. In both cases
rpcdebug -m nfs -s all
rpcdebug -m nfsd -s all
rpcdebug -m rpc -s all
was set.
Best regards,
Florian Möller
--
Dr. Florian Möller
Universität Würzburg
Institut für Mathematik
Emil-Fischer-Straße 30
97074 Würzburg, Germany
Tel. +49 931 3185596
Feb 07 11:10:25 wma7113 kernel: nfs4_free_slot: slotid 0 highest_used_slotid 4294967295
Feb 07 11:10:25 wma7113 kernel: NFS: nfs_weak_revalidate: inode 4625339 is valid
Feb 07 11:10:25 wma7113 kernel: NFS: read(testdir/3167.txt, 1048576@0)
Feb 07 11:10:25 wma7113 kernel: NFS: read(testdir/3167.txt, 1048576@5)
Feb 07 11:10:25 wma7113 kernel: NFS: flush(testdir/3167.txt)
Feb 07 11:10:25 wma7113 kernel: NFS: release(testdir/3167.txt)
Feb 07 11:10:25 wma7113 kernel: --> nfs4_alloc_slot used_slots=0000 highest_used=4294967295 max_slots=30
Feb 07 11:10:25 wma7113 kernel: <-- nfs4_alloc_slot used_slots=0001 highest_used=0 slotid=0
Feb 07 11:10:25 wma7113 kernel: RPC: gss_get_mic_v2
Feb 07 11:10:25 wma7113 kernel: encode_sequence: sessionid=1675430610:3317937782:36:0 seqid=25366 slotid=0 max_slotid=0 cache_this=1
Feb 07 11:10:25 wma7113 kernel: RPC: gss_get_mic_v2
Feb 07 11:10:25 wma7113 kernel: RPC: xs_tcp_send_request(256) = 0
Feb 07 11:10:25 wma7113 kernel: RPC: gss_verify_mic_v2
Feb 07 11:10:25 wma7113 kernel: RPC: gss_verify_mic_v2
Feb 07 11:10:25 wma7113 kernel: --> nfs4_alloc_slot used_slots=0001 highest_used=0 max_slots=30
Feb 07 11:10:25 wma7113 kernel: <-- nfs4_alloc_slot used_slots=0003 highest_used=1 slotid=1
Feb 07 11:10:25 wma7113 kernel: nfs4_free_slot: slotid 1 highest_used_slotid 0
Feb 07 11:10:25 wma7113 kernel: nfs41_sequence_process: Error 0 free the slot
Feb 07 11:10:25 wma7113 kernel: nfs4_free_slot: slotid 0 highest_used_slotid 4294967295
Feb 07 11:10:25 wma7113 kernel: nfs4_close_done: ret = 0
Feb 07 11:10:25 wma7113 kernel: NFS: dentry_delete(testdir/3167.txt, 48084c)
Feb 07 11:10:25 wma7113 kernel: NFS: permission(0:57/4622172), mask=0x81, res=0
Feb 07 11:10:25 wma7113 kernel: NFS: open file(testdir/3168.txt)
Feb 07 11:10:25 wma7113 kernel: --> nfs4_alloc_slot used_slots=0000 highest_used=4294967295 max_slots=30
Feb 07 11:10:25 wma7113 kernel: <-- nfs4_alloc_slot used_slots=0001 highest_used=0 slotid=0
Feb 07 11:10:25 wma7113 kernel: RPC: gss_get_mic_v2
Feb 07 11:10:25 wma7113 kernel: encode_sequence: sessionid=1675430610:3317937782:36:0 seqid=25367 slotid=0 max_slotid=0 cache_this=1
Feb 07 11:10:25 wma7113 kernel: RPC: gss_wrap_kerberos_v2
Feb 07 11:10:25 wma7113 kernel: RPC: xs_tcp_send_request(328) = 0
Feb 07 11:10:25 wma7113 kernel: RPC: gss_verify_mic_v2
Feb 07 11:10:25 wma7113 kernel: RPC: gss_unwrap_kerberos_v2
Feb 07 11:10:25 wma7113 kernel: decode_attr_type: type=0100000
Feb 07 11:10:25 wma7113 kernel: decode_attr_change: change attribute=1799337701143539908
Feb 07 11:10:25 wma7113 kernel: decode_attr_size: file size=5
Feb 07 11:10:25 wma7113 kernel: decode_attr_fsid: fsid=(0xaee0e28f29494091/0xa495028a183ab387)
Feb 07 11:10:25 wma7113 kernel: decode_attr_fileid: fileid=4625340
Feb 07 11:10:25 wma7113 kernel: decode_attr_fs_locations: fs_locations done, error = 0
Feb 07 11:10:25 wma7113 kernel: decode_attr_mode: file mode=0664
Feb 07 11:10:25 wma7113 kernel: decode_attr_nlink: nlink=1
Feb 07 11:10:25 wma7113 kernel: decode_attr_owner: name=moeller@ifm
Feb 07 11:10:25 wma7113 kernel: decode_attr_group: name=ldapusers@ifm
Feb 07 11:10:25 wma7113 kernel: decode_attr_rdev: rdev=(0x0:0x0)
Feb 07 11:10:25 wma7113 kernel: decode_attr_space_used: space used=4096
Feb 07 11:10:25 wma7113 kernel: decode_attr_time_access: atime=1675763820
Feb 07 11:10:25 wma7113 kernel: decode_attr_time_metadata: ctime=1675763820
Feb 07 11:10:25 wma7113 kernel: decode_attr_time_modify: mtime=1675763820
Feb 07 11:10:25 wma7113 kernel: decode_attr_mounted_on_fileid: fileid=4625340
Feb 07 11:10:25 wma7113 kernel: decode_getfattr_attrs: xdr returned 0
Feb 07 11:10:25 wma7113 kernel: decode_getfattr_generic: xdr returned 0
Feb 07 11:10:25 wma7113 kernel: --> nfs4_alloc_slot used_slots=0001 highest_used=0 max_slots=30
Feb 07 11:10:25 wma7113 kernel: <-- nfs4_alloc_slot used_slots=0003 highest_used=1 slotid=1
Feb 07 11:10:25 wma7113 kernel: nfs4_free_slot: slotid 1 highest_used_slotid 0
Feb 07 11:10:25 wma7113 kernel: nfs41_sequence_process: Error 0 free the slot
Feb 07 11:10:25 wma7113 kernel: NFS: nfs_update_inode(0:57/4625340 fh_crc=0x286e2ced ct=3 info=0x427e7f)
<--- 5 seconds delay --->
Feb 07 11:10:31 wma7113 kernel: nfs4_free_slot: slotid 0 highest_used_slotid 4294967295
Feb 07 11:10:31 wma7113 kernel: NFS: nfs_weak_revalidate: inode 4625340 is valid
Feb 07 11:10:31 wma7113 kernel: NFS: read(testdir/3168.txt, 1048576@0)
Feb 07 11:10:31 wma7113 kernel: NFS: read(testdir/3168.txt, 1048576@5)
Feb 07 11:10:31 wma7113 kernel: NFS: flush(testdir/3168.txt)
Feb 07 11:10:31 wma7113 kernel: NFS: release(testdir/3168.txt)
Feb 07 11:10:31 wma7113 kernel: --> nfs4_alloc_slot used_slots=0000 highest_used=4294967295 max_slots=30
Feb 07 11:10:31 wma7113 kernel: <-- nfs4_alloc_slot used_slots=0001 highest_used=0 slotid=0
Feb 07 11:10:31 wma7113 kernel: RPC: gss_get_mic_v2
Feb 07 11:10:31 wma7113 kernel: encode_sequence: sessionid=1675430610:3317937782:36:0 seqid=25368 slotid=0 max_slotid=0 cache_this=1
Feb 07 11:10:31 wma7113 kernel: RPC: gss_get_mic_v2
Feb 07 11:10:31 wma7113 kernel: RPC: xs_tcp_send_request(256) = 0
Feb 07 11:10:31 wma7113 kernel: RPC: gss_verify_mic_v2
Feb 07 11:10:31 wma7113 kernel: RPC: gss_verify_mic_v2
Feb 07 11:10:31 wma7113 kernel: --> nfs4_alloc_slot used_slots=0001 highest_used=0 max_slots=30
Feb 07 11:10:31 wma7113 kernel: <-- nfs4_alloc_slot used_slots=0003 highest_used=1 slotid=1
Feb 07 11:10:31 wma7113 kernel: nfs4_free_slot: slotid 1 highest_used_slotid 0
Feb 07 11:10:31 wma7113 kernel: nfs41_sequence_process: Error 0 free the slot
Feb 07 11:10:31 wma7113 kernel: nfs4_free_slot: slotid 0 highest_used_slotid 4294967295
Feb 07 11:10:31 wma7113 kernel: nfs4_close_done: ret = 0
Feb 07 11:10:31 wma7113 kernel: NFS: dentry_delete(testdir/3168.txt, 48084c)
Feb 07 11:10:31 wma7113 kernel: NFS: permission(0:57/4622172), mask=0x81, res=0
Feb 07 11:10:31 wma7113 kernel: NFS: open file(testdir/3169.txt)
Feb 07 11:10:31 wma7113 kernel: --> nfs4_alloc_slot used_slots=0000 highest_used=4294967295 max_slots=30
Feb 07 11:10:31 wma7113 kernel: <-- nfs4_alloc_slot used_slots=0001 highest_used=0 slotid=0
Feb 07 11:10:31 wma7113 kernel: RPC: gss_get_mic_v2
Feb 07 11:10:31 wma7113 kernel: encode_sequence: sessionid=1675430610:3317937782:36:0 seqid=25369 slotid=0 max_slotid=0 cache_this=1
Feb 07 11:10:31 wma7113 kernel: RPC: gss_wrap_kerberos_v2
Feb 07 11:10:31 wma7113 kernel: RPC: xs_tcp_send_request(328) = 0
Feb 07 11:10:31 wma7113 kernel: RPC: gss_verify_mic_v2
Feb 07 11:10:31 wma7113 kernel: RPC: gss_unwrap_kerberos_v2
Feb 07 11:10:31 wma7113 kernel: decode_attr_type: type=0100000
Feb 07 11:10:31 wma7113 kernel: decode_attr_change: change attribute=1799337701143539908
Feb 07 11:10:31 wma7113 kernel: decode_attr_size: file size=5
Feb 07 11:10:31 wma7113 kernel: decode_attr_fsid: fsid=(0xaee0e28f29494091/0xa495028a183ab387)
Feb 07 11:10:31 wma7113 kernel: decode_attr_fileid: fileid=4625341
Feb 07 11:10:31 wma7113 kernel: decode_attr_fs_locations: fs_locations done, error = 0
Feb 07 11:10:31 wma7113 kernel: decode_attr_mode: file mode=0664
Feb 07 11:10:31 wma7113 kernel: decode_attr_nlink: nlink=1
Feb 07 11:10:31 wma7113 kernel: decode_attr_owner: name=moeller@ifm
Feb 07 11:10:31 wma7113 kernel: decode_attr_group: name=ldapusers@ifm
Feb 07 11:10:31 wma7113 kernel: decode_attr_rdev: rdev=(0x0:0x0)
Feb 07 11:10:31 wma7113 kernel: decode_attr_space_used: space used=4096
Feb 07 11:10:31 wma7113 kernel: decode_attr_time_access: atime=1675763820
Feb 07 11:10:31 wma7113 kernel: decode_attr_time_metadata: ctime=1675763820
Feb 07 11:10:31 wma7113 kernel: decode_attr_time_modify: mtime=1675763820
Feb 07 11:10:31 wma7113 kernel: decode_attr_mounted_on_fileid: fileid=4625341
Feb 07 11:10:31 wma7113 kernel: decode_getfattr_attrs: xdr returned 0
Feb 07 11:10:31 wma7113 kernel: decode_getfattr_generic: xdr returned 0
Feb 07 11:10:31 wma7113 kernel: --> nfs4_alloc_slot used_slots=0001 highest_used=0 max_slots=30
Feb 07 11:10:31 wma7113 kernel: <-- nfs4_alloc_slot used_slots=0003 highest_used=1 slotid=1
Feb 07 11:10:31 wma7113 kernel: nfs4_free_slot: slotid 1 highest_used_slotid 0
Feb 07 11:10:31 wma7113 kernel: nfs41_sequence_process: Error 0 free the slot
Feb 07 11:10:31 wma7113 kernel: NFS: nfs_update_inode(0:57/4625341 fh_crc=0x00286593 ct=3 info=0x427e7f)
Feb 07 11:10:31 wma7113 kernel: nfs4_renew_state: start
Feb 07 11:10:31 wma7113 kernel: nfs4_schedule_state_renewal: requeueing work. Lease period = 60
Feb 07 11:10:31 wma7113 kernel: nfs4_renew_state: done
<--- 5 seconds delay --->
Feb 07 11:10:36 wma7113 kernel: nfs4_free_slot: slotid 0 highest_used_slotid 4294967295
Feb 07 11:10:36 wma7113 kernel: NFS: nfs_weak_revalidate: inode 4625341 is valid
Feb 07 11:10:36 wma7113 kernel: NFS: read(testdir/3169.txt, 1048576@0)
Feb 07 11:10:36 wma7113 kernel: NFS: read(testdir/3169.txt, 1048576@5)
Feb 07 11:10:36 wma7113 kernel: NFS: flush(testdir/3169.txt)
Feb 07 11:10:36 wma7113 kernel: NFS: release(testdir/3169.txt)
Feb 07 11:10:36 wma7113 kernel: --> nfs4_alloc_slot used_slots=0000 highest_used=4294967295 max_slots=30
Feb 07 11:10:36 wma7113 kernel: <-- nfs4_alloc_slot used_slots=0001 highest_used=0 slotid=0
Feb 07 11:10:36 wma7113 kernel: RPC: gss_get_mic_v2
Feb 07 11:10:36 wma7113 kernel: encode_sequence: sessionid=1675430610:3317937782:36:0 seqid=25370 slotid=0 max_slotid=0 cache_this=1
Feb 07 11:10:36 wma7113 kernel: RPC: gss_get_mic_v2
Feb 07 11:10:36 wma7113 kernel: RPC: xs_tcp_send_request(256) = 0
Feb 07 11:10:36 wma7113 kernel: RPC: gss_verify_mic_v2
Feb 07 11:10:36 wma7113 kernel: RPC: gss_verify_mic_v2
Feb 07 11:10:36 wma7113 kernel: --> nfs4_alloc_slot used_slots=0001 highest_used=0 max_slots=30
Feb 07 11:10:36 wma7113 kernel: <-- nfs4_alloc_slot used_slots=0003 highest_used=1 slotid=1
Feb 07 11:10:36 wma7113 kernel: nfs4_free_slot: slotid 1 highest_used_slotid 0
Feb 07 11:10:36 wma7113 kernel: nfs41_sequence_process: Error 0 free the slot
Feb 07 11:10:36 wma7113 kernel: nfs4_free_slot: slotid 0 highest_used_slotid 4294967295
Feb 07 11:10:36 wma7113 kernel: nfs4_close_done: ret = 0
Feb 07 11:10:36 wma7113 kernel: NFS: dentry_delete(testdir/3169.txt, 48084c)
Feb 07 11:10:36 wma7113 kernel: NFS: permission(0:57/4622172), mask=0x81, res=0
Feb 07 11:10:36 wma7113 kernel: NFS: open file(testdir/3170.txt)
Feb 07 11:10:36 wma7113 kernel: --> nfs4_alloc_slot used_slots=0000 highest_used=4294967295 max_slots=30
Feb 07 11:10:36 wma7113 kernel: <-- nfs4_alloc_slot used_slots=0001 highest_used=0 slotid=0
Feb 07 11:10:36 wma7113 kernel: RPC: gss_get_mic_v2
Feb 07 11:10:36 wma7113 kernel: encode_sequence: sessionid=1675430610:3317937782:36:0 seqid=25371 slotid=0 max_slotid=0 cache_this=1
Feb 07 11:10:36 wma7113 kernel: RPC: gss_wrap_kerberos_v2
Feb 07 11:10:36 wma7113 kernel: RPC: xs_tcp_send_request(328) = 0
Feb 07 11:10:36 wma7113 kernel: RPC: gss_verify_mic_v2
Feb 07 11:10:36 wma7113 kernel: RPC: gss_unwrap_kerberos_v2
Feb 07 11:10:36 wma7113 kernel: decode_attr_type: type=0100000
Feb 07 11:10:36 wma7113 kernel: decode_attr_change: change attribute=1799337701143539908
Feb 07 11:10:36 wma7113 kernel: decode_attr_size: file size=5
Feb 07 11:10:36 wma7113 kernel: decode_attr_fsid: fsid=(0xaee0e28f29494091/0xa495028a183ab387)
Feb 07 11:10:36 wma7113 kernel: decode_attr_fileid: fileid=4625342
Feb 07 11:10:36 wma7113 kernel: decode_attr_fs_locations: fs_locations done, error = 0
Feb 07 11:10:36 wma7113 kernel: decode_attr_mode: file mode=0664
Feb 07 11:10:36 wma7113 kernel: decode_attr_nlink: nlink=1
Feb 07 11:10:36 wma7113 kernel: decode_attr_owner: name=moeller@ifm
Feb 07 11:10:36 wma7113 kernel: decode_attr_group: name=ldapusers@ifm
Feb 07 11:10:36 wma7113 kernel: decode_attr_rdev: rdev=(0x0:0x0)
Feb 07 11:10:36 wma7113 kernel: decode_attr_space_used: space used=4096
Feb 07 11:10:36 wma7113 kernel: decode_attr_time_access: atime=1675763820
Feb 07 11:10:36 wma7113 kernel: decode_attr_time_metadata: ctime=1675763820
Feb 07 11:10:36 wma7113 kernel: decode_attr_time_modify: mtime=1675763820
Feb 07 11:10:36 wma7113 kernel: decode_attr_mounted_on_fileid: fileid=4625342
Feb 07 11:10:36 wma7113 kernel: decode_getfattr_attrs: xdr returned 0
Feb 07 11:10:36 wma7113 kernel: decode_getfattr_generic: xdr returned 0
Feb 07 11:10:36 wma7113 kernel: --> nfs4_alloc_slot used_slots=0001 highest_used=0 max_slots=30
Feb 07 11:10:36 wma7113 kernel: <-- nfs4_alloc_slot used_slots=0003 highest_used=1 slotid=1
Feb 07 11:10:36 wma7113 kernel: nfs4_free_slot: slotid 1 highest_used_slotid 0
Feb 07 11:10:36 wma7113 kernel: nfs41_sequence_process: Error 0 free the slot
Feb 07 11:10:36 wma7113 kernel: NFS: nfs_update_inode(0:57/4625342 fh_crc=0x21e97a40 ct=3 info=0x427e7f)
<--- 5 seconds delay --->
Feb 07 11:10:41 wma7113 kernel: nfs4_free_slot: slotid 0 highest_used_slotid 4294967295
Feb 07 11:10:41 wma7113 kernel: NFS: nfs_weak_revalidate: inode 4625342 is valid
Feb 07 11:10:41 wma7113 kernel: NFS: read(testdir/3170.txt, 1048576@0)
Feb 07 11:10:41 wma7113 kernel: NFS: read(testdir/3170.txt, 1048576@5)
Feb 07 11:10:41 wma7113 kernel: NFS: flush(testdir/3170.txt)
Feb 07 11:10:41 wma7113 kernel: NFS: release(testdir/3170.txt)
Feb 07 11:10:41 wma7113 kernel: --> nfs4_alloc_slot used_slots=0000 highest_used=4294967295 max_slots=30
Feb 07 11:10:41 wma7113 kernel: <-- nfs4_alloc_slot used_slots=0001 highest_used=0 slotid=0
Feb 07 11:10:41 wma7113 kernel: RPC: gss_get_mic_v2
Feb 07 11:10:41 wma7113 kernel: encode_sequence: sessionid=1675430610:3317937782:36:0 seqid=25372 slotid=0 max_slotid=0 cache_this=1
Feb 07 11:10:41 wma7113 kernel: RPC: gss_get_mic_v2
Feb 07 11:10:41 wma7113 kernel: RPC: xs_tcp_send_request(256) = 0
Feb 07 11:10:41 wma7113 kernel: RPC: gss_verify_mic_v2
Feb 07 11:10:41 wma7113 kernel: RPC: gss_verify_mic_v2
Feb 07 11:10:41 wma7113 kernel: --> nfs4_alloc_slot used_slots=0001 highest_used=0 max_slots=30
Feb 07 11:10:41 wma7113 kernel: <-- nfs4_alloc_slot used_slots=0003 highest_used=1 slotid=1
Feb 07 11:10:41 wma7113 kernel: nfs4_free_slot: slotid 1 highest_used_slotid 0
Feb 07 11:10:41 wma7113 kernel: nfs41_sequence_process: Error 0 free the slot
Feb 07 11:10:41 wma7113 kernel: nfs4_free_slot: slotid 0 highest_used_slotid 4294967295
Feb 07 11:10:41 wma7113 kernel: nfs4_close_done: ret = 0
Feb 07 11:10:41 wma7113 kernel: NFS: dentry_delete(testdir/3170.txt, 48084c)
Feb 07 11:10:41 wma7113 kernel: NFS: permission(0:57/4622172), mask=0x81, res=0
Feb 07 11:10:41 wma7113 kernel: NFS: open file(testdir/3171.txt)
Feb 07 11:10:41 wma7113 kernel: --> nfs4_alloc_slot used_slots=0000 highest_used=4294967295 max_slots=30
Feb 07 11:10:41 wma7113 kernel: <-- nfs4_alloc_slot used_slots=0001 highest_used=0 slotid=0
Feb 07 11:10:41 wma7113 kernel: RPC: gss_get_mic_v2
Feb 07 11:10:41 wma7113 kernel: encode_sequence: sessionid=1675430610:3317937782:36:0 seqid=25373 slotid=0 max_slotid=0 cache_this=1
Feb 07 11:10:41 wma7113 kernel: RPC: gss_wrap_kerberos_v2
Feb 07 11:10:41 wma7113 kernel: RPC: xs_tcp_send_request(328) = 0
Feb 07 11:10:41 wma7113 kernel: RPC: gss_verify_mic_v2
Feb 07 11:10:41 wma7113 kernel: RPC: gss_unwrap_kerberos_v2
Feb 07 11:10:41 wma7113 kernel: decode_attr_type: type=0100000
Feb 07 11:10:41 wma7113 kernel: decode_attr_change: change attribute=1799337701147539776
Feb 07 11:10:41 wma7113 kernel: decode_attr_size: file size=5
Feb 07 11:10:41 wma7113 kernel: decode_attr_fsid: fsid=(0xaee0e28f29494091/0xa495028a183ab387)
Feb 07 11:10:41 wma7113 kernel: decode_attr_fileid: fileid=4625343
Feb 07 11:10:41 wma7113 kernel: decode_attr_fs_locations: fs_locations done, error = 0
Feb 07 11:10:41 wma7113 kernel: decode_attr_mode: file mode=0664
Feb 07 11:10:41 wma7113 kernel: decode_attr_nlink: nlink=1
Feb 07 11:10:41 wma7113 kernel: decode_attr_owner: name=moeller@ifm
Feb 07 11:10:41 wma7113 kernel: decode_attr_group: name=ldapusers@ifm
Feb 07 11:10:41 wma7113 kernel: decode_attr_rdev: rdev=(0x0:0x0)
Feb 07 11:10:41 wma7113 kernel: decode_attr_space_used: space used=4096
Feb 07 11:10:41 wma7113 kernel: decode_attr_time_access: atime=1675763820
Feb 07 11:10:41 wma7113 kernel: decode_attr_time_metadata: ctime=1675763820
Feb 07 11:10:41 wma7113 kernel: decode_attr_time_modify: mtime=1675763820
Feb 07 11:10:41 wma7113 kernel: decode_attr_mounted_on_fileid: fileid=4625343
Feb 07 11:10:41 wma7113 kernel: decode_getfattr_attrs: xdr returned 0
Feb 07 11:10:41 wma7113 kernel: decode_getfattr_generic: xdr returned 0
Feb 07 11:10:41 wma7113 kernel: --> nfs4_alloc_slot used_slots=0001 highest_used=0 max_slots=30
Feb 07 11:10:41 wma7113 kernel: <-- nfs4_alloc_slot used_slots=0003 highest_used=1 slotid=1
Feb 07 11:10:41 wma7113 kernel: nfs4_free_slot: slotid 1 highest_used_slotid 0
Feb 07 11:10:41 wma7113 kernel: nfs41_sequence_process: Error 0 free the slot
Feb 07 11:10:41 wma7113 kernel: NFS: nfs_update_inode(0:57/4625343 fh_crc=0x98fcf935 ct=3 info=0x427e7f)
<--- 5 seconds delay --->
Feb 07 11:10:46 wma7113 kernel: nfs4_free_slot: slotid 0 highest_used_slotid 4294967295
Feb 07 11:10:46 wma7113 kernel: NFS: nfs_weak_revalidate: inode 4625343 is valid
Feb 07 11:10:46 wma7113 kernel: NFS: read(testdir/3171.txt, 1048576@0)
Feb 07 11:10:46 wma7113 kernel: NFS: read(testdir/3171.txt, 1048576@5)
Feb 07 11:10:46 wma7113 kernel: NFS: flush(testdir/3171.txt)
Feb 07 11:10:46 wma7113 kernel: NFS: release(testdir/3171.txt)
Feb 07 11:10:46 wma7113 kernel: --> nfs4_alloc_slot used_slots=0000 highest_used=4294967295 max_slots=30
Feb 07 11:10:46 wma7113 kernel: <-- nfs4_alloc_slot used_slots=0001 highest_used=0 slotid=0
Feb 07 11:10:46 wma7113 kernel: RPC: gss_get_mic_v2
Feb 07 11:10:46 wma7113 kernel: encode_sequence: sessionid=1675430610:3317937782:36:0 seqid=25374 slotid=0 max_slotid=0 cache_this=1
Feb 07 11:10:46 wma7113 kernel: RPC: gss_get_mic_v2
Feb 07 11:10:46 wma7113 kernel: RPC: xs_tcp_send_request(256) = 0
Feb 07 11:10:46 wma7113 kernel: RPC: gss_verify_mic_v2
Feb 07 11:10:46 wma7113 kernel: RPC: gss_verify_mic_v2
Feb 07 11:10:46 wma7113 kernel: --> nfs4_alloc_slot used_slots=0001 highest_used=0 max_slots=30
Feb 07 11:10:46 wma7113 kernel: <-- nfs4_alloc_slot used_slots=0003 highest_used=1 slotid=1
Feb 07 11:10:46 wma7113 kernel: nfs4_free_slot: slotid 1 highest_used_slotid 0
Feb 07 11:10:46 wma7113 kernel: nfs41_sequence_process: Error 0 free the slot
Feb 07 11:10:46 wma7113 kernel: nfs4_free_slot: slotid 0 highest_used_slotid 4294967295
Feb 07 11:10:46 wma7113 kernel: nfs4_close_done: ret = 0
Feb 07 11:10:46 wma7113 kernel: NFS: dentry_delete(testdir/3171.txt, 48084c)
Feb 07 11:10:46 wma7113 kernel: NFS: permission(0:57/4622172), mask=0x81, res=0
Feb 07 11:10:46 wma7113 kernel: NFS: open file(testdir/3172.txt)
Feb 07 11:10:46 wma7113 kernel: --> nfs4_alloc_slot used_slots=0000 highest_used=4294967295 max_slots=30
Feb 07 11:10:46 wma7113 kernel: <-- nfs4_alloc_slot used_slots=0001 highest_used=0 slotid=0
Feb 07 11:10:46 wma7113 kernel: RPC: gss_get_mic_v2
Feb 07 11:10:46 wma7113 kernel: encode_sequence: sessionid=1675430610:3317937782:36:0 seqid=25375 slotid=0 max_slotid=0 cache_this=1
Feb 07 11:10:46 wma7113 kernel: RPC: gss_wrap_kerberos_v2
Feb 07 11:10:46 wma7113 kernel: RPC: xs_tcp_send_request(328) = 0
Feb 07 11:10:46 wma7113 kernel: RPC: gss_verify_mic_v2
Feb 07 11:10:46 wma7113 kernel: RPC: gss_unwrap_kerberos_v2
Feb 07 11:10:46 wma7113 kernel: decode_attr_type: type=0100000
Feb 07 11:10:46 wma7113 kernel: decode_attr_change: change attribute=1799337701147539776
Feb 07 11:10:46 wma7113 kernel: decode_attr_size: file size=5
Feb 07 11:10:46 wma7113 kernel: decode_attr_fsid: fsid=(0xaee0e28f29494091/0xa495028a183ab387)
Feb 07 11:10:46 wma7113 kernel: decode_attr_fileid: fileid=4625344
Feb 07 11:10:46 wma7113 kernel: decode_attr_fs_locations: fs_locations done, error = 0
Feb 07 11:10:46 wma7113 kernel: decode_attr_mode: file mode=0664
Feb 07 11:10:46 wma7113 kernel: decode_attr_nlink: nlink=1
Feb 07 11:10:46 wma7113 kernel: decode_attr_owner: name=moeller@ifm
Feb 07 11:10:46 wma7113 kernel: decode_attr_group: name=ldapusers@ifm
Feb 07 11:10:46 wma7113 kernel: decode_attr_rdev: rdev=(0x0:0x0)
Feb 07 11:10:46 wma7113 kernel: decode_attr_space_used: space used=4096
Feb 07 11:10:46 wma7113 kernel: decode_attr_time_access: atime=1675763820
Feb 07 11:10:46 wma7113 kernel: decode_attr_time_metadata: ctime=1675763820
Feb 07 11:10:46 wma7113 kernel: decode_attr_time_modify: mtime=1675763820
Feb 07 11:10:46 wma7113 kernel: decode_attr_mounted_on_fileid: fileid=4625344
Feb 07 11:10:46 wma7113 kernel: decode_getfattr_attrs: xdr returned 0
Feb 07 11:10:46 wma7113 kernel: decode_getfattr_generic: xdr returned 0
Feb 07 11:10:46 wma7113 kernel: --> nfs4_alloc_slot used_slots=0001 highest_used=0 max_slots=30
Feb 07 11:10:46 wma7113 kernel: <-- nfs4_alloc_slot used_slots=0003 highest_used=1 slotid=1
Feb 07 11:10:46 wma7113 kernel: nfs4_free_slot: slotid 1 highest_used_slotid 0
Feb 07 11:10:46 wma7113 kernel: nfs41_sequence_process: Error 0 free the slot
Feb 07 11:10:46 wma7113 kernel: NFS: nfs_update_inode(0:57/4625344 fh_crc=0x15f7431d ct=3 info=0x427e7f)
Feb 07 11:08:02 v09 kernel: svc: server 000000006d109dfb, pool 0, transport 00000000433c4cd7, inuse=4
Feb 07 11:08:02 v09 kernel: svc: svc_authenticate (6)
Feb 07 11:08:02 v09 kernel: svc: server 000000004616e212, pool 0, transport 00000000433c4cd7, inuse=5
Feb 07 11:08:02 v09 kernel: RPC: gss_verify_mic_v2
Feb 07 11:08:02 v09 kernel: RPC: gss_get_mic_v2
Feb 07 11:08:02 v09 kernel: RPC: gss_verify_mic_v2
Feb 07 11:08:02 v09 kernel: __find_in_sessionid_hashtbl: 1675430610:3317937782:36:0
Feb 07 11:08:02 v09 kernel: nfsd4_sequence: slotid 0
Feb 07 11:08:02 v09 kernel: check_slot_seqid enter. seqid 25308 slot_seqid 25307
Feb 07 11:08:02 v09 kernel: --> nfsd4_store_cache_entry slot 00000000f22dd884
Feb 07 11:08:02 v09 kernel: RPC: gss_get_mic_v2
Feb 07 11:08:02 v09 kernel: svc: server 000000006d109dfb, pool 0, transport 00000000433c4cd7, inuse=4
Feb 07 11:08:02 v09 kernel: svc: svc_authenticate (6)
Feb 07 11:08:02 v09 kernel: svc: server 000000004616e212, pool 0, transport 00000000433c4cd7, inuse=5
Feb 07 11:08:02 v09 kernel: RPC: gss_verify_mic_v2
Feb 07 11:08:02 v09 kernel: RPC: gss_get_mic_v2
Feb 07 11:08:02 v09 kernel: RPC: gss_unwrap_kerberos_v2
Feb 07 11:08:02 v09 kernel: __find_in_sessionid_hashtbl: 1675430610:3317937782:36:0
Feb 07 11:08:02 v09 kernel: nfsd4_sequence: slotid 0
Feb 07 11:08:02 v09 kernel: check_slot_seqid enter. seqid 25309 slot_seqid 25308
Feb 07 11:08:02 v09 kernel: NFSD: nfsd4_open filename op_openowner 0000000000000000
Feb 07 11:08:02 v09 kernel: --> nfsd4_store_cache_entry slot 00000000f22dd884
Feb 07 11:08:02 v09 kernel: RPC: gss_wrap_kerberos_v2
<--- 5 seconds delay --->
Feb 07 11:08:07 v09 kernel: svc: server 000000006d109dfb, pool 0, transport 00000000433c4cd7, inuse=4
Feb 07 11:08:07 v09 kernel: svc: svc_authenticate (6)
Feb 07 11:08:07 v09 kernel: svc: server 000000004616e212, pool 0, transport 00000000433c4cd7, inuse=5
Feb 07 11:08:07 v09 kernel: RPC: gss_verify_mic_v2
Feb 07 11:08:07 v09 kernel: RPC: gss_get_mic_v2
Feb 07 11:08:07 v09 kernel: RPC: gss_verify_mic_v2
Feb 07 11:08:07 v09 kernel: __find_in_sessionid_hashtbl: 1675430610:3317937782:36:0
Feb 07 11:08:07 v09 kernel: nfsd4_sequence: slotid 0
Feb 07 11:08:07 v09 kernel: check_slot_seqid enter. seqid 25310 slot_seqid 25309
Feb 07 11:08:07 v09 kernel: --> nfsd4_store_cache_entry slot 00000000f22dd884
Feb 07 11:08:07 v09 kernel: RPC: gss_get_mic_v2
Feb 07 11:08:07 v09 kernel: svc: server 000000006d109dfb, pool 0, transport 00000000433c4cd7, inuse=4
Feb 07 11:08:07 v09 kernel: svc: svc_authenticate (6)
Feb 07 11:08:07 v09 kernel: svc: server 000000004616e212, pool 0, transport 00000000433c4cd7, inuse=5
Feb 07 11:08:07 v09 kernel: RPC: gss_verify_mic_v2
Feb 07 11:08:07 v09 kernel: RPC: gss_get_mic_v2
Feb 07 11:08:07 v09 kernel: RPC: gss_unwrap_kerberos_v2
Feb 07 11:08:07 v09 kernel: __find_in_sessionid_hashtbl: 1675430610:3317937782:36:0
Feb 07 11:08:07 v09 kernel: nfsd4_sequence: slotid 0
Feb 07 11:08:07 v09 kernel: check_slot_seqid enter. seqid 25311 slot_seqid 25310
Feb 07 11:08:07 v09 kernel: NFSD: nfsd4_open filename op_openowner 0000000000000000
Feb 07 11:08:07 v09 kernel: --> nfsd4_store_cache_entry slot 00000000f22dd884
Feb 07 11:08:07 v09 kernel: RPC: gss_wrap_kerberos_v2
<--- 5 seconds delay --->
Feb 07 11:08:12 v09 kernel: svc: server 000000006d109dfb, pool 0, transport 00000000433c4cd7, inuse=4
Feb 07 11:08:12 v09 kernel: svc: svc_authenticate (6)
Feb 07 11:08:12 v09 kernel: svc: server 000000004616e212, pool 0, transport 00000000433c4cd7, inuse=5
Feb 07 11:08:12 v09 kernel: RPC: gss_verify_mic_v2
Feb 07 11:08:12 v09 kernel: RPC: gss_get_mic_v2
Feb 07 11:08:12 v09 kernel: RPC: gss_verify_mic_v2
Feb 07 11:08:12 v09 kernel: __find_in_sessionid_hashtbl: 1675430610:3317937782:36:0
Feb 07 11:08:12 v09 kernel: nfsd4_sequence: slotid 0
Feb 07 11:08:12 v09 kernel: check_slot_seqid enter. seqid 25312 slot_seqid 25311
Feb 07 11:08:12 v09 kernel: --> nfsd4_store_cache_entry slot 00000000f22dd884
Feb 07 11:08:12 v09 kernel: RPC: gss_get_mic_v2
Feb 07 11:08:12 v09 kernel: svc: server 000000006d109dfb, pool 0, transport 00000000433c4cd7, inuse=4
Feb 07 11:08:12 v09 kernel: svc: svc_authenticate (6)
Feb 07 11:08:12 v09 kernel: svc: server 000000004616e212, pool 0, transport 00000000433c4cd7, inuse=5
Feb 07 11:08:12 v09 kernel: RPC: gss_verify_mic_v2
Feb 07 11:08:12 v09 kernel: RPC: gss_get_mic_v2
Feb 07 11:08:12 v09 kernel: RPC: gss_unwrap_kerberos_v2
Feb 07 11:08:12 v09 kernel: __find_in_sessionid_hashtbl: 1675430610:3317937782:36:0
Feb 07 11:08:12 v09 kernel: nfsd4_sequence: slotid 0
Feb 07 11:08:12 v09 kernel: check_slot_seqid enter. seqid 25313 slot_seqid 25312
Feb 07 11:08:12 v09 kernel: NFSD: nfsd4_open filename op_openowner 0000000000000000
Feb 07 11:08:12 v09 kernel: --> nfsd4_store_cache_entry slot 00000000f22dd884
Feb 07 11:08:12 v09 kernel: RPC: gss_wrap_kerberos_v2
<--- 5 seconds delay --->
Feb 07 11:08:17 v09 kernel: svc: server 000000006d109dfb, pool 0, transport 00000000433c4cd7, inuse=4
Feb 07 11:08:17 v09 kernel: svc: svc_authenticate (6)
Feb 07 11:08:17 v09 kernel: svc: server 000000004616e212, pool 0, transport 00000000433c4cd7, inuse=5
Feb 07 11:08:17 v09 kernel: RPC: gss_verify_mic_v2
Feb 07 11:08:17 v09 kernel: RPC: gss_get_mic_v2
Feb 07 11:08:17 v09 kernel: RPC: gss_verify_mic_v2
Feb 07 11:08:17 v09 kernel: __find_in_sessionid_hashtbl: 1675430610:3317937782:36:0
Feb 07 11:08:17 v09 kernel: nfsd4_sequence: slotid 0
Feb 07 11:08:17 v09 kernel: check_slot_seqid enter. seqid 25314 slot_seqid 25313
Feb 07 11:08:17 v09 kernel: --> nfsd4_store_cache_entry slot 00000000f22dd884
Feb 07 11:08:17 v09 kernel: RPC: gss_get_mic_v2
Feb 07 11:08:17 v09 kernel: svc: server 000000006d109dfb, pool 0, transport 00000000433c4cd7, inuse=4
Feb 07 11:08:17 v09 kernel: svc: svc_authenticate (6)
Feb 07 11:08:17 v09 kernel: svc: server 000000004616e212, pool 0, transport 00000000433c4cd7, inuse=5
Feb 07 11:08:17 v09 kernel: RPC: gss_verify_mic_v2
Feb 07 11:08:17 v09 kernel: RPC: gss_get_mic_v2
Feb 07 11:08:17 v09 kernel: RPC: gss_unwrap_kerberos_v2
Feb 07 11:08:17 v09 kernel: __find_in_sessionid_hashtbl: 1675430610:3317937782:36:0
Feb 07 11:08:17 v09 kernel: nfsd4_sequence: slotid 0
Feb 07 11:08:17 v09 kernel: check_slot_seqid enter. seqid 25315 slot_seqid 25314
Feb 07 11:08:17 v09 kernel: NFSD: nfsd4_open filename op_openowner 0000000000000000
Feb 07 11:08:17 v09 kernel: --> nfsd4_store_cache_entry slot 00000000f22dd884
Feb 07 11:08:17 v09 kernel: RPC: gss_wrap_kerberos_v2
<--- 5 seconds delay --->
Feb 07 11:08:23 v09 kernel: svc: server 000000006d109dfb, pool 0, transport 00000000433c4cd7, inuse=4
Feb 07 11:08:23 v09 kernel: svc: svc_authenticate (6)
Feb 07 11:08:23 v09 kernel: svc: server 000000004616e212, pool 0, transport 00000000433c4cd7, inuse=5
Feb 07 11:08:23 v09 kernel: RPC: gss_verify_mic_v2
Feb 07 11:08:23 v09 kernel: RPC: gss_get_mic_v2
Feb 07 11:08:23 v09 kernel: RPC: gss_verify_mic_v2
Feb 07 11:08:23 v09 kernel: __find_in_sessionid_hashtbl: 1675430610:3317937782:36:0
Feb 07 11:08:23 v09 kernel: nfsd4_sequence: slotid 0
Feb 07 11:08:23 v09 kernel: check_slot_seqid enter. seqid 25316 slot_seqid 25315
Feb 07 11:08:23 v09 kernel: --> nfsd4_store_cache_entry slot 00000000f22dd884
Feb 07 11:08:23 v09 kernel: RPC: gss_get_mic_v2
Feb 07 11:08:23 v09 kernel: svc: server 000000006d109dfb, pool 0, transport 00000000433c4cd7, inuse=4
Feb 07 11:08:23 v09 kernel: svc: svc_authenticate (6)
Feb 07 11:08:23 v09 kernel: svc: server 000000004616e212, pool 0, transport 00000000433c4cd7, inuse=5
Feb 07 11:08:23 v09 kernel: RPC: gss_verify_mic_v2
Feb 07 11:08:23 v09 kernel: RPC: gss_get_mic_v2
Feb 07 11:08:23 v09 kernel: RPC: gss_unwrap_kerberos_v2
Feb 07 11:08:23 v09 kernel: __find_in_sessionid_hashtbl: 1675430610:3317937782:36:0
Feb 07 11:08:23 v09 kernel: nfsd4_sequence: slotid 0
Feb 07 11:08:23 v09 kernel: check_slot_seqid enter. seqid 25317 slot_seqid 25316
Feb 07 11:08:23 v09 kernel: NFSD: nfsd4_open filename op_openowner 0000000000000000
Feb 07 11:08:23 v09 kernel: --> nfsd4_store_cache_entry slot 00000000f22dd884
Feb 07 11:08:23 v09 kernel: RPC: gss_wrap_kerberos_v2
<--- 5 seconds delay --->
Feb 07 11:08:28 v09 kernel: svc: server 000000006d109dfb, pool 0, transport 00000000433c4cd7, inuse=4
Feb 07 11:08:28 v09 kernel: svc: svc_authenticate (6)
Feb 07 11:08:28 v09 kernel: svc: server 000000004616e212, pool 0, transport 00000000433c4cd7, inuse=5
Feb 07 11:08:28 v09 kernel: RPC: gss_verify_mic_v2
Feb 07 11:08:28 v09 kernel: RPC: gss_get_mic_v2
Feb 07 11:08:28 v09 kernel: RPC: gss_verify_mic_v2
Feb 07 11:08:28 v09 kernel: __find_in_sessionid_hashtbl: 1675430610:3317937782:36:0
Feb 07 11:08:28 v09 kernel: nfsd4_sequence: slotid 0
Feb 07 11:08:28 v09 kernel: check_slot_seqid enter. seqid 25318 slot_seqid 25317
Feb 07 11:08:28 v09 kernel: --> nfsd4_store_cache_entry slot 00000000f22dd884
Feb 07 11:08:28 v09 kernel: RPC: gss_get_mic_v2
Feb 07 11:08:28 v09 kernel: svc: server 000000006d109dfb, pool 0, transport 00000000433c4cd7, inuse=4
Feb 07 11:08:28 v09 kernel: svc: svc_authenticate (6)
Feb 07 11:08:28 v09 kernel: svc: server 000000004616e212, pool 0, transport 00000000433c4cd7, inuse=5
Feb 07 11:08:28 v09 kernel: RPC: gss_verify_mic_v2
Feb 07 11:08:28 v09 kernel: RPC: gss_get_mic_v2
Feb 07 11:08:28 v09 kernel: RPC: gss_unwrap_kerberos_v2
Feb 07 11:08:28 v09 kernel: __find_in_sessionid_hashtbl: 1675430610:3317937782:36:0
Feb 07 11:08:28 v09 kernel: nfsd4_sequence: slotid 0
Feb 07 11:08:28 v09 kernel: check_slot_seqid enter. seqid 25319 slot_seqid 25318
Feb 07 11:08:28 v09 kernel: NFSD: nfsd4_open filename op_openowner 0000000000000000
Feb 07 11:08:28 v09 kernel: --> nfsd4_store_cache_entry slot 00000000f22dd884
Feb 07 11:08:28 v09 kernel: RPC: gss_wrap_kerberos_v2
<--- 5 seconds delay --->
Feb 07 11:08:33 v09 kernel: svc: server 000000006d109dfb, pool 0, transport 00000000433c4cd7, inuse=4
Feb 07 11:08:33 v09 kernel: svc: svc_authenticate (6)
Feb 07 11:08:33 v09 kernel: svc: server 000000004616e212, pool 0, transport 00000000433c4cd7, inuse=5
Feb 07 11:08:33 v09 kernel: RPC: gss_verify_mic_v2
Feb 07 11:08:33 v09 kernel: RPC: gss_get_mic_v2
Feb 07 11:08:33 v09 kernel: RPC: gss_verify_mic_v2
Feb 07 11:08:33 v09 kernel: __find_in_sessionid_hashtbl: 1675430610:3317937782:36:0
Feb 07 11:08:33 v09 kernel: nfsd4_sequence: slotid 0
Feb 07 11:08:33 v09 kernel: check_slot_seqid enter. seqid 25320 slot_seqid 25319
Feb 07 11:08:33 v09 kernel: --> nfsd4_store_cache_entry slot 00000000f22dd884
Feb 07 11:08:33 v09 kernel: RPC: gss_get_mic_v2
Feb 07 11:08:33 v09 kernel: svc: server 000000006d109dfb, pool 0, transport 00000000433c4cd7, inuse=4
Feb 07 11:08:33 v09 kernel: svc: svc_authenticate (6)
Feb 07 11:08:33 v09 kernel: svc: server 000000004616e212, pool 0, transport 00000000433c4cd7, inuse=5
Feb 07 11:08:33 v09 kernel: RPC: gss_verify_mic_v2
Feb 07 11:08:33 v09 kernel: RPC: gss_get_mic_v2
Feb 07 11:08:33 v09 kernel: RPC: gss_unwrap_kerberos_v2
Feb 07 11:08:33 v09 kernel: __find_in_sessionid_hashtbl: 1675430610:3317937782:36:0
Feb 07 11:08:33 v09 kernel: nfsd4_sequence: slotid 0
Feb 07 11:08:33 v09 kernel: check_slot_seqid enter. seqid 25321 slot_seqid 25320
Feb 07 11:08:33 v09 kernel: NFSD: nfsd4_open filename op_openowner 0000000000000000
Feb 07 11:08:33 v09 kernel: --> nfsd4_store_cache_entry slot 00000000f22dd884
Feb 07 11:08:33 v09 kernel: RPC: gss_wrap_kerberos_v2
