[linux-cifs-client] Samba file read hang
Maciej Babinski
maciej at killer-robot.net
Tue Jun 22 17:46:21 GMT 2004
I'm having problems with CIFS file reads hanging after the connection
has been idle for a while. Tasks get stuck for some time, and
eventually exit with a read error. The problem is fairly
reproducible, and is fixed with a umount/mount (once the hanging
processes die). dmesg output with cifsFYI turned on follows below
with filenames changed to protect the innocent. /proc/fs/cifs/Stats
lists the mount as oscillating between normal and DISCONNECTED
states.
nsport.c: For smb_command 115
fs/cifs/transport.c: Sending smb of length 218
fs/cifs/connect.c: Peek length rcvd: 0x24 beginning 0x91)
fs/cifs/connect.c: Mid 0x66d1 matched - waking up
fs/cifs/connect.c: UID = 2048
fs/cifs/connect.c: CIFS Session Established successfully
fs/cifs/transport.c: For smb_command 117
fs/cifs/transport.c: Sending smb of length 76
fs/cifs/connect.c: Peek length rcvd: 0x24 beginning 0x42)
fs/cifs/connect.c: Mid 0x66d2 matched - waking up
fs/cifs/connect.c: Tcon flags: 0x1
fs/cifs/cifssmb.c: reconnect tcon rc = 0
fs/cifs/file.c: CIFS VFS: in cifs_reopen_file as Xid: 673201 with uid: 5000
fs/cifs/file.c: inode = 0xc70e21c4 file flags are 0x8000 for \path\to\file.txt
fs/cifs/transport.c: For smb_command 162
fs/cifs/transport.c: Sending smb of length 340
fs/cifs/connect.c: Peek length rcvd: 0x24 beginning 0x6b)
fs/cifs/connect.c: Mid 0x66d3 matched - waking up
fs/cifs/inode.c: CIFS VFS: in cifs_get_inode_info as Xid: 673202 with uid: 5000
fs/cifs/inode.c: Getting info on \path\to\file.txt
fs/cifs/cifssmb.c: In QPathInfo path \path\to\file.txt
fs/cifs/transport.c: For smb_command 50
fs/cifs/transport.c: Sending smb of length 328
fs/cifs/connect.c: Peek length rcvd: 0x24 beginning 0x186)
fs/cifs/connect.c: Mid 0x66d4 matched - waking up
fs/cifs/inode.c: Old time 313110909
fs/cifs/inode.c: New time 313110939
fs/cifs/inode.c: File inode
fs/cifs/inode.c: CIFS VFS: leaving cifs_get_inode_info (xid = 673202) rc = 0
fs/cifs/file.c: Exclusive Oplock granted on inode c70e21c4
fs/cifs/file.c: CIFS VFS: leaving cifs_reopen_file (xid = 673201) rc = 0
fs/cifs/transport.c: For smb_command 46
fs/cifs/transport.c: Sending smb of length 59
fs/cifs/connect.c: Peek length rcvd: 0x24 beginning 0xff3f)
CIFS VFS: Invalid size or format for SMB found with length 36 and pdu_lenght 65343
Received Data is: : dump of 37 bytes of data at 0xcbbb3e40
3bff0000 424d53ff 0000002e c0018000 . . ÿ ; ÿ S M B . . . . . . . À
00000000 00000000 00000000 cc830800 . . . . . . . . . . . . . . . Ì
66d50800 03000000 . . Õ f .
fs/cifs/connect.c: Reconnecting tcp session
fs/cifs/connect.c: State: 0x3 Flags: 0x0
fs/cifs/connect.c: Post shutdown state: 0x3 Flags: 0x0
fs/cifs/connect.c: Socket created
CIFS VFS: No response buffer
fs/cifs/transport.c: marking request for retry
fs/cifs/misc.c: Null buffer passed to cifs_buf_release
CIFS VFS: Send error in read = -11
fs/cifs/transport.c: For smb_command 114
fs/cifs/transport.c: Sending smb of length 47
fs/cifs/connect.c: Peek length rcvd: 0x24 beginning 0x7d)
fs/cifs/connect.c: Mid 0x66d6 matched - waking up
fs/cifs/connect.c: Security Mode: 0x3 Capabilities: 0xe3fd Time Zone: 300
fs/cifs/connect.c: In sesssetup
fs/cifs/transport.c: For smb_command 115
fs/cifs/transport.c: Sending smb of length 218
fs/cifs/connect.c: Peek length rcvd: 0x24 beginning 0x91)
fs/cifs/connect.c: Mid 0x66d7 matched - waking up
fs/cifs/connect.c: UID = 2048
fs/cifs/connect.c: CIFS Session Established successfully
fs/cifs/transport.c: For smb_command 117
fs/cifs/transport.c: Sending smb of length 76
fs/cifs/connect.c: Peek length rcvd: 0x24 beginning 0x42)
fs/cifs/connect.c: Mid 0x66d8 matched - waking up
fs/cifs/connect.c: Tcon flags: 0x1
fs/cifs/cifssmb.c: reconnect tcon rc = 0
fs/cifs/file.c: CIFS VFS: in cifs_reopen_file as Xid: 673203 with uid: 5000
fs/cifs/file.c: inode = 0xc70e21c4 file flags are 0x8000 for \path\to\file.txt
fs/cifs/transport.c: For smb_command 162
fs/cifs/transport.c: Sending smb of length 340
fs/cifs/connect.c: Peek length rcvd: 0x24 beginning 0x6b)
fs/cifs/connect.c: Mid 0x66d9 matched - waking up
fs/cifs/inode.c: CIFS VFS: in cifs_get_inode_info as Xid: 673204 with uid: 5000
fs/cifs/inode.c: Getting info on \path\to\file.txt
fs/cifs/cifssmb.c: In QPathInfo path \path\to\file.txt
fs/cifs/transport.c: For smb_command 50
fs/cifs/transport.c: Sending smb of length 328
fs/cifs/connect.c: Peek length rcvd: 0x24 beginning 0x186)
fs/cifs/connect.c: Mid 0x66da matched - waking up
fs/cifs/inode.c: Old time 313110939
fs/cifs/inode.c: New time 313110976
fs/cifs/inode.c: File inode
fs/cifs/inode.c: CIFS VFS: leaving cifs_get_inode_info (xid = 673204) rc = 0
fs/cifs/file.c: Exclusive Oplock granted on inode c70e21c4
fs/cifs/file.c: CIFS VFS: leaving cifs_reopen_file (xid = 673203) rc = 0
fs/cifs/transport.c: For smb_command 46
fs/cifs/transport.c: Sending smb of length 59
fs/cifs/connect.c: Peek length rcvd: 0x24 beginning 0xff3f)
CIFS VFS: Invalid size or format for SMB found with length 36 and pdu_lenght 65343
Received Data is: : dump of 37 bytes of data at 0xce1bbda0
3bff0000 424d53ff 0000002e c0018000 . . ÿ ; ÿ S M B . . . . . . . À
00000000 00000000 00000000 cc830800 . . . . . . . . . . . . . . . Ì
66db0800 6700ff00 . . Û f .
fs/cifs/connect.c: Reconnecting tcp session
fs/cifs/connect.c: State: 0x3 Flags: 0x0
fs/cifs/connect.c: Post shutdown state: 0x3 Flags: 0x0
fs/cifs/connect.c: Socket created
CIFS VFS: No response buffer
fs/cifs/transport.c: marking request for retry
fs/cifs/misc.c: Null buffer passed to cifs_buf_release
CIFS VFS: Send error in read = -11
fs/cifs/transport.c: For smb_command 114
fs/cifs/transport.c: Sending smb of length 47
fs/cifs/connect.c: Peek length rcvd: 0x24 beginning 0x7d)
fs/cifs/connect.c: Mid 0x66dc matched - waking up
fs/cifs/connect.c: Security Mode: 0x3 Capabilities: 0xe3fd Time Zone: 300
fs/cifs/connect.c: In sesssetup
fs/cifs/transport.c: For smb_command 115
fs/cifs/transport.c: Sending smb of length 218
fs/cifs/connect.c: Peek length rcvd: 0x24 beginning 0x91)
fs/cifs/connect.c: Mid 0x66dd matched - waking up
fs/cifs/connect.c: UID = 2048
fs/cifs/connect.c: CIFS Session Established successfully
fs/cifs/transport.c: For smb_command 117
fs/cifs/transport.c: Sending smb of length 76
fs/cifs/connect.c: Peek length rcvd: 0x24 beginning 0x42)
fs/cifs/connect.c: Mid 0x66de matched - waking up
fs/cifs/connect.c: Tcon flags: 0x1
fs/cifs/cifssmb.c: reconnect tcon rc = 0
fs/cifs/file.c: CIFS VFS: in cifs_reopen_file as Xid: 673205 with uid: 5000
fs/cifs/file.c: inode = 0xc70e21c4 file flags are 0x8000 for \path\to\file.txt
fs/cifs/transport.c: For smb_command 162
fs/cifs/transport.c: Sending smb of length 340
fs/cifs/connect.c: Peek length rcvd: 0x24 beginning 0x6b)
fs/cifs/connect.c: Mid 0x66df matched - waking up
fs/cifs/inode.c: CIFS VFS: in cifs_get_inode_info as Xid: 673206 with uid: 5000
fs/cifs/inode.c: Getting info on \path\to\file.txt
fs/cifs/cifssmb.c: In QPathInfo path \path\to\file.txt
fs/cifs/transport.c: For smb_command 50
fs/cifs/transport.c: Sending smb of length 328
fs/cifs/connect.c: Peek length rcvd: 0x24 beginning 0x186)
fs/cifs/connect.c: Mid 0x66e0 matched - waking up
fs/cifs/inode.c: Old time 313110976
fs/cifs/inode.c: New time 313111006
fs/cifs/inode.c: File inode
fs/cifs/inode.c: CIFS VFS: leaving cifs_get_inode_info (xid = 673206) rc = 0
fs/cifs/file.c: Exclusive Oplock granted on inode c70e21c4
fs/cifs/file.c: CIFS VFS: leaving cifs_reopen_file (xid = 673205) rc = 0
fs/cifs/transport.c: For smb_command 46
fs/cifs/transport.c: Sending smb of length 59
fs/cifs/connect.c: Peek length rcvd: 0x24 beginning 0xff3f)
CIFS VFS: Invalid size or format for SMB found with length 36 and pdu_lenght 65343
Received Data is: : dump of 37 bytes of data at 0xc207bd40
3bff0000 424d53ff 0000002e c0018000 . . ÿ ; ÿ S M B . . . . . . . À
00000000 00000000 00000000 cc830800 . . . . . . . . . . . . . . . Ì
66e10800 6700ff00 . . á f .
fs/cifs/connect.c: Reconnecting tcp session
fs/cifs/connect.c: State: 0x3 Flags: 0x0
fs/cifs/connect.c: Post shutdown state: 0x3 Flags: 0x0
fs/cifs/connect.c: Socket created
CIFS VFS: No response buffer
fs/cifs/transport.c: marking request for retry
fs/cifs/misc.c: Null buffer passed to cifs_buf_release
CIFS VFS: Send error in read = -11
fs/cifs/transport.c: For smb_command 114
fs/cifs/transport.c: Sending smb of length 47
fs/cifs/connect.c: Peek length rcvd: 0x24 beginning 0x7d)
fs/cifs/connect.c: Mid 0x66e2 matched - waking up
fs/cifs/connect.c: Security Mode: 0x3 Capabilities: 0xe3fd Time Zone: 300
fs/cifs/connect.c: In sesssetup
fs/cifs/transport.c: For smb_command 115
fs/cifs/transport.c: Sending smb of length 218
fs/cifs/connect.c: Peek length rcvd: 0x24 beginning 0x91)
fs/cifs/connect.c: Mid 0x66e3 matched - waking up
fs/cifs/connect.c: UID = 2048
fs/cifs/connect.c: CIFS Session Established successfully
fs/cifs/transport.c: For smb_command 117
fs/cifs/transport.c: Sending smb of length 76
fs/cifs/connect.c: Peek length rcvd: 0x24 beginning 0x42)
fs/cifs/connect.c: Mid 0x66e4 matched - waking up
fs/cifs/connect.c: Tcon flags: 0x1
fs/cifs/cifssmb.c: reconnect tcon rc = 0
fs/cifs/file.c: CIFS VFS: in cifs_reopen_file as Xid: 673207 with uid: 5000
fs/cifs/file.c: inode = 0xc70e21c4 file flags are 0x8000 for \path\to\file.txt
fs/cifs/transport.c: For smb_command 162
fs/cifs/transport.c: Sending smb of length 340
fs/cifs/connect.c: Peek length rcvd: 0x24 beginning 0x6b)
fs/cifs/connect.c: Mid 0x66e5 matched - waking up
fs/cifs/inode.c: CIFS VFS: in cifs_get_inode_info as Xid: 673208 with uid: 5000
fs/cifs/inode.c: Getting info on \path\to\file.txt
fs/cifs/cifssmb.c: In QPathInfo path \path\to\file.txt
fs/cifs/transport.c: For smb_command 50
fs/cifs/transport.c: Sending smb of length 328
fs/cifs/connect.c: Peek length rcvd: 0x24 beginning 0x186)
fs/cifs/connect.c: Mid 0x66e6 matched - waking up
fs/cifs/inode.c: Old time 313111006
fs/cifs/inode.c: New time 313111037
fs/cifs/inode.c: File inode
fs/cifs/inode.c: CIFS VFS: leaving cifs_get_inode_info (xid = 673208) rc = 0
fs/cifs/file.c: Exclusive Oplock granted on inode c70e21c4
fs/cifs/file.c: CIFS VFS: leaving cifs_reopen_file (xid = 673207) rc = 0
fs/cifs/transport.c: For smb_command 46
fs/cifs/transport.c: Sending smb of length 59
fs/cifs/connect.c: Peek length rcvd: 0x24 beginning 0xff3f)
CIFS VFS: Invalid size or format for SMB found with length 36 and pdu_lenght 65343
Received Data is: : dump of 37 bytes of data at 0xcbbbbe20
3bff0000 424d53ff 0000002e c0018000 . . ÿ ; ÿ S M B . . . . . . . À
00000000 00000000 00000000 cc830800 . . . . . . . . . . . . . . . Ì
66e70800 6700ff00 . . ç f .
fs/cifs/connect.c: Reconnecting tcp session
fs/cifs/connect.c: State: 0x3 Flags: 0x0
fs/cifs/connect.c: Post shutdown state: 0x3 Flags: 0x0
fs/cifs/connect.c: Socket created
CIFS VFS: No response buffer
fs/cifs/transport.c: marking request for retry
fs/cifs/misc.c: Null buffer passed to cifs_buf_release
CIFS VFS: Send error in read = -11
fs/cifs/transport.c: For smb_command 114
fs/cifs/transport.c: Sending smb of length 47
fs/cifs/connect.c: Peek length rcvd: 0x24 beginning 0x7d)
fs/cifs/connect.c: Mid 0x66e8 matched - waking up
fs/cifs/connect.c: Security Mode: 0x3 Capabilities: 0xe3fd Time Zone: 300
fs/cifs/connect.c: In sesssetup
fs/cifs/transport.c: For smb_command 115
fs/cifs/transport.c: Sending smb of length 218
fs/cifs/connect.c: Peek length rcvd: 0x24 beginning 0x91)
fs/cifs/connect.c: Mid 0x66e9 matched - waking up
fs/cifs/connect.c: UID = 2048
fs/cifs/connect.c: CIFS Session Established successfully
fs/cifs/transport.c: For smb_command 117
fs/cifs/transport.c: Sending smb of length 76
fs/cifs/connect.c: Peek length rcvd: 0x24 beginning 0x42)
fs/cifs/connect.c: Mid 0x66ea matched - waking up
fs/cifs/connect.c: Tcon flags: 0x1
fs/cifs/cifssmb.c: reconnect tcon rc = 0
fs/cifs/file.c: CIFS VFS: in cifs_reopen_file as Xid: 673209 with uid: 5000
fs/cifs/file.c: inode = 0xc70e21c4 file flags are 0x8000 for \path\to\file.txt
fs/cifs/transport.c: For smb_command 162
fs/cifs/transport.c: Sending smb of length 340
fs/cifs/connect.c: Peek length rcvd: 0x24 beginning 0x6b)
fs/cifs/connect.c: Mid 0x66eb matched - waking up
fs/cifs/inode.c: CIFS VFS: in cifs_get_inode_info as Xid: 673210 with uid: 5000
fs/cifs/inode.c: Getting info on \path\to\file.txt
fs/cifs/cifssmb.c: In QPathInfo path \path\to\file.txt
fs/cifs/transport.c: For smb_command 50
fs/cifs/transport.c: Sending smb of length 328
fs/cifs/connect.c: Peek length rcvd: 0x24 beginning 0x186)
fs/cifs/connect.c: Mid 0x66ec matched - waking up
fs/cifs/inode.c: Old time 313111037
fs/cifs/inode.c: New time 313111067
fs/cifs/inode.c: File inode
fs/cifs/inode.c: CIFS VFS: leaving cifs_get_inode_info (xid = 673210) rc = 0
fs/cifs/file.c: Exclusive Oplock granted on inode c70e21c4
fs/cifs/file.c: CIFS VFS: leaving cifs_reopen_file (xid = 673209) rc = 0
fs/cifs/transport.c: For smb_command 46
fs/cifs/transport.c: Sending smb of length 59
fs/cifs/connect.c: Peek length rcvd: 0x24 beginning 0xff3f)
CIFS VFS: Invalid size or format for SMB found with length 36 and pdu_lenght 65343
Received Data is: : dump of 37 bytes of data at 0xc772bde0
3bff0000 424d53ff 0000002e c0018000 . . ÿ ; ÿ S M B . . . . . . . À
00000000 00000000 00000000 cc830800 . . . . . . . . . . . . . . . Ì
66ed0800 6700ff00 . . í f .
fs/cifs/connect.c: Reconnecting tcp session
fs/cifs/connect.c: State: 0x3 Flags: 0x0
fs/cifs/connect.c: Post shutdown state: 0x3 Flags: 0x0
fs/cifs/connect.c: Socket created
CIFS VFS: No response buffer
fs/cifs/transport.c: marking request for retry
fs/cifs/misc.c: Null buffer passed to cifs_buf_release
CIFS VFS: Send error in read = -11
fs/cifs/transport.c: For smb_command 114
fs/cifs/transport.c: Sending smb of length 47
fs/cifs/connect.c: Peek length rcvd: 0x24 beginning 0x7d)
fs/cifs/connect.c: Mid 0x66ee matched - waking up
fs/cifs/connect.c: Security Mode: 0x3 Capabilities: 0xe3fd Time Zone: 300
fs/cifs/connect.c: In sesssetup
fs/cifs/transport.c: For smb_command 115
fs/cifs/transport.c: Sending smb of length 218
More information about the linux-cifs-client
mailing list