Re: CIFS problem on Ubuntu 18.04.1

[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index]

 



If you were using Ubuntu 16.04 before - was that using the default
cifs dialect (ie "vers=1.0)?

For security reasons, it has been upgraded in more recent kernels to
SMB3 default (SMB2.1 dialect or later) but it looks like you are using
vers=2.0 (SMB2.0) so you may have hit some bugs in the server's SMB2
implementation.  Have you tried mounting with vers=1.0 to see if this
bypasses it?

Looking at the logs, other than the server returning unexpected length
fields (which is suspicious), you may have hit a session reconnect
problem in paths with multiple writes pending that we have been
debugging although a little hard to tell.

SMB2 is the least tested dialect since Windows Vista is not very
common anymore (compared to the many much earlier versions of Windows
and old NAS which only supported cifs, and later versions of Windows,
and Macs and Samba) which all support SMB2.1 or later.   I wouldn't be
too surprised to see SMB2 reconnect bug.  Can you try mounting with
vers=2.1 or vers=1.0?

It is fairly easy on Ubuntu to test out the current mainline kernel
(see instructions at https://wiki.ubuntu.com/Kernel/MainlineBuilds and
choose 4.19-rc1) to see if the problem has been fixed since the 4.15
kernel.

My guess is that some illegal responses by the server, or server crash
(temporarily killing the session) is causing the client to go into a
buggy reconnect path.  Probably safer to use SMB2.1 or SMB1 in that
case.
On Thu, Aug 30, 2018 at 8:28 AM Rauste Yrjö <Yrjo.Rauste@xxxxxx> wrote:
>
> Hello,
>
> After several trials with mount command arguments, I came to this e-mail list based on instruction on the WWW page https://wiki.samba.org/index.php/LinuxCIFS_troubleshooting
>
> Under Ubuntu 16.04, the cifs-traffic to a couple of QNAP boxes (TVS-871) and a couple of Windows disks worked.  After the Ubuntu 18.04.1 with its newer kernel, the traffic (it seems a QNAP box) made the system unstable and started to generate kernel oopses and protection faults.  Following the instructions of the WWW page referred to above, I generated some debugging data (at the end of this e-mail).  My programs and scripts work as follows:
> 1. cd to a directory on the NAS box,
> 2. wget an image catalogue file and save it to the default directory on the NAS box,
> 3. list the existing .zip files in the default directory with the ls command
> 4. wget the new (present in the catalogue but not in the directory) .zip files
> It seems that the problems start in this download/write of .zip files, which are typically around 1 GB in size.
> I have mounted the NAS box in question:
>
> mount -t cifs //espnr086/ba3131 /mnt/i -o rw,user=user_y,file_mode=0777,dir_mode=0777,domain=AD,vers=2.0
>
> If this is a known problem (my misunderstanding of how cifs disks should be mounted) I hope somebody can tell me.  If not, I hope the problem can be corrected in some future release of mount.cifs (or some other software), which then finds its way to Ubuntu 18.04.
>
> Ciao
> Yrjö
>
> *******************************************************************************
> Listings:
> *******************************************************************************
>
> Kernel version:
> 4.15.0-33-generic
>
> cifs version:
> mount.cifs version: 6.8
>
> Contents of /proc/fs/cifs/DebugData:
>
> Display Internal CIFS Data Structures for Debugging
> ---------------------------------------------------
> CIFS Version 2.10
> Features: dfs fscache lanman posix spnego xattr acl
> Active VFS Requests: 5
> Servers:
> Number of credits: 68
> 1) Name: 130.188.44.20 Uses: 2 Capability: 0x300007     Session Status: 1       TCP status: 4
>         Local Users To Server: 1 SecMode: 0x1 Req On Wire: 0
>         Shares:
>         0) IPC: \\130.188.44.20\IPC$ Mounts: 1 DevInfo: 0x0 Attributes: 0x0
>         PathComponentMax: 0 Status: 1 type: 0   DISCONNECTED
>
>         1) \\espnr032\Server-K Mounts: 1 DevInfo: 0x20 Attributes: 0xc700ff
>         PathComponentMax: 255 Status: 1 type: DISK      DISCONNECTED
>
>         2) \\espnr032\Server-F Mounts: 1 DevInfo: 0x20 Attributes: 0xc700ff
>         PathComponentMax: 255 Status: 1 type: DISK      DISCONNECTED
>
>         MIDs:
>
> Number of credits: 1
> 2) Name: 130.188.45.144 Uses: 1 Capability: 0x300001    Session Status: 1       TCP status: 4
>         Local Users To Server: 2 SecMode: 0x1 Req On Wire: 0
>         Shares:
>         0) IPC: \\130.188.45.144\IPC$ Mounts: 1 DevInfo: 0x0 Attributes: 0x0
>         PathComponentMax: 0 Status: 1 type: 0   DISCONNECTED
>
>         1) \\espnr085\BA3131 Mounts: 2 DevInfo: 0x20 Attributes: 0x5002f
>         PathComponentMax: 255 Status: 1 type: DISK      DISCONNECTED
>
>         MIDs:
>
> Number of credits: 510
> 3) Name: 130.188.45.148 Uses: 1 Capability: 0x300001    Session Status: 1       TCP status: 1
>         Local Users To Server: 1 SecMode: 0x1 Req On Wire: 2
>         Shares:
>         0) IPC: \\130.188.45.148\IPC$ Mounts: 1 DevInfo: 0x0 Attributes: 0x0
>         PathComponentMax: 0 Status: 1 type: 0
>
>         1) \\espnr086\ba3131 Mounts: 1 DevInfo: 0x20 Attributes: 0x5002f
>         PathComponentMax: 255 Status: 1 type: DISK
>
>         MIDs:
>         State: 2 com: 5 pid: 2235 cbdata: 000000005c35f472 mid 16637
>         State: 2 com: 5 pid: 2873 cbdata: 0000000068cfca65 mid 16638
>
> Contents of dmesg:
> ... the beginning of dmesg corresponds to the program logic as far as I understand it, here it is writing a .zip file to the NAS disk ...
> [  988.283243] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2misc.c: SMB2 len 85
> [  989.724864] smb2_check_message: 257 callbacks suppressed
> [  989.724876] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2misc.c: Calculated size 85 length 84 mismatch mid 14700
> [  989.725248] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2misc.c: Calculated size 85 length 84 mismatch mid 14701
> [  989.725824] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2misc.c: Calculated size 85 length 84 mismatch mid 14702
> [  989.726209] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2misc.c: Calculated size 85 length 84 mismatch mid 14703
> [  989.729398] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2misc.c: Calculated size 85 length 84 mismatch mid 14704
> [  989.729762] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2misc.c: Calculated size 85 length 84 mismatch mid 14705
> [  989.749330] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2misc.c: Calculated size 85 length 84 mismatch mid 14706
> [  989.749716] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2misc.c: Calculated size 85 length 84 mismatch mid 14707
> [  989.785296] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2misc.c: Calculated size 85 length 84 mismatch mid 14708
> [  989.785672] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2misc.c: Calculated size 85 length 84 mismatch mid 14709
> [  990.182352] smb2_async_writev: 313 callbacks suppressed
> [  990.182355] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2pdu.c: async write at 116700178 8192 bytes
> [  990.182764] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2pdu.c: async write at 116708370 8192 bytes
> [  990.183311] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2pdu.c: async write at 116716562 8192 bytes
> [  990.183691] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2pdu.c: async write at 116724754 8192 bytes
> [  990.184277] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2pdu.c: async write at 116732946 8192 bytes
> [  990.184631] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2pdu.c: async write at 116741138 8192 bytes
> [  990.185514] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2pdu.c: async write at 116749330 8192 bytes
> [  990.185882] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2pdu.c: async write at 116757522 8192 bytes
> [  990.190603] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2pdu.c: async write at 116765714 8192 bytes
> [  990.191012] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2pdu.c: async write at 116773906 8192 bytes
> [  993.227831] __smb_send_rqst: 1873 callbacks suppressed
> [  993.227835] /build/linux-81MBYC/linux-4.15.0/fs/cifs/transport.c: Sending smb: smb_len=8304
> [  993.228187] cifs_demultiplex_thread: 1873 callbacks suppressed
> [  993.228189] /build/linux-81MBYC/linux-4.15.0/fs/cifs/connect.c: RFC1002 header 0x50
> [  993.228193] smb2_check_message: 1873 callbacks suppressed
> [  993.228195] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2misc.c: smb2_check_message length: 0x54, smb_buf_length: 0x50
> [  993.228196] smb2_calc_size: 1873 callbacks suppressed
> [  993.228197] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2misc.c: SMB2 len 85
> [  993.228223] /build/linux-81MBYC/linux-4.15.0/fs/cifs/transport.c: Sending smb: smb_len=240
> [  993.228408] /build/linux-81MBYC/linux-4.15.0/fs/cifs/connect.c: RFC1002 header 0x50
> [  993.228412] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2misc.c: smb2_check_message length: 0x54, smb_buf_length: 0x50
> [  993.228413] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2misc.c: SMB2 len 85
> [  993.232408] /build/linux-81MBYC/linux-4.15.0/fs/cifs/transport.c: Sending smb: smb_len=8304
> [  993.232751] /build/linux-81MBYC/linux-4.15.0/fs/cifs/connect.c: RFC1002 header 0x50
> [  993.232755] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2misc.c: smb2_check_message length: 0x54, smb_buf_length: 0x50
> [  993.232757] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2misc.c: SMB2 len 85
> [  993.232789] /build/linux-81MBYC/linux-4.15.0/fs/cifs/transport.c: Sending smb: smb_len=8304
> [  993.233101] /build/linux-81MBYC/linux-4.15.0/fs/cifs/connect.c: RFC1002 header 0x50
> [  993.233105] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2misc.c: smb2_check_message length: 0x54, smb_buf_length: 0x50
> [  993.233106] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2misc.c: SMB2 len 85
> [  993.234374] /build/linux-81MBYC/linux-4.15.0/fs/cifs/transport.c: Sending smb: smb_len=8304
> [  993.234715] /build/linux-81MBYC/linux-4.15.0/fs/cifs/connect.c: RFC1002 header 0x50
> [  993.234719] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2misc.c: smb2_check_message length: 0x54, smb_buf_length: 0x50
> [  993.234721] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2misc.c: SMB2 len 85
> [  993.234749] /build/linux-81MBYC/linux-4.15.0/fs/cifs/transport.c: Sending smb: smb_len=8304
> [  993.235095] /build/linux-81MBYC/linux-4.15.0/fs/cifs/connect.c: RFC1002 header 0x50
> [  993.235099] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2misc.c: smb2_check_message length: 0x54, smb_buf_length: 0x50
> [  993.235100] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2misc.c: SMB2 len 85
> [  993.236551] /build/linux-81MBYC/linux-4.15.0/fs/cifs/transport.c: Sending smb: smb_len=8304
> [  993.236929] /build/linux-81MBYC/linux-4.15.0/fs/cifs/connect.c: RFC1002 header 0x50
> [  993.236934] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2misc.c: smb2_check_message length: 0x54, smb_buf_length: 0x50
> [  993.236935] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2misc.c: SMB2 len 85
> [  993.237604] /build/linux-81MBYC/linux-4.15.0/fs/cifs/transport.c: Sending smb: smb_len=8304
> [  993.237991] /build/linux-81MBYC/linux-4.15.0/fs/cifs/connect.c: RFC1002 header 0x50
> [  993.237994] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2misc.c: smb2_check_message length: 0x54, smb_buf_length: 0x50
> [  993.237996] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2misc.c: SMB2 len 85
> [  993.240947] /build/linux-81MBYC/linux-4.15.0/fs/cifs/transport.c: Sending smb: smb_len=8304
> [  993.241323] /build/linux-81MBYC/linux-4.15.0/fs/cifs/connect.c: RFC1002 header 0x50
> [  993.241327] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2misc.c: smb2_check_message length: 0x54, smb_buf_length: 0x50
> [  993.241328] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2misc.c: SMB2 len 85
> [  993.241358] /build/linux-81MBYC/linux-4.15.0/fs/cifs/transport.c: Sending smb: smb_len=8304
> [  993.241706] /build/linux-81MBYC/linux-4.15.0/fs/cifs/connect.c: RFC1002 header 0x50
> [  993.241709] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2misc.c: smb2_check_message length: 0x54, smb_buf_length: 0x50
> [  993.241711] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2misc.c: SMB2 len 85
> [  993.531409] general protection fault: 0000 [#1] SMP PTI
> [  993.531415] Modules linked in: arc4 md4 nls_utf8 cifs ccm fscache snd_hda_codec_hdmi gpio_ich dcdbas nvidia_drm(POE) nvidia_modeset(POE) snd_hda_codec_idt coretemp snd_hda_codec_generic snd_hda_intel kvm_intel snd_hda_codec dell_smm_hwmon snd_hda_core kvm nvidia(POE) snd_hwdep snd_pcm irqbypass snd_seq_midi snd_seq_midi_event snd_rawmidi input_leds drm_kms_helper snd_seq serio_raw drm lpc_ich ipmi_devintf snd_seq_device snd_timer ipmi_msghandler fb_sys_fops snd syscopyarea sysfillrect sysimgblt soundcore mac_hid i5000_edac i5k_amb shpchp sch_fq_codel parport_pc ppdev lp parport ip_tables x_tables autofs4 uas usb_storage hid_generic usbhid hid psmouse ahci tg3 libahci pata_acpi ptp pps_core
> [  993.531472] CPU: 3 PID: 2229 Comm: wget Tainted: P           OE    4.15.0-33-generic #36-Ubuntu
> [  993.531474] Hardware name: Dell Inc.                 Precision WorkStation 490    /0DT031, BIOS A08 04/25/2008
> [  993.531481] RIP: 0010:prefetch_freepointer+0x15/0x30
> [  993.531483] RSP: 0018:ffffbb25c52b7a98 EFLAGS: 00010282
> [  993.531486] RAX: 0000000000000000 RBX: 9f912ff2d581367a RCX: 0000000000006e1e
> [  993.531488] RDX: 0000000000006e1d RSI: 9f912ff2d581367a RDI: ffff9e495c807080
> [  993.531490] RBP: ffffbb25c52b7a98 R08: ffff9e496fceb530 R09: 0000000000000000
> [  993.531492] R10: ffffbb25c52b7c88 R11: 0000000000000700 R12: 0000000001011200
> [  993.531494] R13: ffff9e495c807080 R14: ffff9e48dd979f80 R15: ffff9e495c807080
> [  993.531497] FS:  00007fb604a4c2c0(0000) GS:ffff9e496fcc0000(0000) knlGS:0000000000000000
> [  993.531499] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
> [  993.531501] CR2: 00007f8cf6508070 CR3: 0000000412e3e000 CR4: 00000000000006e0
> [  993.531503] Call Trace:
> [  993.531510]  kmem_cache_alloc+0xa2/0x1b0
> [  993.531515]  ? mempool_alloc_slab+0x15/0x20
> [  993.531519]  ? wait_woken+0x80/0x80
> [  993.531522]  mempool_alloc_slab+0x15/0x20
> [  993.531524]  mempool_alloc+0x71/0x190
> [  993.531528]  ? update_cfs_group+0xc4/0xe0
> [  993.531567]  ? cifs_page_mkwrite+0x50/0x50 [cifs]
> [  993.531584]  cifs_small_buf_get+0x1a/0x30 [cifs]
> [  993.531602]  small_smb2_init+0x62/0xb0 [cifs]
> [  993.531618]  ? cifs_page_mkwrite+0x50/0x50 [cifs]
> [  993.531635]  smb2_async_writev+0x9b/0x380 [cifs]
> [  993.531640]  ? copyin+0x26/0x30
> [  993.531643]  ? copy_page_from_iter+0x110/0x220
> [  993.531659]  cifs_write_from_iter.isra.26+0x383/0x5a0 [cifs]
> [  993.531674]  ? cifs_write_from_iter.isra.26+0x383/0x5a0 [cifs]
> [  993.531691]  cifs_user_writev+0x126/0x270 [cifs]
> [  993.531707]  cifs_strict_writev+0x137/0x290 [cifs]
> [  993.531712]  new_sync_write+0xe7/0x140
> [  993.531715]  __vfs_write+0x29/0x40
> [  993.531717]  vfs_write+0xb1/0x1a0
> [  993.531720]  SyS_write+0x55/0xc0
> [  993.531724]  do_syscall_64+0x73/0x130
> [  993.531729]  entry_SYSCALL_64_after_hwframe+0x3d/0xa2
> [  993.531731] RIP: 0033:0x7fb6035de154
> [  993.531733] RSP: 002b:00007ffdb48aa938 EFLAGS: 00000246 ORIG_RAX: 0000000000000001
> [  993.531736] RAX: ffffffffffffffda RBX: 0000000000002000 RCX: 00007fb6035de154
> [  993.531738] RDX: 0000000000002000 RSI: 000055a95dd0a630 RDI: 0000000000000004
> [  993.531740] RBP: 000055a95dd0a630 R08: 000055a95dce3740 R09: 00007fb604a4c2c0
> [  993.531742] R10: 0000000000002000 R11: 0000000000000246 R12: 000055a95dce3660
> [  993.531745] R13: 0000000000002000 R14: 00007fb6038b62a0 R15: 00007fb6038b5760
> [  993.531747] Code: eb bb 49 8b 74 24 60 48 c7 c7 78 ba 8e 9b e8 c3 bd ea ff eb 90 90 66 66 66 66 90 55 48 85 f6 48 89 e5 74 14 48 63 47 20 48 01 c6 <48> 33 36 48 33 b7 40 01 00 00 0f 18 0e 5d c3 66 90 66 2e 0f 1f
> [  993.531788] RIP: prefetch_freepointer+0x15/0x30 RSP: ffffbb25c52b7a98
> [  993.531795] ---[ end trace 5ba833e5337f0207 ]---
> [  993.532228] /build/linux-81MBYC/linux-4.15.0/fs/cifs/file.c: Flush inode 000000002ab449ad file 000000008315eb06 rc 0
> [  993.532232] /build/linux-81MBYC/linux-4.15.0/fs/cifs/file.c: Flush inode 0000000090677f98 file 00000000b57b534f rc 0
> [  993.532240] /build/linux-81MBYC/linux-4.15.0/fs/cifs/file.c: Flush inode 00000000005793bd file 000000001727b70a rc 0
> [  993.532462] /build/linux-81MBYC/linux-4.15.0/fs/cifs/file.c: Flush inode 000000002ab449ad file 000000008315eb06 rc 0
> [  993.532467] /build/linux-81MBYC/linux-4.15.0/fs/cifs/file.c: Flush inode 00000000005793bd file 000000001727b70a rc 0
> [  993.532581] cifs_revalidate_dentry_attr: 4 callbacks suppressed
> [  993.532583] /build/linux-81MBYC/linux-4.15.0/fs/cifs/inode.c: CIFS VFS: in cifs_revalidate_dentry_attr as Xid: 1270 with uid: 1000
> [  993.532588] build_path_from_dentry_optional_prefix: 124 callbacks suppressed
> [  993.532590] /build/linux-81MBYC/linux-4.15.0/fs/cifs/dir.c: name: \S1B_IW_GRDH_1SDV_20180829T050437_20180829T050502_012473_017006_7AFC.zip
> [  993.532593] /build/linux-81MBYC/linux-4.15.0/fs/cifs/dir.c: name: \2015\S1B_IW_GRDH_1SDV_20180829T050437_20180829T050502_012473_017006_7AFC.zip
> [  993.532595] /build/linux-81MBYC/linux-4.15.0/fs/cifs/dir.c: name: \Suomi\2015\S1B_IW_GRDH_1SDV_20180829T050437_20180829T050502_012473_017006_7AFC.zip
> [  993.532598] /build/linux-81MBYC/linux-4.15.0/fs/cifs/dir.c: name: \S1\Suomi\2015\S1B_IW_GRDH_1SDV_20180829T050437_20180829T050502_012473_017006_7AFC.zip
> [  993.532600] /build/linux-81MBYC/linux-4.15.0/fs/cifs/dir.c: name: \ZipArchive\S1\Suomi\2015\S1B_IW_GRDH_1SDV_20180829T050437_20180829T050502_012473_017006_7AFC.zip
> [  993.532602] cifs_revalidate_dentry_attr: 4 callbacks suppressed
> [  993.532605] /build/linux-81MBYC/linux-4.15.0/fs/cifs/inode.c: Update attributes: \ZipArchive\S1\Suomi\2015\S1B_IW_GRDH_1SDV_20180829T050437_20180829T050502_012473_017006_7AFC.zip inode 0x0000000090677f98 count 1 dentry: 0x00000000ccf8bc17 d_time 0 jiffies 4295140790
> [  993.532609] cifs_get_inode_info: 15 callbacks suppressed
> [  993.532610] /build/linux-81MBYC/linux-4.15.0/fs/cifs/inode.c: Getting info on \ZipArchive\S1\Suomi\2015\S1B_IW_GRDH_1SDV_20180829T050437_20180829T050502_012473_017006_7AFC.zip
> [  993.532625] SMB2_open: 23 callbacks suppressed
> [  993.532649] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2pdu.c: create/open
> [  993.532654] general protection fault: 0000 [#2] SMP PTI
> [  993.532655] Modules linked in: arc4 md4 nls_utf8 cifs ccm fscache snd_hda_codec_hdmi gpio_ich dcdbas nvidia_drm(POE) nvidia_modeset(POE) snd_hda_codec_idt coretemp snd_hda_codec_generic snd_hda_intel kvm_intel snd_hda_codec dell_smm_hwmon snd_hda_core kvm nvidia(POE) snd_hwdep snd_pcm irqbypass snd_seq_midi snd_seq_midi_event snd_rawmidi input_leds drm_kms_helper snd_seq serio_raw drm lpc_ich ipmi_devintf snd_seq_device snd_timer ipmi_msghandler fb_sys_fops snd syscopyarea sysfillrect sysimgblt soundcore mac_hid i5000_edac i5k_amb shpchp sch_fq_codel parport_pc ppdev lp parport ip_tables x_tables autofs4 uas usb_storage hid_generic usbhid hid psmouse ahci tg3 libahci pata_acpi ptp pps_core
> [  993.532819] CPU: 3 PID: 2227 Comm: yrgets1zip3tria Tainted: P      D    OE    4.15.0-33-generic #36-Ubuntu
> [  993.532821] Hardware name: Dell Inc.                 Precision WorkStation 490    /0DT031, BIOS A08 04/25/2008
> [  993.532824] RIP: 0010:kmem_cache_alloc+0x81/0x1b0
> [  993.532828] RSP: 0018:ffffbb25c52af738 EFLAGS: 00010282
> [  993.532831] RAX: 9f912ff2d581367a RBX: 9f912ff2d581367a RCX: 0000000000006e1f
> [  993.532833] RDX: 0000000000006e1e RSI: 0000000001011200 RDI: 000000000002b530
> [  993.532835] RBP: ffffbb25c52af768 R08: ffff9e496fceb530 R09: 0000000000001605
> [  993.532837] R10: ffffbb25c52af990 R11: ffffffff9c15380d R12: 0000000001011200
> [  993.532839] R13: ffff9e495c807080 R14: 9f912ff2d581367a R15: ffff9e495c807080
> [  993.532842] FS:  00007f37c0fe54c0(0000) GS:ffff9e496fcc0000(0000) knlGS:0000000000000000
> [  993.532844] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
> [  993.532846] CR2: 00007fff64ef7d48 CR3: 000000039b470000 CR4: 00000000000006e0
> [  993.532848] Call Trace:
> [  993.532852]  ? mempool_alloc_slab+0x15/0x20
> [  993.532855]  ? wait_woken+0x80/0x80
> [  993.532858]  mempool_alloc_slab+0x15/0x20
> [  993.532861]  mempool_alloc+0x71/0x190
> [  993.532883]  cifs_small_buf_get+0x1a/0x30 [cifs]
> [  993.532901]  small_smb2_init+0x62/0xb0 [cifs]
> [  993.532919]  SMB2_open+0xc3/0xb50 [cifs]
> [  993.532938]  ? cifsConvertToUTF16+0x1e9/0x420 [cifs]
> [  993.532956]  ? cifs_strndup_to_utf16+0xbd/0x110 [cifs]
> [  993.532974]  smb2_open_op_close+0xad/0x220 [cifs]
> [  993.533003]  ? smb2_open_op_close+0xad/0x220 [cifs]
> [  993.533011]  ? alloc_pages_current+0x6a/0xe0
> [  993.533033]  smb2_query_path_info+0x74/0xf0 [cifs]
> [  993.533054]  cifs_get_inode_info+0x4eb/0xab0 [cifs]
> [  993.533067]  ? printk_deferred+0x52/0x7c
> [  993.533089]  cifs_revalidate_dentry_attr+0x1d8/0x250 [cifs]
> [  993.533109]  cifs_revalidate_dentry+0x13/0x30 [cifs]
> [  993.533134]  cifs_d_revalidate+0x27/0xb0 [cifs]
> [  993.533142]  d_revalidate.part.18+0x10/0x20
> [  993.533148]  lookup_fast+0x307/0x320
> [  993.533151]  path_openat+0x163/0x1770
> [  993.533156]  ? tty_insert_flip_string_fixed_flag+0x86/0xe0
> [  993.533160]  do_filp_open+0x9b/0x110
> [  993.533164]  ? __check_object_size+0xaf/0x1b0
> [  993.533168]  ? __alloc_fd+0x46/0x170
> [  993.533171]  do_sys_open+0x1bb/0x2c0
> [  993.533174]  ? do_sys_open+0x1bb/0x2c0
> [  993.533177]  SyS_openat+0x14/0x20
> [  993.533180]  do_syscall_64+0x73/0x130
> [  993.533183]  entry_SYSCALL_64_after_hwframe+0x3d/0xa2
> [  993.533185] RIP: 0033:0x7f37c0af8c8e
> [  993.533187] RSP: 002b:00007fff64efadc0 EFLAGS: 00000246 ORIG_RAX: 0000000000000101
> [  993.533191] RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 00007f37c0af8c8e
> [  993.533193] RDX: 0000000000000000 RSI: 00007fff64efe1c3 RDI: 00000000ffffff9c
> [  993.533195] RBP: 00007fff64efae50 R08: 000000000000001b R09: 0000000000000007
> [  993.533197] R10: 0000000000000000 R11: 0000000000000246 R12: 00000000004007e0
> [  993.533199] R13: 00007fff64efc780 R14: 0000000000000000 R15: 0000000000000000
> [  993.533202] Code: 8b 5c 65 49 83 78 10 00 4d 8b 30 0f 84 00 01 00 00 4d 85 f6 0f 84 f7 00 00 00 49 63 5f 20 49 8b 3f 48 8d 4a 01 4c 89 f0 4c 01 f3 <48> 33 1b 49 33 9f 40 01 00 00 65 48 0f c7 0f 0f 94 c0 84 c0 74
> [  993.533271] RIP: kmem_cache_alloc+0x81/0x1b0 RSP: ffffbb25c52af738
> [  993.533278] ---[ end trace 5ba833e5337f0208 ]---
> [  993.533354] /build/linux-81MBYC/linux-4.15.0/fs/cifs/file.c: Flush inode 000000002ab449ad file 000000008315eb06 rc 0
> [  993.533359] /build/linux-81MBYC/linux-4.15.0/fs/cifs/file.c: Flush inode 00000000005793bd file 000000001727b70a rc 0
> [  993.534083] /build/linux-81MBYC/linux-4.15.0/fs/cifs/file.c: Flush inode 00000000984fcbf4 file 000000005b17e4a6 rc 0
> [  993.535593] /build/linux-81MBYC/linux-4.15.0/fs/cifs/inode.c: CIFS VFS: in cifs_revalidate_dentry_attr as Xid: 1271 with uid: 1000
> [  993.535598] /build/linux-81MBYC/linux-4.15.0/fs/cifs/dir.c: name: \ZipArchive
> [  993.535602] /build/linux-81MBYC/linux-4.15.0/fs/cifs/inode.c: Update attributes: \ZipArchive inode 0x00000000b642b83a count 1 dentry: 0x000000001e6a650e d_time 4294981125 jiffies 4295140791
> [  993.535606] /build/linux-81MBYC/linux-4.15.0/fs/cifs/inode.c: Getting info on \ZipArchive
> [  993.535620] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2pdu.c: create/open
> [  993.536012] smb2_calc_size: 525 callbacks suppressed
> [  993.536015] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2misc.c: SMB2 data length 0 offset 0
> [  993.536042] cifs_sync_mid_result: 249 callbacks suppressed
> [  993.536045] /build/linux-81MBYC/linux-4.15.0/fs/cifs/transport.c: cifs_sync_mid_result: cmd=5 mid=16634 state=4
> [  993.536051] cifs_small_buf_release: 88 callbacks suppressed
> [  993.536052] /build/linux-81MBYC/linux-4.15.0/fs/cifs/misc.c: Null buffer passed to cifs_small_buf_release
> [  993.536058] query_info: 14 callbacks suppressed
> [  993.536059] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2pdu.c: Query Info
> [  993.536245] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2misc.c: SMB2 data length 122 offset 72
> [  993.536267] /build/linux-81MBYC/linux-4.15.0/fs/cifs/transport.c: cifs_sync_mid_result: cmd=16 mid=16635 state=4
> [  993.536271] /build/linux-81MBYC/linux-4.15.0/fs/cifs/misc.c: Null buffer passed to cifs_small_buf_release
> [  993.536275] SMB2_close: 18 callbacks suppressed
> [  993.536276] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2pdu.c: Close
> [  993.536458] /build/linux-81MBYC/linux-4.15.0/fs/cifs/transport.c: cifs_sync_mid_result: cmd=6 mid=16636 state=4
> [  993.536461] general protection fault: 0000 [#3] SMP PTI
> [  993.536463] Modules linked in: arc4 md4
> [  993.536469] /build/linux-81MBYC/linux-4.15.0/fs/cifs/misc.c: Null buffer passed to cifs_small_buf_release
> [  993.536471]  nls_utf8 cifs ccm fscache snd_hda_codec_hdmi gpio_ich dcdbas nvidia_drm(POE)
> [  993.536481] cifs_revalidate_cache: 11690 callbacks suppressed
> [  993.536486] /build/linux-81MBYC/linux-4.15.0/fs/cifs/inode.c: cifs_revalidate_cache: revalidating inode 137560067
> [  993.536488] cifs_revalidate_cache: 11681 callbacks suppressed
> [  993.536492] /build/linux-81MBYC/linux-4.15.0/fs/cifs/inode.c: cifs_revalidate_cache: inode 137560067 is unchanged
> [  993.536495] cifs_revalidate_dentry_attr: 4 callbacks suppressed
> [  993.536498] /build/linux-81MBYC/linux-4.15.0/fs/cifs/inode.c: CIFS VFS: leaving cifs_revalidate_dentry_attr (xid = 1271) rc = 0
> [  993.536503] /build/linux-81MBYC/linux-4.15.0/fs/cifs/inode.c: CIFS VFS: in cifs_revalidate_dentry_attr as Xid: 1272 with uid: 1000
> [  993.536506]  nvidia_modeset(POE) snd_hda_codec_idt
> [  993.536510] /build/linux-81MBYC/linux-4.15.0/fs/cifs/dir.c: name: \S1
> [  993.536511]  coretemp snd_hda_codec_generic snd_hda_intel kvm_intel
> [  993.536518] /build/linux-81MBYC/linux-4.15.0/fs/cifs/dir.c: name: \ZipArchive\S1
> [  993.536521]  snd_hda_codec dell_smm_hwmon snd_hda_core
> [  993.536528] /build/linux-81MBYC/linux-4.15.0/fs/cifs/inode.c: Update attributes: \ZipArchive\S1 inode 0x00000000070df301 count 1 dentry: 0x00000000a0801158 d_time 4294981125 jiffies 4295140791
> [  993.536529]  kvm nvidia(POE) snd_hwdep snd_pcm
> [  993.536536] /build/linux-81MBYC/linux-4.15.0/fs/cifs/inode.c: Getting info on \ZipArchive\S1
> [  993.536537]  irqbypass snd_seq_midi snd_seq_midi_event snd_rawmidi input_leds drm_kms_helper snd_seq serio_raw drm lpc_ich
> [  993.536549] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2pdu.c: create/open
> [  993.536553]  ipmi_devintf snd_seq_device snd_timer ipmi_msghandler fb_sys_fops snd syscopyarea sysfillrect sysimgblt soundcore mac_hid i5000_edac i5k_amb shpchp sch_fq_codel parport_pc ppdev lp parport ip_tables x_tables autofs4 uas usb_storage hid_generic usbhid hid psmouse ahci tg3 libahci pata_acpi ptp pps_core
> [  993.536593] CPU: 3 PID: 2107 Comm: cifsd Tainted: P      D    OE    4.15.0-33-generic #36-Ubuntu
> [  993.536595] Hardware name: Dell Inc.                 Precision WorkStation 490    /0DT031, BIOS A08 04/25/2008
> [  993.536609] RIP: 0010:kmem_cache_alloc+0x81/0x1b0
> [  993.536611] RSP: 0018:ffffbb25c262bdb0 EFLAGS: 00010282
> [  993.536614] RAX: 9f912ff2d581367a RBX: 9f912ff2d581367a RCX: 0000000000006e1f
> [  993.536617] RDX: 0000000000006e1e RSI: 0000000001011200 RDI: 000000000002b530
> [  993.536619] RBP: ffffbb25c262bde0 R08: ffff9e496fceb530 R09: ffff9e496fc00000
> [  993.536622] R10: ffffbb25c262bde0 R11: 0000000000000000 R12: 0000000001011200
> [  993.536624] R13: ffff9e495c807080 R14: 9f912ff2d581367a R15: ffff9e495c807080
> [  993.536627] FS:  0000000000000000(0000) GS:ffff9e496fcc0000(0000) knlGS:0000000000000000
> [  993.536630] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
> [  993.536632] CR2: 00005649633f4ddc CR3: 0000000411e96000 CR4: 00000000000006e0
> [  993.536634] Call Trace:
> [  993.536640]  ? mempool_alloc_slab+0x15/0x20
> [  993.536644]  ? wait_woken+0x80/0x80
> [  993.536647]  mempool_alloc_slab+0x15/0x20
> [  993.536650]  mempool_alloc+0x71/0x190
> [  993.536689]  cifs_small_buf_get+0x1a/0x30 [cifs]
> [  993.536705]  cifs_demultiplex_thread+0x5c7/0xaf0 [cifs]
> [  993.536710]  ? __schedule+0x299/0x8a0
> [  993.536715]  kthread+0x121/0x140
> [  993.536734]  ? cifs_handle_standard+0x190/0x190 [cifs]
> [  993.536738]  ? kthread_create_worker_on_cpu+0x70/0x70
> [  993.536745]  ret_from_fork+0x35/0x40
> [  993.536748] Code: 8b 5c 65 49 83 78 10 00 4d 8b 30 0f 84 00 01 00 00 4d 85 f6 0f 84 f7 00 00 00 49 63 5f 20 49 8b 3f 48 8d 4a 01 4c 89 f0 4c 01 f3 <48> 33 1b 49 33 9f 40 01 00 00 65 48 0f c7 0f 0f 94 c0 84 c0 74
> [  993.536849] RIP: kmem_cache_alloc+0x81/0x1b0 RSP: ffffbb25c262bdb0
> [  993.536876] ---[ end trace 5ba833e5337f0209 ]---
> [ 1010.454891] /build/linux-81MBYC/linux-4.15.0/fs/cifs/connect.c: Received no data or error: 0
> [ 1010.454899] /build/linux-81MBYC/linux-4.15.0/fs/cifs/connect.c: Reconnecting tcp session
> [ 1010.454902] /build/linux-81MBYC/linux-4.15.0/fs/cifs/connect.c: cifs_reconnect: marking sessions and tcons for reconnect
> [ 1010.454905] /build/linux-81MBYC/linux-4.15.0/fs/cifs/connect.c: cifs_reconnect: tearing down socket
> [ 1010.454908] /build/linux-81MBYC/linux-4.15.0/fs/cifs/connect.c: State: 0x3 Flags: 0x0
> [ 1010.454933] /build/linux-81MBYC/linux-4.15.0/fs/cifs/connect.c: Post shutdown state: 0x3 Flags: 0x0
> [ 1010.454944] /build/linux-81MBYC/linux-4.15.0/fs/cifs/connect.c: cifs_reconnect: moving mids to private list
> [ 1010.454946] /build/linux-81MBYC/linux-4.15.0/fs/cifs/connect.c: cifs_reconnect: issuing mid callbacks
> [ 1010.454960] /build/linux-81MBYC/linux-4.15.0/fs/cifs/connect.c: Socket created
> [ 1010.454963] /build/linux-81MBYC/linux-4.15.0/fs/cifs/connect.c: sndbuf 16384 rcvbuf 87380 rcvtimeo 0x6d6
> [ 1010.455203] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2pdu.c: In echo request
> [ 1010.455208] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2pdu.c: Need negotiate, reconnecting tcons
> [ 1010.455214] /build/linux-81MBYC/linux-4.15.0/fs/cifs/smb2pdu.c: Negotiate protocol
> [ 1010.455224] general protection fault: 0000 [#4] SMP PTI
> [ 1010.455226] Modules linked in: arc4 md4 nls_utf8 cifs ccm fscache snd_hda_codec_hdmi gpio_ich dcdbas nvidia_drm(POE) nvidia_modeset(POE) snd_hda_codec_idt coretemp snd_hda_codec_generic snd_hda_intel kvm_intel snd_hda_codec dell_smm_hwmon snd_hda_core kvm nvidia(POE) snd_hwdep snd_pcm irqbypass snd_seq_midi snd_seq_midi_event snd_rawmidi input_leds drm_kms_helper snd_seq serio_raw drm lpc_ich ipmi_devintf snd_seq_device snd_timer ipmi_msghandler fb_sys_fops snd syscopyarea sysfillrect sysimgblt soundcore mac_hid i5000_edac i5k_amb shpchp sch_fq_codel parport_pc ppdev lp parport ip_tables x_tables autofs4 uas usb_storage hid_generic usbhid hid psmouse ahci tg3 libahci pata_acpi ptp pps_core
> [ 1010.455289] CPU: 3 PID: 1224 Comm: kworker/3:3 Tainted: P      D    OE    4.15.0-33-generic #36-Ubuntu
> [ 1010.455291] Hardware name: Dell Inc.                 Precision WorkStation 490    /0DT031, BIOS A08 04/25/2008
> [ 1010.455331] Workqueue: cifsiod smb2_reconnect_server [cifs]
> [ 1010.455338] RIP: 0010:kmem_cache_alloc+0x81/0x1b0
> [ 1010.455340] RSP: 0018:ffffbb25c35efb98 EFLAGS: 00010282
> [ 1010.455344] RAX: 9f912ff2d581367a RBX: 9f912ff2d581367a RCX: 0000000000006e1f
> [ 1010.455346] RDX: 0000000000006e1e RSI: 0000000001011200 RDI: 000000000002b530
> [ 1010.455348] RBP: ffffbb25c35efbc8 R08: ffff9e496fceb530 R09: 0000000000001690
> [ 1010.455351] R10: ffffbb25c35efd60 R11: ffffffff9c15380d R12: 0000000001011200
> [ 1010.455353] R13: ffff9e495c807080 R14: 9f912ff2d581367a R15: ffff9e495c807080
> [ 1010.455356] FS:  0000000000000000(0000) GS:ffff9e496fcc0000(0000) knlGS:0000000000000000
> [ 1010.455359] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
> [ 1010.455362] CR2: 00007fa344092000 CR3: 00000004164c2000 CR4: 00000000000006e0
> [ 1010.455364] Call Trace:
> [ 1010.455370]  ? mempool_alloc_slab+0x15/0x20
> [ 1010.455376]  ? wait_woken+0x80/0x80
> [ 1010.455380]  mempool_alloc_slab+0x15/0x20
> [ 1010.455383]  mempool_alloc+0x71/0x190
> [ 1010.455403]  cifs_small_buf_get+0x1a/0x30 [cifs]
> [ 1010.455424]  small_smb2_init+0x62/0xb0 [cifs]
> [ 1010.455445]  SMB2_negotiate+0x6a/0x7f0 [cifs]
> [ 1010.455449]  ? vprintk_default+0x29/0x50
> [ 1010.455452]  ? vprintk_func+0x27/0x60
> [ 1010.455455]  ? printk+0x52/0x6e
> [ 1010.455476]  smb2_negotiate+0x1d/0x30 [cifs]
> [ 1010.455496]  ? smb2_negotiate+0x1d/0x30 [cifs]
> [ 1010.455514]  cifs_negotiate_protocol+0x71/0xd0 [cifs]
> [ 1010.455535]  smb2_reconnect+0x101/0x410 [cifs]
> [ 1010.455541]  ? __internal_add_timer+0x1f/0x60
> [ 1010.455562]  smb2_reconnect_server+0x190/0x2d0 [cifs]
> [ 1010.455566]  process_one_work+0x1de/0x410
> [ 1010.455570]  worker_thread+0x32/0x410
> [ 1010.455573]  kthread+0x121/0x140
> [ 1010.455576]  ? process_one_work+0x410/0x410
> [ 1010.455579]  ? kthread_create_worker_on_cpu+0x70/0x70
> [ 1010.455584]  ret_from_fork+0x35/0x40
> [ 1010.455587] Code: 8b 5c 65 49 83 78 10 00 4d 8b 30 0f 84 00 01 00 00 4d 85 f6 0f 84 f7 00 00 00 49 63 5f 20 49 8b 3f 48 8d 4a 01 4c 89 f0 4c 01 f3 <48> 33 1b 49 33 9f 40 01 00 00 65 48 0f c7 0f 0f 94 c0 84 c0 74
> [ 1010.455635] RIP: kmem_cache_alloc+0x81/0x1b0 RSP: ffffbb25c35efb98
> [ 1010.455667] ---[ end trace 5ba833e5337f020a ]---
> [ 1023.439350] /build/linux-81MBYC/linux-4.15.0/fs/cifs/inode.c: CIFS VFS: in cifs_revalidate_dentry_attr as Xid: 1273 with uid: 0
> [ 1023.439360] /build/linux-81MBYC/linux-4.15.0/fs/cifs/inode.c: Update attributes:  inode 0x00000000a5058640 count 1 dentry: 0x0000000052484a7f d_time 0 jiffies 4295148268
>
> ... after this there are still a couple of oopses or protection faults, but I think the most important things take place before the first one ...
>
>


-- 
Thanks,

Steve




[Linux USB Devel]     [Video for Linux]     [Linux Audio Users]     [Yosemite News]     [Linux Kernel]     [Linux SCSI]

  Powered by Linux