It is a harmless warning. Some servers do not support "DFS" (the Global Name Space feature of CIFS/SMB2/SMB3 that allows servers to redirect requests to a particular subdirectory to one or more other servers (to allow extending the name space across more than one server). For those servers they return an error that we were remapping to a generic EIO error which is confusing. I removed the logging of this warning a month ago, so you shouldn't see this on 4.17. If it is annoying enough in your environment, you could request that it be backported by your distro or equivalent - it is very low risk. See commit: https://git.kernel.org/pub/scm/linux/kernel/git/torvalds/linux.git/commit/fs/cifs?id=2564f2ff83975e05dbd2f13301f7147b63e67c91 On Sun, Apr 29, 2018 at 9:28 AM, xnor <xnoreq@xxxxxxxxx> wrote: > Linux 4.16.5 (same problem happened with 4.15 as well though): > > # mount -t cifs -o credentials=~/.smbcredentials,vers=3.0,uid=...,nounix > '//192.168.1.1/shared' /mnt/shared > > 192.168.1.1 is a Windows 10 Pro Version 1709 > > > What does this error mean? What is the reason for it? > > > dmesg follows: > > [ 6836.506102] fs/cifs/smb2pdu.c: In echo request > [ 6836.506109] fs/cifs/transport.c: Sending smb: smb_len=68 > [ 6836.509726] fs/cifs/connect.c: RFC1002 header 0x44 > [ 6836.509732] fs/cifs/smb2misc.c: smb2_check_message length: 0x48, > smb_buf_length: 0x44 > [ 6836.509733] fs/cifs/smb2misc.c: SMB2 len 72 > [ 6841.812992] fs/cifs/inode.c: CIFS VFS: in cifs_revalidate_dentry_attr as > Xid: 35 with uid: 0 > [ 6841.812996] fs/cifs/inode.c: Update attributes: inode 0x00000000e154d7fa > count 1 dentry: 0x000000007f48a396 d_time 0 jiffies 4296929848 > [ 6841.812997] fs/cifs/inode.c: Getting info on > [ 6841.813001] fs/cifs/smb2pdu.c: create/open > [ 6841.813007] fs/cifs/transport.c: Sending smb: smb_len=128 > [ 6841.813601] fs/cifs/connect.c: RFC1002 header 0x98 > [ 6841.813607] fs/cifs/smb2misc.c: smb2_check_message length: 0x9c, > smb_buf_length: 0x98 > [ 6841.813608] smb2_calc_size: 8 callbacks suppressed > [ 6841.813609] fs/cifs/smb2misc.c: SMB2 data length 0 offset 0 > [ 6841.813610] fs/cifs/smb2misc.c: SMB2 len 157 > [ 6841.813611] fs/cifs/smb2misc.c: Calculated size 157 length 156 mismatch > mid 31 > [ 6841.813663] cifs_sync_mid_result: 18 callbacks suppressed > [ 6841.813665] fs/cifs/transport.c: cifs_sync_mid_result: cmd=5 mid=31 > state=4 > [ 6841.813666] cifs_small_buf_release: 15 callbacks suppressed > [ 6841.813667] fs/cifs/misc.c: Null buffer passed to cifs_small_buf_release > [ 6841.813670] fs/cifs/smb2pdu.c: Query Info > [ 6841.813672] fs/cifs/transport.c: Sending smb: smb_len=104 > [ 6841.815900] fs/cifs/connect.c: RFC1002 header 0xb0 > [ 6841.815903] fs/cifs/smb2misc.c: smb2_check_message length: 0xb4, > smb_buf_length: 0xb0 > [ 6841.815905] fs/cifs/smb2misc.c: SMB2 data length 104 offset 72 > [ 6841.815905] fs/cifs/smb2misc.c: SMB2 len 180 > [ 6841.815917] fs/cifs/transport.c: cifs_sync_mid_result: cmd=16 mid=32 > state=4 > [ 6841.815918] fs/cifs/misc.c: Null buffer passed to cifs_small_buf_release > [ 6841.815920] fs/cifs/smb2pdu.c: Close > [ 6841.815922] fs/cifs/transport.c: Sending smb: smb_len=88 > [ 6841.816353] fs/cifs/connect.c: RFC1002 header 0x7c > [ 6841.816355] fs/cifs/smb2misc.c: smb2_check_message length: 0x80, > smb_buf_length: 0x7c > [ 6841.816356] fs/cifs/smb2misc.c: SMB2 len 128 > [ 6841.816363] fs/cifs/transport.c: cifs_sync_mid_result: cmd=6 mid=33 > state=4 > [ 6841.816364] fs/cifs/misc.c: Null buffer passed to cifs_small_buf_release > [ 6841.816368] fs/cifs/inode.c: cifs_revalidate_cache: revalidating inode > 10133099161590399 > [ 6841.816370] fs/cifs/inode.c: cifs_revalidate_cache: invalidating inode > 10133099161590399 mapping > [ 6841.816371] fs/cifs/inode.c: CIFS VFS: leaving > cifs_revalidate_dentry_attr (xid = 35) rc = 0 > [ 6841.816386] fs/cifs/cifsfs.c: CIFS VFS: in cifs_statfs as Xid: 36 with > uid: 0 > [ 6841.816387] fs/cifs/smb2pdu.c: create/open > [ 6841.816389] fs/cifs/transport.c: Sending smb: smb_len=128 > [ 6841.816799] fs/cifs/connect.c: RFC1002 header 0x98 > [ 6841.816812] fs/cifs/smb2misc.c: smb2_check_message length: 0x9c, > smb_buf_length: 0x98 > [ 6841.816813] fs/cifs/smb2misc.c: SMB2 data length 0 offset 0 > [ 6841.816814] fs/cifs/smb2misc.c: SMB2 len 157 > [ 6841.816815] fs/cifs/smb2misc.c: Calculated size 157 length 156 mismatch > mid 34 > [ 6841.816821] fs/cifs/transport.c: cifs_sync_mid_result: cmd=5 mid=34 > state=4 > [ 6841.816822] fs/cifs/misc.c: Null buffer passed to cifs_small_buf_release > [ 6841.816824] fs/cifs/smb2pdu.c: Query FSInfo level 7 > [ 6841.816826] fs/cifs/transport.c: Sending smb: smb_len=105 > [ 6841.818902] fs/cifs/connect.c: RFC1002 header 0x68 > [ 6841.818905] fs/cifs/smb2misc.c: smb2_check_message length: 0x6c, > smb_buf_length: 0x68 > [ 6841.818906] fs/cifs/smb2misc.c: SMB2 data length 32 offset 72 > [ 6841.818907] fs/cifs/smb2misc.c: SMB2 len 108 > [ 6841.818914] fs/cifs/transport.c: cifs_sync_mid_result: cmd=16 mid=35 > state=4 > [ 6841.818915] fs/cifs/misc.c: Null buffer passed to cifs_small_buf_release > [ 6841.818917] fs/cifs/smb2pdu.c: Close > [ 6841.818919] fs/cifs/transport.c: Sending smb: smb_len=88 > [ 6841.819481] fs/cifs/connect.c: RFC1002 header 0x7c > [ 6841.819484] fs/cifs/smb2misc.c: smb2_check_message length: 0x80, > smb_buf_length: 0x7c > [ 6841.819484] fs/cifs/smb2misc.c: SMB2 len 128 > [ 6841.819504] fs/cifs/transport.c: cifs_sync_mid_result: cmd=6 mid=36 > state=4 > [ 6841.819506] fs/cifs/misc.c: Null buffer passed to cifs_small_buf_release > [ 6841.819508] fs/cifs/cifsfs.c: CIFS VFS: leaving cifs_statfs (xid = 36) rc > = 0 > [ 6841.840663] fs/cifs/connect.c: cifs_put_tcon: tc_count=1 > [ 6841.840675] fs/cifs/connect.c: CIFS VFS: in cifs_put_tcon as Xid: 37 with > uid: 0 > [ 6841.840676] fs/cifs/smb2pdu.c: Tree Disconnect > [ 6841.840681] fs/cifs/transport.c: Sending smb: smb_len=68 > [ 6841.842707] fs/cifs/connect.c: RFC1002 header 0x44 > [ 6841.842712] fs/cifs/smb2misc.c: smb2_check_message length: 0x48, > smb_buf_length: 0x44 > [ 6841.842713] fs/cifs/smb2misc.c: SMB2 len 72 > [ 6841.842721] fs/cifs/transport.c: cifs_sync_mid_result: cmd=4 mid=37 > state=4 > [ 6841.842724] fs/cifs/fscache.c: cifs_fscache_release_super_cookie: > (0x0000000065e26ee8) > [ 6841.842727] fs/cifs/connect.c: cifs_put_smb_ses: ses_count=1 > [ 6841.842729] fs/cifs/connect.c: CIFS VFS: in cifs_free_ipc as Xid: 38 with > uid: 0 > [ 6841.842729] fs/cifs/smb2pdu.c: Tree Disconnect > [ 6841.842731] fs/cifs/connect.c: CIFS VFS: leaving cifs_free_ipc (xid = 38) > rc = -5 > [ 6841.842731] fs/cifs/connect.c: failed to disconnect IPC tcon (rc=-5) > [ 6841.842732] fs/cifs/connect.c: CIFS VFS: in cifs_put_smb_ses as Xid: 39 > with uid: 0 > [ 6841.842734] fs/cifs/smb2pdu.c: disconnect session 000000000c4e8364 > [ 6841.842735] fs/cifs/transport.c: Sending smb: smb_len=68 > [ 6841.845750] fs/cifs/connect.c: RFC1002 header 0x44 > [ 6841.845753] fs/cifs/smb2misc.c: smb2_check_message length: 0x48, > smb_buf_length: 0x44 > [ 6841.845754] fs/cifs/smb2misc.c: SMB2 len 72 > [ 6841.845770] fs/cifs/transport.c: cifs_sync_mid_result: cmd=2 mid=38 > state=4 > [ 6841.845779] fs/cifs/fscache.c: cifs_fscache_release_client_cookie: > (0x00000000c6ed612e/0x00000000f6b80d58) > [ 6841.855712] fs/cifs/cifsfs.c: Devname: //192.168.1.1/shared flags: 0 > [ 6841.855731] fs/cifs/connect.c: Username: User > [ 6841.855734] fs/cifs/connect.c: file mode: 0x1b0 dir mode: 0x1f8 > [ 6841.855736] fs/cifs/connect.c: CIFS VFS: in cifs_mount as Xid: 40 with > uid: 0 > [ 6841.855737] fs/cifs/connect.c: UNC: \\192.168.1.1\shared > [ 6841.855744] fs/cifs/connect.c: Socket created > [ 6841.855745] fs/cifs/connect.c: sndbuf 16384 rcvbuf 87380 rcvtimeo 0x834 > [ 6841.856822] fs/cifs/fscache.c: cifs_fscache_get_client_cookie: > (0x00000000433261bb/0x00000000f6b80d58) > [ 6841.856825] fs/cifs/connect.c: CIFS VFS: in cifs_get_smb_ses as Xid: 41 > with uid: 0 > [ 6841.856826] fs/cifs/connect.c: Existing smb sess not found > [ 6841.856829] fs/cifs/smb2pdu.c: Negotiate protocol > [ 6841.856833] fs/cifs/transport.c: Sending smb: smb_len=102 > [ 6841.856838] fs/cifs/connect.c: Demultiplex PID: 724 > [ 6841.859599] fs/cifs/connect.c: RFC1002 header 0x1c0 > [ 6841.859604] fs/cifs/smb2misc.c: smb2_check_message length: 0x1c4, > smb_buf_length: 0x1c0 > [ 6841.859605] fs/cifs/smb2misc.c: SMB2 data length 320 offset 128 > [ 6841.859606] fs/cifs/smb2misc.c: SMB2 len 452 > [ 6841.859851] fs/cifs/transport.c: cifs_sync_mid_result: cmd=0 mid=0 > state=4 > [ 6841.859858] fs/cifs/smb2pdu.c: mode 0x1 > [ 6841.859859] fs/cifs/smb2pdu.c: negotiated smb3.0 dialect > [ 6841.859862] fs/cifs/asn1.c: OID len = 10 oid = 0x1 0x3 0x6 0x1 > [ 6841.859863] fs/cifs/asn1.c: OID len = 10 oid = 0x1 0x3 0x6 0x1 > [ 6841.859864] fs/cifs/connect.c: Security Mode: 0x1 Capabilities: 0x300047 > TimeAdjust: 0 > [ 6841.859865] fs/cifs/smb2pdu.c: Session Setup > [ 6841.859866] fs/cifs/smb2pdu.c: sess setup type 4 > [ 6841.859868] fs/cifs/transport.c: Sending smb: smb_len=120 > [ 6841.860305] fs/cifs/connect.c: RFC1002 header 0xdc > [ 6841.860308] fs/cifs/smb2misc.c: smb2_check_message length: 0xe0, > smb_buf_length: 0xdc > [ 6841.860309] fs/cifs/smb2misc.c: SMB2 data length 148 offset 72 > [ 6841.860310] fs/cifs/smb2misc.c: SMB2 len 224 > [ 6841.860353] fs/cifs/transport.c: cifs_sync_mid_result: cmd=1 mid=1 > state=4 > [ 6841.860356] Status code returned 0xc0000016 > STATUS_MORE_PROCESSING_REQUIRED > [ 6841.860359] fs/cifs/smb2maperror.c: Mapping SMB2 status code 0xc0000016 > to POSIX err -5 > [ 6841.860360] fs/cifs/misc.c: Null buffer passed to cifs_small_buf_release > [ 6841.860363] fs/cifs/smb2pdu.c: rawntlmssp session setup challenge phase > [ 6841.863462] fs/cifs/smb2misc.c: SMB2 data length 0 offset 72 > [ 6841.863464] fs/cifs/smb2misc.c: Calculated size 77 length 76 mismatch mid > 2 > [ 6841.863513] fs/cifs/misc.c: Null buffer passed to cifs_small_buf_release > [ 6841.863525] fs/cifs/smb2pdu.c: SMB2/3 session established successfully > [ 6841.863527] fs/cifs/connect.c: CIFS VFS: leaving cifs_get_smb_ses (xid = > 41) rc = 0 > [ 6841.863530] fs/cifs/connect.c: CIFS VFS: in cifs_setup_ipc as Xid: 42 > with uid: 0 > [ 6841.863531] fs/cifs/smb2pdu.c: TCON > [ 6841.866174] fs/cifs/misc.c: Null buffer passed to cifs_small_buf_release > [ 6841.866176] fs/cifs/smb2pdu.c: connection to pipe share > [ 6841.866178] fs/cifs/smb2pdu.c: validate negotiate > [ 6841.866179] fs/cifs/smb2pdu.c: SMB2 IOCTL > [ 6841.869607] fs/cifs/smb2misc.c: SMB2 data length 24 offset 112 > [ 6841.869630] fs/cifs/misc.c: Null buffer passed to cifs_small_buf_release > [ 6841.869633] fs/cifs/smb2pdu.c: validate negotiate info successful > [ 6841.869636] fs/cifs/connect.c: CIFS VFS: leaving cifs_setup_ipc (xid = > 42) rc = 0 > [ 6841.869637] fs/cifs/connect.c: IPC tcon rc = 0 ipc tid = 1 > [ 6841.869640] fs/cifs/connect.c: CIFS VFS: in cifs_get_tcon as Xid: 43 with > uid: 0 > [ 6841.869641] fs/cifs/smb2pdu.c: TCON > [ 6841.873185] fs/cifs/smb2pdu.c: connection to disk share > [ 6841.873187] fs/cifs/smb2pdu.c: validate negotiate > [ 6841.873188] fs/cifs/smb2pdu.c: SMB2 IOCTL > [ 6841.875975] fs/cifs/smb2misc.c: SMB2 data length 24 offset 112 > [ 6841.876085] fs/cifs/smb2pdu.c: validate negotiate info successful > [ 6841.876088] fs/cifs/connect.c: CIFS VFS: leaving cifs_get_tcon (xid = 43) > rc = 0 > [ 6841.876089] fs/cifs/connect.c: Tcon rc = 0 > [ 6841.876092] fs/cifs/fscache.c: cifs_fscache_get_super_cookie: > (0x00000000f6b80d58/0x0000000065e26ee8) > [ 6841.876093] fs/cifs/smb2pdu.c: create/open > [ 6841.879194] fs/cifs/smb2misc.c: SMB2 data length 0 offset 0 > [ 6841.879195] fs/cifs/smb2misc.c: Calculated size 157 length 156 mismatch > mid 7 > [ 6841.879247] fs/cifs/smb2pdu.c: Query FSInfo level 5 > [ 6841.880045] fs/cifs/smb2pdu.c: Query FSInfo level 4 > [ 6841.882951] fs/cifs/smb2pdu.c: Query FSInfo level 11 > [ 6841.886061] fs/cifs/smb2pdu.c: Close > [ 6841.889655] fs/cifs/connect.c: build_unc_path_to_root: > full_path=\\192.168.1.1\shared > [ 6841.889657] fs/cifs/smb2ops.c: smb2_get_dfs_refer path > <\192.168.1.1\shared> > [ 6841.889660] fs/cifs/smb2pdu.c: SMB2 IOCTL > [ 6841.890543] fs/cifs/smb2misc.c: Calculated size 77 length 76 mismatch mid > 12 > [ 6841.890624] Status code returned 0xc000019c STATUS_FS_DRIVER_REQUIRED > [ 6841.890628] fs/cifs/smb2maperror.c: Mapping SMB2 status code 0xc000019c > to POSIX err -5 > [ 6841.890630] CIFS VFS: ioctl error in smb2_get_dfs_refer rc=-5 > [ 6841.891117] fs/cifs/smb2pdu.c: create/open > [ 6841.893072] fs/cifs/smb2misc.c: Calculated size 157 length 156 mismatch > mid 13 > [ 6841.893118] fs/cifs/smb2pdu.c: Close > [ 6841.894469] fs/cifs/smb2pdu.c: create/open > [ 6841.896265] fs/cifs/smb2misc.c: Calculated size 157 length 156 mismatch > mid 15 > [ 6841.896335] fs/cifs/smb2pdu.c: Close > [ 6841.899768] fs/cifs/connect.c: CIFS VFS: leaving cifs_mount (xid = 40) rc > = 0 > [ 6841.900208] fs/cifs/inode.c: CIFS VFS: in cifs_root_iget as Xid: 44 with > uid: 0 > [ 6841.900210] fs/cifs/inode.c: Getting info on > [ 6841.900214] fs/cifs/smb2pdu.c: create/open > [ 6841.903186] fs/cifs/smb2misc.c: Calculated size 157 length 156 mismatch > mid 17 > [ 6841.903238] fs/cifs/smb2pdu.c: Query Info > [ 6841.906244] fs/cifs/smb2pdu.c: Close > [ 6841.909722] fs/cifs/inode.c: looking for uniqueid=10133099161590399 > [ 6841.909729] fs/cifs/inode.c: cifs_revalidate_cache: revalidating inode > 10133099161590399 > [ 6841.909730] fs/cifs/inode.c: cifs_revalidate_cache: inode > 10133099161590399 is new > [ 6841.909734] fs/cifs/cifsfs.c: Get root dentry for > [ 6841.909736] fs/cifs/cifsfs.c: dentry root is: 000000005c54625c > > > Thanks. > > -- > To unsubscribe from this list: send the line "unsubscribe linux-cifs" in > the body of a message to majordomo@xxxxxxxxxxxxxxx > More majordomo info at http://vger.kernel.org/majordomo-info.html -- Thanks, Steve -- To unsubscribe from this list: send the line "unsubscribe linux-cifs" in the body of a message to majordomo@xxxxxxxxxxxxxxx More majordomo info at http://vger.kernel.org/majordomo-info.html
