[Pkg-virtualbox-devel] Bug#721688: BUG: soft lockup, leading to host system being unusable

[Will Aoki]

Here's another crash with identical symptoms. The function at the top of
the stack is different, but in both cases they were called from
VBoxDrvLinuxIOCtl_4_1_18.

Nov 23 23:49:28 desk1 kernel: [47739.677801] BUG: soft lockup - CPU#5 stuck for 23s! [VirtualBox:7821]
Nov 23 23:49:28 desk1 kernel: [47739.677806] Modules linked in: parport_pc ppdev lp parport snd_hrtimer cpufreq_stats cpufreq_powersave cpufreq_userspace cpufreq_conservative pci_stub vboxpci(O) vboxnetadp(O) vboxnetflt(O) vboxdrv(O) nfsd nfs nfs_acl auth_rpcgss fscache lockd sunrpc xfs loop snd_hda_codec_hdmi snd_hda_codec_via snd_hda_intel snd_hda_codec snd_hwdep snd_pcm_oss snd_mixer_oss snd_pcm snd_page_alloc snd_seq_midi snd_seq_midi_event i915 snd_rawmidi snd_seq snd_seq_device snd_timer drm_kms_helper snd drm iTCO_wdt i2c_i801 soundcore i2c_algo_bit iTCO_vendor_support psmouse acpi_cpufreq joydev i2c_core coretemp mperf serio_raw pcspkr evdev video processor button ext4 crc16 jbd2 mbcache sha256_generic dm_crypt dm_mod raid1 md_mod microcode hid_microsoft usbhid hid sg sr_mod cdrom sd_mod crc_t10dif crc32c_intel ghash_clmulni_intel aesni_intel aes_x86_64 aes_generic cryptd thermal ehci_hcd xhci_hcd ahci libahci fan thermal_sys libata usbcore scsi_mod atl1c usb_common [last unloaded: scsi_wait_scan]
Nov 23 23:49:28 desk1 kernel: [47739.677881] CPU 5 
Nov 23 23:49:28 desk1 kernel: [47739.677883] Modules linked in: parport_pc ppdev lp parport snd_hrtimer cpufreq_stats cpufreq_powersave cpufreq_userspace cpufreq_conservative pci_stub vboxpci(O) vboxnetadp(O) vboxnetflt(O) vboxdrv(O) nfsd nfs nfs_acl auth_rpcgss fscache lockd sunrpc xfs loop snd_hda_codec_hdmi snd_hda_codec_via snd_hda_intel snd_hda_codec snd_hwdep snd_pcm_oss snd_mixer_oss snd_pcm snd_page_alloc snd_seq_midi snd_seq_midi_event i915 snd_rawmidi snd_seq snd_seq_device snd_timer drm_kms_helper snd drm iTCO_wdt i2c_i801 soundcore i2c_algo_bit iTCO_vendor_support psmouse acpi_cpufreq joydev i2c_core coretemp mperf serio_raw pcspkr evdev video processor button ext4 crc16 jbd2 mbcache sha256_generic dm_crypt dm_mod raid1 md_mod microcode hid_microsoft usbhid hid sg sr_mod cdrom sd_mod crc_t10dif crc32c_intel ghash_clmulni_intel aesni_intel aes_x86_64 aes_generic cryptd thermal ehci_hcd xhci_hcd ahci libahci fan thermal_sys libata usbcore scsi_mod atl1c usb_common [last unloaded: scsi_wait_scan]
Nov 23 23:49:28 desk1 kernel: [47739.677942] 
Nov 23 23:49:28 desk1 kernel: [47739.677945] Pid: 7821, comm: VirtualBox Tainted: G           O 3.2.0-4-amd64 #1 Debian 3.2.51-1 Gigabyte Technology Co., Ltd. To be filled by O.E.M./Z77-D3H
Nov 23 23:49:28 desk1 kernel: [47739.677951] RIP: 0010:[<ffffffff810707cb>]  [<ffffffff810707cb>] csd_lock+0x8/0x14
Nov 23 23:49:28 desk1 kernel: [47739.677959] RSP: 0018:ffff8803a1c2dc80  EFLAGS: 00000202
Nov 23 23:49:28 desk1 kernel: [47739.677961] RAX: 00000000fffffffa RBX: ffffffffa05e3b8e RCX: 0000000000000000
Nov 23 23:49:28 desk1 kernel: [47739.677964] RDX: 00000000ffffffff RSI: ffffffffa0556cc9 RDI: ffff88041f354280
Nov 23 23:49:28 desk1 kernel: [47739.677966] RBP: ffffffffa0556cc9 R08: ffff88040b8be810 R09: ffff88040b8be810
Nov 23 23:49:28 desk1 kernel: [47739.677968] R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
Nov 23 23:49:28 desk1 kernel: [47739.677971] R13: 0000000000015bd9 R14: ffffc90006e26000 R15: ffffc90006e01000
Nov 23 23:49:28 desk1 kernel: [47739.677974] FS:  00007fd7b2e98700(0000) GS:ffff88041f340000(0000) knlGS:0000000000000000
Nov 23 23:49:28 desk1 kernel: [47739.677977] CS:  0010 DS: 0000 ES: 0000 CR0: 000000008005003b
Nov 23 23:49:28 desk1 kernel: [47739.677979] CR2: 0000000002260000 CR3: 00000003dbb5b000 CR4: 00000000001426e0
Nov 23 23:49:28 desk1 kernel: [47739.677982] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
Nov 23 23:49:28 desk1 kernel: [47739.677984] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
Nov 23 23:49:28 desk1 kernel: [47739.677987] Process VirtualBox (pid: 7821, threadinfo ffff8803a1c2c000, task ffff880409c9a180)
Nov 23 23:49:28 desk1 kernel: [47739.677990] Stack:
Nov 23 23:49:28 desk1 kernel: [47739.677991]  ffffffff81070984 ffffffff8134f247 0000000000000000 0000000000000000
Nov 23 23:49:28 desk1 kernel: [47739.677996]  0000000000000000 0000000000000000 0000000000000000 0000000000000004
Nov 23 23:49:28 desk1 kernel: [47739.678000]  ffff8803a1c2dd28 0000000000000001 ffffc90006e01000 0000000000000000
Nov 23 23:49:28 desk1 kernel: [47739.678005] Call Trace:
Nov 23 23:49:28 desk1 kernel: [47739.678009]  [<ffffffff81070984>] ? smp_call_function_single+0xce/0xf2
Nov 23 23:49:28 desk1 kernel: [47739.678015]  [<ffffffff8134f247>] ? _raw_spin_unlock_irqrestore+0xe/0xf
Nov 23 23:49:28 desk1 kernel: [47739.678028]  [<ffffffffa0556e45>] ? VBoxHost_RTMpPokeCpu+0x33/0x39 [vboxdrv]
Nov 23 23:49:28 desk1 kernel: [47739.678042]  [<ffffffffa0555845>] ? rtR0MemAllocEx+0xba/0x112 [vboxdrv]
Nov 23 23:49:28 desk1 kernel: [47739.678052]  [<ffffffffa0551b54>] ? supdrvIOCtl+0x11f6/0x2277 [vboxdrv]
Nov 23 23:49:28 desk1 kernel: [47739.678060]  [<ffffffffa0555845>] ? rtR0MemAllocEx+0xba/0x112 [vboxdrv]
Nov 23 23:49:28 desk1 kernel: [47739.678068]  [<ffffffffa054d35e>] ? VBoxDrvLinuxIOCtl_4_1_18+0x132/0x1b0 [vboxdrv]
Nov 23 23:49:28 desk1 kernel: [47739.678075]  [<ffffffff81107b11>] ? do_vfs_ioctl+0x459/0x49a
Nov 23 23:49:28 desk1 kernel: [47739.678080]  [<ffffffff81039ac2>] ? finish_task_switch+0x88/0xb9
Nov 23 23:49:28 desk1 kernel: [47739.678084]  [<ffffffff8134de91>] ? __schedule+0x5f9/0x610
Nov 23 23:49:28 desk1 kernel: [47739.678088]  [<ffffffff81107b9d>] ? sys_ioctl+0x4b/0x72
Nov 23 23:49:28 desk1 kernel: [47739.678092]  [<ffffffff81354212>] ? system_call_fastpath+0x16/0x1b
Nov 23 23:49:28 desk1 kernel: [47739.678094] Code: 9c 58 0f 1f 44 00 00 c3 57 9d 0f 1f 44 00 00 c3 e8 eb ff ff ff 48 89 c2 fa 66 0f 1f 44 00 00 48 89 d0 c3 eb 02 f3 90 f6 47 20 01 <75> f8 66 c7 47 20 01 00 0f ae f0 c3 48 c7 c0 c0 41 01 00 48 8b 
Nov 23 23:49:28 desk1 kernel: [47739.678133] Call Trace:
Nov 23 23:49:28 desk1 kernel: [47739.678136]  [<ffffffff81070984>] ? smp_call_function_single+0xce/0xf2
Nov 23 23:49:28 desk1 kernel: [47739.678140]  [<ffffffff8134f247>] ? _raw_spin_unlock_irqrestore+0xe/0xf
Nov 23 23:49:28 desk1 kernel: [47739.678149]  [<ffffffffa0556e45>] ? VBoxHost_RTMpPokeCpu+0x33/0x39 [vboxdrv]
Nov 23 23:49:28 desk1 kernel: [47739.678160]  [<ffffffffa0555845>] ? rtR0MemAllocEx+0xba/0x112 [vboxdrv]
Nov 23 23:49:28 desk1 kernel: [47739.678169]  [<ffffffffa0551b54>] ? supdrvIOCtl+0x11f6/0x2277 [vboxdrv]
Nov 23 23:49:28 desk1 kernel: [47739.678176]  [<ffffffffa0555845>] ? rtR0MemAllocEx+0xba/0x112 [vboxdrv]
Nov 23 23:49:28 desk1 kernel: [47739.678184]  [<ffffffffa054d35e>] ? VBoxDrvLinuxIOCtl_4_1_18+0x132/0x1b0 [vboxdrv]
Nov 23 23:49:28 desk1 kernel: [47739.678189]  [<ffffffff81107b11>] ? do_vfs_ioctl+0x459/0x49a
Nov 23 23:49:28 desk1 kernel: [47739.678193]  [<ffffffff81039ac2>] ? finish_task_switch+0x88/0xb9
Nov 23 23:49:28 desk1 kernel: [47739.678196]  [<ffffffff8134de91>] ? __schedule+0x5f9/0x610
Nov 23 23:49:28 desk1 kernel: [47739.678200]  [<ffffffff81107b9d>] ? sys_ioctl+0x4b/0x72
Nov 23 23:49:28 desk1 kernel: [47739.678203]  [<ffffffff81354212>] ? system_call_fastpath+0x16/0x1b
Nov 23 23:49:56 desk1 kernel: [47767.627998] BUG: soft lockup - CPU#5 stuck for 23s! [VirtualBox:7821]
Nov 23 23:49:56 desk1 kernel: [47767.628002] Modules linked in: parport_pc ppdev lp parport snd_hrtimer cpufreq_stats cpufreq_powersave cpufreq_userspace cpufreq_conservative pci_stub vboxpci(O) vboxnetadp(O) vboxnetflt(O) vboxdrv(O) nfsd nfs nfs_acl auth_rpcgss fscache lockd sunrpc xfs loop snd_hda_codec_hdmi snd_hda_codec_via snd_hda_intel snd_hda_codec snd_hwdep snd_pcm_oss snd_mixer_oss snd_pcm snd_page_alloc snd_seq_midi snd_seq_midi_event i915 snd_rawmidi snd_seq snd_seq_device snd_timer drm_kms_helper snd drm iTCO_wdt i2c_i801 soundcore i2c_algo_bit iTCO_vendor_support psmouse acpi_cpufreq joydev i2c_core coretemp mperf serio_raw pcspkr evdev video processor button ext4 crc16 jbd2 mbcache sha256_generic dm_crypt dm_mod raid1 md_mod microcode hid_microsoft usbhid hid sg sr_mod cdrom sd_mod crc_t10dif crc32c_intel ghash_clmulni_intel aesni_intel aes_x86_64 aes_generic cryptd thermal ehci_hcd xhci_hcd ahci libahci fan thermal_sys libata usbcore scsi_mod atl1c usb_common [last unloaded: scsi_wait_scan]
Nov 23 23:49:56 desk1 kernel: [47767.628077] CPU 5 
Nov 23 23:49:56 desk1 kernel: [47767.628079] Modules linked in: parport_pc ppdev lp parport snd_hrtimer cpufreq_stats cpufreq_powersave cpufreq_userspace cpufreq_conservative pci_stub vboxpci(O) vboxnetadp(O) vboxnetflt(O) vboxdrv(O) nfsd nfs nfs_acl auth_rpcgss fscache lockd sunrpc xfs loop snd_hda_codec_hdmi snd_hda_codec_via snd_hda_intel snd_hda_codec snd_hwdep snd_pcm_oss snd_mixer_oss snd_pcm snd_page_alloc snd_seq_midi snd_seq_midi_event i915 snd_rawmidi snd_seq snd_seq_device snd_timer drm_kms_helper snd drm iTCO_wdt i2c_i801 soundcore i2c_algo_bit iTCO_vendor_support psmouse acpi_cpufreq joydev i2c_core coretemp mperf serio_raw pcspkr evdev video processor button ext4 crc16 jbd2 mbcache sha256_generic dm_crypt dm_mod raid1 md_mod microcode hid_microsoft usbhid hid sg sr_mod cdrom sd_mod crc_t10dif crc32c_intel ghash_clmulni_intel aesni_intel aes_x86_64 aes_generic cryptd thermal ehci_hcd xhci_hcd ahci libahci fan thermal_sys libata usbcore scsi_mod atl1c usb_common [last unloaded: scsi_wait_scan]
Nov 23 23:49:56 desk1 kernel: [47767.628138] 
Nov 23 23:49:56 desk1 kernel: [47767.628141] Pid: 7821, comm: VirtualBox Tainted: G           O 3.2.0-4-amd64 #1 Debian 3.2.51-1 Gigabyte Technology Co., Ltd. To be filled by O.E.M./Z77-D3H
Nov 23 23:49:56 desk1 kernel: [47767.628147] RIP: 0010:[<ffffffff810707cb>]  [<ffffffff810707cb>] csd_lock+0x8/0x14
Nov 23 23:49:56 desk1 kernel: [47767.628155] RSP: 0018:ffff8803a1c2dc80  EFLAGS: 00000202
Nov 23 23:49:56 desk1 kernel: [47767.628157] RAX: 00000000fffffffa RBX: ffffffffa05e3b8e RCX: 0000000000000000
Nov 23 23:49:56 desk1 kernel: [47767.628160] RDX: 00000000ffffffff RSI: ffffffffa0556cc9 RDI: ffff88041f354280
Nov 23 23:49:56 desk1 kernel: [47767.628162] RBP: ffffffffa0556cc9 R08: ffff88040b8be810 R09: ffff88040b8be810
Nov 23 23:49:56 desk1 kernel: [47767.628165] R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
Nov 23 23:49:56 desk1 kernel: [47767.628167] R13: 0000000000015bd9 R14: ffffc90006e26000 R15: ffffc90006e01000
Nov 23 23:49:56 desk1 kernel: [47767.628170] FS:  00007fd7b2e98700(0000) GS:ffff88041f340000(0000) knlGS:0000000000000000
Nov 23 23:49:56 desk1 kernel: [47767.628173] CS:  0010 DS: 0000 ES: 0000 CR0: 000000008005003b
Nov 23 23:49:56 desk1 kernel: [47767.628176] CR2: 0000000002260000 CR3: 00000003dbb5b000 CR4: 00000000001426e0
Nov 23 23:49:56 desk1 kernel: [47767.628178] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
Nov 23 23:49:56 desk1 kernel: [47767.628181] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
Nov 23 23:49:56 desk1 kernel: [47767.628184] Process VirtualBox (pid: 7821, threadinfo ffff8803a1c2c000, task ffff880409c9a180)
Nov 23 23:49:56 desk1 kernel: [47767.628186] Stack:
Nov 23 23:49:56 desk1 kernel: [47767.628190]  ffffffff81070984 ffffffff8134f247 0000000000000000 0000000000000000
Nov 23 23:49:56 desk1 kernel: [47767.628195]  0000000000000000 0000000000000000 0000000000000000 0000000000000004
Nov 23 23:49:56 desk1 kernel: [47767.628199]  ffff8803a1c2dd28 0000000000000001 ffffc90006e01000 0000000000000000
Nov 23 23:49:56 desk1 kernel: [47767.628203] Call Trace:
Nov 23 23:49:56 desk1 kernel: [47767.628208]  [<ffffffff81070984>] ? smp_call_function_single+0xce/0xf2
Nov 23 23:49:56 desk1 kernel: [47767.628213]  [<ffffffff8134f247>] ? _raw_spin_unlock_irqrestore+0xe/0xf
Nov 23 23:49:56 desk1 kernel: [47767.628226]  [<ffffffffa0556e45>] ? VBoxHost_RTMpPokeCpu+0x33/0x39 [vboxdrv]
Nov 23 23:49:56 desk1 kernel: [47767.628239]  [<ffffffffa0555845>] ? rtR0MemAllocEx+0xba/0x112 [vboxdrv]
Nov 23 23:49:56 desk1 kernel: [47767.628250]  [<ffffffffa0551b54>] ? supdrvIOCtl+0x11f6/0x2277 [vboxdrv]
Nov 23 23:49:56 desk1 kernel: [47767.628257]  [<ffffffffa0555845>] ? rtR0MemAllocEx+0xba/0x112 [vboxdrv]
Nov 23 23:49:56 desk1 kernel: [47767.628266]  [<ffffffffa054d35e>] ? VBoxDrvLinuxIOCtl_4_1_18+0x132/0x1b0 [vboxdrv]
Nov 23 23:49:56 desk1 kernel: [47767.628272]  [<ffffffff81107b11>] ? do_vfs_ioctl+0x459/0x49a
Nov 23 23:49:56 desk1 kernel: [47767.628277]  [<ffffffff81039ac2>] ? finish_task_switch+0x88/0xb9
Nov 23 23:49:56 desk1 kernel: [47767.628280]  [<ffffffff8134de91>] ? __schedule+0x5f9/0x610
Nov 23 23:49:56 desk1 kernel: [47767.628284]  [<ffffffff81107b9d>] ? sys_ioctl+0x4b/0x72
Nov 23 23:49:56 desk1 kernel: [47767.628288]  [<ffffffff81354212>] ? system_call_fastpath+0x16/0x1b
Nov 23 23:49:56 desk1 kernel: [47767.628290] Code: 9c 58 0f 1f 44 00 00 c3 57 9d 0f 1f 44 00 00 c3 e8 eb ff ff ff 48 89 c2 fa 66 0f 1f 44 00 00 48 89 d0 c3 eb 02 f3 90 f6 47 20 01 <75> f8 66 c7 47 20 01 00 0f ae f0 c3 48 c7 c0 c0 41 01 00 48 8b 
Nov 23 23:49:56 desk1 kernel: [47767.628329] Call Trace:
Nov 23 23:49:56 desk1 kernel: [47767.628332]  [<ffffffff81070984>] ? smp_call_function_single+0xce/0xf2
Nov 23 23:49:56 desk1 kernel: [47767.628336]  [<ffffffff8134f247>] ? _raw_spin_unlock_irqrestore+0xe/0xf
Nov 23 23:49:56 desk1 kernel: [47767.628345]  [<ffffffffa0556e45>] ? VBoxHost_RTMpPokeCpu+0x33/0x39 [vboxdrv]
Nov 23 23:49:56 desk1 kernel: [47767.628356]  [<ffffffffa0555845>] ? rtR0MemAllocEx+0xba/0x112 [vboxdrv]
Nov 23 23:49:56 desk1 kernel: [47767.628365]  [<ffffffffa0551b54>] ? supdrvIOCtl+0x11f6/0x2277 [vboxdrv]
Nov 23 23:49:56 desk1 kernel: [47767.628372]  [<ffffffffa0555845>] ? rtR0MemAllocEx+0xba/0x112 [vboxdrv]
Nov 23 23:49:56 desk1 kernel: [47767.628380]  [<ffffffffa054d35e>] ? VBoxDrvLinuxIOCtl_4_1_18+0x132/0x1b0 [vboxdrv]
Nov 23 23:49:56 desk1 kernel: [47767.628385]  [<ffffffff81107b11>] ? do_vfs_ioctl+0x459/0x49a
Nov 23 23:49:56 desk1 kernel: [47767.628389]  [<ffffffff81039ac2>] ? finish_task_switch+0x88/0xb9
Nov 23 23:49:56 desk1 kernel: [47767.628392]  [<ffffffff8134de91>] ? __schedule+0x5f9/0x610
Nov 23 23:49:56 desk1 kernel: [47767.628396]  [<ffffffff81107b9d>] ? sys_ioctl+0x4b/0x72
Nov 23 23:49:56 desk1 kernel: [47767.628400]  [<ffffffff81354212>] ? system_call_fastpath+0x16/0x1b