Vbox Crashes on shut down with linux guest (32 or 64 bit)

[Joat_Mon]

I am a Linux newbie. This whole exercise is to learn a little bit about Linux.

I have install Linux Mint Cinnamon 16 (tried both 32bit and 64bit versions).

Both versions run fine as a virtual guest.

When I close the VM (Save, Send Shutdown Sig, or Power Off), VirtualBox crashes. The VirtualBox Manager posts 'Aborted' for the power status of the VM.

I am able to start it back up, but at the 'select startup disk' prompt, I have to hit 'cancel' to continue the startup. It doesn't seem right that Linux is even asking for startup disk.

Here is a partial VM log:

Code: Select all

VirtualBox VM 4.3.8 r92456 win.amd64 (Feb 25 2014 18:23:31) release log
00:00:07.478959 Log opened 2014-03-18T21:02:19.405729200Z
00:00:07.478960 Build Type: release
00:00:07.478975 OS Product: Windows Vista Business Edition
00:00:07.478977 OS Release: 6.0.6000
00:00:07.478977 OS Service Pack: 
00:00:07.478987 Host RAM: 8189MB total, 5846MB available
00:00:07.478988 Executable: C:\Program Files\Oracle\VirtualBox\VirtualBox.exe
00:00:07.478989 Process ID: 3068
00:00:07.478990 Package type: WINDOWS_64BITS_GENERIC
00:00:07.493442 Installed Extension Packs:
00:00:07.493479   Oracle VM VirtualBox Extension Pack (Version: 4.3.8 r92456; VRDE Module: VBoxVRDP)
00:00:07.528634 SUP: Loaded VMMR0.r0 (C:\Program Files\Oracle\VirtualBox\VMMR0.r0) at 0xfffff8800c804000 - ModuleInit at fffff8800c823240 and ModuleTerm at fffff8800c823550 using the native ring-0 loader
00:00:07.528751 SUP: VMMR0EntryEx located at fffff8800c824780, VMMR0EntryFast at fffff8800c8238d0 and VMMR0EntryInt at fffff8800c8238c0
00:00:07.528760 SUP: windbg> .reload /f C:\Program Files\Oracle\VirtualBox\VMMR0.r0=0xfffff8800c804000
00:00:07.554019 Guest OS type: 'Ubuntu'
00:00:07.641822 File system of 'C:\Users\Jim\VirtualBox VMs\Linux-Cinnamon\Snapshots' (snapshots) is ntfs
00:00:07.641836 File system of 'C:\Users\Jim\VirtualBox VMs\Linux-Cinnamon\Linux-Cinnamon.vdi' is ntfs
00:00:07.861405 Shared clipboard mode: Off
00:00:07.863605 Drag'n'drop mode: Off
00:00:11.395770 OpenGL Info: This is an ATI card, taking care of fullscreen..

