All of lore.kernel.org
 help / color / mirror / Atom feed
From: "Steinbeißer, Sebastian" <Sebastian.Steinbeisser@lrz.de>
To: "tom@talpey.com" <tom@talpey.com>,
	"gregkh@linuxfoundation.org" <gregkh@linuxfoundation.org>,
	"pc@manguebit.com" <pc@manguebit.com>
Cc: "smfrench@gmail.com" <smfrench@gmail.com>,
	"regressions@lists.linux.dev" <regressions@lists.linux.dev>
Subject: Re: Potential smb/dfs regression introduced in kernel 6.6
Date: Wed, 31 Jul 2024 05:02:46 +0000	[thread overview]
Message-ID: <1359665240338585cfbc4eac58339ab1d0423788.camel@lrz.de> (raw)
In-Reply-To: <ac58f36e10af65cd672fba66e99faa2c@manguebit.com>


[-- Attachment #1.1.1: Type: text/plain, Size: 1045 bytes --]

Dear Paulo,

that might have fixed it.
Logs attached.

Best
Sebastian
+-------------------------------------------------------------+
 Sebastian Steinbeißer
 Leibniz-Rechenzentrum   Tel.:+49-89-35831-8779
 Boltzmannstraße 1       E-Mail: Sebastian.Steinbeisser@lrz.de
 D-85748 Garching
+-------------------------------------------------------------+


-----Original Message-----
From: Paulo Alcantara <pc@manguebit.com>
To: "Steinbeißer, Sebastian" <Sebastian.Steinbeisser@lrz.de>,
tom@talpey.com <tom@talpey.com>, gregkh@linuxfoundation.org
<gregkh@linuxfoundation.org>
Cc: smfrench@gmail.com <smfrench@gmail.com>,
regressions@lists.linux.dev <regressions@lists.linux.dev>
Subject: Re: Potential smb/dfs regression introduced in kernel 6.6
Date: 31.07.2024 04:35:45

Hi Sebastian,

Steinbeißer, Sebastian  <Sebastian.Steinbeisser@lrz.de> writes:

> Find the logs attached.

Thanks.

Could you please try the attached patch instead?

Whether it works or not, please provide new network traces and logs.

[-- Attachment #1.1.2: trace.log --]
[-- Type: text/x-log, Size: 43793 bytes --]

Display Internal CIFS Data Structures for Debugging
---------------------------------------------------
CIFS Version 2.49
Features: DFS,STATS2,DEBUG2,ALLOW_INSECURE_LEGACY,CIFS_POSIX,XATTR,ACL
CIFSMaxBufSize: 16384
Active VFS Requests: 0

Servers: 
	[NONE]
DFS cache
---------
[  133.247220] ohci-pci 0000:00:06.0: frame counter not updating; disabled
[  133.247237] ohci-pci 0000:00:06.0: HC died; cleaning up
[  133.247955] usb 2-1: USB disconnect, device number 2
[  171.264677] CIFS: fs/smb/client/dfs_cache.c: clearing dfs cache
[  178.532126] e1000 0000:00:03.0 enp0s3: entered promiscuous mode
[  178.560236] audit: type=1400 audit(1722401963.312:30): apparmor="DENIED" operation="capable" class="cap" profile="tcpdump" pid=1993 comm="tcpdump" capability=2  capname="dac_read_search"
[  133.247220] ohci-pci 0000:00:06.0: frame counter not updating; disabled
[  133.247237] ohci-pci 0000:00:06.0: HC died; cleaning up
[  133.247955] usb 2-1: USB disconnect, device number 2
[  171.264677] CIFS: fs/smb/client/dfs_cache.c: clearing dfs cache
[  178.532126] e1000 0000:00:03.0 enp0s3: entered promiscuous mode
[  178.560236] audit: type=1400 audit(1722401963.312:30): apparmor="DENIED" operation="capable" class="cap" profile="tcpdump" pid=1993 comm="tcpdump" capability=2  capname="dac_read_search"
[  244.129754] CIFS: fs/smb/client/fs_context.c: CIFS: parsing cifs mount option 'source'
[  244.129771] CIFS: fs/smb/client/fs_context.c: CIFS: parsing cifs mount option 'ip'
[  244.129777] CIFS: address conversion returned 1 for 10.156.103.200
[  244.129782] CIFS: fs/smb/client/fs_context.c: CIFS: parsing cifs mount option 'unc'
[  244.129787] CIFS: fs/smb/client/fs_context.c: CIFS: parsing cifs mount option 'user'
[  244.129791] CIFS: fs/smb/client/fs_context.c: CIFS: parsing cifs mount option 'domain'
[  244.129795] CIFS: fs/smb/client/fs_context.c: Domain name set
[  244.129797] CIFS: fs/smb/client/fs_context.c: CIFS: parsing cifs mount option 'pass'
[  244.129801] CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount.
[  244.129805] CIFS: fs/smb/client/cifsfs.c: cifs_smb3_do_mount: devname=//10.156.103.200/volume01 flags=0x0
[  244.129811] CIFS: fs/smb/client/connect.c: file mode: 0755  dir mode: 0755
[  244.134068] CIFS: fs/smb/client/connect.c: VFS: in cifs_mount_get_session as Xid: 0 with uid: 0
[  244.134077] CIFS: fs/smb/client/connect.c: UNC: \\10.156.103.200\volume01
[  244.134084] CIFS: fs/smb/client/connect.c: generic_ip_connect: connecting to 10.156.103.200:445
[  244.134105] CIFS: fs/smb/client/connect.c: Socket created
[  244.134107] CIFS: fs/smb/client/connect.c: sndbuf 16384 rcvbuf 131072 rcvtimeo 0x6d6
[  244.135478] CIFS: fs/smb/client/connect.c: VFS: in cifs_get_smb_ses as Xid: 1 with uid: 0
[  244.135487] CIFS: fs/smb/client/connect.c: Existing smb sess not found
[  244.135495] CIFS: fs/smb/client/smb2pdu.c: Negotiate protocol
[  244.135498] CIFS: fs/smb/client/connect.c: Demultiplex PID: 2018
[  244.139435] CIFS: fs/smb/client/transport.c: wait_for_free_credits: remove 1 credits total=0
[  244.139464] CIFS: fs/smb/client/transport.c: Sending smb: smb_len=244
[  244.140189] CIFS: fs/smb/client/connect.c: RFC1002 header 0x134
[  244.140211] CIFS: fs/smb/client/smb2misc.c: SMB2 data length 114 offset 128
[  244.140216] CIFS: fs/smb/client/smb2misc.c: SMB2 len 242
[  244.140220] CIFS: fs/smb/client/smb2misc.c: length of negcontexts 60 pad 6
[  244.140227] CIFS: fs/smb/client/smb2ops.c: smb2_add_credits: added 1 credits total=1
[  244.147674] CIFS: fs/smb/client/transport.c: cifs_sync_mid_result: cmd=0 mid=0 state=64
[  244.147688] CIFS: fs/smb/client/misc.c: Null buffer passed to cifs_small_buf_release
[  244.147693] CIFS: fs/smb/client/smb2pdu.c: mode 0x1
[  244.147696] CIFS: fs/smb/client/smb2pdu.c: negotiated smb3.1.1 dialect
[  244.147704] CIFS: fs/smb/client/smb2pdu.c: decoding 2 negotiate contexts
[  244.147706] CIFS: fs/smb/client/smb2pdu.c: decode SMB3.11 encryption neg context of len 4
[  244.147709] CIFS: fs/smb/client/smb2pdu.c: SMB311 cipher type:2
[  244.147713] CIFS: fs/smb/client/connect.c: cifs_setup_session: channel connect bitmap: 0x1
[  244.147717] CIFS: fs/smb/client/connect.c: Security Mode: 0x1 Capabilities: 0x300057 TimeAdjust: 0
[  244.147721] CIFS: fs/smb/client/smb2pdu.c: Session Setup
[  244.147723] CIFS: fs/smb/client/smb2pdu.c: sess setup type 2
[  244.147726] CIFS: fs/smb/client/smb2pdu.c: Fresh session. Previous: 0
[  244.147732] CIFS: fs/smb/client/transport.c: wait_for_free_credits: remove 1 credits total=0
[  244.147748] CIFS: fs/smb/client/transport.c: Sending smb: smb_len=136
[  244.149031] CIFS: fs/smb/client/connect.c: RFC1002 header 0xfe
[  244.149050] CIFS: fs/smb/client/smb2misc.c: SMB2 data length 182 offset 72
[  244.149055] CIFS: fs/smb/client/smb2misc.c: SMB2 len 254
[  244.149062] CIFS: fs/smb/client/smb2ops.c: smb2_add_credits: added 128 credits total=128
[  244.149094] CIFS: fs/smb/client/transport.c: cifs_sync_mid_result: cmd=1 mid=1 state=64
[  244.149104] CIFS: fs/smb/client/smb2maperror.c: Mapping SMB2 status code 0xc0000016 to POSIX err -5
[  244.149117] CIFS: fs/smb/client/misc.c: Null buffer passed to cifs_small_buf_release
[  244.149122] CIFS: fs/smb/client/sess.c: decode_ntlmssp_challenge: negotiate=0xe2088235 challenge=0x60898235
[  244.149129] CIFS: fs/smb/client/smb2pdu.c: rawntlmssp session setup challenge phase
[  244.149134] CIFS: fs/smb/client/smb2pdu.c: Fresh session. Previous: 0
[  244.156908] CIFS: fs/smb/client/transport.c: wait_for_free_credits: remove 1 credits total=127
[  244.156940] CIFS: fs/smb/client/transport.c: Sending smb: smb_len=396
[  244.218746] CIFS: fs/smb/client/connect.c: RFC1002 header 0x48
[  244.218766] CIFS: fs/smb/client/smb2misc.c: SMB2 data length 0 offset 72
[  244.218770] CIFS: fs/smb/client/smb2misc.c: SMB2 len 73
[  244.218774] CIFS: fs/smb/client/smb2ops.c: smb2_add_credits: added 1 credits total=128
[  244.218818] CIFS: fs/smb/client/transport.c: cifs_sync_mid_result: cmd=1 mid=2 state=64
[  244.218830] CIFS: fs/smb/client/misc.c: Null buffer passed to cifs_small_buf_release
[  244.218856] CIFS: fs/smb/client/smb2pdu.c: SMB2/3 session established successfully
[  244.218861] CIFS: fs/smb/client/sess.c: Cleared reconnect bitmask for chan 0; now 0x0
[  244.218867] CIFS: fs/smb/client/connect.c: VFS: in cifs_setup_ipc as Xid: 2 with uid: 0
[  244.218871] CIFS: fs/smb/client/smb2pdu.c: TCON
[  244.218876] CIFS: fs/smb/client/transport.c: wait_for_free_credits: remove 1 credits total=127
[  244.218901] CIFS: fs/smb/client/transport.c: Sending smb: smb_len=118
[  244.219698] CIFS: fs/smb/client/connect.c: RFC1002 header 0x50
[  244.219711] CIFS: fs/smb/client/smb2misc.c: SMB2 len 80
[  244.219716] CIFS: fs/smb/client/smb2ops.c: smb2_add_credits: added 1 credits total=126
[  244.219770] CIFS: fs/smb/client/transport.c: cifs_sync_mid_result: cmd=3 mid=3 state=64
[  244.219775] CIFS: fs/smb/client/misc.c: Null buffer passed to cifs_small_buf_release
[  244.219778] CIFS: fs/smb/client/smb2pdu.c: connection to pipe share
[  244.219782] CIFS: fs/smb/client/connect.c: VFS: leaving cifs_setup_ipc (xid = 2) rc = 0
[  244.219785] CIFS: fs/smb/client/connect.c: IPC tcon rc=0 ipc tid=0x1
[  244.219788] CIFS: fs/smb/client/connect.c: VFS: leaving cifs_get_smb_ses (xid = 1) rc = 0
[  244.219792] CIFS: fs/smb/client/dfs_cache.c: cache_refresh_path: search path: \10.156.103.200\volume01
[  244.219798] CIFS: fs/smb/client/dfs_cache.c: get_dfs_referral: ipc=\\10.156.103.200\IPC$ referral=\10.156.103.200\volume01
[  244.219801] CIFS: fs/smb/client/smb2ops.c: smb2_get_dfs_refer: path: \10.156.103.200\volume01
[  244.219806] CIFS: fs/smb/client/smb2pdu.c: SMB2 IOCTL
[  244.219809] CIFS: fs/smb/client/transport.c: wait_for_free_credits: remove 1 credits total=125
[  244.219816] CIFS: fs/smb/client/transport.c: Sending smb: smb_len=176
[  244.220350] CIFS: fs/smb/client/connect.c: RFC1002 header 0x49
[  244.220362] CIFS: fs/smb/client/smb2misc.c: SMB2 data length 0 offset 0
[  244.220365] CIFS: fs/smb/client/smb2misc.c: SMB2 len 73
[  244.220369] CIFS: fs/smb/client/smb2ops.c: smb2_add_credits: added 1 credits total=126
[  244.220397] CIFS: fs/smb/client/transport.c: cifs_sync_mid_result: cmd=11 mid=4 state=64
[  244.220403] CIFS: fs/smb/client/smb2maperror.c: Mapping SMB2 status code 0xc0000225 to POSIX err -2
[  244.220406] CIFS: fs/smb/client/misc.c: Null buffer passed to cifs_small_buf_release
[  244.220409] CIFS: fs/smb/client/dfs.c: dfs_mount_share: no dfs referral for \10.156.103.200\volume01: -2
[  244.220412] CIFS: fs/smb/client/dfs.c: dfs_mount_share: assuming non-dfs mount...
[  244.220416] CIFS: fs/smb/client/connect.c: VFS: in cifs_get_tcon as Xid: 3 with uid: 0
[  244.220419] CIFS: fs/smb/client/smb2pdu.c: TCON
[  244.220423] CIFS: fs/smb/client/transport.c: wait_for_free_credits: remove 1 credits total=125
[  244.220431] CIFS: fs/smb/client/transport.c: Sending smb: smb_len=126
[  244.259752] CIFS: fs/smb/client/connect.c: RFC1002 header 0x50
[  244.259772] CIFS: fs/smb/client/smb2misc.c: SMB2 len 80
[  244.259778] CIFS: fs/smb/client/smb2ops.c: smb2_add_credits: added 1 credits total=126
[  244.259945] CIFS: fs/smb/client/transport.c: cifs_sync_mid_result: cmd=3 mid=5 state=64
[  244.259957] CIFS: fs/smb/client/misc.c: Null buffer passed to cifs_small_buf_release
[  244.259961] CIFS: fs/smb/client/smb2pdu.c: connection to disk share
[  244.259966] CIFS: fs/smb/client/connect.c: VFS: leaving cifs_get_tcon (xid = 3) rc = 0
[  244.259970] CIFS: fs/smb/client/connect.c: Tcon rc = 0
[  244.259974] CIFS: fs/smb/client/smb2pdu.c: create/open
[  244.259980] CIFS: fs/smb/client/transport.c: wait_for_free_credits: remove 1 credits total=125
[  244.260000] CIFS: fs/smb/client/transport.c: Sending smb: smb_len=156
[  244.260714] CIFS: fs/smb/client/connect.c: RFC1002 header 0xd0
[  244.260722] CIFS: fs/smb/client/smb2misc.c: SMB2 data length 56 offset 152
[  244.260725] CIFS: fs/smb/client/smb2misc.c: SMB2 len 208
[  244.260729] CIFS: fs/smb/client/smb2ops.c: smb2_add_credits: added 1 credits total=126
[  244.260765] CIFS: fs/smb/client/transport.c: cifs_sync_mid_result: cmd=5 mid=6 state=64
[  244.260771] CIFS: fs/smb/client/misc.c: Null buffer passed to cifs_small_buf_release
[  244.260776] CIFS: fs/smb/client/smb2pdu.c: SMB2 IOCTL
[  244.260780] CIFS: fs/smb/client/transport.c: wait_for_free_credits: remove 1 credits total=125
[  244.260789] CIFS: fs/smb/client/transport.c: Sending smb: smb_len=125
[  244.261286] CIFS: fs/smb/client/connect.c: RFC1002 header 0x49
[  244.261293] CIFS: fs/smb/client/smb2misc.c: SMB2 data length 0 offset 0
[  244.261296] CIFS: fs/smb/client/smb2misc.c: SMB2 len 73
[  244.261299] CIFS: fs/smb/client/smb2ops.c: smb2_add_credits: added 1 credits total=126
[  244.261432] CIFS: fs/smb/client/transport.c: cifs_sync_mid_result: cmd=11 mid=7 state=64
[  244.261442] CIFS: fs/smb/client/smb2maperror.c: Mapping SMB2 status code 0xc0000010 to POSIX err -95
[  244.261447] CIFS: fs/smb/client/misc.c: Null buffer passed to cifs_small_buf_release
[  244.261451] CIFS: fs/smb/client/smb2ops.c: server does not support query network interfaces
[  244.261454] CIFS: fs/smb/client/smb2pdu.c: Query FSInfo level 5
[  244.261468] CIFS: fs/smb/client/transport.c: wait_for_free_credits: remove 1 credits total=125
[  244.261482] CIFS: fs/smb/client/transport.c: Sending smb: smb_len=109
[  244.261964] CIFS: fs/smb/client/connect.c: RFC1002 header 0x5c
[  244.261970] CIFS: fs/smb/client/smb2misc.c: SMB2 data length 20 offset 72
[  244.261973] CIFS: fs/smb/client/smb2misc.c: SMB2 len 92
[  244.261976] CIFS: fs/smb/client/smb2ops.c: smb2_add_credits: added 1 credits total=126
[  244.262077] CIFS: fs/smb/client/transport.c: cifs_sync_mid_result: cmd=16 mid=8 state=64
[  244.262087] CIFS: fs/smb/client/misc.c: Null buffer passed to cifs_small_buf_release
[  244.262093] CIFS: fs/smb/client/smb2pdu.c: Query FSInfo level 4
[  244.262099] CIFS: fs/smb/client/transport.c: wait_for_free_credits: remove 1 credits total=125
[  244.262112] CIFS: fs/smb/client/transport.c: Sending smb: smb_len=109
[  244.262451] CIFS: fs/smb/client/connect.c: RFC1002 header 0x50
[  244.262457] CIFS: fs/smb/client/smb2misc.c: SMB2 data length 8 offset 72
[  244.262459] CIFS: fs/smb/client/smb2misc.c: SMB2 len 80
[  244.262462] CIFS: fs/smb/client/smb2ops.c: smb2_add_credits: added 1 credits total=126
[  244.262517] CIFS: fs/smb/client/transport.c: cifs_sync_mid_result: cmd=16 mid=9 state=64
[  244.262527] CIFS: fs/smb/client/misc.c: Null buffer passed to cifs_small_buf_release
[  244.262531] CIFS: fs/smb/client/smb2pdu.c: Query FSInfo level 1
[  244.263103] CIFS: fs/smb/client/smb2misc.c: SMB2 data length 34 offset 72
[  244.263156] CIFS: fs/smb/client/smb2pdu.c: Query FSInfo level 11
[  244.263627] CIFS: fs/smb/client/smb2misc.c: SMB2 data length 28 offset 72
[  244.263671] CIFS: fs/smb/client/smb2pdu.c: Close
[  244.264226] CIFS: fs/smb/client/connect.c: cifs_is_path_remote: full_path: 
[  244.264231] CIFS: fs/smb/client/smb2pdu.c: create/open
[  244.265166] CIFS: fs/smb/client/smb2pdu.c: Close
[  244.265737] CIFS: fs/smb/client/smb2pdu.c: create/open
[  244.266415] CIFS: fs/smb/client/smb2pdu.c: Close
[  244.266830] CIFS: fs/smb/client/sess.c: ses already at max_channels (1), nothing to open
[  244.266837] CIFS: fs/smb/client/connect.c: VFS: leaving cifs_mount (xid = 0) rc = 0
[  244.266966] CIFS: fs/smb/client/inode.c: VFS: in cifs_root_iget as Xid: 4 with uid: 0
[  244.268798] CIFS: fs/smb/client/inode.c: looking for uniqueid=16150111042041544768
[  244.268817] CIFS: fs/smb/client/inode.c: cifs_revalidate_cache: revalidating inode 16150111042041544768
[  244.268819] CIFS: fs/smb/client/inode.c: cifs_revalidate_cache: inode 16150111042041544768 is new
[  244.268822] CIFS: fs/smb/client/inode.c: VFS: leaving cifs_root_iget (xid = 4) rc = 0
[  244.268825] CIFS: fs/smb/client/cifsfs.c: Get root dentry for 
[  244.268826] CIFS: fs/smb/client/cifsfs.c: dentry root is: 00000000ce50e32f
[  244.269273] CIFS: fs/smb/client/dir.c: VFS: in cifs_lookup as Xid: 5 with uid: 1000
[  244.269275] CIFS: fs/smb/client/dir.c: parent inode = 0x000000005f772979 name is: .Trash and dentry = 0x00000000af8879f3
[  244.269278] CIFS: fs/smb/client/dir.c: NULL inode in lookup
[  244.269279] CIFS: fs/smb/client/dir.c: Full path: \.Trash inode = 0x0000000000000000
[  244.269996] CIFS: fs/smb/client/smb2maperror.c: Mapping SMB2 status code 0xc0000034 to POSIX err -2
[  244.270001] CIFS: fs/smb/client/inode.c: cifs_get_fattr: unhandled err rc -2
[  244.270003] CIFS: fs/smb/client/dir.c: VFS: leaving cifs_lookup (xid = 5) rc = -2
[  244.270039] CIFS: fs/smb/client/dir.c: VFS: in cifs_lookup as Xid: 6 with uid: 1000
[  244.270041] CIFS: fs/smb/client/dir.c: parent inode = 0x000000005f772979 name is: .Trash-1000 and dentry = 0x000000008d27745d
[  244.270044] CIFS: fs/smb/client/dir.c: NULL inode in lookup
[  244.270044] CIFS: fs/smb/client/dir.c: Full path: \.Trash-1000 inode = 0x0000000000000000
[  244.270479] CIFS: fs/smb/client/smb2maperror.c: Mapping SMB2 status code 0xc0000034 to POSIX err -2
[  244.270483] CIFS: fs/smb/client/inode.c: cifs_get_fattr: unhandled err rc -2
[  244.270484] CIFS: fs/smb/client/dir.c: VFS: leaving cifs_lookup (xid = 6) rc = -2
[  251.155628] CIFS: fs/smb/client/inode.c: VFS: in cifs_revalidate_dentry_attr as Xid: 7 with uid: 0
[  251.155641] CIFS: fs/smb/client/inode.c: Update attributes:  inode 0x000000005f772979 count 2 dentry: 0x00000000ce50e32f d_time 0 jiffies 4294954999
[  251.155678] wait_for_free_credits: 10 callbacks suppressed
[  251.155681] CIFS: fs/smb/client/transport.c: wait_for_free_credits: remove 3 credits total=123
[  251.155702] __smb_send_rqst: 10 callbacks suppressed
[  251.155704] CIFS: fs/smb/client/transport.c: Sending smb: smb_len=348
[  251.157202] cifs_demultiplex_thread: 10 callbacks suppressed
[  251.157209] CIFS: fs/smb/client/connect.c: RFC1002 header 0x20c
[  251.157221] smb2_calc_size: 8 callbacks suppressed
[  251.157223] CIFS: fs/smb/client/smb2misc.c: SMB2 data length 56 offset 152
[  251.157227] smb2_calc_size: 16 callbacks suppressed
[  251.157228] CIFS: fs/smb/client/smb2misc.c: SMB2 len 208
[  251.157233] smb2_add_credits: 16 callbacks suppressed
[  251.157235] CIFS: fs/smb/client/smb2ops.c: smb2_add_credits: added 1 credits total=124
[  251.157242] CIFS: fs/smb/client/smb2misc.c: SMB2 data length 120 offset 72
[  251.157245] CIFS: fs/smb/client/smb2misc.c: SMB2 len 192
[  251.157248] CIFS: fs/smb/client/smb2ops.c: smb2_add_credits: added 1 credits total=125
[  251.157258] CIFS: fs/smb/client/smb2misc.c: SMB2 len 124
[  251.157261] CIFS: fs/smb/client/smb2ops.c: smb2_add_credits: added 1 credits total=126
[  251.157342] cifs_sync_mid_result: 12 callbacks suppressed
[  251.157348] CIFS: fs/smb/client/transport.c: cifs_sync_mid_result: cmd=5 mid=26 state=64
[  251.157355] CIFS: fs/smb/client/transport.c: cifs_sync_mid_result: cmd=16 mid=27 state=64
[  251.157359] CIFS: fs/smb/client/transport.c: cifs_sync_mid_result: cmd=6 mid=28 state=64
[  251.157362] cifs_small_buf_release: 12 callbacks suppressed
[  251.157364] CIFS: fs/smb/client/misc.c: Null buffer passed to cifs_small_buf_release
[  251.157368] CIFS: fs/smb/client/misc.c: Null buffer passed to cifs_small_buf_release
[  251.157370] CIFS: fs/smb/client/misc.c: Null buffer passed to cifs_small_buf_release
[  251.157380] CIFS: fs/smb/client/inode.c: cifs_revalidate_cache: revalidating inode 16150111042041544768
[  251.157384] CIFS: fs/smb/client/inode.c: cifs_revalidate_cache: inode 16150111042041544768 is unchanged
[  251.157388] CIFS: fs/smb/client/inode.c: VFS: leaving cifs_revalidate_dentry_attr (xid = 7) rc = 0
[  251.157476] CIFS: fs/smb/client/readdir.c: VFS: in cifs_readdir as Xid: 8 with uid: 0
[  251.157482] CIFS: fs/smb/client/readdir.c: Full path:  start at: 0
[  251.157491] CIFS: fs/smb/client/transport.c: wait_for_free_credits: remove 2 credits total=124
[  251.157506] CIFS: fs/smb/client/transport.c: Sending smb: smb_len=254
[  251.158614] CIFS: fs/smb/client/connect.c: RFC1002 header 0x288
[  251.158624] CIFS: fs/smb/client/smb2misc.c: SMB2 data length 56 offset 152
[  251.158628] CIFS: fs/smb/client/smb2misc.c: SMB2 len 208
[  251.158631] CIFS: fs/smb/client/smb2ops.c: smb2_add_credits: added 1 credits total=125
[  251.158639] CIFS: fs/smb/client/smb2misc.c: SMB2 data length 368 offset 72
[  251.158641] CIFS: fs/smb/client/smb2misc.c: SMB2 len 440
[  251.158644] CIFS: fs/smb/client/smb2ops.c: smb2_add_credits: added 1 credits total=126
[  251.158723] CIFS: fs/smb/client/transport.c: cifs_sync_mid_result: cmd=5 mid=29 state=64
[  251.158734] CIFS: fs/smb/client/transport.c: cifs_sync_mid_result: cmd=14 mid=30 state=64
[  251.158739] CIFS: fs/smb/client/misc.c: Null buffer passed to cifs_small_buf_release
[  251.158743] CIFS: fs/smb/client/misc.c: Null buffer passed to cifs_small_buf_release
[  251.158745] CIFS: fs/smb/client/smb2pdu.c: num entries 4 last_index 6 srch start 00000000e682a81c srch end 00000000fdd41978
[  251.158754] CIFS: fs/smb/client/readdir.c: initiate cifs search rc 0
[  251.158758] CIFS: fs/smb/client/smb2misc.c: SMB2 data length 368 offset 72
[  251.158761] CIFS: fs/smb/client/smb2misc.c: SMB2 len 440
[  251.158764] CIFS: fs/smb/client/readdir.c: found entry - pos_in_buf 0
[  251.158767] CIFS: fs/smb/client/readdir.c: entry 2 found
[  251.158769] CIFS: fs/smb/client/readdir.c: loop through 4 times filling dir for net buf 0000000011df4709
[  251.158773] CIFS: fs/smb/client/smb2misc.c: SMB2 data length 368 offset 72
[  251.158775] CIFS: fs/smb/client/smb2misc.c: SMB2 len 440
[  251.158778] CIFS: fs/smb/client/readdir.c: new entry 0000000091c1d519 old entry 00000000e682a81c
[  251.158782] CIFS: fs/smb/client/readdir.c: new entry 00000000953762b0 old entry 0000000091c1d519
[  251.158787] CIFS: fs/smb/client/readdir.c: cifs_prime_dcache: for volume02
[  251.158790] CIFS: fs/smb/client/readdir.c: new entry 00000000fdd41978 old entry 00000000953762b0
[  251.158794] CIFS: fs/smb/client/readdir.c: cifs_prime_dcache: for volume03
[  251.158797] CIFS: fs/smb/client/readdir.c: last entry in buf at pos 6 volume03
[  251.158801] CIFS: fs/smb/client/readdir.c: VFS: leaving cifs_readdir (xid = 8) rc = 0
[  251.158817] CIFS: fs/smb/client/readdir.c: VFS: in cifs_readdir as Xid: 9 with uid: 0
[  251.158821] CIFS: fs/smb/client/readdir.c: calling findnext2
[  251.158826] CIFS: fs/smb/client/transport.c: wait_for_free_credits: remove 1 credits total=125
[  251.158841] CIFS: fs/smb/client/transport.c: Sending smb: smb_len=102
[  251.159443] CIFS: fs/smb/client/connect.c: RFC1002 header 0x49
[  251.159451] CIFS: fs/smb/client/smb2misc.c: SMB2 data length 0 offset 0
[  251.159453] CIFS: fs/smb/client/smb2misc.c: SMB2 len 73
[  251.159457] CIFS: fs/smb/client/smb2ops.c: smb2_add_credits: added 1 credits total=126
[  251.159496] CIFS: fs/smb/client/transport.c: cifs_sync_mid_result: cmd=14 mid=31 state=64
[  251.159503] CIFS: fs/smb/client/smb2maperror.c: Mapping SMB2 status code 0x80000006 to POSIX err -61
[  251.159507] CIFS: fs/smb/client/misc.c: Null buffer passed to cifs_small_buf_release
[  251.159511] CIFS: fs/smb/client/readdir.c: index not in buffer - could not findnext into it
[  251.159514] CIFS: fs/smb/client/readdir.c: Could not find entry
[  251.159516] CIFS: fs/smb/client/readdir.c: VFS: leaving cifs_readdir (xid = 9) rc = 0
[  251.159564] CIFS: fs/smb/client/file.c: Closedir inode = 0x000000005f772979
[  251.159568] CIFS: fs/smb/client/file.c: VFS: in cifs_closedir as Xid: 10 with uid: 0
[  251.159571] CIFS: fs/smb/client/file.c: Freeing private data in close dir
[  251.159574] CIFS: fs/smb/client/smb2pdu.c: Close
[  251.159578] CIFS: fs/smb/client/transport.c: wait_for_free_credits: remove 1 credits total=125
[  251.159588] CIFS: fs/smb/client/transport.c: Sending smb: smb_len=92
[  251.160109] CIFS: fs/smb/client/connect.c: RFC1002 header 0x7c
[  251.160117] CIFS: fs/smb/client/smb2misc.c: SMB2 len 124
[  251.160122] CIFS: fs/smb/client/smb2ops.c: smb2_add_credits: added 1 credits total=126
[  251.160156] CIFS: fs/smb/client/transport.c: cifs_sync_mid_result: cmd=6 mid=32 state=64
[  251.160162] CIFS: fs/smb/client/misc.c: Null buffer passed to cifs_small_buf_release
[  251.160165] CIFS: fs/smb/client/file.c: Closing uncompleted readdir with rc 0
[  251.160168] CIFS: fs/smb/client/file.c: closedir free smb buf in srch struct
[  251.160170] CIFS: fs/smb/client/file.c: VFS: leaving cifs_closedir (xid = 10) rc = 0
[  254.033334] CIFS: fs/smb/client/inode.c: VFS: in cifs_revalidate_dentry_attr as Xid: 11 with uid: 0
[  254.033350] CIFS: fs/smb/client/inode.c: Update attributes:  inode 0x000000005f772979 count 2 dentry: 0x00000000ce50e32f d_time 0 jiffies 4294955719
[  254.033380] CIFS: fs/smb/client/transport.c: wait_for_free_credits: remove 3 credits total=123
[  254.033405] CIFS: fs/smb/client/transport.c: Sending smb: smb_len=348
[  254.034748] CIFS: fs/smb/client/connect.c: RFC1002 header 0x20c
[  254.034768] CIFS: fs/smb/client/smb2misc.c: SMB2 data length 56 offset 152
[  254.034774] CIFS: fs/smb/client/smb2misc.c: SMB2 len 208
[  254.034781] CIFS: fs/smb/client/smb2ops.c: smb2_add_credits: added 1 credits total=124
[  254.034792] CIFS: fs/smb/client/smb2misc.c: SMB2 data length 120 offset 72
[  254.034798] CIFS: fs/smb/client/smb2ops.c: smb2_add_credits: added 1 credits total=125
[  254.034812] CIFS: fs/smb/client/smb2ops.c: smb2_add_credits: added 1 credits total=126
[  254.034863] CIFS: fs/smb/client/transport.c: cifs_sync_mid_result: cmd=5 mid=33 state=64
[  254.034875] CIFS: fs/smb/client/transport.c: cifs_sync_mid_result: cmd=16 mid=34 state=64
[  254.034878] CIFS: fs/smb/client/transport.c: cifs_sync_mid_result: cmd=6 mid=35 state=64
[  254.034882] CIFS: fs/smb/client/misc.c: Null buffer passed to cifs_small_buf_release
[  254.034886] CIFS: fs/smb/client/misc.c: Null buffer passed to cifs_small_buf_release
[  254.034888] CIFS: fs/smb/client/misc.c: Null buffer passed to cifs_small_buf_release
[  254.034898] CIFS: fs/smb/client/inode.c: cifs_revalidate_cache: revalidating inode 16150111042041544768
[  254.034902] CIFS: fs/smb/client/inode.c: cifs_revalidate_cache: inode 16150111042041544768 is unchanged
[  254.034906] CIFS: fs/smb/client/inode.c: VFS: leaving cifs_revalidate_dentry_attr (xid = 11) rc = 0
[  254.034958] CIFS: fs/smb/client/readdir.c: VFS: in cifs_readdir as Xid: 12 with uid: 0
[  254.034964] CIFS: fs/smb/client/readdir.c: Full path:  start at: 0
[  254.034973] CIFS: fs/smb/client/transport.c: wait_for_free_credits: remove 2 credits total=124
[  254.034986] CIFS: fs/smb/client/transport.c: Sending smb: smb_len=254
[  254.035911] CIFS: fs/smb/client/connect.c: RFC1002 header 0x288
[  254.035923] CIFS: fs/smb/client/smb2misc.c: SMB2 data length 56 offset 152
[  254.035966] CIFS: fs/smb/client/smb2pdu.c: num entries 4 last_index 6 srch start 00000000940789bf srch end 000000001f133971
[  254.035974] CIFS: fs/smb/client/readdir.c: initiate cifs search rc 0
[  254.035978] CIFS: fs/smb/client/readdir.c: found entry - pos_in_buf 0
[  254.035981] CIFS: fs/smb/client/readdir.c: entry 2 found
[  254.035983] CIFS: fs/smb/client/readdir.c: loop through 4 times filling dir for net buf 000000006250886a
[  254.035987] CIFS: fs/smb/client/readdir.c: new entry 000000004562868b old entry 00000000940789bf
[  254.035990] CIFS: fs/smb/client/readdir.c: new entry 00000000076a5fe8 old entry 000000004562868b
[  254.035995] CIFS: fs/smb/client/readdir.c: cifs_prime_dcache: for volume02
[  254.035999] CIFS: fs/smb/client/readdir.c: new entry 000000001f133971 old entry 00000000076a5fe8
[  254.036002] CIFS: fs/smb/client/readdir.c: cifs_prime_dcache: for volume03
[  254.036004] CIFS: fs/smb/client/readdir.c: last entry in buf at pos 6 volume03
[  254.036008] CIFS: fs/smb/client/readdir.c: VFS: leaving cifs_readdir (xid = 12) rc = 0
[  254.036019] CIFS: fs/smb/client/readdir.c: VFS: in cifs_readdir as Xid: 13 with uid: 0
[  254.036022] CIFS: fs/smb/client/readdir.c: calling findnext2
[  254.036026] CIFS: fs/smb/client/transport.c: wait_for_free_credits: remove 1 credits total=125
[  254.036033] CIFS: fs/smb/client/transport.c: Sending smb: smb_len=102
[  254.036403] CIFS: fs/smb/client/connect.c: RFC1002 header 0x49
[  254.036493] CIFS: fs/smb/client/smb2maperror.c: Mapping SMB2 status code 0x80000006 to POSIX err -61
[  254.036506] CIFS: fs/smb/client/readdir.c: index not in buffer - could not findnext into it
[  254.036509] CIFS: fs/smb/client/readdir.c: Could not find entry
[  254.036512] CIFS: fs/smb/client/readdir.c: VFS: leaving cifs_readdir (xid = 13) rc = 0
[  254.036577] CIFS: fs/smb/client/file.c: Closedir inode = 0x000000005f772979
[  254.036587] CIFS: fs/smb/client/file.c: VFS: in cifs_closedir as Xid: 14 with uid: 0
[  254.036591] CIFS: fs/smb/client/file.c: Freeing private data in close dir
[  254.036594] CIFS: fs/smb/client/smb2pdu.c: Close
[  254.036599] CIFS: fs/smb/client/transport.c: wait_for_free_credits: remove 1 credits total=125
[  254.036614] CIFS: fs/smb/client/transport.c: Sending smb: smb_len=92
[  254.037902] CIFS: fs/smb/client/connect.c: RFC1002 header 0x7c
[  254.037976] CIFS: fs/smb/client/file.c: Closing uncompleted readdir with rc 0
[  254.037990] CIFS: fs/smb/client/file.c: closedir free smb buf in srch struct
[  254.038000] CIFS: fs/smb/client/file.c: VFS: leaving cifs_closedir (xid = 14) rc = 0
[  254.042279] CIFS: fs/smb/client/dir.c: VFS: in cifs_lookup as Xid: 15 with uid: 0
[  254.042287] CIFS: fs/smb/client/dir.c: parent inode = 0x000000005f772979 name is: volume02 and dentry = 0x00000000cd7d7968
[  254.042296] CIFS: fs/smb/client/dir.c: NULL inode in lookup
[  254.042298] CIFS: fs/smb/client/dir.c: Full path: \volume02 inode = 0x0000000000000000
[  254.042319] CIFS: fs/smb/client/transport.c: wait_for_free_credits: remove 3 credits total=123
[  254.042338] CIFS: fs/smb/client/transport.c: Sending smb: smb_len=364
[  254.043581] CIFS: fs/smb/client/connect.c: RFC1002 header 0x21c
[  254.043761] CIFS: fs/smb/client/smb2inode.c: smb2_query_reparse_point: path: \volume02
[  254.043778] CIFS: fs/smb/client/transport.c: wait_for_free_credits: remove 3 credits total=123
[  254.043796] CIFS: fs/smb/client/transport.c: Sending smb: smb_len=388
[  254.044672] CIFS: fs/smb/client/connect.c: RFC1002 header 0x19c
[  254.044804] CIFS: fs/smb/client/smb2maperror.c: Mapping SMB2 status code 0xc0000010 to POSIX err -95
[  254.044822] CIFS: fs/smb/client/inode.c: cifs_create_junction_fattr: creating fake fattr
[  254.044828] CIFS: fs/smb/client/inode.c: looking for uniqueid=16150392817665966144
[  254.044838] CIFS: fs/smb/client/inode.c: cifs_revalidate_cache: revalidating inode 16150392817665966144
[  254.044841] CIFS: fs/smb/client/inode.c: cifs_revalidate_cache: inode 16150392817665966144 is new
[  254.044846] CIFS: fs/smb/client/dir.c: VFS: leaving cifs_lookup (xid = 15) rc = 0
[  254.044886] CIFS: fs/smb/client/dir.c: VFS: in cifs_lookup as Xid: 16 with uid: 0
[  254.044890] CIFS: fs/smb/client/dir.c: parent inode = 0x000000005f772979 name is: volume03 and dentry = 0x000000007170900a
[  254.044897] CIFS: fs/smb/client/dir.c: NULL inode in lookup
[  254.044899] CIFS: fs/smb/client/dir.c: Full path: \volume03 inode = 0x0000000000000000
[  254.046354] CIFS: fs/smb/client/smb2inode.c: smb2_query_reparse_point: path: \volume03
[  254.047389] CIFS: fs/smb/client/smb2maperror.c: Mapping SMB2 status code 0xc0000010 to POSIX err -95
[  254.047406] CIFS: fs/smb/client/inode.c: cifs_create_junction_fattr: creating fake fattr
[  254.047412] CIFS: fs/smb/client/inode.c: looking for uniqueid=16149548603189231680
[  254.047425] CIFS: fs/smb/client/inode.c: cifs_revalidate_cache: revalidating inode 16149548603189231680
[  254.047428] CIFS: fs/smb/client/inode.c: cifs_revalidate_cache: inode 16149548603189231680 is new
[  254.047433] CIFS: fs/smb/client/dir.c: VFS: leaving cifs_lookup (xid = 16) rc = 0
[  254.047529] CIFS: fs/smb/client/namespace.c: cifs_d_automount: volume02
[  254.047546] CIFS: fs/smb/client/namespace.c: cifs_do_automount: ctx: source=//10.156.103.200/volume01/volume02 UNC=\\10.156.103.200\volume01 prepath=volume02 dfs_automount=0
[  254.047553] CIFS: fs/smb/client/cifsfs.c: cifs_smb3_do_mount: devname=//10.156.103.200/volume01/volume02 flags=0x0
[  254.047558] CIFS: fs/smb/client/connect.c: file mode: 0755  dir mode: 0755
[  254.051463] CIFS: fs/smb/client/connect.c: VFS: in cifs_mount_get_session as Xid: 17 with uid: 0
[  254.051471] CIFS: fs/smb/client/connect.c: UNC: \\10.156.103.200\volume01
[  254.051476] CIFS: fs/smb/client/connect.c: Existing tcp session with server found
[  254.051478] CIFS: fs/smb/client/connect.c: VFS: in cifs_get_smb_ses as Xid: 18 with uid: 0
[  254.051481] CIFS: fs/smb/client/connect.c: Existing smb sess found (status=1)
[  254.051484] CIFS: fs/smb/client/connect.c: VFS: leaving cifs_get_smb_ses (xid = 18) rc = 0
[  254.051488] CIFS: fs/smb/client/dfs_cache.c: cache_refresh_path: search path: \10.156.103.200\volume01
[  254.051494] CIFS: fs/smb/client/dfs_cache.c: get_dfs_referral: ipc=\\10.156.103.200\IPC$ referral=\10.156.103.200\volume01
[  254.051497] CIFS: fs/smb/client/smb2ops.c: smb2_get_dfs_refer: path: \10.156.103.200\volume01
[  254.051502] CIFS: fs/smb/client/smb2pdu.c: SMB2 IOCTL
[  254.052259] CIFS: fs/smb/client/smb2maperror.c: Mapping SMB2 status code 0xc0000225 to POSIX err -2
[  254.052272] CIFS: fs/smb/client/dfs.c: dfs_mount_share: no dfs referral for \10.156.103.200\volume01: -2
[  254.052276] CIFS: fs/smb/client/dfs.c: dfs_mount_share: assuming non-dfs mount...
[  254.052279] CIFS: fs/smb/client/connect.c: Found match on UNC path
[  254.052281] CIFS: fs/smb/client/connect.c: __cifs_put_smb_ses: id=0x96530000073bdb4c ses_count=2 ses_status=1 ipc=\\10.156.103.200\IPC$
[  254.052287] CIFS: fs/smb/client/smb2pdu.c: create/open
[  254.053004] CIFS: fs/smb/client/smb2pdu.c: Query FSInfo level 5
[  254.053583] CIFS: fs/smb/client/smb2pdu.c: Query FSInfo level 4
[  254.054153] CIFS: fs/smb/client/smb2pdu.c: Query FSInfo level 1
[  254.054758] CIFS: fs/smb/client/smb2pdu.c: Query FSInfo level 11
[  254.055306] CIFS: fs/smb/client/smb2pdu.c: Close
[  254.055725] CIFS: fs/smb/client/connect.c: cifs_is_path_remote: full_path: \volume02
[  254.055734] CIFS: fs/smb/client/smb2pdu.c: create/open
[  254.056658] CIFS: fs/smb/client/smb2pdu.c: Close
[  254.057246] CIFS: fs/smb/client/smb2pdu.c: create/open
[  254.057769] CIFS: fs/smb/client/smb2pdu.c: Close
[  254.058021] CIFS: fs/smb/client/smb2pdu.c: create/open
[  254.058676] CIFS: fs/smb/client/smb2pdu.c: Close
[  254.058965] CIFS: fs/smb/client/sess.c: ses already at max_channels (1), nothing to open
[  254.058968] CIFS: fs/smb/client/connect.c: VFS: leaving cifs_mount (xid = 17) rc = 0
[  254.059024] CIFS: fs/smb/client/inode.c: VFS: in cifs_root_iget as Xid: 19 with uid: 0
[  254.059904] CIFS: fs/smb/client/smb2inode.c: smb2_query_reparse_point: path: \volume02
[  254.060544] CIFS: fs/smb/client/smb2maperror.c: Mapping SMB2 status code 0xc0000010 to POSIX err -95
[  254.060547] CIFS: fs/smb/client/inode.c: cifs_create_junction_fattr: creating fake fattr
[  254.060549] CIFS: VFS: Autodisabling the use of server inode numbers on \\10.156.103.200\volume01
[  254.060551] CIFS: VFS: The server doesn't seem to support them properly or the files might be on different servers (DFS)
[  254.060552] CIFS: VFS: Hardlinks will not be recognized on this mount. Consider mounting with the "noserverino" option to silence this message.
[  254.060553] CIFS: fs/smb/client/inode.c: looking for uniqueid=16150392817665966144
[  254.060566] CIFS: fs/smb/client/inode.c: cifs_revalidate_cache: revalidating inode 16150392817665966144
[  254.060567] CIFS: fs/smb/client/inode.c: cifs_revalidate_cache: inode 16150392817665966144 is new
[  254.060569] CIFS: fs/smb/client/inode.c: VFS: leaving cifs_root_iget (xid = 19) rc = 0
[  254.060572] CIFS: fs/smb/client/cifsfs.c: dentry root is: 00000000b8d44a8c
[  254.060579] CIFS: fs/smb/client/namespace.c: leaving cifs_d_automount [ok]
[  254.060678] CIFS: fs/smb/client/readdir.c: VFS: in cifs_readdir as Xid: 20 with uid: 0
[  254.060681] CIFS: fs/smb/client/dir.c: using cifs_sb prepath <volume02>
[  254.060683] CIFS: fs/smb/client/readdir.c: Full path: \volume02 start at: 0
[  254.084954] CIFS: fs/smb/client/smb2pdu.c: num entries 2 last_index 4 srch start 00000000ab540d41 srch end 0000000032af63b8
[  254.085016] CIFS: fs/smb/client/readdir.c: initiate cifs search rc 0
[  254.085022] CIFS: fs/smb/client/readdir.c: found entry - pos_in_buf 0
[  254.085025] CIFS: fs/smb/client/readdir.c: entry 2 found
[  254.085027] CIFS: fs/smb/client/readdir.c: loop through 2 times filling dir for net buf 00000000452de380
[  254.085031] CIFS: fs/smb/client/readdir.c: new entry 0000000032af63b8 old entry 00000000ab540d41
[  254.085035] CIFS: fs/smb/client/readdir.c: last entry in buf at pos 4 
[  254.085038] CIFS: fs/smb/client/readdir.c: VFS: leaving cifs_readdir (xid = 20) rc = 0
[  254.085057] CIFS: fs/smb/client/readdir.c: VFS: in cifs_readdir as Xid: 21 with uid: 0
[  254.085061] CIFS: fs/smb/client/dir.c: using cifs_sb prepath <volume02>
[  254.085063] CIFS: fs/smb/client/readdir.c: calling findnext2
[  254.085767] CIFS: fs/smb/client/smb2maperror.c: Mapping SMB2 status code 0x80000006 to POSIX err -61
[  254.085790] CIFS: fs/smb/client/readdir.c: index not in buffer - could not findnext into it
[  254.085792] CIFS: fs/smb/client/readdir.c: Could not find entry
[  254.085794] CIFS: fs/smb/client/readdir.c: VFS: leaving cifs_readdir (xid = 21) rc = 0
[  254.085849] CIFS: fs/smb/client/file.c: Closedir inode = 0x000000002595c003
[  254.085853] CIFS: fs/smb/client/file.c: VFS: in cifs_closedir as Xid: 22 with uid: 0
[  254.085856] CIFS: fs/smb/client/file.c: Freeing private data in close dir
[  254.085859] CIFS: fs/smb/client/smb2pdu.c: Close
[  254.086645] CIFS: fs/smb/client/file.c: Closing uncompleted readdir with rc 0
[  254.086651] CIFS: fs/smb/client/file.c: closedir free smb buf in srch struct
[  254.086654] CIFS: fs/smb/client/file.c: VFS: leaving cifs_closedir (xid = 22) rc = 0
[  254.086682] CIFS: fs/smb/client/namespace.c: cifs_d_automount: volume03
[  254.086695] CIFS: fs/smb/client/namespace.c: cifs_do_automount: ctx: source=//10.156.103.200/volume01/volume03 UNC=\\10.156.103.200\volume01 prepath=volume03 dfs_automount=0
[  254.086701] CIFS: fs/smb/client/cifsfs.c: cifs_smb3_do_mount: devname=//10.156.103.200/volume01/volume03 flags=0x0
[  254.086707] CIFS: fs/smb/client/connect.c: file mode: 0755  dir mode: 0755
[  254.090378] CIFS: fs/smb/client/connect.c: VFS: in cifs_mount_get_session as Xid: 23 with uid: 0
[  254.090385] CIFS: fs/smb/client/connect.c: UNC: \\10.156.103.200\volume01
[  254.090388] CIFS: fs/smb/client/connect.c: Existing tcp session with server found
[  254.090391] CIFS: fs/smb/client/connect.c: VFS: in cifs_get_smb_ses as Xid: 24 with uid: 0
[  254.090394] CIFS: fs/smb/client/connect.c: Existing smb sess found (status=1)
[  254.090397] CIFS: fs/smb/client/connect.c: VFS: leaving cifs_get_smb_ses (xid = 24) rc = 0
[  254.090400] CIFS: fs/smb/client/dfs_cache.c: cache_refresh_path: search path: \10.156.103.200\volume01
[  254.090406] CIFS: fs/smb/client/dfs_cache.c: get_dfs_referral: ipc=\\10.156.103.200\IPC$ referral=\10.156.103.200\volume01
[  254.090409] CIFS: fs/smb/client/smb2ops.c: smb2_get_dfs_refer: path: \10.156.103.200\volume01
[  254.090415] CIFS: fs/smb/client/smb2pdu.c: SMB2 IOCTL
[  254.091083] CIFS: fs/smb/client/smb2maperror.c: Mapping SMB2 status code 0xc0000225 to POSIX err -2
[  254.091095] CIFS: fs/smb/client/dfs.c: dfs_mount_share: no dfs referral for \10.156.103.200\volume01: -2
[  254.091098] CIFS: fs/smb/client/dfs.c: dfs_mount_share: assuming non-dfs mount...
[  254.091101] CIFS: fs/smb/client/connect.c: Found match on UNC path
[  254.091103] CIFS: fs/smb/client/connect.c: __cifs_put_smb_ses: id=0x96530000073bdb4c ses_count=2 ses_status=1 ipc=\\10.156.103.200\IPC$
[  254.091109] CIFS: fs/smb/client/smb2pdu.c: create/open
[  254.091893] CIFS: fs/smb/client/smb2pdu.c: Query FSInfo level 5
[  254.092727] CIFS: fs/smb/client/smb2pdu.c: Query FSInfo level 4
[  254.093748] CIFS: fs/smb/client/smb2pdu.c: Query FSInfo level 1
[  254.094179] CIFS: fs/smb/client/smb2pdu.c: Query FSInfo level 11
[  254.094646] CIFS: fs/smb/client/smb2pdu.c: Close
[  254.095151] CIFS: fs/smb/client/connect.c: cifs_is_path_remote: full_path: \volume03
[  254.095159] CIFS: fs/smb/client/smb2pdu.c: create/open
[  254.096240] CIFS: fs/smb/client/smb2pdu.c: Close
[  254.096811] CIFS: fs/smb/client/smb2pdu.c: create/open
[  254.097382] CIFS: fs/smb/client/smb2pdu.c: Close
[  254.097614] CIFS: fs/smb/client/smb2pdu.c: create/open
[  254.098552] CIFS: fs/smb/client/sess.c: ses already at max_channels (1), nothing to open
[  254.098554] CIFS: fs/smb/client/connect.c: VFS: leaving cifs_mount (xid = 23) rc = 0
[  254.098614] CIFS: fs/smb/client/inode.c: VFS: in cifs_root_iget as Xid: 25 with uid: 0
[  254.099486] CIFS: fs/smb/client/smb2inode.c: smb2_query_reparse_point: path: \volume03
[  254.100175] CIFS: fs/smb/client/smb2maperror.c: Mapping SMB2 status code 0xc0000010 to POSIX err -95
[  254.100180] CIFS: fs/smb/client/inode.c: cifs_create_junction_fattr: creating fake fattr
[  254.100182] CIFS: VFS: Autodisabling the use of server inode numbers on \\10.156.103.200\volume01
[  254.100185] CIFS: VFS: The server doesn't seem to support them properly or the files might be on different servers (DFS)
[  254.100186] CIFS: VFS: Hardlinks will not be recognized on this mount. Consider mounting with the "noserverino" option to silence this message.
[  254.100186] CIFS: fs/smb/client/inode.c: looking for uniqueid=16149548603189231680
[  254.100195] CIFS: fs/smb/client/inode.c: cifs_revalidate_cache: revalidating inode 16149548603189231680
[  254.100196] CIFS: fs/smb/client/inode.c: cifs_revalidate_cache: inode 16149548603189231680 is new
[  254.100198] CIFS: fs/smb/client/inode.c: VFS: leaving cifs_root_iget (xid = 25) rc = 0
[  254.100202] CIFS: fs/smb/client/cifsfs.c: dentry root is: 000000000674ed79
[  254.100213] CIFS: fs/smb/client/namespace.c: leaving cifs_d_automount [ok]
[  254.100371] CIFS: fs/smb/client/readdir.c: VFS: in cifs_readdir as Xid: 26 with uid: 0
[  254.100374] CIFS: fs/smb/client/dir.c: using cifs_sb prepath <volume03>
[  254.100377] CIFS: fs/smb/client/readdir.c: Full path: \volume03 start at: 0
[  254.108821] CIFS: fs/smb/client/smb2pdu.c: num entries 2 last_index 4 srch start 00000000a9655634 srch end 000000004b4cddd1
[  254.108832] CIFS: fs/smb/client/readdir.c: initiate cifs search rc 0
[  254.108835] CIFS: fs/smb/client/readdir.c: found entry - pos_in_buf 0
[  254.108837] CIFS: fs/smb/client/readdir.c: entry 2 found
[  254.108839] CIFS: fs/smb/client/readdir.c: loop through 2 times filling dir for net buf 00000000e85196ec
[  254.108842] CIFS: fs/smb/client/readdir.c: new entry 000000004b4cddd1 old entry 00000000a9655634
[  254.108844] CIFS: fs/smb/client/readdir.c: last entry in buf at pos 4 
[  254.108846] CIFS: fs/smb/client/readdir.c: VFS: leaving cifs_readdir (xid = 26) rc = 0
[  254.108857] CIFS: fs/smb/client/readdir.c: VFS: in cifs_readdir as Xid: 27 with uid: 0
[  254.108859] CIFS: fs/smb/client/dir.c: using cifs_sb prepath <volume03>
[  254.108861] CIFS: fs/smb/client/readdir.c: calling findnext2
[  254.120507] CIFS: fs/smb/client/smb2maperror.c: Mapping SMB2 status code 0x80000006 to POSIX err -61
[  254.120666] CIFS: fs/smb/client/readdir.c: index not in buffer - could not findnext into it
[  254.120688] CIFS: fs/smb/client/readdir.c: Could not find entry
[  254.120690] CIFS: fs/smb/client/readdir.c: VFS: leaving cifs_readdir (xid = 27) rc = 0
[  254.120710] CIFS: fs/smb/client/file.c: Closedir inode = 0x000000005d4227a5
[  254.120712] CIFS: fs/smb/client/file.c: VFS: in cifs_closedir as Xid: 28 with uid: 0
[  254.120714] CIFS: fs/smb/client/file.c: Freeing private data in close dir
[  254.121562] CIFS: fs/smb/client/file.c: Closing uncompleted readdir with rc 0
[  254.121564] CIFS: fs/smb/client/file.c: closedir free smb buf in srch struct
[  254.121565] CIFS: fs/smb/client/file.c: VFS: leaving cifs_closedir (xid = 28) rc = 0
Display Internal CIFS Data Structures for Debugging
---------------------------------------------------
CIFS Version 2.49
Features: DFS,STATS2,DEBUG2,ALLOW_INSECURE_LEGACY,CIFS_POSIX,XATTR,ACL
CIFSMaxBufSize: 16384
Active VFS Requests: 0

Servers: 
1) ConnectionId: 0x1 Hostname: 10.156.103.200 
ClientGUID: C31F4211-517A-184E-B81E-8CB17B90C7C9
Number of credits: 126,1,1 Dialect 0x311
Server capabilities: 0x300057
TCP status: 1 Instance: 1
Local Users To Server: 1 SecMode: 0x1 Req On Wire: 0 Net namespace: 4026531840 
In Send: 0 In MaxReq Wait: 0
Compression: disabled on mount

	Sessions: 
	1) Address: 10.156.103.200 Uses: 1 Capability: 0x300057	Session Status: 1 
	Security type: RawNTLMSSP  SessionId: 0x96530000073bdb4c
	User: 0 Cred User: 0

	Shares: 
	0) IPC: \\10.156.103.200\IPC$ Mounts: 1 DevInfo: 0x0 Attributes: 0x0
	PathComponentMax: 0 Status: 1 type: 0 Serial Number: 0x0
	Share Capabilities: DFS,	Share Flags: 0x3
	tid: 0x1	Maximal Access: 0x1f01ff

	1) \\10.156.103.200\volume01 Mounts: 3 DevInfo: 0x20 Attributes: 0x400cf
	PathComponentMax: 255 Status: 1 type: DISK Serial Number: 0x805e865e
	Share Capabilities: None Aligned, Partition Aligned, TRIM-support,	Share Flags: 0x800
	tid: 0x2	Optimal sector size: 0x1000	Maximal Access: 0x1301bf


	MIDs: 
