virtualbox 6.1.40 crashes on ubuntu 20.04.5

[Chris Friesen]

Fairly frequently, while running scripted operations using vboxmanage, I end up with a VM that aborts.

The kernel log looks like this:

[4767262.949705] vboxdrv: Found 36 processor cores
[4767262.968599] vboxdrv: TSC mode is Invariant, tentative frequency 2999981764 Hz
[4767262.968604] vboxdrv: Successfully loaded version 6.1.40 r154048 (interface 0x00320000)
[4767263.296728] VBoxNetFlt: Successfully started.
[4767263.326045] VBoxNetAdp: Successfully started.
[4767341.590392] SUPR0GipMap: fGetGipCpu=0x1b
[4767342.466796] vboxdrv: 0000000000000000 VMMR0.r0
[4767342.643994] vboxdrv: 0000000000000000 VBoxDDR0.r0
[4767342.690265] VMMR0InitVM: eflags=246 fKernelFeatures=0x0 (SUPKERNELFEATURES_SMAP=0)
[4768567.806720] vboxdrv: 0000000000000000 VMMR0.r0
[4768567.996711] vboxdrv: 0000000000000000 VBoxDDR0.r0
[4768568.045773] VMMR0InitVM: eflags=246 fKernelFeatures=0x0 (SUPKERNELFEATURES_SMAP=0)
[4768823.978453] traps: CharIo[3296011] general protection fault ip:7f670c0b1cec sp:7f66da914c88 error:0 in VBoxRT.so[7f670bf42000+1ca000]
[4768823.979645] Core dump to |/lib/systemd/systemd-coredump pipe failed


