public inbox for [email protected]
 help / color / mirror / Atom feed
* Problematic interaction of io_uring and CIFS
@ 2022-07-08 12:05 Fabian Ebner
  2022-07-08 17:48 ` Enzo Matsumiya
  2022-07-08 18:12 ` Jens Axboe
  0 siblings, 2 replies; 10+ messages in thread
From: Fabian Ebner @ 2022-07-08 12:05 UTC (permalink / raw)
  To: io-uring, linux-cifs; +Cc: Thomas Lamprecht

[-- Attachment #1: Type: text/plain, Size: 2852 bytes --]

(Re-sending without the log from the older kernel, because the mail hit
the 100000 char limit with that)

Hi,
it seems that in kernels >= 5.15, io_uring and CIFS don't interact
nicely sometimes, leading to IO errors. Unfortunately, my reproducer is
a QEMU VM with a disk on CIFS (original report by one of our users [0]),
but I can try to cook up something simpler if you want.

Bisecting got me to 8ef12efe26c8 ("io_uring: run regular file
completions from task_work") being the first bad commit.

Attached are debug logs taken with Ubuntu's build of 5.18.6. QEMU trace
was taken with '-trace luring*' and CIFS debug log was enabled as
described in [1].

Without CIFS debugging, the error messages in syslog are, for 5.18.6:
> Jun 29 12:41:45 pve702 kernel: [  112.664911] CIFS: VFS: \\192.168.20.241 Error -512 sending data on socket to server
> Jun 29 12:41:46 pve702 kernel: [  112.796227] CIFS: Status code returned 0xc00000d0 STATUS_REQUEST_NOT_ACCEPTED
> Jun 29 12:41:46 pve702 kernel: [  112.796250] CIFS: VFS: \\192.168.20.241 Send error in SessSetup = -5
> Jun 29 12:41:46 pve702 kernel: [  112.797781] CIFS: VFS: \\192.168.20.241 Send error in SessSetup = -11
> Jun 29 12:41:46 pve702 kernel: [  112.798065] CIFS: VFS: \\192.168.20.241 Send error in SessSetup = -11
> Jun 29 12:41:46 pve702 kernel: [  112.813485] CIFS: Status code returned 0xc00000d0 STATUS_REQUEST_NOT_ACCEPTED
> Jun 29 12:41:46 pve702 kernel: [  112.813497] CIFS: VFS: \\192.168.20.241 Send error in SessSetup = -5
> Jun 29 12:41:46 pve702 kernel: [  112.826829] CIFS: Status code returned 0xc00000d0 STATUS_REQUEST_NOT_ACCEPTED
> Jun 29 12:41:46 pve702 kernel: [  112.826837] CIFS: VFS: \\192.168.20.241 Send error in SessSetup = -5
> Jun 29 12:41:46 pve702 kernel: [  112.839369] CIFS: Status code returned 0xc00000d0 STATUS_REQUEST_NOT_ACCEPTED
> Jun 29 12:41:46 pve702 kernel: [  112.839381] CIFS: VFS: \\192.168.20.241 Send error in SessSetup = -5
> Jun 29 12:41:46 pve702 kernel: [  112.851854] CIFS: Status code returned 0xc00000d0 STATUS_REQUEST_NOT_ACCEPTED
> Jun 29 12:41:46 pve702 kernel: [  112.851867] CIFS: VFS: \\192.168.20.241 Send error in SessSetup = -5
> Jun 29 12:41:46 pve702 kernel: [  112.870763] CIFS: Status code returned 0xc00000d0 STATUS_REQUEST_NOT_ACCEPTED
> Jun 29 12:41:46 pve702 kernel: [  112.870777] CIFS: VFS: \\192.168.20.241 Send error in SessSetup = -5

and, for 8ef12efe26c8:
> Jun 29 12:50:35 pve702 kernel: [   92.099496] CIFS: VFS: \\192.168.20.241 Error -512 sending data on socket to server
> Jun 29 12:50:35 pve702 kernel: [   92.110467] CIFS: VFS: \\192.168.20.241 Error -512 sending data on socket to server

If there's any additional information I should provide, just let me know.

[0]: https://forum.proxmox.com/threads/110464/post-475858
[1]:
https://wiki.samba.org/index.php/LinuxCIFS_troubleshooting#Enabling_Debugging

[-- Attachment #2: kernel-5.18.6-cifs-dbg --]
[-- Type: text/plain, Size: 55717 bytes --]

Jun 29 11:25:38 pve702 kernel: [   86.558961] CIFS: fs/cifs/connect.c: RFC1002 header 0x1050
Jun 29 11:25:38 pve702 kernel: [   86.558965] CIFS: fs/cifs/cifssmb.c: cifs_readv_receive: mid=2584 offset=360448 bytes=4096
Jun 29 11:25:38 pve702 kernel: [   86.558967] CIFS: fs/cifs/cifssmb.c: 0: iov_base=00000000658c0204 iov_len=0
Jun 29 11:25:38 pve702 kernel: [   86.558968] CIFS: fs/cifs/cifssmb.c: 1: iov_base=00000000658c0204 iov_len=80
Jun 29 11:25:38 pve702 kernel: [   86.558970] CIFS: fs/cifs/cifssmb.c: cifs_readv_receive: total_read=80 data_offset=80
Jun 29 11:25:38 pve702 kernel: [   86.558977] CIFS: fs/cifs/cifssmb.c: total_read=4176 buflen=4176 remaining=4096
Jun 29 11:25:38 pve702 kernel: [   86.558979] CIFS: fs/cifs/smb2pdu.c: smb2_readv_callback: mid=2584 state=4 result=0 bytes=4096
Jun 29 11:25:38 pve702 kernel: [   86.558982] CIFS: fs/cifs/smb2ops.c: smb2_add_credits: added 1 credits total=8190
Jun 29 11:25:38 pve702 kernel: [   86.559511] CIFS: fs/cifs/smb2ops.c: smb2_wait_mtu_credits: removed 64 credits total=8126
Jun 29 11:25:38 pve702 kernel: [   86.559515] CIFS: fs/cifs/smb2ops.c: smb2_adjust_credits: adjust added 63 credits total=8189
Jun 29 11:25:38 pve702 kernel: [   86.559516] CIFS: fs/cifs/smb2pdu.c: smb2_async_readv: offset=1376256 bytes=4096
Jun 29 11:25:38 pve702 kernel: [   86.559522] CIFS: fs/cifs/transport.c: Sending smb: smb_len=117
Jun 29 11:25:38 pve702 kernel: [   86.559680] CIFS: fs/cifs/connect.c: RFC1002 header 0x1050
Jun 29 11:25:38 pve702 kernel: [   86.559684] CIFS: fs/cifs/cifssmb.c: cifs_readv_receive: mid=2585 offset=1376256 bytes=4096
Jun 29 11:25:38 pve702 kernel: [   86.559686] CIFS: fs/cifs/cifssmb.c: 0: iov_base=00000000658c0204 iov_len=0
Jun 29 11:25:38 pve702 kernel: [   86.559688] CIFS: fs/cifs/cifssmb.c: 1: iov_base=00000000658c0204 iov_len=80
Jun 29 11:25:38 pve702 kernel: [   86.559689] CIFS: fs/cifs/cifssmb.c: cifs_readv_receive: total_read=80 data_offset=80
Jun 29 11:25:38 pve702 kernel: [   86.559697] CIFS: fs/cifs/cifssmb.c: total_read=4176 buflen=4176 remaining=4096
Jun 29 11:25:38 pve702 kernel: [   86.559698] CIFS: fs/cifs/smb2pdu.c: smb2_readv_callback: mid=2585 state=4 result=0 bytes=4096
Jun 29 11:25:38 pve702 kernel: [   86.559701] CIFS: fs/cifs/smb2ops.c: smb2_add_credits: added 1 credits total=8190
Jun 29 11:25:38 pve702 kernel: [   86.559838] CIFS: fs/cifs/smb2ops.c: smb2_wait_mtu_credits: removed 64 credits total=8126
Jun 29 11:25:38 pve702 kernel: [   86.559843] CIFS: fs/cifs/smb2ops.c: smb2_adjust_credits: adjust added 63 credits total=8189
Jun 29 11:25:38 pve702 kernel: [   86.559845] CIFS: fs/cifs/smb2pdu.c: smb2_async_readv: offset=2424832 bytes=4096
Jun 29 11:25:38 pve702 kernel: [   86.559850] CIFS: fs/cifs/transport.c: Sending smb: smb_len=117
Jun 29 11:25:38 pve702 kernel: [   86.559992] CIFS: fs/cifs/connect.c: RFC1002 header 0x1050
Jun 29 11:25:38 pve702 kernel: [   86.559997] CIFS: fs/cifs/cifssmb.c: cifs_readv_receive: mid=2586 offset=2424832 bytes=4096
Jun 29 11:25:38 pve702 kernel: [   86.559999] CIFS: fs/cifs/cifssmb.c: 0: iov_base=00000000658c0204 iov_len=0
Jun 29 11:25:38 pve702 kernel: [   86.560000] CIFS: fs/cifs/cifssmb.c: 1: iov_base=00000000658c0204 iov_len=80
Jun 29 11:25:38 pve702 kernel: [   86.560008] CIFS: fs/cifs/cifssmb.c: cifs_readv_receive: total_read=80 data_offset=80
Jun 29 11:25:38 pve702 kernel: [   86.560013] CIFS: fs/cifs/cifssmb.c: total_read=4176 buflen=4176 remaining=4096
Jun 29 11:25:38 pve702 kernel: [   86.560015] CIFS: fs/cifs/smb2pdu.c: smb2_readv_callback: mid=2586 state=4 result=0 bytes=4096
Jun 29 11:25:38 pve702 kernel: [   86.560018] CIFS: fs/cifs/smb2ops.c: smb2_add_credits: added 1 credits total=8190
Jun 29 11:25:38 pve702 kernel: [   86.560261] CIFS: fs/cifs/smb2ops.c: smb2_wait_mtu_credits: removed 64 credits total=8126
Jun 29 11:25:38 pve702 kernel: [   86.560266] CIFS: fs/cifs/smb2ops.c: smb2_adjust_credits: adjust added 63 credits total=8189
Jun 29 11:25:38 pve702 kernel: [   86.560268] CIFS: fs/cifs/smb2pdu.c: smb2_async_readv: offset=4521984 bytes=4096
Jun 29 11:25:38 pve702 kernel: [   86.560286] CIFS: fs/cifs/transport.c: Sending smb: smb_len=117
Jun 29 11:25:38 pve702 kernel: [   86.560685] CIFS: fs/cifs/connect.c: RFC1002 header 0x1050
Jun 29 11:25:38 pve702 kernel: [   86.560693] CIFS: fs/cifs/cifssmb.c: cifs_readv_receive: mid=2587 offset=4521984 bytes=4096
Jun 29 11:25:38 pve702 kernel: [   86.560698] CIFS: fs/cifs/cifssmb.c: 0: iov_base=00000000658c0204 iov_len=0
Jun 29 11:25:38 pve702 kernel: [   86.560701] CIFS: fs/cifs/cifssmb.c: 1: iov_base=00000000658c0204 iov_len=80
Jun 29 11:25:38 pve702 kernel: [   86.560704] CIFS: fs/cifs/cifssmb.c: cifs_readv_receive: total_read=80 data_offset=80
Jun 29 11:25:38 pve702 kernel: [   86.560710] CIFS: fs/cifs/cifssmb.c: total_read=4176 buflen=4176 remaining=4096
Jun 29 11:25:38 pve702 kernel: [   86.560713] CIFS: fs/cifs/smb2pdu.c: smb2_readv_callback: mid=2587 state=4 result=0 bytes=4096
Jun 29 11:25:38 pve702 kernel: [   86.560720] CIFS: fs/cifs/smb2ops.c: smb2_add_credits: added 1 credits total=8190
Jun 29 11:25:39 pve702 kernel: [   86.790452] CIFS: fs/cifs/file.c: VFS: in cifs_write_from_iter as Xid: 90 with uid: 0
Jun 29 11:25:39 pve702 kernel: [   86.790461] CIFS: fs/cifs/smb2pdu.c: async write at 1376256 4096 bytes
Jun 29 11:25:39 pve702 kernel: [   86.790479] CIFS: fs/cifs/smb2pdu.c: async write at 1380352 4096 bytes
Jun 29 11:25:39 pve702 kernel: [   86.790490] CIFS: fs/cifs/file.c: VFS: leaving cifs_write_from_iter (xid = 90) rc = 0
Jun 29 11:25:39 pve702 kernel: [   86.790493] CIFS: fs/cifs/file.c: VFS: in cifs_write_from_iter as Xid: 91 with uid: 0
Jun 29 11:25:39 pve702 kernel: [   86.790497] CIFS: fs/cifs/smb2pdu.c: async write at 3350528 4096 bytes
Jun 29 11:25:39 pve702 kernel: [   86.790521] CIFS: fs/cifs/file.c: VFS: leaving cifs_write_from_iter (xid = 91) rc = 0
Jun 29 11:25:39 pve702 kernel: [   86.790523] CIFS: fs/cifs/file.c: VFS: in cifs_write_from_iter as Xid: 92 with uid: 0
Jun 29 11:25:39 pve702 kernel: [   86.790526] CIFS: fs/cifs/smb2pdu.c: async write at 1612185600 4096 bytes
Jun 29 11:25:39 pve702 kernel: [   86.790572] CIFS: fs/cifs/file.c: VFS: leaving cifs_write_from_iter (xid = 92) rc = 0
Jun 29 11:25:39 pve702 kernel: [   86.790575] CIFS: fs/cifs/file.c: VFS: in cifs_write_from_iter as Xid: 93 with uid: 0
Jun 29 11:25:39 pve702 kernel: [   86.790577] CIFS: fs/cifs/smb2pdu.c: async write at 2149187584 4096 bytes
Jun 29 11:25:39 pve702 kernel: [   86.790619] CIFS: fs/cifs/file.c: VFS: leaving cifs_write_from_iter (xid = 93) rc = 0
Jun 29 11:25:39 pve702 kernel: [   86.790622] CIFS: fs/cifs/file.c: VFS: in cifs_write_from_iter as Xid: 94 with uid: 0
Jun 29 11:25:39 pve702 kernel: [   86.790624] CIFS: fs/cifs/smb2pdu.c: async write at 2149240832 4096 bytes
Jun 29 11:25:39 pve702 kernel: [   86.790639] CIFS: fs/cifs/file.c: VFS: leaving cifs_write_from_iter (xid = 94) rc = 0
Jun 29 11:25:39 pve702 kernel: [   86.790641] CIFS: fs/cifs/file.c: VFS: in cifs_write_from_iter as Xid: 95 with uid: 0
Jun 29 11:25:39 pve702 kernel: [   86.790642] CIFS: fs/cifs/smb2pdu.c: async write at 2149289984 4096 bytes
Jun 29 11:25:39 pve702 kernel: [   86.790685] CIFS: fs/cifs/file.c: VFS: leaving cifs_write_from_iter (xid = 95) rc = 0
Jun 29 11:25:39 pve702 kernel: [   86.790691] CIFS: fs/cifs/file.c: VFS: in cifs_write_from_iter as Xid: 96 with uid: 0
Jun 29 11:25:39 pve702 kernel: [   86.790693] CIFS: fs/cifs/smb2pdu.c: async write at 2152919040 4096 bytes
Jun 29 11:25:39 pve702 kernel: [   86.790719] CIFS: fs/cifs/file.c: VFS: leaving cifs_write_from_iter (xid = 96) rc = 0
Jun 29 11:25:39 pve702 kernel: [   86.790721] CIFS: fs/cifs/file.c: VFS: in cifs_write_from_iter as Xid: 97 with uid: 0
Jun 29 11:25:39 pve702 kernel: [   86.790723] CIFS: fs/cifs/smb2pdu.c: async write at 2180411392 4096 bytes
Jun 29 11:25:39 pve702 kernel: [   86.790739] CIFS: fs/cifs/file.c: VFS: leaving cifs_write_from_iter (xid = 97) rc = 0
Jun 29 11:25:39 pve702 kernel: [   86.791244] smb2_calc_size: 17 callbacks suppressed
Jun 29 11:25:39 pve702 kernel: [   86.791249] CIFS: fs/cifs/smb2misc.c: SMB2 len 81
Jun 29 11:25:39 pve702 kernel: [   86.791253] smb2_check_message: 7 callbacks suppressed
Jun 29 11:25:39 pve702 kernel: [   86.791254] CIFS: fs/cifs/smb2misc.c: Calculated size 81 length 80 mismatch mid 2952
Jun 29 11:25:39 pve702 kernel: [   86.791271] CIFS: fs/cifs/smb2misc.c: SMB2 len 81
Jun 29 11:25:39 pve702 kernel: [   86.791273] CIFS: fs/cifs/smb2misc.c: Calculated size 81 length 80 mismatch mid 2953
Jun 29 11:25:39 pve702 kernel: [   86.791282] CIFS: fs/cifs/smb2misc.c: SMB2 len 81
Jun 29 11:25:39 pve702 kernel: [   86.791283] CIFS: fs/cifs/smb2misc.c: Calculated size 81 length 80 mismatch mid 2954
Jun 29 11:25:39 pve702 kernel: [   86.791495] CIFS: fs/cifs/smb2misc.c: SMB2 len 81
Jun 29 11:25:39 pve702 kernel: [   86.791498] CIFS: fs/cifs/smb2misc.c: Calculated size 81 length 80 mismatch mid 2955
Jun 29 11:25:39 pve702 kernel: [   86.791503] CIFS: fs/cifs/smb2misc.c: SMB2 len 81
Jun 29 11:25:39 pve702 kernel: [   86.791504] CIFS: fs/cifs/smb2misc.c: Calculated size 81 length 80 mismatch mid 2957
Jun 29 11:25:39 pve702 kernel: [   86.791507] CIFS: fs/cifs/smb2misc.c: SMB2 len 81
Jun 29 11:25:39 pve702 kernel: [   86.791507] CIFS: fs/cifs/smb2misc.c: Calculated size 81 length 80 mismatch mid 2958
Jun 29 11:25:39 pve702 kernel: [   86.791510] CIFS: fs/cifs/smb2misc.c: SMB2 len 81
Jun 29 11:25:39 pve702 kernel: [   86.791511] CIFS: fs/cifs/smb2misc.c: Calculated size 81 length 80 mismatch mid 2960
Jun 29 11:25:39 pve702 kernel: [   86.791513] CIFS: fs/cifs/smb2misc.c: SMB2 len 81
Jun 29 11:25:39 pve702 kernel: [   86.791514] CIFS: fs/cifs/smb2misc.c: Calculated size 81 length 80 mismatch mid 2959
Jun 29 11:25:39 pve702 kernel: [   86.791525] CIFS: fs/cifs/smb2misc.c: SMB2 len 81
Jun 29 11:25:39 pve702 kernel: [   86.791526] CIFS: fs/cifs/smb2misc.c: Calculated size 81 length 80 mismatch mid 2956
Jun 29 11:25:39 pve702 kernel: [   86.791835] CIFS: fs/cifs/file.c: VFS: in cifs_fsync as Xid: 98 with uid: 0
Jun 29 11:25:39 pve702 kernel: [   86.791839] CIFS: fs/cifs/file.c: Sync file - name: vm-109-disk-0.qcow2 datasync: 0x1
Jun 29 11:25:39 pve702 kernel: [   86.791843] CIFS: fs/cifs/smb2pdu.c: flush
Jun 29 11:25:39 pve702 kernel: [   86.791848] CIFS: fs/cifs/transport.c: wait_for_free_credits: remove 1 credits total=8189
Jun 29 11:25:39 pve702 kernel: [   86.793033] CIFS: fs/cifs/smb2misc.c: SMB2 len 73
Jun 29 11:25:39 pve702 kernel: [   86.793037] CIFS: fs/cifs/smb2ops.c: smb2_is_status_pending: status pending add 1 credits total=8190
Jun 29 11:25:39 pve702 kernel: [   86.793975] cifs_sync_mid_result: 17 callbacks suppressed
Jun 29 11:25:39 pve702 kernel: [   86.793977] CIFS: fs/cifs/transport.c: cifs_sync_mid_result: cmd=7 mid=2961 state=4
Jun 29 11:25:39 pve702 kernel: [   86.793980] cifs_small_buf_release: 17 callbacks suppressed
Jun 29 11:25:39 pve702 kernel: [   86.793981] CIFS: fs/cifs/misc.c: Null buffer passed to cifs_small_buf_release
Jun 29 11:25:39 pve702 kernel: [   86.793983] CIFS: fs/cifs/file.c: VFS: leaving cifs_fsync (xid = 98) rc = 0
Jun 29 11:25:39 pve702 kernel: [   86.795147] CIFS: fs/cifs/file.c: VFS: in cifs_write_from_iter as Xid: 99 with uid: 0
Jun 29 11:25:39 pve702 kernel: [   86.795152] CIFS: fs/cifs/smb2pdu.c: async write at 1612185600 4096 bytes
Jun 29 11:25:39 pve702 kernel: [   86.795212] CIFS: fs/cifs/file.c: VFS: leaving cifs_write_from_iter (xid = 99) rc = 0
Jun 29 11:25:39 pve702 kernel: [   86.795368] CIFS: fs/cifs/smb2misc.c: Calculated size 81 length 80 mismatch mid 2963
Jun 29 11:25:39 pve702 kernel: [   86.795569] CIFS: fs/cifs/file.c: VFS: in cifs_fsync as Xid: 100 with uid: 0
Jun 29 11:25:39 pve702 kernel: [   86.795572] CIFS: fs/cifs/file.c: Sync file - name: vm-109-disk-0.qcow2 datasync: 0x1
Jun 29 11:25:39 pve702 kernel: [   86.795575] CIFS: fs/cifs/smb2pdu.c: flush
Jun 29 11:25:39 pve702 kernel: [   86.795577] CIFS: fs/cifs/transport.c: wait_for_free_credits: remove 1 credits total=8188
Jun 29 11:25:39 pve702 kernel: [   86.796879] CIFS: fs/cifs/smb2ops.c: smb2_is_status_pending: status pending add 1 credits total=8189
Jun 29 11:25:39 pve702 kernel: [   86.797013] CIFS: fs/cifs/transport.c: cifs_sync_mid_result: cmd=7 mid=2965 state=4
Jun 29 11:25:39 pve702 kernel: [   86.797016] CIFS: fs/cifs/misc.c: Null buffer passed to cifs_small_buf_release
Jun 29 11:25:39 pve702 kernel: [   86.797018] CIFS: fs/cifs/file.c: VFS: leaving cifs_fsync (xid = 100) rc = 0
Jun 29 11:25:39 pve702 kernel: [   86.797445] CIFS: fs/cifs/file.c: VFS: in cifs_write_from_iter as Xid: 101 with uid: 0
Jun 29 11:25:39 pve702 kernel: [   86.797474] CIFS: fs/cifs/file.c: VFS: leaving cifs_write_from_iter (xid = 101) rc = 0
Jun 29 11:25:39 pve702 kernel: [   86.797927] CIFS: fs/cifs/file.c: VFS: in cifs_fsync as Xid: 103 with uid: 0
Jun 29 11:25:39 pve702 kernel: [   86.797931] CIFS: fs/cifs/file.c: Sync file - name: vm-109-disk-0.qcow2 datasync: 0x1
Jun 29 11:25:39 pve702 kernel: [   86.797934] CIFS: fs/cifs/smb2pdu.c: flush
Jun 29 11:25:39 pve702 kernel: [   86.797936] CIFS: fs/cifs/transport.c: wait_for_free_credits: remove 1 credits total=8189
Jun 29 11:25:39 pve702 kernel: [   86.799140] CIFS: fs/cifs/smb2ops.c: smb2_is_status_pending: status pending add 1 credits total=8185
Jun 29 11:25:39 pve702 kernel: [   86.799439] CIFS: fs/cifs/transport.c: cifs_sync_mid_result: cmd=7 mid=2970 state=4
Jun 29 11:25:39 pve702 kernel: [   86.799443] CIFS: fs/cifs/misc.c: Null buffer passed to cifs_small_buf_release
Jun 29 11:25:39 pve702 kernel: [   86.799445] CIFS: fs/cifs/file.c: VFS: leaving cifs_fsync (xid = 103) rc = 0
Jun 29 11:25:39 pve702 kernel: [   86.800540] CIFS: fs/cifs/file.c: VFS: in cifs_fsync as Xid: 105 with uid: 0
Jun 29 11:25:39 pve702 kernel: [   86.800543] CIFS: fs/cifs/file.c: Sync file - name: vm-109-disk-0.qcow2 datasync: 0x1
Jun 29 11:25:39 pve702 kernel: [   86.800546] CIFS: fs/cifs/smb2pdu.c: flush
Jun 29 11:25:39 pve702 kernel: [   86.800549] CIFS: fs/cifs/transport.c: wait_for_free_credits: remove 1 credits total=8188
Jun 29 11:25:39 pve702 kernel: [   86.801753] CIFS: fs/cifs/smb2ops.c: smb2_is_status_pending: status pending add 1 credits total=8189
Jun 29 11:25:39 pve702 kernel: [   86.801895] CIFS: fs/cifs/transport.c: cifs_sync_mid_result: cmd=7 mid=2979 state=4
Jun 29 11:25:39 pve702 kernel: [   86.801900] CIFS: fs/cifs/misc.c: Null buffer passed to cifs_small_buf_release
Jun 29 11:25:39 pve702 kernel: [   86.801903] CIFS: fs/cifs/file.c: VFS: leaving cifs_fsync (xid = 105) rc = 0
Jun 29 11:25:39 pve702 kernel: [   86.806239] CIFS: fs/cifs/file.c: VFS: in cifs_fsync as Xid: 108 with uid: 0
Jun 29 11:25:39 pve702 kernel: [   86.806243] CIFS: fs/cifs/file.c: Sync file - name: vm-109-disk-0.qcow2 datasync: 0x1
Jun 29 11:25:39 pve702 kernel: [   86.806246] CIFS: fs/cifs/smb2pdu.c: flush
Jun 29 11:25:39 pve702 kernel: [   86.806248] CIFS: fs/cifs/transport.c: wait_for_free_credits: remove 1 credits total=8180
Jun 29 11:25:39 pve702 kernel: [   86.807227] CIFS: fs/cifs/smb2ops.c: smb2_is_status_pending: status pending add 1 credits total=8187
Jun 29 11:25:39 pve702 kernel: [   86.808350] CIFS: fs/cifs/transport.c: cifs_sync_mid_result: cmd=7 mid=3009 state=4
Jun 29 11:25:39 pve702 kernel: [   86.808354] CIFS: fs/cifs/misc.c: Null buffer passed to cifs_small_buf_release
Jun 29 11:25:39 pve702 kernel: [   86.808357] CIFS: fs/cifs/file.c: VFS: leaving cifs_fsync (xid = 108) rc = 0
Jun 29 11:25:39 pve702 kernel: [   86.809283] CIFS: fs/cifs/file.c: VFS: in cifs_fsync as Xid: 110 with uid: 0
Jun 29 11:25:39 pve702 kernel: [   86.809287] CIFS: fs/cifs/file.c: Sync file - name: vm-109-disk-0.qcow2 datasync: 0x1
Jun 29 11:25:39 pve702 kernel: [   86.809290] CIFS: fs/cifs/smb2pdu.c: flush
Jun 29 11:25:39 pve702 kernel: [   86.809292] CIFS: fs/cifs/transport.c: wait_for_free_credits: remove 1 credits total=8189
Jun 29 11:25:39 pve702 kernel: [   86.810474] CIFS: fs/cifs/smb2ops.c: smb2_is_status_pending: status pending add 1 credits total=8190
Jun 29 11:25:39 pve702 kernel: [   86.810550] CIFS: fs/cifs/transport.c: cifs_sync_mid_result: cmd=7 mid=3021 state=4
Jun 29 11:25:39 pve702 kernel: [   86.810554] CIFS: fs/cifs/misc.c: Null buffer passed to cifs_small_buf_release
Jun 29 11:25:39 pve702 kernel: [   86.810557] CIFS: fs/cifs/file.c: VFS: leaving cifs_fsync (xid = 110) rc = 0
Jun 29 11:25:39 pve702 kernel: [   86.899021] CIFS: fs/cifs/smb2ops.c: smb2_is_status_pending: status pending add 1 credits total=8186
Jun 29 11:25:39 pve702 kernel: [   86.899610] CIFS: fs/cifs/smb2ops.c: smb2_is_status_pending: status pending add 3 credits total=8189
Jun 29 11:25:39 pve702 kernel: [   87.245988] CIFS: fs/cifs/file.c: VFS: in cifs_fsync as Xid: 112 with uid: 0
Jun 29 11:25:39 pve702 kernel: [   87.245994] CIFS: fs/cifs/file.c: Sync file - name: vm-109-disk-0.qcow2 datasync: 0x1
Jun 29 11:25:39 pve702 kernel: [   87.245998] CIFS: fs/cifs/smb2pdu.c: flush
Jun 29 11:25:39 pve702 kernel: [   87.246001] CIFS: fs/cifs/transport.c: wait_for_free_credits: remove 1 credits total=8189
Jun 29 11:25:39 pve702 kernel: [   87.247237] CIFS: fs/cifs/smb2ops.c: smb2_is_status_pending: status pending add 1 credits total=8190
Jun 29 11:25:39 pve702 kernel: [   87.247814] CIFS: fs/cifs/transport.c: cifs_sync_mid_result: cmd=7 mid=4869 state=4
Jun 29 11:25:39 pve702 kernel: [   87.247821] CIFS: fs/cifs/misc.c: Null buffer passed to cifs_small_buf_release
Jun 29 11:25:39 pve702 kernel: [   87.247824] CIFS: fs/cifs/file.c: VFS: leaving cifs_fsync (xid = 112) rc = 0
Jun 29 11:25:39 pve702 kernel: [   87.402898] CIFS: fs/cifs/file.c: VFS: in cifs_fsync as Xid: 115 with uid: 0
Jun 29 11:25:39 pve702 kernel: [   87.402906] CIFS: fs/cifs/file.c: Sync file - name: vm-109-disk-0.qcow2 datasync: 0x1
Jun 29 11:25:39 pve702 kernel: [   87.402912] CIFS: fs/cifs/smb2pdu.c: flush
Jun 29 11:25:39 pve702 kernel: [   87.402919] CIFS: fs/cifs/transport.c: wait_for_free_credits: remove 1 credits total=8189
Jun 29 11:25:39 pve702 kernel: [   87.404738] CIFS: fs/cifs/transport.c: cifs_sync_mid_result: cmd=7 mid=5272 state=4
Jun 29 11:25:39 pve702 kernel: [   87.404744] CIFS: fs/cifs/misc.c: Null buffer passed to cifs_small_buf_release
Jun 29 11:25:39 pve702 kernel: [   87.404746] CIFS: fs/cifs/file.c: VFS: leaving cifs_fsync (xid = 115) rc = 0
Jun 29 11:25:39 pve702 kernel: [   87.405322] CIFS: fs/cifs/file.c: VFS: in cifs_fsync as Xid: 117 with uid: 0
Jun 29 11:25:39 pve702 kernel: [   87.405324] CIFS: fs/cifs/file.c: Sync file - name: vm-109-disk-0.qcow2 datasync: 0x1
Jun 29 11:25:39 pve702 kernel: [   87.405327] CIFS: fs/cifs/smb2pdu.c: flush
Jun 29 11:25:39 pve702 kernel: [   87.405328] CIFS: fs/cifs/transport.c: wait_for_free_credits: remove 1 credits total=8189
Jun 29 11:25:39 pve702 kernel: [   87.406579] CIFS: fs/cifs/transport.c: cifs_sync_mid_result: cmd=7 mid=5277 state=4
Jun 29 11:25:39 pve702 kernel: [   87.406582] CIFS: fs/cifs/misc.c: Null buffer passed to cifs_small_buf_release
Jun 29 11:25:39 pve702 kernel: [   87.406585] CIFS: fs/cifs/file.c: VFS: leaving cifs_fsync (xid = 117) rc = 0
Jun 29 11:25:39 pve702 kernel: [   87.410843] CIFS: fs/cifs/file.c: VFS: in cifs_fsync as Xid: 120 with uid: 0
Jun 29 11:25:39 pve702 kernel: [   87.410846] CIFS: fs/cifs/file.c: Sync file - name: vm-109-disk-0.qcow2 datasync: 0x1
Jun 29 11:25:39 pve702 kernel: [   87.410849] CIFS: fs/cifs/smb2pdu.c: flush
Jun 29 11:25:39 pve702 kernel: [   87.410851] CIFS: fs/cifs/transport.c: wait_for_free_credits: remove 1 credits total=8189
Jun 29 11:25:39 pve702 kernel: [   87.412247] CIFS: fs/cifs/transport.c: cifs_sync_mid_result: cmd=7 mid=5284 state=4
Jun 29 11:25:39 pve702 kernel: [   87.412255] CIFS: fs/cifs/misc.c: Null buffer passed to cifs_small_buf_release
Jun 29 11:25:39 pve702 kernel: [   87.412260] CIFS: fs/cifs/file.c: VFS: leaving cifs_fsync (xid = 120) rc = 0
Jun 29 11:25:39 pve702 kernel: [   87.439910] CIFS: fs/cifs/transport.c: signal is pending after attempt to send
Jun 29 11:25:39 pve702 kernel: [   87.439920] CIFS: fs/cifs/transport.c: partial send (wanted=65652 sent=53364): terminating session
Jun 29 11:25:39 pve702 kernel: [   87.439970] CIFS: VFS: \\192.168.20.241 Error -512 sending data on socket to server
Jun 29 11:25:39 pve702 kernel: [   87.440440] CIFS: fs/cifs/connect.c: Mark tcp session as need reconnect
Jun 29 11:25:39 pve702 kernel: [   87.440443] CIFS: fs/cifs/connect.c: cifs_mark_tcp_ses_conns_for_reconnect: marking necessary sessions and tcons for reconnect
Jun 29 11:25:39 pve702 kernel: [   87.440450] CIFS: fs/cifs/sess.c: Set reconnect bitmask for chan 0; now 0x1
Jun 29 11:25:39 pve702 kernel: [   87.440455] CIFS: fs/cifs/connect.c: cifs_abort_connection: tearing down socket
Jun 29 11:25:39 pve702 kernel: [   87.440457] CIFS: fs/cifs/connect.c: State: 0x3 Flags: 0x0
Jun 29 11:25:39 pve702 kernel: [   87.440460] CIFS: fs/cifs/connect.c: Post shutdown state: 0x3 Flags: 0x0
Jun 29 11:25:39 pve702 kernel: [   87.440493] CIFS: fs/cifs/connect.c: cifs_abort_connection: moving mids to private list
Jun 29 11:25:39 pve702 kernel: [   87.440495] CIFS: fs/cifs/connect.c: cifs_abort_connection: issuing mid callbacks
Jun 29 11:25:39 pve702 kernel: [   87.440506] CIFS: fs/cifs/dns_resolve.c: dns_resolve_server_name_to_ip: probably server name is whole unc: \\192.168.20.241
Jun 29 11:25:39 pve702 kernel: [   87.440514] CIFS: fs/cifs/dns_resolve.c: dns_resolve_server_name_to_ip: unc is IP, skipping dns upcall: 192.168.20.241
Jun 29 11:25:39 pve702 kernel: [   87.440516] CIFS: fs/cifs/connect.c: reconn_set_ipaddr_from_hostname: next dns resolution scheduled for 9088680469733379950 seconds in the future
Jun 29 11:25:39 pve702 kernel: [   87.440519] CIFS: fs/cifs/connect.c: __cifs_reconnect: reconn_set_ipaddr_from_hostname: rc=0
Jun 29 11:25:39 pve702 kernel: [   87.440526] CIFS: fs/cifs/connect.c: generic_ip_connect: connecting to 192.168.20.241:445
Jun 29 11:25:39 pve702 kernel: [   87.440547] CIFS: fs/cifs/connect.c: Socket created
Jun 29 11:25:39 pve702 kernel: [   87.440550] CIFS: fs/cifs/connect.c: sndbuf 16384 rcvbuf 131072 rcvtimeo 0x6d6
Jun 29 11:25:39 pve702 kernel: [   87.441300] CIFS: fs/cifs/smb2pdu.c: smb2_reconnect: aborting reconnect due to a received signal by the process
Jun 29 11:25:39 pve702 kernel: [   87.560702] CIFS: fs/cifs/smb2ops.c: smb2_set_credits: set 1 credits
Jun 29 11:25:39 pve702 kernel: [   87.560711] CIFS: fs/cifs/smb2ops.c: set credits to 1 due to smb2 reconnect
Jun 29 11:25:39 pve702 kernel: [   87.560734] CIFS: fs/cifs/smb2pdu.c: sess reconnect mask: 0x1, tcon reconnect: 1
Jun 29 11:25:39 pve702 kernel: [   87.560745] CIFS: fs/cifs/smb2pdu.c: Negotiate protocol
Jun 29 11:25:39 pve702 kernel: [   87.560752] CIFS: fs/cifs/smb2pdu.c: sess reconnect mask: 0x1, tcon reconnect: 1
Jun 29 11:25:39 pve702 kernel: [   87.560806] CIFS: fs/cifs/smb2pdu.c: Reconnecting tcons and channels
Jun 29 11:25:39 pve702 kernel: [   87.560808] CIFS: fs/cifs/smb2pdu.c: sess reconnect mask: 0x1, tcon reconnect: 1
Jun 29 11:25:39 pve702 kernel: [   87.565083] smb2_calc_size: 8 callbacks suppressed
Jun 29 11:25:39 pve702 kernel: [   87.565085] CIFS: fs/cifs/smb2misc.c: SMB2 data length 74 offset 128
Jun 29 11:25:39 pve702 kernel: [   87.565088] CIFS: fs/cifs/smb2misc.c: length of negcontexts 60 pad 6
Jun 29 11:25:39 pve702 kernel: [   87.565101] CIFS: fs/cifs/smb2pdu.c: mode 0x1
Jun 29 11:25:39 pve702 kernel: [   87.565103] CIFS: fs/cifs/smb2pdu.c: negotiated smb3.1.1 dialect
Jun 29 11:25:39 pve702 kernel: [   87.565109] CIFS: fs/cifs/smb2pdu.c: decoding 2 negotiate contexts
Jun 29 11:25:39 pve702 kernel: [   87.565110] CIFS: fs/cifs/smb2pdu.c: decode SMB3.11 encryption neg context of len 4
Jun 29 11:25:39 pve702 kernel: [   87.565111] CIFS: fs/cifs/smb2pdu.c: SMB311 cipher type:2
Jun 29 11:25:39 pve702 kernel: [   87.565114] CIFS: fs/cifs/connect.c: Free previous auth_key.response = 0000000061b01a87
Jun 29 11:25:39 pve702 kernel: [   87.565117] CIFS: fs/cifs/connect.c: Security Mode: 0x1 Capabilities: 0x300047 TimeAdjust: 0
Jun 29 11:25:39 pve702 kernel: [   87.565119] CIFS: fs/cifs/smb2pdu.c: Session Setup
Jun 29 11:25:39 pve702 kernel: [   87.565119] CIFS: fs/cifs/smb2pdu.c: sess setup type 2
Jun 29 11:25:39 pve702 kernel: [   87.565121] CIFS: fs/cifs/smb2pdu.c: Fresh session. Previous: 48b0efa2
Jun 29 11:25:39 pve702 kernel: [   87.565396] CIFS: fs/cifs/smb2misc.c: SMB2 data length 202 offset 72
Jun 29 11:25:39 pve702 kernel: [   87.565404] CIFS: Status code returned 0xc0000016 STATUS_MORE_PROCESSING_REQUIRED
Jun 29 11:25:39 pve702 kernel: [   87.565418] CIFS: fs/cifs/smb2maperror.c: Mapping SMB2 status code 0xc0000016 to POSIX err -5
Jun 29 11:25:39 pve702 kernel: [   87.565422] CIFS: fs/cifs/sess.c: decode_ntlmssp_challenge: negotiate=0xe2088235 challenge=0xe28a8235
Jun 29 11:25:39 pve702 kernel: [   87.565426] CIFS: fs/cifs/smb2pdu.c: rawntlmssp session setup challenge phase
Jun 29 11:25:39 pve702 kernel: [   87.565427] CIFS: fs/cifs/smb2pdu.c: Fresh session. Previous: 48b0efa2
Jun 29 11:25:39 pve702 kernel: [   87.571398] CIFS: fs/cifs/smb2misc.c: SMB2 data length 0 offset 72
Jun 29 11:25:39 pve702 kernel: [   87.571426] CIFS: fs/cifs/smb2pdu.c: SMB2/3 session established successfully
Jun 29 11:25:39 pve702 kernel: [   87.571428] CIFS: fs/cifs/sess.c: Cleared reconnect bitmask for chan 0; now 0x0
Jun 29 11:25:39 pve702 kernel: [   87.571439] CIFS: fs/cifs/smb2pdu.c: TCON
Jun 29 11:25:39 pve702 kernel: [   87.572058] CIFS: fs/cifs/smb2pdu.c: connection to disk share
Jun 29 11:25:39 pve702 kernel: [   87.572064] CIFS: fs/cifs/smb2pdu.c: reconnect tcon rc = 0
Jun 29 11:25:39 pve702 kernel: [   87.572068] CIFS: fs/cifs/connect.c: Security Mode: 0x1 Capabilities: 0x300047 TimeAdjust: 0
Jun 29 11:25:39 pve702 kernel: [   87.572069] CIFS: fs/cifs/file.c: VFS: in cifs_reopen_file as Xid: 132 with uid: 0
Jun 29 11:25:39 pve702 kernel: [   87.572070] CIFS: fs/cifs/smb2pdu.c: Session Setup
Jun 29 11:25:39 pve702 kernel: [   87.572071] CIFS: fs/cifs/smb2pdu.c: sess setup type 2
Jun 29 11:25:39 pve702 kernel: [   87.572074] CIFS: fs/cifs/file.c: inode = 0x0000000065dff21a file flags 0xc002 for \images\109\vm-109-disk-0.qcow2
Jun 29 11:25:39 pve702 kernel: [   87.572075] CIFS: fs/cifs/smb2pdu.c: Binding to sess id: f7cd8472
Jun 29 11:25:39 pve702 kernel: [   87.572090] CIFS: fs/cifs/smb2pdu.c: create/open
Jun 29 11:25:39 pve702 kernel: [   87.572207] CIFS: fs/cifs/smb2misc.c: SMB2 data length 0 offset 0
Jun 29 11:25:39 pve702 kernel: [   87.572220] CIFS: Status code returned 0xc00000d0 STATUS_REQUEST_NOT_ACCEPTED
Jun 29 11:25:39 pve702 kernel: [   87.572228] CIFS: fs/cifs/smb2maperror.c: Mapping SMB2 status code 0xc00000d0 to POSIX err -5
Jun 29 11:25:39 pve702 kernel: [   87.572233] CIFS: VFS: \\192.168.20.241 Send error in SessSetup = -5
Jun 29 11:25:39 pve702 kernel: [   87.572453] CIFS: fs/cifs/smb2ops.c: trying to put 1 credits from the old server instance 1
Jun 29 11:25:39 pve702 kernel: [   87.572458] CIFS: fs/cifs/file.c: VFS: in cifs_reopen_file as Xid: 133 with uid: 0
Jun 29 11:25:39 pve702 kernel: [   87.572468] CIFS: fs/cifs/connect.c: Security Mode: 0x1 Capabilities: 0x300047 TimeAdjust: 0
Jun 29 11:25:39 pve702 kernel: [   87.572470] CIFS: fs/cifs/smb2pdu.c: Session Setup
Jun 29 11:25:39 pve702 kernel: [   87.572472] CIFS: fs/cifs/smb2pdu.c: sess setup type 2
Jun 29 11:25:39 pve702 kernel: [   87.572474] CIFS: fs/cifs/smb2pdu.c: Binding to sess id: f7cd8472
Jun 29 11:25:39 pve702 kernel: [   87.574196] CIFS: fs/cifs/smb2misc.c: SMB2 data length 0 offset 0
Jun 29 11:25:39 pve702 kernel: [   87.574201] CIFS: fs/cifs/smb2ops.c: Session expired or deleted
Jun 29 11:25:39 pve702 kernel: [   87.574205] CIFS: fs/cifs/connect.c: Mark tcp session as need reconnect
Jun 29 11:25:39 pve702 kernel: [   87.574208] CIFS: fs/cifs/connect.c: cifs_mark_tcp_ses_conns_for_reconnect: marking necessary sessions and tcons for reconnect
Jun 29 11:25:39 pve702 kernel: [   87.574210] CIFS: fs/cifs/connect.c: cifs_abort_connection: tearing down socket
Jun 29 11:25:39 pve702 kernel: [   87.574211] CIFS: fs/cifs/connect.c: State: 0x3 Flags: 0x0
Jun 29 11:25:39 pve702 kernel: [   87.574225] CIFS: fs/cifs/connect.c: Post shutdown state: 0x3 Flags: 0x0
Jun 29 11:25:39 pve702 kernel: [   87.574250] CIFS: fs/cifs/connect.c: cifs_abort_connection: moving mids to private list
Jun 29 11:25:39 pve702 kernel: [   87.574252] CIFS: fs/cifs/connect.c: cifs_abort_connection: issuing mid callbacks
Jun 29 11:25:39 pve702 kernel: [   87.574259] CIFS: fs/cifs/dns_resolve.c: dns_resolve_server_name_to_ip: probably server name is whole unc: \\192.168.20.241
Jun 29 11:25:39 pve702 kernel: [   87.574262] CIFS: fs/cifs/dns_resolve.c: dns_resolve_server_name_to_ip: unc is IP, skipping dns upcall: 192.168.20.241
Jun 29 11:25:39 pve702 kernel: [   87.574265] CIFS: fs/cifs/connect.c: reconn_set_ipaddr_from_hostname: next dns resolution scheduled for 9088680469733379950 seconds in the future
Jun 29 11:25:39 pve702 kernel: [   87.574269] CIFS: fs/cifs/connect.c: __cifs_reconnect: reconn_set_ipaddr_from_hostname: rc=0
Jun 29 11:25:39 pve702 kernel: [   87.574271] CIFS: fs/cifs/connect.c: generic_ip_connect: connecting to 192.168.20.241:445
Jun 29 11:25:39 pve702 kernel: [   87.574321] CIFS: fs/cifs/connect.c: Socket created
Jun 29 11:25:39 pve702 kernel: [   87.574323] CIFS: fs/cifs/connect.c: sndbuf 16384 rcvbuf 131072 rcvtimeo 0x6d6
Jun 29 11:25:39 pve702 kernel: [   87.574349] CIFS: VFS: \\192.168.20.241 Send error in SessSetup = -11
Jun 29 11:25:39 pve702 kernel: [   87.574602] CIFS: fs/cifs/connect.c: cifs_put_tcon: tc_count=2
Jun 29 11:25:39 pve702 kernel: [   87.574609] CIFS: fs/cifs/smb2ops.c: smb2_set_credits: set 1 credits
Jun 29 11:25:39 pve702 kernel: [   87.574610] CIFS: fs/cifs/smb2ops.c: set credits to 1 due to smb2 reconnect
Jun 29 11:25:39 pve702 kernel: [   87.574618] CIFS: fs/cifs/smb2pdu.c: sess reconnect mask: 0x1, tcon reconnect: 1
Jun 29 11:25:39 pve702 kernel: [   87.574620] CIFS: fs/cifs/smb2pdu.c: Negotiate protocol
Jun 29 11:25:39 pve702 kernel: [   87.574679] CIFS: fs/cifs/file.c: cifs_reopen returned 0xfffffff5
Jun 29 11:25:39 pve702 kernel: [   87.574682] CIFS: fs/cifs/file.c: oplock: 2
Jun 29 11:25:39 pve702 kernel: [   87.574684] CIFS: fs/cifs/file.c: VFS: leaving cifs_reopen_file (xid = 132) rc = -11
Jun 29 11:25:39 pve702 kernel: [   87.574686] CIFS: fs/cifs/file.c: VFS: in cifs_reopen_file as Xid: 134 with uid: 0
Jun 29 11:25:39 pve702 kernel: [   87.574689] CIFS: fs/cifs/file.c: inode = 0x0000000065dff21a file flags 0xc002 for \images\109\vm-109-disk-0.qcow2
Jun 29 11:25:39 pve702 kernel: [   87.574732] CIFS: fs/cifs/smb2pdu.c: create/open
Jun 29 11:25:39 pve702 kernel: [   87.574736] CIFS: fs/cifs/smb2pdu.c: sess reconnect mask: 0x1, tcon reconnect: 1
Jun 29 11:25:39 pve702 kernel: [   87.580473] CIFS: fs/cifs/smb2misc.c: SMB2 data length 74 offset 128
Jun 29 11:25:39 pve702 kernel: [   87.580483] CIFS: fs/cifs/smb2misc.c: length of negcontexts 60 pad 6
Jun 29 11:25:39 pve702 kernel: [   87.580511] CIFS: fs/cifs/smb2pdu.c: mode 0x1
Jun 29 11:25:39 pve702 kernel: [   87.580513] CIFS: fs/cifs/smb2pdu.c: negotiated smb3.1.1 dialect
Jun 29 11:25:39 pve702 kernel: [   87.580518] CIFS: fs/cifs/smb2pdu.c: decoding 2 negotiate contexts
Jun 29 11:25:39 pve702 kernel: [   87.580520] CIFS: fs/cifs/smb2pdu.c: decode SMB3.11 encryption neg context of len 4
Jun 29 11:25:39 pve702 kernel: [   87.580522] CIFS: fs/cifs/smb2pdu.c: SMB311 cipher type:2
Jun 29 11:25:39 pve702 kernel: [   87.580524] CIFS: fs/cifs/connect.c: Free previous auth_key.response = 000000005b183786
Jun 29 11:25:39 pve702 kernel: [   87.580528] CIFS: fs/cifs/connect.c: Security Mode: 0x1 Capabilities: 0x300047 TimeAdjust: 0
Jun 29 11:25:39 pve702 kernel: [   87.580530] CIFS: fs/cifs/smb2pdu.c: Session Setup
Jun 29 11:25:39 pve702 kernel: [   87.580532] CIFS: fs/cifs/smb2pdu.c: sess setup type 2
Jun 29 11:25:39 pve702 kernel: [   87.580535] CIFS: fs/cifs/smb2pdu.c: Fresh session. Previous: f7cd8472
Jun 29 11:25:39 pve702 kernel: [   87.581045] CIFS: fs/cifs/smb2misc.c: SMB2 data length 202 offset 72
Jun 29 11:25:39 pve702 kernel: [   87.581056] CIFS: Status code returned 0xc0000016 STATUS_MORE_PROCESSING_REQUIRED
Jun 29 11:25:39 pve702 kernel: [   87.581063] CIFS: fs/cifs/smb2maperror.c: Mapping SMB2 status code 0xc0000016 to POSIX err -5
Jun 29 11:25:39 pve702 kernel: [   87.581069] CIFS: fs/cifs/sess.c: decode_ntlmssp_challenge: negotiate=0xe2088235 challenge=0xe28a8235
Jun 29 11:25:39 pve702 kernel: [   87.581074] CIFS: fs/cifs/smb2pdu.c: rawntlmssp session setup challenge phase
Jun 29 11:25:39 pve702 kernel: [   87.581076] CIFS: fs/cifs/smb2pdu.c: Fresh session. Previous: f7cd8472
Jun 29 11:25:39 pve702 kernel: [   87.587027] CIFS: fs/cifs/smb2misc.c: SMB2 data length 0 offset 72
Jun 29 11:25:39 pve702 kernel: [   87.587055] CIFS: fs/cifs/smb2pdu.c: SMB2/3 session established successfully
Jun 29 11:25:39 pve702 kernel: [   87.587057] CIFS: fs/cifs/sess.c: Cleared reconnect bitmask for chan 0; now 0x0
Jun 29 11:25:39 pve702 kernel: [   87.587070] CIFS: fs/cifs/smb2pdu.c: TCON
Jun 29 11:25:39 pve702 kernel: [   87.587467] CIFS: fs/cifs/smb2pdu.c: connection to pipe share
Jun 29 11:25:39 pve702 kernel: [   87.587479] CIFS: fs/cifs/smb2pdu.c: reconnect tcon rc = 0
Jun 29 11:25:39 pve702 kernel: [   87.587483] CIFS: fs/cifs/connect.c: cifs_put_smb_ses: ses_count=2
Jun 29 11:25:39 pve702 kernel: [   87.587485] CIFS: fs/cifs/connect.c: cifs_put_smb_ses: ses_count=2
Jun 29 11:25:39 pve702 kernel: [   87.587487] CIFS: fs/cifs/connect.c: cifs_put_smb_ses: ses ipc: \\192.168.20.241\IPC$
Jun 29 11:25:39 pve702 kernel: [   87.587488] CIFS: fs/cifs/smb2pdu.c: Reconnecting tcons and channels finished
Jun 29 11:25:39 pve702 kernel: [   87.587491] CIFS: fs/cifs/smb2pdu.c: Reconnecting tcons and channels
Jun 29 11:25:39 pve702 kernel: [   87.587490] CIFS: fs/cifs/connect.c: Security Mode: 0x1 Capabilities: 0x300047 TimeAdjust: 0
Jun 29 11:25:39 pve702 kernel: [   87.587493] CIFS: fs/cifs/smb2pdu.c: sess reconnect mask: 0x0, tcon reconnect: 1
Jun 29 11:25:39 pve702 kernel: [   87.587497] CIFS: fs/cifs/smb2pdu.c: Session Setup
Jun 29 11:25:39 pve702 kernel: [   87.587500] CIFS: fs/cifs/smb2pdu.c: sess setup type 2
Jun 29 11:25:39 pve702 kernel: [   87.587504] CIFS: fs/cifs/smb2pdu.c: Binding to sess id: 1128d761
Jun 29 11:25:39 pve702 kernel: [   87.587682] CIFS: fs/cifs/smb2misc.c: SMB2 data length 0 offset 0
Jun 29 11:25:39 pve702 kernel: [   87.587704] CIFS: Status code returned 0xc00000d0 STATUS_REQUEST_NOT_ACCEPTED
Jun 29 11:25:39 pve702 kernel: [   87.587710] CIFS: fs/cifs/smb2maperror.c: Mapping SMB2 status code 0xc00000d0 to POSIX err -5
Jun 29 11:25:39 pve702 kernel: [   87.587714] CIFS: VFS: \\192.168.20.241 Send error in SessSetup = -5
Jun 29 11:25:39 pve702 kernel: [   87.587889] CIFS: fs/cifs/file.c: cifs_reopen returned 0xfffffffb
Jun 29 11:25:39 pve702 kernel: [   87.587890] CIFS: fs/cifs/file.c: oplock: 2
Jun 29 11:25:39 pve702 kernel: [   87.587893] CIFS: fs/cifs/file.c: VFS: leaving cifs_reopen_file (xid = 133) rc = -5
Jun 29 11:25:39 pve702 kernel: [   87.587897] CIFS: fs/cifs/smb2pdu.c: reconnect tcon rc = 0
Jun 29 11:25:39 pve702 kernel: [   87.587903] CIFS: fs/cifs/connect.c: cifs_put_tcon: tc_count=2
Jun 29 11:25:39 pve702 kernel: [   87.587906] CIFS: fs/cifs/smb2pdu.c: Reconnecting tcons and channels finished
Jun 29 11:25:39 pve702 kernel: [   87.587908] CIFS: fs/cifs/file.c: VFS: in cifs_reopen_file as Xid: 135 with uid: 0
Jun 29 11:25:39 pve702 kernel: [   87.587912] CIFS: fs/cifs/file.c: inode = 0x0000000065dff21a file flags 0xc002 for \images\109\vm-109-disk-0.qcow2
Jun 29 11:25:39 pve702 kernel: [   87.587921] CIFS: fs/cifs/smb2pdu.c: create/open
Jun 29 11:25:39 pve702 kernel: [   87.587924] CIFS: fs/cifs/smb2pdu.c: sess reconnect mask: 0x0, tcon reconnect: 1
Jun 29 11:25:39 pve702 kernel: [   87.587926] CIFS: fs/cifs/smb2pdu.c: reconnect tcon rc = 0
Jun 29 11:25:39 pve702 kernel: [   87.587935] CIFS: fs/cifs/smb2pdu.c: Reconnecting tcons and channels
Jun 29 11:25:39 pve702 kernel: [   87.587937] CIFS: fs/cifs/smb2pdu.c: sess reconnect mask: 0x0, tcon reconnect: 1
Jun 29 11:25:39 pve702 kernel: [   87.587938] CIFS: fs/cifs/smb2pdu.c: reconnect tcon rc = 0
Jun 29 11:25:39 pve702 kernel: [   87.587939] CIFS: fs/cifs/connect.c: cifs_put_tcon: tc_count=2
Jun 29 11:25:39 pve702 kernel: [   87.587941] CIFS: fs/cifs/smb2pdu.c: Reconnecting tcons and channels finished
Jun 29 11:25:39 pve702 kernel: [   87.589450] CIFS: fs/cifs/smb2misc.c: SMB2 data length 0 offset 0
Jun 29 11:25:39 pve702 kernel: [   87.589456] CIFS: fs/cifs/smb2ops.c: Session expired or deleted
Jun 29 11:25:39 pve702 kernel: [   87.589460] CIFS: fs/cifs/connect.c: Mark tcp session as need reconnect
Jun 29 11:25:39 pve702 kernel: [   87.589461] CIFS: fs/cifs/connect.c: cifs_mark_tcp_ses_conns_for_reconnect: marking necessary sessions and tcons for reconnect
Jun 29 11:25:39 pve702 kernel: [   87.589465] CIFS: fs/cifs/connect.c: cifs_abort_connection: tearing down socket
Jun 29 11:25:39 pve702 kernel: [   87.589467] CIFS: fs/cifs/connect.c: State: 0x3 Flags: 0x0
Jun 29 11:25:39 pve702 kernel: [   87.589484] CIFS: fs/cifs/connect.c: Post shutdown state: 0x3 Flags: 0x0
Jun 29 11:25:39 pve702 kernel: [   87.589492] CIFS: fs/cifs/connect.c: cifs_abort_connection: moving mids to private list
Jun 29 11:25:39 pve702 kernel: [   87.589494] CIFS: fs/cifs/connect.c: cifs_abort_connection: issuing mid callbacks
Jun 29 11:25:39 pve702 kernel: [   87.589502] CIFS: fs/cifs/dns_resolve.c: dns_resolve_server_name_to_ip: probably server name is whole unc: \\192.168.20.241
Jun 29 11:25:39 pve702 kernel: [   87.589506] CIFS: fs/cifs/dns_resolve.c: dns_resolve_server_name_to_ip: unc is IP, skipping dns upcall: 192.168.20.241
Jun 29 11:25:39 pve702 kernel: [   87.589509] CIFS: fs/cifs/connect.c: reconn_set_ipaddr_from_hostname: next dns resolution scheduled for 9088680469733379950 seconds in the future
Jun 29 11:25:39 pve702 kernel: [   87.589512] CIFS: fs/cifs/file.c: cifs_reopen returned 0xfffffff5
Jun 29 11:25:39 pve702 kernel: [   87.589512] CIFS: fs/cifs/connect.c: __cifs_reconnect: reconn_set_ipaddr_from_hostname: rc=0
Jun 29 11:25:39 pve702 kernel: [   87.589516] CIFS: fs/cifs/file.c: oplock: 2
Jun 29 11:25:39 pve702 kernel: [   87.589519] CIFS: fs/cifs/file.c: VFS: leaving cifs_reopen_file (xid = 135) rc = -11
Jun 29 11:25:39 pve702 kernel: [   87.589520] CIFS: fs/cifs/file.c: VFS: in cifs_reopen_file as Xid: 136 with uid: 0
Jun 29 11:25:39 pve702 kernel: [   87.589524] CIFS: fs/cifs/connect.c: generic_ip_connect: connecting to 192.168.20.241:445
Jun 29 11:25:39 pve702 kernel: [   87.589539] CIFS: fs/cifs/connect.c: Socket created
Jun 29 11:25:39 pve702 kernel: [   87.589540] CIFS: fs/cifs/connect.c: sndbuf 16384 rcvbuf 131072 rcvtimeo 0x6d6
Jun 29 11:25:39 pve702 kernel: [   87.589605] CIFS: fs/cifs/file.c: inode = 0x0000000065dff21a file flags 0xc002 for \images\109\vm-109-disk-0.qcow2
Jun 29 11:25:39 pve702 kernel: [   87.589620] CIFS: fs/cifs/smb2pdu.c: create/open
Jun 29 11:25:39 pve702 kernel: [   87.589681] CIFS: fs/cifs/smb2ops.c: smb2_set_credits: set 1 credits
Jun 29 11:25:39 pve702 kernel: [   87.589686] CIFS: fs/cifs/smb2ops.c: set credits to 1 due to smb2 reconnect
Jun 29 11:25:39 pve702 kernel: [   87.589701] CIFS: fs/cifs/smb2pdu.c: Reconnecting tcons and channels
Jun 29 11:25:39 pve702 kernel: [   87.589703] CIFS: fs/cifs/smb2pdu.c: sess reconnect mask: 0x1, tcon reconnect: 1
Jun 29 11:25:39 pve702 kernel: [   87.589706] CIFS: fs/cifs/smb2pdu.c: Negotiate protocol
Jun 29 11:25:39 pve702 kernel: [   87.589746] CIFS: fs/cifs/smb2pdu.c: sess reconnect mask: 0x1, tcon reconnect: 1
Jun 29 11:25:39 pve702 kernel: [   87.593779] CIFS: fs/cifs/smb2misc.c: length of negcontexts 60 pad 6
Jun 29 11:25:39 pve702 kernel: [   87.593794] CIFS: fs/cifs/smb2pdu.c: mode 0x1
Jun 29 11:25:39 pve702 kernel: [   87.593798] CIFS: fs/cifs/smb2pdu.c: negotiated smb3.1.1 dialect
Jun 29 11:25:39 pve702 kernel: [   87.593803] CIFS: fs/cifs/smb2pdu.c: decoding 2 negotiate contexts
Jun 29 11:25:39 pve702 kernel: [   87.593805] CIFS: fs/cifs/smb2pdu.c: decode SMB3.11 encryption neg context of len 4
Jun 29 11:25:39 pve702 kernel: [   87.593807] CIFS: fs/cifs/smb2pdu.c: SMB311 cipher type:2
Jun 29 11:25:39 pve702 kernel: [   87.593808] CIFS: fs/cifs/connect.c: Free previous auth_key.response = 000000005b183786
Jun 29 11:25:39 pve702 kernel: [   87.593812] CIFS: fs/cifs/connect.c: Security Mode: 0x1 Capabilities: 0x300047 TimeAdjust: 0
Jun 29 11:25:39 pve702 kernel: [   87.593815] CIFS: fs/cifs/smb2pdu.c: Session Setup
Jun 29 11:25:39 pve702 kernel: [   87.593816] CIFS: fs/cifs/smb2pdu.c: sess setup type 2
Jun 29 11:25:39 pve702 kernel: [   87.593818] CIFS: fs/cifs/smb2pdu.c: Fresh session. Previous: 1128d761
Jun 29 11:25:39 pve702 kernel: [   87.594110] CIFS: Status code returned 0xc0000016 STATUS_MORE_PROCESSING_REQUIRED
Jun 29 11:25:39 pve702 kernel: [   87.594117] CIFS: fs/cifs/smb2maperror.c: Mapping SMB2 status code 0xc0000016 to POSIX err -5
Jun 29 11:25:39 pve702 kernel: [   87.594122] CIFS: fs/cifs/sess.c: decode_ntlmssp_challenge: negotiate=0xe2088235 challenge=0xe28a8235
Jun 29 11:25:39 pve702 kernel: [   87.594126] CIFS: fs/cifs/smb2pdu.c: rawntlmssp session setup challenge phase
Jun 29 11:25:39 pve702 kernel: [   87.594127] CIFS: fs/cifs/smb2pdu.c: Fresh session. Previous: 1128d761
Jun 29 11:25:39 pve702 kernel: [   87.599700] CIFS: fs/cifs/smb2pdu.c: SMB2/3 session established successfully
Jun 29 11:25:39 pve702 kernel: [   87.599705] CIFS: fs/cifs/sess.c: Cleared reconnect bitmask for chan 0; now 0x0
Jun 29 11:25:39 pve702 kernel: [   87.599713] CIFS: fs/cifs/smb2pdu.c: TCON
Jun 29 11:25:39 pve702 kernel: [   87.600305] CIFS: fs/cifs/smb2pdu.c: connection to disk share
Jun 29 11:25:39 pve702 kernel: [   87.600311] CIFS: fs/cifs/smb2pdu.c: reconnect tcon rc = 0
Jun 29 11:25:39 pve702 kernel: [   87.600315] CIFS: fs/cifs/connect.c: cifs_put_tcon: tc_count=2
Jun 29 11:25:39 pve702 kernel: [   87.600336] CIFS: fs/cifs/connect.c: Security Mode: 0x1 Capabilities: 0x300047 TimeAdjust: 0
Jun 29 11:25:39 pve702 kernel: [   87.600338] CIFS: fs/cifs/smb2pdu.c: Session Setup
Jun 29 11:25:39 pve702 kernel: [   87.600340] CIFS: fs/cifs/smb2pdu.c: sess setup type 2
Jun 29 11:25:39 pve702 kernel: [   87.600342] CIFS: fs/cifs/smb2pdu.c: Binding to sess id: 25423a26
Jun 29 11:25:39 pve702 kernel: [   87.600468] CIFS: Status code returned 0xc00000d0 STATUS_REQUEST_NOT_ACCEPTED
Jun 29 11:25:39 pve702 kernel: [   87.600474] CIFS: fs/cifs/smb2maperror.c: Mapping SMB2 status code 0xc00000d0 to POSIX err -5
Jun 29 11:25:39 pve702 kernel: [   87.600477] CIFS: VFS: \\192.168.20.241 Send error in SessSetup = -5
Jun 29 11:25:39 pve702 kernel: [   87.600793] CIFS: fs/cifs/file.c: cifs_reopen returned 0xfffffffb
Jun 29 11:25:39 pve702 kernel: [   87.600794] CIFS: fs/cifs/file.c: oplock: 2
Jun 29 11:25:39 pve702 kernel: [   87.600796] CIFS: fs/cifs/file.c: VFS: leaving cifs_reopen_file (xid = 134) rc = -5
Jun 29 11:25:39 pve702 kernel: [   87.600806] CIFS: fs/cifs/file.c: inode = 0x0000000065dff21a file flags 0xc002 for \images\109\vm-109-disk-0.qcow2
Jun 29 11:25:39 pve702 kernel: [   87.600812] CIFS: fs/cifs/smb2pdu.c: reconnect tcon rc = 0
Jun 29 11:25:39 pve702 kernel: [   87.600817] CIFS: fs/cifs/connect.c: cifs_put_smb_ses: ses_count=2
Jun 29 11:25:39 pve702 kernel: [   87.600819] CIFS: fs/cifs/connect.c: cifs_put_smb_ses: ses_count=2
Jun 29 11:25:39 pve702 kernel: [   87.600821] CIFS: fs/cifs/connect.c: cifs_put_smb_ses: ses ipc: \\192.168.20.241\IPC$
Jun 29 11:25:39 pve702 kernel: [   87.600823] CIFS: fs/cifs/smb2pdu.c: Reconnecting tcons and channels finished
Jun 29 11:25:39 pve702 kernel: [   87.600826] CIFS: fs/cifs/smb2pdu.c: create/open
Jun 29 11:25:39 pve702 kernel: [   87.601911] CIFS: fs/cifs/smb2ops.c: Session expired or deleted
Jun 29 11:25:39 pve702 kernel: [   87.601916] CIFS: fs/cifs/connect.c: Mark tcp session as need reconnect
Jun 29 11:25:39 pve702 kernel: [   87.601918] CIFS: fs/cifs/connect.c: cifs_mark_tcp_ses_conns_for_reconnect: marking necessary sessions and tcons for reconnect
Jun 29 11:25:39 pve702 kernel: [   87.601920] CIFS: fs/cifs/connect.c: cifs_abort_connection: tearing down socket
Jun 29 11:25:39 pve702 kernel: [   87.601922] CIFS: fs/cifs/connect.c: State: 0x3 Flags: 0x0
Jun 29 11:25:39 pve702 kernel: [   87.601945] CIFS: fs/cifs/connect.c: Post shutdown state: 0x3 Flags: 0x0
Jun 29 11:25:39 pve702 kernel: [   87.601951] CIFS: fs/cifs/connect.c: cifs_abort_connection: moving mids to private list
Jun 29 11:25:39 pve702 kernel: [   87.601952] CIFS: fs/cifs/connect.c: cifs_abort_connection: issuing mid callbacks
Jun 29 11:25:39 pve702 kernel: [   87.601958] CIFS: fs/cifs/dns_resolve.c: dns_resolve_server_name_to_ip: probably server name is whole unc: \\192.168.20.241
Jun 29 11:25:39 pve702 kernel: [   87.601960] CIFS: fs/cifs/dns_resolve.c: dns_resolve_server_name_to_ip: unc is IP, skipping dns upcall: 192.168.20.241
Jun 29 11:25:39 pve702 kernel: [   87.601962] CIFS: fs/cifs/connect.c: reconn_set_ipaddr_from_hostname: next dns resolution scheduled for 9088680469733379950 seconds in the future
Jun 29 11:25:39 pve702 kernel: [   87.601966] CIFS: fs/cifs/connect.c: __cifs_reconnect: reconn_set_ipaddr_from_hostname: rc=0
Jun 29 11:25:39 pve702 kernel: [   87.601967] CIFS: fs/cifs/connect.c: generic_ip_connect: connecting to 192.168.20.241:445
Jun 29 11:25:39 pve702 kernel: [   87.601975] CIFS: fs/cifs/file.c: cifs_reopen returned 0xfffffff5
Jun 29 11:25:39 pve702 kernel: [   87.601975] CIFS: fs/cifs/connect.c: Socket created
Jun 29 11:25:39 pve702 kernel: [   87.601978] CIFS: fs/cifs/connect.c: sndbuf 16384 rcvbuf 131072 rcvtimeo 0x6d6
Jun 29 11:25:39 pve702 kernel: [   87.601979] CIFS: fs/cifs/file.c: oplock: 2
Jun 29 11:25:39 pve702 kernel: [   87.601982] CIFS: fs/cifs/file.c: VFS: leaving cifs_reopen_file (xid = 136) rc = -11
Jun 29 11:25:39 pve702 kernel: [   87.601986] CIFS: fs/cifs/file.c: VFS: in cifs_reopen_file as Xid: 137 with uid: 0
Jun 29 11:25:39 pve702 kernel: [   87.601988] CIFS: fs/cifs/file.c: inode = 0x0000000065dff21a file flags 0xc002 for \images\109\vm-109-disk-0.qcow2
Jun 29 11:25:39 pve702 kernel: [   87.601993] CIFS: fs/cifs/smb2pdu.c: create/open
Jun 29 11:25:39 pve702 kernel: [   87.601994] CIFS: fs/cifs/smb2pdu.c: smb2_reconnect: aborting reconnect due to a received signal by the process
Jun 29 11:25:39 pve702 kernel: [   87.601996] CIFS: fs/cifs/file.c: cifs_reopen returned 0xfffffe00
Jun 29 11:25:39 pve702 kernel: [   87.601997] CIFS: fs/cifs/file.c: oplock: 2
Jun 29 11:25:39 pve702 kernel: [   87.601998] CIFS: fs/cifs/file.c: VFS: leaving cifs_reopen_file (xid = 137) rc = -512
Jun 29 11:25:39 pve702 kernel: [   87.602105] CIFS: fs/cifs/smb2ops.c: smb2_set_credits: set 1 credits
Jun 29 11:25:39 pve702 kernel: [   87.602109] CIFS: fs/cifs/smb2ops.c: set credits to 1 due to smb2 reconnect
Jun 29 11:25:39 pve702 kernel: [   87.602118] CIFS: fs/cifs/smb2pdu.c: Reconnecting tcons and channels
Jun 29 11:25:39 pve702 kernel: [   87.602122] CIFS: fs/cifs/smb2pdu.c: Negotiate protocol
Jun 29 11:25:39 pve702 kernel: [   87.606178] CIFS: fs/cifs/smb2misc.c: length of negcontexts 60 pad 6
Jun 29 11:25:39 pve702 kernel: [   87.612152] CIFS: fs/cifs/smb2pdu.c: mode 0x1
Jun 29 11:25:39 pve702 kernel: [   87.612160] CIFS: fs/cifs/smb2pdu.c: negotiated smb3.1.1 dialect
Jun 29 11:25:39 pve702 kernel: [   87.612166] CIFS: fs/cifs/smb2pdu.c: decoding 2 negotiate contexts
Jun 29 11:25:39 pve702 kernel: [   87.612168] CIFS: fs/cifs/smb2pdu.c: decode SMB3.11 encryption neg context of len 4
Jun 29 11:25:39 pve702 kernel: [   87.612170] CIFS: fs/cifs/smb2pdu.c: SMB311 cipher type:2
Jun 29 11:25:39 pve702 kernel: [   87.612172] CIFS: fs/cifs/connect.c: Free previous auth_key.response = 000000005b183786
Jun 29 11:25:39 pve702 kernel: [   87.612177] CIFS: fs/cifs/connect.c: Security Mode: 0x1 Capabilities: 0x300047 TimeAdjust: 0
Jun 29 11:25:39 pve702 kernel: [   87.612180] CIFS: fs/cifs/smb2pdu.c: Session Setup
Jun 29 11:25:39 pve702 kernel: [   87.612182] CIFS: fs/cifs/smb2pdu.c: sess setup type 2
Jun 29 11:25:39 pve702 kernel: [   87.612186] CIFS: fs/cifs/smb2pdu.c: Fresh session. Previous: 25423a26
Jun 29 11:25:39 pve702 kernel: [   87.620054] CIFS: Status code returned 0xc0000016 STATUS_MORE_PROCESSING_REQUIRED
Jun 29 11:25:39 pve702 kernel: [   87.620065] CIFS: fs/cifs/smb2maperror.c: Mapping SMB2 status code 0xc0000016 to POSIX err -5
Jun 29 11:25:39 pve702 kernel: [   87.620073] CIFS: fs/cifs/sess.c: decode_ntlmssp_challenge: negotiate=0xe2088235 challenge=0xe28a8235
Jun 29 11:25:39 pve702 kernel: [   87.620077] CIFS: fs/cifs/smb2pdu.c: rawntlmssp session setup challenge phase
Jun 29 11:25:39 pve702 kernel: [   87.620080] CIFS: fs/cifs/smb2pdu.c: Fresh session. Previous: 25423a26
Jun 29 11:25:39 pve702 kernel: [   87.626394] CIFS: fs/cifs/smb2pdu.c: SMB2/3 session established successfully
Jun 29 11:25:39 pve702 kernel: [   87.626400] CIFS: fs/cifs/sess.c: Cleared reconnect bitmask for chan 0; now 0x0
Jun 29 11:25:39 pve702 kernel: [   87.626407] CIFS: fs/cifs/smb2pdu.c: TCON
Jun 29 11:25:39 pve702 kernel: [   87.627083] CIFS: fs/cifs/smb2pdu.c: connection to disk share
Jun 29 11:25:39 pve702 kernel: [   87.627089] CIFS: fs/cifs/smb2pdu.c: reconnect tcon rc = 0
Jun 29 11:25:39 pve702 kernel: [   87.627093] CIFS: fs/cifs/connect.c: cifs_put_tcon: tc_count=2
Jun 29 11:25:39 pve702 kernel: [   87.627097] CIFS: fs/cifs/smb2pdu.c: reconnect tcon rc = 0
Jun 29 11:25:39 pve702 kernel: [   87.627099] CIFS: fs/cifs/connect.c: cifs_put_smb_ses: ses_count=2
Jun 29 11:25:39 pve702 kernel: [   87.627100] CIFS: fs/cifs/connect.c: cifs_put_smb_ses: ses_count=2
Jun 29 11:25:39 pve702 kernel: [   87.627101] CIFS: fs/cifs/connect.c: cifs_put_smb_ses: ses ipc: \\192.168.20.241\IPC$
Jun 29 11:25:39 pve702 kernel: [   87.627103] CIFS: fs/cifs/smb2pdu.c: Reconnecting tcons and channels finished
Jun 29 11:25:40 pve702 kernel: [   87.931326] CIFS: fs/cifs/inode.c: VFS: in cifs_revalidate_dentry_attr as Xid: 138 with uid: 0
Jun 29 11:25:40 pve702 kernel: [   87.931335] CIFS: fs/cifs/inode.c: Update attributes:  inode 0x0000000040c6d6b7 count 1 dentry: 0x00000000e37f44f0 d_time 0 jiffies 4294914258
Jun 29 11:25:40 pve702 kernel: [   87.932294] CIFS: fs/cifs/inode.c: cifs_revalidate_cache: revalidating inode 319
Jun 29 11:25:40 pve702 kernel: [   87.932297] CIFS: fs/cifs/inode.c: cifs_revalidate_cache: inode 319 is unchanged
Jun 29 11:25:40 pve702 kernel: [   87.932300] CIFS: fs/cifs/inode.c: VFS: leaving cifs_revalidate_dentry_attr (xid = 138) rc = 0
Jun 29 11:25:40 pve702 kernel: [   87.934147] CIFS: fs/cifs/inode.c: VFS: in cifs_revalidate_dentry_attr as Xid: 139 with uid: 0
Jun 29 11:25:40 pve702 kernel: [   87.934152] CIFS: fs/cifs/inode.c: Update attributes: \dump inode 0x00000000cf25111c count 1 dentry: 0x000000006242bdd1 d_time 4294897571 jiffies 4294914259
Jun 29 11:25:40 pve702 kernel: [   87.934797] CIFS: fs/cifs/inode.c: cifs_revalidate_cache: revalidating inode 11001916822272032900
Jun 29 11:25:40 pve702 kernel: [   87.934800] CIFS: fs/cifs/inode.c: cifs_revalidate_cache: inode 11001916822272032900 is unchanged
Jun 29 11:25:40 pve702 kernel: [   87.934802] CIFS: fs/cifs/inode.c: VFS: leaving cifs_revalidate_dentry_attr (xid = 139) rc = 0
Jun 29 11:25:40 pve702 kernel: [   87.934817] CIFS: fs/cifs/inode.c: VFS: in cifs_revalidate_dentry_attr as Xid: 140 with uid: 0
Jun 29 11:25:40 pve702 kernel: [   87.934819] CIFS: fs/cifs/inode.c: Update attributes: \private inode 0x00000000e90c88c2 count 1 dentry: 0x0000000066982e92 d_time 4294897572 jiffies 4294914259
Jun 29 11:25:40 pve702 kernel: [   87.935117] CIFS: fs/cifs/inode.c: cifs_revalidate_cache: revalidating inode 113
Jun 29 11:25:40 pve702 kernel: [   87.935119] CIFS: fs/cifs/inode.c: cifs_revalidate_cache: inode 113 is unchanged
Jun 29 11:25:40 pve702 kernel: [   87.935120] CIFS: fs/cifs/inode.c: VFS: leaving cifs_revalidate_dentry_attr (xid = 140) rc = 0
Jun 29 11:25:40 pve702 kernel: [   87.935132] CIFS: fs/cifs/inode.c: VFS: in cifs_revalidate_dentry_attr as Xid: 141 with uid: 0
Jun 29 11:25:40 pve702 kernel: [   87.935133] CIFS: fs/cifs/inode.c: Update attributes: \template inode 0x00000000bbb749ac count 1 dentry: 0x000000006c9ccc4a d_time 4294897572 jiffies 4294914259
Jun 29 11:25:40 pve702 kernel: [   87.935422] CIFS: fs/cifs/inode.c: cifs_revalidate_cache: revalidating inode 114
Jun 29 11:25:40 pve702 kernel: [   87.935424] CIFS: fs/cifs/inode.c: cifs_revalidate_cache: inode 114 is unchanged
Jun 29 11:25:40 pve702 kernel: [   87.935425] CIFS: fs/cifs/inode.c: VFS: leaving cifs_revalidate_dentry_attr (xid = 141) rc = 0
Jun 29 11:25:40 pve702 kernel: [   87.935427] CIFS: fs/cifs/inode.c: VFS: in cifs_revalidate_dentry_attr as Xid: 142 with uid: 0
Jun 29 11:25:40 pve702 kernel: [   87.935428] CIFS: fs/cifs/inode.c: Update attributes: \template\cache inode 0x0000000019253485 count 1 dentry: 0x000000005c653f96 d_time 4294897572 jiffies 4294914259
Jun 29 11:25:40 pve702 kernel: [   87.935747] CIFS: fs/cifs/inode.c: cifs_revalidate_cache: revalidating inode 115
Jun 29 11:25:40 pve702 kernel: [   87.935749] CIFS: fs/cifs/inode.c: cifs_revalidate_cache: inode 115 is unchanged
Jun 29 11:25:40 pve702 kernel: [   87.935750] CIFS: fs/cifs/inode.c: VFS: leaving cifs_revalidate_dentry_attr (xid = 142) rc = 0
Jun 29 11:25:40 pve702 kernel: [   87.935762] CIFS: fs/cifs/inode.c: VFS: in cifs_revalidate_dentry_attr as Xid: 143 with uid: 0
Jun 29 11:25:40 pve702 kernel: [   87.935764] CIFS: fs/cifs/inode.c: Update attributes: \template\iso inode 0x000000003e8e4a38 count 1 dentry: 0x00000000335cbebc d_time 4294897572 jiffies 4294914259
Jun 29 11:25:40 pve702 kernel: [   87.936052] CIFS: fs/cifs/inode.c: cifs_revalidate_cache: revalidating inode 116
Jun 29 11:25:40 pve702 kernel: [   87.936054] CIFS: fs/cifs/inode.c: cifs_revalidate_cache: inode 116 is unchanged
Jun 29 11:25:40 pve702 kernel: [   87.936055] CIFS: fs/cifs/inode.c: VFS: leaving cifs_revalidate_dentry_attr (xid = 143) rc = 0
Jun 29 11:25:40 pve702 kernel: [   87.936066] CIFS: fs/cifs/inode.c: VFS: in cifs_revalidate_dentry_attr as Xid: 144 with uid: 0
Jun 29 11:25:40 pve702 kernel: [   87.936068] CIFS: fs/cifs/inode.c: Update attributes: \snippets inode 0x000000001bee8638 count 1 dentry: 0x0000000062920d50 d_time 4294897572 jiffies 4294914259
Jun 29 11:25:40 pve702 kernel: [   87.936607] CIFS: fs/cifs/inode.c: cifs_revalidate_cache: revalidating inode 117
Jun 29 11:25:40 pve702 kernel: [   87.936610] CIFS: fs/cifs/inode.c: cifs_revalidate_cache: inode 117 is unchanged
Jun 29 11:25:40 pve702 kernel: [   87.936611] CIFS: fs/cifs/inode.c: VFS: leaving cifs_revalidate_dentry_attr (xid = 144) rc = 0
Jun 29 11:25:40 pve702 kernel: [   87.936628] CIFS: fs/cifs/inode.c: VFS: in cifs_revalidate_dentry_attr as Xid: 145 with uid: 0
Jun 29 11:25:40 pve702 kernel: [   87.936630] CIFS: fs/cifs/inode.c: Update attributes: \images inode 0x00000000d1e72e2b count 1 dentry: 0x00000000d2161375 d_time 4294910159 jiffies 4294914260
Jun 29 11:25:40 pve702 kernel: [   87.936915] CIFS: fs/cifs/inode.c: cifs_revalidate_cache: revalidating inode 118
Jun 29 11:25:40 pve702 kernel: [   87.936917] CIFS: fs/cifs/inode.c: cifs_revalidate_cache: inode 118 is unchanged
Jun 29 11:25:40 pve702 kernel: [   87.936918] CIFS: fs/cifs/inode.c: VFS: leaving cifs_revalidate_dentry_attr (xid = 145) rc = 0
Jun 29 11:25:40 pve702 kernel: [   87.938299] CIFS: fs/cifs/cifsfs.c: VFS: in cifs_statfs as Xid: 146 with uid: 0
Jun 29 11:25:40 pve702 kernel: [   87.938896] CIFS: fs/cifs/cifsfs.c: VFS: leaving cifs_statfs (xid = 146) rc = 0

[-- Attachment #3: kernel-5.18.6-qemu-trace --]
[-- Type: text/plain, Size: 2330 bytes --]

luring_co_submit bs 0x55d65e397f70 s 0x55d65e39f210 luringcb 0x7f7e5f0f2800 fd 16 offset 3635503104 nbytes 4096 type 1
luring_do_submit LuringState 0x55d65e39f210 blocked 0 plugged 1 queued 1 inflight 0
luring_co_submit bs 0x55d65e397f70 s 0x55d65e39f210 luringcb 0x7f7e5f2f4800 fd 16 offset 848687104 nbytes 12288 type 1
luring_do_submit LuringState 0x55d65e39f210 blocked 0 plugged 1 queued 2 inflight 0
luring_io_unplug LuringState 0x55d65e39f210 blocked 0 plugged 1 queued 2 inflight 0
luring_io_uring_submit LuringState 0x55d65e39f210 ret 2
luring_process_completion LuringState 0x55d65e39f210 luringcb 0x7f7e5f0f2800 ret 4096
luring_process_completion LuringState 0x55d65e39f210 luringcb 0x7f7e5f2f4800 ret 12288
luring_io_plug LuringState 0x55d65e39f210 plug
luring_co_submit bs 0x55d65e397f70 s 0x55d65e39f210 luringcb 0x7f7e5eff1640 fd 16 offset 3494113280 nbytes 4096 type 2
luring_do_submit LuringState 0x55d65e39f210 blocked 0 plugged 1 queued 1 inflight 0
luring_co_submit bs 0x55d65e397f70 s 0x55d65e39f210 luringcb 0x7f7e5eef0640 fd 16 offset 190185472 nbytes 1310720 type 2
luring_do_submit LuringState 0x55d65e39f210 blocked 0 plugged 1 queued 2 inflight 0
luring_co_submit bs 0x55d65e397f70 s 0x55d65e39f210 luringcb 0x7f7e5edef640 fd 16 offset 191496192 nbytes 323584 type 2
luring_do_submit LuringState 0x55d65e39f210 blocked 0 plugged 1 queued 3 inflight 0
luring_io_unplug LuringState 0x55d65e39f210 blocked 0 plugged 1 queued 3 inflight 0
luring_io_uring_submit LuringState 0x55d65e39f210 ret 3
luring_process_completion LuringState 0x55d65e39f210 luringcb 0x7f7e5eff1640 ret 4096
luring_process_completion LuringState 0x55d65e39f210 luringcb 0x7f7e5edef640 ret -4
luring_io_plug LuringState 0x55d65e39f210 plug
luring_co_submit bs 0x55d65e397f70 s 0x55d65e39f210 luringcb 0x7f7e5ecee800 fd 16 offset 3495968768 nbytes 4096 type 1
luring_do_submit LuringState 0x55d65e39f210 blocked 0 plugged 1 queued 2 inflight 1
luring_io_unplug LuringState 0x55d65e39f210 blocked 0 plugged 1 queued 2 inflight 1
luring_io_uring_submit LuringState 0x55d65e39f210 ret 2
luring_process_completion LuringState 0x55d65e39f210 luringcb 0x7f7e5edef640 ret -5
luring_process_completion LuringState 0x55d65e39f210 luringcb 0x7f7e5eef0640 ret -5
luring_process_completion LuringState 0x55d65e39f210 luringcb 0x7f7e5ecee800 ret -4

^ permalink raw reply	[flat|nested] 10+ messages in thread

end of thread, other threads:[~2022-10-05  8:21 UTC | newest]

Thread overview: 10+ messages (download: mbox.gz follow: Atom feed
-- links below jump to the message on this page --
2022-07-08 12:05 Problematic interaction of io_uring and CIFS Fabian Ebner
2022-07-08 17:48 ` Enzo Matsumiya
2022-07-09  3:30   ` Shyam Prasad N
2022-07-09  3:39     ` Shyam Prasad N
2022-07-11 13:40       ` Fabian Ebner
2022-08-26  8:21         ` Fiona Ebner
2022-10-04  8:59           ` Fiona Ebner
2022-10-04 14:02             ` Jens Axboe
2022-10-05  8:20               ` Fiona Ebner
2022-07-08 18:12 ` Jens Axboe

This is a public inbox, see mirroring instructions
for how to clone and mirror all data and code used for this inbox