00:00:11.431619 
00:00:11.431619 [/Devices/VMMDev/0/LUN#999/] (level 4)
00:00:11.431620   Driver <string>  = "MainStatus" (cb=11)
00:00:11.431621 
00:00:11.431622 [/Devices/VMMDev/0/LUN#999/Config/] (level 5)
00:00:11.431623   First   <integer> = 0x0000000000000000 (0)
00:00:11.431624   Last    <integer> = 0x0000000000000000 (0)
00:00:11.431625   papLeds <integer> = 0x0000000004c056b8 (79 713 976)
00:00:11.431626 
00:00:11.431627 [/Devices/acpi/] (level 2)
00:00:11.431628 
00:00:11.431628 [/Devices/acpi/0/] (level 3)
00:00:11.431629   PCIBusNo      <integer> = 0x0000000000000000 (0)
00:00:11.431630   PCIDeviceNo   <integer> = 0x0000000000000007 (7)
00:00:11.431631   PCIFunctionNo <integer> = 0x0000000000000000 (0)
00:00:11.431632   Trusted       <integer> = 0x0000000000000001 (1)
00:00:11.431633 
00:00:11.431633 [/Devices/acpi/0/Config/] (level 4)
00:00:11.431635   CpuHotPlug        <integer> = 0x0000000000000000 (0)
00:00:11.431636   FdcEnabled        <integer> = 0x0000000000000000 (0)
00:00:11.431637   HostBusPciAddress <integer> = 0x0000000000000000 (0)
00:00:11.431638   HpetEnabled       <integer> = 0x0000000000000000 (0)
00:00:11.431638   IOAPIC            <integer> = 0x0000000000000000 (0)
00:00:11.431639   IocPciAddress     <integer> = 0x0000000000010000 (65 536)
00:00:11.431640   NumCPUs           <integer> = 0x0000000000000001 (1)
00:00:11.431641   RamHoleSize       <integer> = 0x0000000020000000 (536 870 912, 512 MB)
00:00:11.431643   RamSize           <integer> = 0x0000000020000000 (536 870 912, 512 MB)
00:00:11.431645   Serial0IoPortBase <integer> = 0x0000000000000000 (0)
00:00:11.431645   Serial0Irq        <integer> = 0x0000000000000000 (0)
00:00:11.431646   Serial1IoPortBase <integer> = 0x0000000000000000 (0)
00:00:11.431647   Serial1Irq        <integer> = 0x0000000000000000 (0)
00:00:11.431648   ShowCpu           <integer> = 0x0000000000000000 (0)
00:00:11.431649   ShowRtc           <integer> = 0x0000000000000000 (0)
00:00:11.431650   SmcEnabled        <integer> = 0x0000000000000000 (0)
00:00:11.431651 
 
00:01:12.561114 Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xdb19e610), OR(0x0), NOT(0xffffffff), flags(0x0)
00:02:04.812027 Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xdba45010), OR(0x0), NOT(0xffffffff), flags(0x0)
00:02:04.812164 Guest Additions capability report: (0x5 -> 0x4) seamless: no, hostWindowMapping: no, graphics: yes
00:02:04.813259 Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xd9e41a10), OR(0x0), NOT(0xffffffff), flags(0x0)
00:02:04.816236 Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xd9e24c10), OR(0x0), NOT(0xffffffff), flags(0x0)
00:02:05.079718 Guest Additions capability report: (0x4 -> 0x4) seamless: no, hostWindowMapping: no, graphics: yes
00:02:05.080102 VBVA_INFO_SCREEN: [0] @0,0 1440x1050, line 0x1680, BPP 32, flags 0x1
00:02:05.080137 Guest Additions capability report: (0x4 -> 0x0) seamless: no, hostWindowMapping: no, graphics: no
00:02:05.103162 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=000000000e9b0000 w=640 h=480 bpp=32 cbLine=0xA00, flags=0x1
00:02:05.270314 Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xda12b410), OR(0x0), NOT(0xffffffff), flags(0x0)
00:02:07.025285 Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xdb67b210), OR(0x0), NOT(0xffffffff), flags(0x0)
00:02:08.494632 Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xd9ad8e10), OR(0x0), NOT(0xffffffff), flags(0x0)
00:02:18.231574 Changing the VM state from 'RUNNING' to 'SUSPENDING'.
00:02:18.234341 AIOMgr: Endpoint for file 'C:\Users\Jim\VirtualBox VMs\Linux-Cinnamon\Linux-Cinnamon.vdi' (flags 000c0781) created successfully
00:02:18.336810 PDMR3Suspend: 105 040 459 ns run time
00:02:18.336860 Changing the VM state from 'SUSPENDING' to 'SUSPENDED'.
00:02:20.548846 Changing the VM state from 'SUSPENDED' to 'SAVING'.
00:02:20.549586 VUSB: detached 'HidMouse' from port 1
00:02:23.017336 SSM: Footer at 0xef08e14 (250646036), 33 directory entries.
00:02:23.017400 VUSB: attached 'HidMouse' to port 1
00:02:23.017686 SSM: Successfully saved the VM state to 'C:\Users\Jim\VirtualBox VMs\Linux-Cinnamon\Snapshots\2014-03-18T21-04-32-457124600Z.sav'
00:02:23.017721 Changing the VM state from 'SAVING' to 'SUSPENDED'.
00:02:23.017809 Console::powerDown(): A request to power off the VM has been issued (mMachineState=Saving, InUninit=0)
00:02:23.017846 Changing the VM state from 'SUSPENDED' to 'POWERING_OFF'.
00:02:23.017877 ****************** Guest state at power off ******************
00:02:23.017887 Guest CPUM (VCPU 0) state: 
00:02:23.017892 eax=00000000 ebx=c18cbf90 ecx=00000000 edx=00000000 esi=0008f800 edi=00000000
00:02:23.017895 eip=c10446c5 esp=c18cbf6c ebp=c18cbf6c iopl=0         nv up ei pl nz na po nc
00:02:23.017898 cs={0060 base=0000000000000000 limit=ffffffff flags=0000c09b} dr0=00000000 dr1=00000000
00:02:23.017903 ds={007b base=0000000000000000 limit=ffffffff flags=0000c0f3} dr2=00000000 dr3=00000000
00:02:23.017905 es={007b base=0000000000000000 limit=ffffffff flags=0000c0f3} dr4=00000000 dr5=00000000
00:02:23.017907 fs={00d8 base=000000001e1a9000 limit=ffffffff flags=00008093} dr6=ffff0ff0 dr7=00000400
00:02:23.017910 gs={00e0 base=00000000dfbe6040 limit=00000018 flags=00004091} cr0=8005003b cr2=b7645720
00:02:23.017912 ss={0068 base=0000000000000000 limit=ffffffff flags=0000c093} cr3=19b19000 cr4=000006f0
00:02:23.017914 gdtr=00000000dfbde000:00ff  idtr=00000000fffba000:07ff  eflags=00200246
00:02:23.017916 ldtr={0000 base=00000000 limit=0000ffff flags=00000000}
00:02:23.017917 tr  ={0080 base=dfbe3ec0 limit=0000206b flags=00000089}
00:02:23.017918 SysEnter={cs=0060 eip=c1632c44 esp=dfbe6040}
00:02:23.017919 FCW=037f FSW=0020 FTW=0000 FOP=0000 MXCSR=00001f80 MXCSR_MASK=0002ffff
00:02:23.017921 FPUIP=00000000 CS=0000 Rsrvd1=0000  FPUDP=00000000 DS=0000 Rsvrd2=0000
00:02:23.017923 ST(0)=FPR0={bffb'8a3d8980'68ab8c1a} t0 -1.0000737897098563521562 ^ 16379
00:02:23.017926 ST(1)=FPR1={bffb'8a3d8980'68ab8c1a} t0 -1.0000737897098563521562 ^ 16379
00:02:23.017929 ST(2)=FPR2={bffb'8a3d8980'68ab8c1a} t0 -1.0000737897098563521562 ^ 16379
00:02:23.017932 ST(3)=FPR3={bffb'8a3d8980'68ab8c1a} t0 -1.0000737897098563521562 ^ 16379
00:02:23.017935 ST(4)=FPR4={bffb'8a3d8980'68ab8c1a} t0 -1.0000737897098563521562 ^ 16379
00:02:23.017937 ST(5)=FPR5={bffb'8a3d8980'68ab8c1a} t0 -1.0000737897098563521562 ^ 16379
00:02:23.017940 ST(6)=FPR6={bffb'8a3d8980'68ab8c1a} t0 -1.0000737897098563521562 ^ 16379
00:02:23.017942 ST(7)=FPR7={bffb'8a3d8980'68ab8c1a} t0 -1.0000737897098563521562 ^ 16379
00:02:23.017945 XMM0 =00000000'00000000'00ff0000'00000000  XMM1 =2e687475'6f6d796c'70006873'616c7073
00:02:23.017948 XMM2 =00000000'00000000'00000000'00000000  XMM3 =00000000'00000000'00000000'00000000
00:02:23.017950 XMM4 =00000000'00000000'00000000'00000000  XMM5 =00000000'00000000'00000000'00000000
00:02:23.017952 XMM6 =00000000'00000000'00000000'00000000  XMM7 =00000000'00000000'00000000'00000000
00:02:23.017954 XMM8 =00000000'00000000'00000000'00000000  XMM9 =00000000'00000000'00000000'00000000
00:02:23.017956 XMM10=00000000'00000000'00000000'00000000  XMM11=00000000'00000000'00000000'00000000
00:02:23.017959 XMM12=00000000'00000000'00000000'00000000  XMM13=00000000'00000000'00000000'00000000
00:02:23.017961 XMM14=00000000'00000000'00000000'00000000  XMM15=00000000'00000000'00000000'00000000
00:02:23.017963 EFER         =0000000000000800
00:02:23.017963 PAT          =0007010600070106
00:02:23.017964 STAR         =0000000000000000
00:02:23.017965 CSTAR        =0000000000000000
00:02:23.017966 LSTAR        =0000000000000000
00:02:23.017966 SFMASK       =0000000000000000
00:02:23.017967 KERNELGSBASE =0000000000000000
00:02:23.017967 ***
00:02:23.017973 Guest paging mode:  PAE+NX (changed 4585 times), A20 enabled (changed 2 times)
00:02:23.017975 Shadow paging mode: Nested
00:02:23.017976 Host paging mode:   AMD64+G+NX
00:02:23.017977 ***
00:02:23.017980 Active Timers (pVM=0000000000500000)
00:02:23.017981 pTimerR3         offNext  offPrev  offSched Clock               Time             Expire HzHint State                     Description
00:02:23.017986 000000000ba44010 00013fa0 00000000 00000000 Real             5261099            5256316      0 2-ACTIVE                  VGA Refresh Timer
00:02:23.017991 000000000ba57fb0 ffffff80 fffec060 00000000 Real             5261099            5256336      0 2-ACTIVE                  EMT Yielder
00:02:23.017996 000000000ba57f30 00000000 00000080 00000000 Real             5261099            5256826      0 2-ACTIVE                  CPU Load Timer
00:02:23.018000 000000000ba55160 ffffd0a0 00000000 00000000 Virt        126626616966       126522289715      0 2-ACTIVE                  USB Frame Timer
00:02:23.018003 000000000ba52200 00000000 00002f60 00000000 Virt        126626616966       126523861335      0 2-ACTIVE                  Audio timer
00:02:23.018007 000000000ba3b110 00000e60 00000000 00000000 VrSy        126519385677       126524267807     52 2-ACTIVE                  APIC Timer #0
00:02:23.018010 000000000ba3bf70 0001ae10 fffff1a0 00000000 VrSy        126519385677       126990000000      0 2-ACTIVE                  MC146818 RTC/CMOS - Second
00:02:23.018013 000000000ba56d80 00000000 fffe51f0 00000000 VrSy        126519385677      1199864031601      0 2-ACTIVE                  ACPI PM Timer
00:02:23.018017 ***
00:02:23.018019 ***
00:02:23.018020 ************** End of Guest state at power off ***************
00:02:23.018078 PDMR3PowerOff: 7 268 ns run time
00:02:23.018086 Changing the VM state from 'POWERING_OFF' to 'OFF'.