The VM logs leading up to the crash are as follows:
00:03:13.164247 VMMDev: Guest Log: BIOS: AHCI 2-P#2: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000001388000 sectors
00:03:17.611998 PIT: mode=2 count=0x48d3 (18643) - 64.00 Hz (ch=0)
00:03:17.648621 Display::i_handleDisplayResize: uScreenId=0 pvVRAM=00007f66e550d000 w=640 h=480 bpp=32 cbLine=0xA00 flags=0x0 origin=0,0
00:03:20.112117 Display::i_handleDisplayResize: uScreenId=0 pvVRAM=0000000000000000 w=720 h=400 bpp=0 cbLine=0x0 flags=0x0 origin=0,0
00:03:20.113956 PIT: mode=2 count=0x10000 (65536) - 18.20 Hz (ch=0)
00:03:20.114328 VMMDev: Guest Log: BIOS: Boot : bseqnr=1, bootseq=0423
00:03:20.115032 VMMDev: Guest Log: BIOS: CDROM boot failure code : 0003
00:03:20.115301 VMMDev: Guest Log: BIOS: Boot from CD-ROM failed
00:03:20.115624 VMMDev: Guest Log: BIOS: Boot : bseqnr=2, bootseq=0042
00:03:20.116295 VMMDev: Guest Log: BIOS: Booting from Hard Disk...
00:03:20.205904 VMMDev: Guest Log: int13_harddisk: function 02, unmapped device for ELDL=83
00:03:20.206130 VMMDev: Guest Log: int13_harddisk: function 00, unmapped device for ELDL=83
00:03:20.206344 VMMDev: Guest Log: int13_harddisk: function 02, unmapped device for ELDL=83
00:03:20.206563 VMMDev: Guest Log: int13_harddisk: function 00, unmapped device for ELDL=83
00:03:20.206770 VMMDev: Guest Log: int13_harddisk: function 02, unmapped device for ELDL=83
00:03:20.206977 VMMDev: Guest Log: int13_harddisk: function 00, unmapped device for ELDL=83
00:03:24.841424 GIM: KVM: VCPU  0: Enabled system-time struct. at 0x0000000267601000 - u32TscScale=0xaaaaeae0 i8TscShift=-1 uVersion=2 fFlags=0x1 uTsc=0x87b44a98e uVirtNanoTS=0x2d3c29e21 TscKHz=2999982
00:03:24.841572 TM: Switching TSC mode from 'VirtTscEmulated' to 'RealTscOffset'
00:03:25.023046 GIM: KVM: Enabled wall-clock struct. at 0x0000000267600000 - u32Sec=1678830115 u32Nano=405292781 uVersion=2
00:03:25.122504 PIT: mode=2 count=0x4a9 (1193) - 1000.15 Hz (ch=0)
00:03:25.123298 APIC0: Switched mode to x2APIC
00:03:25.130906 IEM: rdmsr(0x4e) -> #GP(0)
00:03:25.249631 PIT: mode=0 count=0x10000 (65536) - 18.20 Hz (ch=0)
00:03:25.256732 APIC1: Switched mode to x2APIC
00:03:25.256739 GIM: KVM: VCPU  1: Enabled system-time struct. at 0x0000000267601040 - u32TscScale=0xaaaaeae0 i8TscShift=-1 uVersion=2 fFlags=0x1 uTsc=0x8c587ea79 uVirtNanoTS=0x2ec83bd14 TscKHz=2999982
00:03:25.261826 APIC2: Switched mode to x2APIC
00:03:25.261790 GIM: KVM: VCPU  2: Enabled system-time struct. at 0x0000000267601080 - u32TscScale=0xaaaaeae0 i8TscShift=-1 uVersion=2 fFlags=0x1 uTsc=0x8c670fc8d uVirtNanoTS=0x2ecd16de3 TscKHz=2999982
00:03:25.266295 APIC3: Switched mode to x2APIC
00:03:25.266217 GIM: KVM: VCPU  3: Enabled system-time struct. at 0x00000002676010c0 - u32TscScale=0xaaaaeae0 i8TscShift=-1 uVersion=2 fFlags=0x1 uTsc=0x8c73d516b uVirtNanoTS=0x2ed158a46 TscKHz=2999982
00:03:25.267282 IEM: rdmsr(0x4e) -> #GP(0)
00:03:25.258297 IEM: rdmsr(0x4e) -> #GP(0)
00:03:25.270909 APIC4: Switched mode to x2APIC
00:03:25.270945 GIM: KVM: VCPU  4: Enabled system-time struct. at 0x0000000267601100 - u32TscScale=0xaaaaeae0 i8TscShift=-1 uVersion=2 fFlags=0x1 uTsc=0x8c8118332 uVirtNanoTS=0x2ed5c45a3 TscKHz=2999982
00:03:25.271945 IEM: rdmsr(0x4e) -> #GP(0)
00:03:25.275316 APIC5: Switched mode to x2APIC
00:03:25.275301 GIM: KVM: VCPU  5: Enabled system-time struct. at 0x0000000267601140 - u32TscScale=0xaaaaeae0 i8TscShift=-1 uVersion=2 fFlags=0x1 uTsc=0x8c8da894f uVirtNanoTS=0x2ed9f47c6 TscKHz=2999982
00:03:25.279862 APIC6: Switched mode to x2APIC
00:03:25.279806 GIM: KVM: VCPU  6: Enabled system-time struct. at 0x0000000267601180 - u32TscScale=0xaaaaeae0 i8TscShift=-1 uVersion=2 fFlags=0x1 uTsc=0x8c9aa76bd uVirtNanoTS=0x2ede49705 TscKHz=2999982
00:03:25.280912 IEM: rdmsr(0x4e) -> #GP(0)
00:03:25.284276 APIC7: Switched mode to x2APIC
00:03:25.284315 GIM: KVM: VCPU  7: Enabled system-time struct. at 0x00000002676011c0 - u32TscScale=0xaaaaeae0 i8TscShift=-1 uVersion=2 fFlags=0x1 uTsc=0x8ca74a0c3 uVirtNanoTS=0x2ee27fa76 TscKHz=2999982
00:03:25.285324 IEM: rdmsr(0x4e) -> #GP(0)
00:03:25.276352 IEM: rdmsr(0x4e) -> #GP(0)
00:03:25.262854 IEM: rdmsr(0x4e) -> #GP(0)
00:03:29.241357 IEM: rdmsr(0xc0011029) -> #GP(0)
00:03:29.542677 PIIX3 ATA: Ctl#1: RESET, DevSel=0 AIOIf=0 CmdIf0=0xa0 (-1 usec ago) CmdIf1=0x00 (-1 usec ago)
00:03:29.542759 PIIX3 ATA: Ctl#1: finished processing RESET
00:03:29.818815 AHCI#0: Reset the HBA
00:03:29.818836 VD#0: Cancelling all active requests
00:03:29.818840 VD#1: Cancelling all active requests
00:03:29.818842 VD#2: Cancelling all active requests
00:03:29.890597 AHCI#0: Port 0 reset
00:03:29.891786 VD#0: Cancelling all active requests
00:03:30.662210 AHCI#0: Port 1 reset
00:03:30.663473 VD#1: Cancelling all active requests
00:03:31.310798 AHCI#0: Port 2 reset
00:03:31.312098 VD#2: Cancelling all active requests

(that's the end of the log file)

[Chris Friesen]

I've confirmed that 6.1.38 also shows the same problem. The VM just crashed with the following kernel log on the host:

[4772713.567279] traps: CharIo[3455726] general protection fault ip:7f219559983c sp:7f21340fdc88 error:0 in VBoxRT.so[7f219542a000+1ca000]
[4772713.567787] Core dump to |/lib/systemd/systemd-coredump pipe failed

[galitsyn]

Hi cbf123,

Have you tried VirtualBox 6.1.42 or 7.0.6? If you still have the issue with the latest version, could you please try to get a core dump following the instructions from https://www.virtualbox.org/wiki/Core_dump?