--

DFS cache
---------

[-- Attachment #1.1.3: trace.pcap --]
[-- Type: application/vnd.tcpdump.pcap, Size: 42659 bytes --]

[-- Attachment #1.2: This is a digitally signed message part --]
[-- Type: application/pgp-signature, Size: 488 bytes --]

[-- Attachment #2: smime.p7s --]
[-- Type: application/pkcs7-signature, Size: 5874 bytes --]

  reply	other threads:[~2024-07-31  5:02 UTC|newest]

Thread overview: 28+ messages / expand[flat|nested]  mbox.gz  Atom feed  top
2024-06-27 13:28 Potential smb/dfs regression introduced in kernel 6.6 Steinbeißer, Sebastian
2024-06-27 13:37 ` Greg KH
     [not found] ` <f3a02647b6fcb27a70da4c334930ad3c@manguebit.com>
2024-06-28  8:19   ` Steinbeißer, Sebastian
2024-07-11  6:20     ` Steinbeißer, Sebastian
2024-07-11  7:27       ` gregkh
2024-07-11 15:20       ` Christian Heusel
2024-07-14 19:12       ` Paulo Alcantara
2024-07-15  5:41         ` Steinbeißer, Sebastian
2024-07-15 12:21           ` Paulo Alcantara
2024-07-15 13:26             ` Steinbeißer, Sebastian
2024-07-15 14:24               ` Paulo Alcantara
2024-07-16  6:14         ` Steinbeißer, Sebastian
2024-07-17 18:24           ` Paulo Alcantara
2024-07-17 20:03             ` Tom Talpey
2024-07-18  5:43               ` Steinbeißer, Sebastian
2024-07-25  1:28                 ` Paulo Alcantara
2024-07-26  8:19                   ` Steinbeißer, Sebastian
2024-07-26 18:00                     ` Paulo Alcantara
2024-07-29  5:57                   ` Steinbeißer, Sebastian
2024-07-29 12:34                     ` Paulo Alcantara
2024-07-30  5:25                       ` Steinbeißer, Sebastian
2024-07-31  2:35                         ` Paulo Alcantara
2024-07-31  5:02                           ` Steinbeißer, Sebastian [this message]
2024-07-31  5:07                             ` Steinbeißer, Sebastian
2024-07-31 14:25                               ` Paulo Alcantara
2024-08-01 13:07                                 ` Steinbeißer, Sebastian
2024-08-01 16:25                                   ` Paulo Alcantara
2024-08-01 19:02                                     ` Tom Talpey

Reply instructions:

You may reply publicly to this message via plain-text email
using any one of the following methods:

* Save the following mbox file, import it into your mail client,
  and reply-to-all from there: mbox

  Avoid top-posting and favor interleaved quoting:
  https://en.wikipedia.org/wiki/Posting_style#Interleaved_style

* Reply using the --to, --cc, and --in-reply-to
  switches of git-send-email(1):

  git send-email \
    --in-reply-to=1359665240338585cfbc4eac58339ab1d0423788.camel@lrz.de \
    --to=sebastian.steinbeisser@lrz.de \
    --cc=gregkh@linuxfoundation.org \
    --cc=pc@manguebit.com \
    --cc=regressions@lists.linux.dev \
    --cc=smfrench@gmail.com \
    --cc=tom@talpey.com \
    /path/to/YOUR_REPLY

  https://kernel.org/pub/software/scm/git/docs/git-send-email.html

* If your mail client supports setting the In-Reply-To header
  via mailto: links, try the mailto: link
Be sure your reply has a Subject: header at the top and a blank line before the message body.
This is an external index of several public inboxes,
see mirroring instructions on how to clone and mirror
all data and code used by this external index.