My host is Windows 7 x64 with an AMD motherboard.
VirtualBox is Version 4.3.8
I am running, successfully, VM's for Windows XP, and Android emulators.

I'm sure I have left out important information. Let me know!

Thanks,

Jim

[socratis]

I'm sure I have left out important information. Let me know!

Yes, indeed! Post the whole VBox.log file as a zipped attachment.

[Joat_Mon]

[Thanks,
I have included history logs as well as latest. They seem to vary somewhat.

Also, I failed to mention that the crash error message from VirtualBox was:
------------------+
Failed to save the state of the virtual machine .

Machine terminated with pending save state!.

Result Code: E_FAIL (0x80004005)
Component: SessionMachine
Interface: ISession {12f4dcdb-12b2-4ec1-b7cd-ddd9f6c5bf4d}
-------------------+

[Perryg]

VirtualBox VM 4.3.8 r92456 win.amd64 (Feb 25 2014 18:23:31) release log
00:00:51.463410 Guest Additions information report: Version 4.2.16 r86992 '4.2.16_Ubuntu'

If this were a Linux host it probably wouldn't matter a lot but on a Windows host you really need to keep the guest additions at the same version as the Host Program.

Also there are a few issues with ATI graphics and that may also be part of the problem since hangs or aborts are usually tied to a driver issue. Update the GAs and see if it helps, and if not I would consider posting a ticket at bugtracker being sure to post the guests log file and a detailed description of how to replicate the crash.

[socratis]

The only difference I could see between your logs and my logs are the states that the VM goes during shutdown:

VBox.log   : RUNNING -> POWERING_OFF -> OFF
VBox.log.1 : RUNNING -> SUSPENDING -> SUSPENDED -> POWERING_OFF -> OFF
VBox.log.2 : RUNNING -> SUSPENDING -> SUSPENDED -> SAVING -> SUSPENDED -> POWERING_OFF -> OFF
VBox.log.3 : RUNNING -> SUSPENDING -> SUSPENDED -> SAVING -> SUSPENDED -> POWERING_OFF -> OFF
Mine       : RUNNING -> SUSPENDING -> SUSPENDED -> POWERING_OFF -> OFF -> DESTROYING -> TERMINATED

All of yours are missing the Destroying and Terminated steps. I would follow what Perryg has proposed. I don't have any better alternatives, that's why I'm with the 'green man' on this one .

[Joat_Mon]

Thanks Folks,

I updated the guest addition to match VBox. Little did I know that the Cinnamon install included an older version.

This did not help.

After disabling the display hardware 3D acceleration, the bugger worked OK!
I download the latest ATI drivers (only 4 months old) and that did not help.

Now I present the latest log for your perusal. I will search for ATI driver problems before I post a bug ticket.

Regards,

Jim (Jack Of All Trades _ Master Of None)

[Perryg]

Code: Select all

00:00:51.052094 OpenGL Warning: Assertion failed: pCtxInfo->CreateInfo.requestedVisualBits & CR_DOUBLE_BIT, file D:\tinderbox\win-4.3\src\VBox\HostServices\SharedOpenGL\crserverlib\server_main.c, line 1171

I believe it is time to open a ticket.