Cannot create snapshot of running VM after upgrade to 4.0.2

[ctr]

Dear All,

I recently upgraded to VirtualBox 4.0.2 on two host machines.

One machine has developed a problem that it cannot create snapshots of a running VM. I get an error, and this text in the VBox.log file:

00:00:08.417 Guest Additions information report: Version 4.0.2 r69518 '4.0.2'
00:00:08.417 Guest Additions information report: Interface = 0x00010004 osType = 0x00033000
00:00:08.417 Guest Additions capability report: (0x0) seamless: no, hostWindowMapping: no, graphics: no
00:00:08.417 Guest reported fixed hypervisor window at 0x00000000b8400000 (size = 0x1000000, rc = VINF_SUCCESS)
00:00:10.681 Changing the VM state from 'RUNNING' to 'RUNNING_LS'.
00:00:10.697
00:00:10.697 !!R0-Assertion Failed!!
00:00:10.697 Expression: PGM_PAGE_GET_STATE(pPage) == PGM_PAGE_STATE_ALLOCATED
00:00:10.697 Location : e:\tinderbox\win-rel\src\vbox\vmm\vmmall\PGMAllBth.h(3087) pgmR0BthEPTProtSyncPT
00:00:10.697 !!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!
00:00:10.697 !!
00:00:10.697 !! Guru Meditation -2701 (VERR_VMM_RING0_ASSERTION)
00:00:10.697 !!
00:00:10.697 !!R0-Assertion Failed!!
00:00:10.697 Expression: PGM_PAGE_GET_STATE(pPage) == PGM_PAGE_STATE_ALLOCATED
00:00:10.697 Location : e:\tinderbox\win-rel\src\vbox\vmm\vmmall\PGMAllBth.h(3087) pgmR0BthEPTProtSyncPT
00:00:10.697
00:00:10.697 !! ACTIVE TRAP=0e ERRCD=0000000000000000 CR2=00000000007bd000 PC=00000000806d6746 Type=0 (Guest!)
00:00:10.697 !!
00:00:10.697 !! CallRing3JmpBuf:
00:00:10.697 !!
00:00:10.697 SavedEsp=fffff880718b15c8 SavedEbp=fffff880718b15d0 SpResume=fffff880718b14f0 SpCheck=fffff880718b1960
00:00:10.697 pvSavedStack=0000000006c2c000 cbSavedStack=0x470 fInRing3Call=false
00:00:10.697 cbUsedMax=0x0 cbUsedAvg=0x0 cbUsedTotal=0x0 cUsedTotal=0x0
00:00:10.697 rax=volatile rbx=fffff88008c54000 rcx=volatile rdx=volatile
00:00:10.697 rsi=00000000007bd000 rdi=0000000000000000 r8=volatile r9=volatile
00:00:10.697 r10=volatile r11=volatile r12=0000000000000003 r13=00000000025bf000
00:00:10.697 r14=fffff88008c54000 r15=0000000000000003
00:00:10.697 rip=fffff88009a4005e rsp=fffff880718b15c0 rbp=fffff88008c39000 rfl=00000246
00:00:10.697 DBGF: RTDbgModCreateFromImage failed with rc=VERR_NOT_IMPLEMENTED for module "VBoxEhci" (C:\PROGRA~1\Oracle\VIRTUA~1/ExtensionPacks/Oracle_VM_VirtualBox_Extension_Pack/win.amd64/VBoxEhciR3.DLL)
00:00:10.697 DBGF: RTDbgModCreateFromImage failed with rc=VERR_NOT_IMPLEMENTED for module "VBoxDD2" (C:\PROGRA~1\Oracle\VIRTUA~1/VBoxDD2.DLL)
00:00:10.697 DBGF: RTDbgModCreateFromImage failed with rc=VERR_NOT_IMPLEMENTED for module "VBoxC" (C:\PROGRA~1\Oracle\VIRTUA~1/VBoxC.DLL)
00:00:10.697 DBGF: RTDbgModCreateFromImage failed with rc=VERR_NOT_IMPLEMENTED for module "VBoxDD" (C:\PROGRA~1\Oracle\VIRTUA~1/VBoxDD.DLL)
00:00:10.697 DBGF: RTDbgModCreateFromImage failed with rc=VERR_NOT_IMPLEMENTED for module "VMMR0.r0" (C:\PROGRA~1\Oracle\VIRTUA~1/VMMR0.r0)
00:00:10.697 DBGF: RTDbgModCreateFromImage failed with rc=VERR_NOT_IMPLEMENTED for module "VBoxDDR0.r0" (C:\PROGRA~1\Oracle\VIRTUA~1/VBoxDDR0.r0)
00:00:10.697 DBGF: RTDbgModCreateFromImage failed with rc=VERR_NOT_IMPLEMENTED for module "VBoxDD2R0.r0" (C:\PROGRA~1\Oracle\VIRTUA~1/VBoxDD2R0.r0)
00:00:10.697 DBGF: RTDbgModCreateFromImage failed with rc=VERR_NOT_IMPLEMENTED for module "VBoxEhciR0.r0" (C:\PROGRA~1\Oracle\VIRTUA~1/ExtensionPacks/Oracle_VM_VirtualBox_Extension_Pack/win.amd64/VBoxEhciR0.r0)
00:00:10.698 !!
00:00:10.698 !! Call Stack:
00:00:10.698 !!
00:00:10.698 RBP Ret RBP Ret RIP RIP Symbol [line]
00:00:10.698 fffff880718b15d0 fffff88008c39000 fffff88009a4005e 0000000000000000
00:00:10.698 fffff88008c39000 0000000000000000 0000000000000000 fffff88009a4005e VMMR0.r0!VMMRZCallRing3+8e
00:00:10.698 !!
00:00:10.698 !! Raw stack (mind the direction).
00:00:10.698 !! pbEMTStackR0=0000000006c2c000 pbEMTStackBottomR0=0000000006c2e000 VMM_STACK_SIZE=0x2000
00:00:10.698 !! pbEmtStackR3=0000000006c2c000
00:00:10.698 !!
00:00:10.698 0000000006c2c000 0000: 00 00 00 00 00 00 00 00-00 00 00 00 00 00 00 00 ................
00:00:10.698 0000000006c2c010 0010: 00 00 00 00 00 00 00 00-00 00 00 00 00 00 00 00 ................
00:00:10.698 0000000006c2c020 0020: 00 00 00 00 00 00 00 00-00 00 00 00 00 00 00 00 ................
00:00:10.698 0000000006c2c030 0030: 00 00 00 00 00 00 00 00-00 00 00 00 00 00 00 00 ................
00:00:10.698 0000000006c2c040 0040: 00 00 00 00 00 00 00 00-00 00 00 00 00 00 00 00 ................
00:00:10.698 0000000006c2c050 0050: 00 00 00 00 00 00 00 00-00 00 00 00 00 00 00 00 ................
00:00:10.698 0000000006c2c060 0060: 00 00 00 00 00 00 00 00-00 00 00 00 00 00 00 00 ................
00:00:10.698 0000000006c2c070 0070: 00 00 00 00 00 00 00 00-00 00 00 00 00 00 00 00 ................
00:00:10.698 0000000006c2c080 0080: 00 00 00 00 00 00 00 00-00 00 00 00 00 00 00 00 ................
00:00:10.698 0000000006c2c090 0090: 00 00 00 00 00 00 00 00-00 00 00 00 00 00 00 00 ................
00:00:10.698 0000000006c2c0a0 00a0: 46 02 00 00 00 00 00 00-00 40 c5 08 80 f8 ff ff F........@......
00:00:10.698 0000000006c2c0b0 00b0: 00 d0 7b 00 00 00 00 00-00 00 00 00 00 00 00 00 ..{.............
00:00:10.698 0000000006c2c0c0 00c0: 03 00 00 00 00 00 00 00-00 f0 5b 02 00 00 00 00 ..........[.....
00:00:10.698 0000000006c2c0d0 00d0: 00 40 c5 08 80 f8 ff ff-03 00 00 00 00 00 00 00 .@..............
00:00:10.698 0000000006c2c0e0 00e0: 00 90 c3 08 80 f8 ff ff-5e 00 a4 09 80 f8 ff ff ........^.......
00:00:10.698 0000000006c2c0f0 00f0: 90 7e a8 09 80 f8 ff ff-89 0b a3 09 80 f8 ff ff .~..............
00:00:10.698 0000000006c2c100 0100: 90 75 a8 09 80 f8 ff ff-60 d0 c1 06 80 f8 ff ff .u......`.......

The VM then shuts down - losing my current work.

I CAN make snapshots if the VM is shut down at the time I create the snapshot.

I have tested a brand new VM with an 8GB virtual disk, starting the Fedora 13 live CD image. That exhibited the same problem.

I have tried uninstalling and reinstalling VirtualBox on the host system.

Any help would be gratefully received.

Thanks,

Chris.

[Perryg]

Did you install/update the Extension Pack? How to install Extension Pack

[ctr]

Yes, I did install the Extension Pack. I downloaded the latest version at the same time as the main VirtualBox installer.

[Perryg]

Post the complete guests log file (as an attachment).

[ctr]

I have posted a complete VBox.log that demonstrates the same problem on PasteBin http://pastebin.com/SBjA6pfr (the log file is too large for me to upload here.)
N.B. This is a second log file - the log file that I quoted from above has already been overwritten.

In case it's relevant, here is cygwin's /proc/cpuinfo output from the host machine:

processor : 0
vendor_id : GenuineIntel
cpu family : 6
model : 30
model name : Intel(R) Core(TM) i7 CPU 870 @ 2.93GHz
stepping : 5
cpu MHz : 2926
cache size : 256 KB
fpu : yes
fpu_exception : yes
cpuid level : 11
wp : yes
flags : fpu vme de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush dts acpi mmx fxsr sse sse2 ss ht tm pbe pni dtes64 monitor ds_cpl vmx smx est tm2 ssse3 cx16 xtpr pdcm sse4_1 sse4_2 popcnt lahf_lm
TLB size : 0 4K pages
clflush size : 64
cache_alignment : 64
address sizes : 36 bits physical, 48 bits virtual
power management:

Don't worry - VirtualBox is not being run through Cygwin in any way.

C.

[tpnykane]

Facing exactly the same problem on a fresh VirtualBox install and fresh Ubuntu 10 guest.

Running Windows 7 64-bit as host OS and
Ubuntu 10.01 64-bit server as guest OS. Guest OS was a fresh install. The first attempt to take a snapshot failed in Guru meditation (nice Amiga flashback btw)

Full contents of vbox.log can be found from here: http://pastebin.com/YrVRKHxW

Details from the crashing time start from row 871.

HW details (extracted from the posted log snippet):

VirtualBox 4.0.2 r69518 win.amd64 (Jan 18 2011 17:03:32) release log
Log opened 2011-02-09T09:08:35.639862200Z
OS Product: Windows 7
OS Release: 6.1.7600
OS Service Pack:
DMI Product Name: 43194NG
DMI Product Version: ThinkPad W510
Host RAM: 8123MB RAM, available: 5469MB

Here's an extract from the start of the assertion failure:

Code: Select all

03:04:39.888 Changing the VM state from 'RUNNING' to 'RUNNING_LS'.
03:04:39.978 
03:04:39.978 !!R0-Assertion Failed!!
03:04:39.978 Expression: PGM_PAGE_GET_STATE(pPage) == PGM_PAGE_STATE_ALLOCATED
03:04:39.978 Location  : e:\tinderbox\win-rel\src\vbox\vmm\vmmall\PGMAllBth.h(3087) pgmR0BthEPTProtSyncPT
03:04:39.978 !!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!
03:04:39.978 !!
03:04:39.978 !!                 Guru Meditation -2701 (VERR_VMM_RING0_ASSERTION)
03:04:39.978 !!
03:04:39.978 !!R0-Assertion Failed!!
03:04:39.978 Expression: PGM_PAGE_GET_STATE(pPage) == PGM_PAGE_STATE_ALLOCATED
03:04:39.978 Location  : e:\tinderbox\win-rel\src\vbox\vmm\vmmall\PGMAllBth.h(3087) pgmR0BthEPTProtSyncPT
03:04:39.978 
03:04:39.978 !! ACTIVE TRAP=0e ERRCD=0000000000000000 CR2=000000002c1c2ff8 PC=ffffffff81012c2f Type=0 (Guest!)
03:04:39.978 !!
03:04:39.978 !! CallRing3JmpBuf:
03:04:39.978 !!
03:04:39.978 SavedEsp=fffff8800bd9a5c8 SavedEbp=fffff8800bd9a5d0 SpResume=fffff8800bd9a4f0 SpCheck=fffff8800bd9a960
03:04:39.978 pvSavedStack=0000000005b0c000 cbSavedStack=0x470  fInRing3Call=false
03:04:39.978 cbUsedMax=0x0 cbUsedAvg=0x0 cbUsedTotal=0x0 cUsedTotal=0x0
03:04:39.978 rax=volatile         rbx=fffff8800807a000 rcx=volatile         rdx=volatile
03:04:39.978 rsi=000000002c1c2ff8 rdi=0000000000000000  r8=volatile          r9=volatile        
03:04:39.978 r10=volatile         r11=volatile         r12=0000000000000160 r13=0000000003a8f000
03:04:39.978 r14=fffff8800807a000 r15=0000000000000160
03:04:39.978 rip=fffff8800d4e605e rsp=fffff8800bd9a5c0 rbp=fffff8800805f000 rfl=00000246
03:04:39.989 DBGF: RTDbgModCreateFromImage failed with rc=VERR_NOT_IMPLEMENTED for module "VBoxDD2" (C:\PROGRA~1\Oracle\VIRTUA~1/VBoxDD2.DLL)
03:04:39.989 DBGF: RTDbgModCreateFromImage failed with rc=VERR_NOT_IMPLEMENTED for module "VBoxC" (C:\PROGRA~1\Oracle\VIRTUA~1/VBoxC.DLL)
03:04:39.989 DBGF: RTDbgModCreateFromImage failed with rc=VERR_NOT_IMPLEMENTED for module "VBoxDD" (C:\PROGRA~1\Oracle\VIRTUA~1/VBoxDD.DLL)
03:04:39.989 DBGF: RTDbgModCreateFromImage failed with rc=VERR_NOT_IMPLEMENTED for module "VMMR0.r0" (C:\PROGRA~1\Oracle\VIRTUA~1/VMMR0.r0)
03:04:39.989 DBGF: RTDbgModCreateFromImage failed with rc=VERR_NOT_IMPLEMENTED for module "VBoxDDR0.r0" (C:\PROGRA~1\Oracle\VIRTUA~1/VBoxDDR0.r0)
03:04:39.989 DBGF: RTDbgModCreateFromImage failed with rc=VERR_NOT_IMPLEMENTED for module "VBoxDD2R0.r0" (C:\PROGRA~1\Oracle\VIRTUA~1/VBoxDD2R0.r0)
03:04:39.990 !!
03:04:39.990 !! Call Stack:
03:04:39.990 !!
03:04:39.990 RBP              Ret RBP          Ret RIP          RIP              Symbol [line]
03:04:39.990 fffff8800bd9a5d0 fffff8800805f000 fffff8800d4e605e 0000000000000000
03:04:39.990 fffff8800805f000 0000000000000000 0000000000000000 fffff8800d4e605e VMMR0.r0!VMMRZCallRing3+8e
03:04:39.990 !!
03:04:39.990 !! Raw stack (mind the direction). 
03:04:39.990 !! pbEMTStackR0=0000000005b0c000 pbEMTStackBottomR0=0000000005b0e000 VMM_STACK_SIZE=0x2000
03:04:39.990 !! pbEmtStackR3=0000000005b0c000
03:04:39.990 !!

Looks like something is not implemented?

Toni