Page 1 of 1
VM Windows Server 2008 hang ups
Posted: 5. Oct 2009, 08:55
by edvl
Hello,
i using Debian lenny 64 with a 2.6.30-1-amd64 (Debian 2.6.30-6) Kernel, gcc 4.3.5 an Virtualbox 3.0.6 as Host.
The Guest is a Windows 2008 Small Business Server with additions.
The guest is running with VBoxHeadless.
The whole week it runs stable but it seems to hang up on sundays betweek 0:55 an 5:00 am.
The CPU usage is at 50% (quadcore prozessor, but using only 2 cores) when it hung.
In the Virtualbox-log is no error and in the Windows Server Eventlog i see only that on the events was not logging at the hang up.
The only way to fix it, is to poweroff the Guest and restart it.
I checked the tasks at Windows side but there is nothing that start at this time.
And the hang up is between this timespace and not at one time.
How can i solve the problem?
How does i know if it is a Virtualbox or Windows Server problem?
Is the W2K8 Server released on Virtualbox 3.0.6?
Does someone has the same problem?
Thanks ...
Re: VM Windows Server 2008 hang ups
Posted: 5. Oct 2009, 17:52
by Perryg
Please provide the Host RAM and the guest settings.
Since you do not see an error in the VBox Log or the server log it might be a little tricky to track down.
Also post the VBox log file from the period that this happened.
Please use code blocks for the log file or submit it as an attachment.
Re: VM Windows Server 2008 hang ups
Posted: 6. Oct 2009, 06:26
by edvl
The host have a CPU Intel Core 2 Quad Processor Q9550S / 2.83 Ghz with VT-X, RAM 4x 2GB DDR3.
Here is the VBox.log.3, from this sunday morning. the hang up was at 0:55. The log is from 05:03 am.
Code: Select all
00:00:18.198 VirtualBox 3.0.6 r52128 linux.amd64 (Sep 9 2009 18:48:50) release log
00:00:18.198 Log opened 2009-10-04T05:03:56.079471000Z
00:00:18.206 OS Product: Linux
00:00:18.206 OS Release: 2.6.30-1-amd64
00:00:18.206 OS Version: #1 SMP Sat Aug 15 18:09:19 UTC 2009
00:00:18.206 Host RAM: 8008MB RAM, available: 7733MB
00:00:18.206 Executable: /usr/lib/virtualbox/VBoxHeadless
00:00:18.206 Process ID: 20473
00:00:18.206 Package type: LINUX_64BITS_DEBIAN_5_0
00:00:18.259 VRDP: TCP server listening on port 3389.
00:00:18.288 SUP: Loaded VMMR0.r0 (/usr/lib/virtualbox/VMMR0.r0) at 0xffffffffa03ae920 - ModuleInit at ffffffffa03c0c80 and ModuleTerm at ffffffffa03c0c40
00:00:18.288 SUP: VMMR0EntryEx located at ffffffffa03c0b50, VMMR0EntryFast at ffffffffa03bfd60 and VMMR0EntryInt at ffffffffa03bfaf0
00:00:18.404 VBoxSharedClipboard mode: Bidirectional
00:00:18.497 ************************* CFGM dump *************************
00:00:18.497 pRoot=000000000246d820:{/}
00:00:18.497 [/] (level 0)
00:00:18.497 Name <string> = "lamm-vb-server" (cch=15)
00:00:18.497 UUID <bytes> = "c2 fb 68 08 ca 29 42 4a a0 cc 86 50 19 ad ce 96" (cb=16)
00:00:18.497 RamSize <integer> = 0x000000013f800000 (5360320512)
00:00:18.497 RamHoleSize <integer> = 0x0000000020000000 (536870912)
00:00:18.497 NumCPUs <integer> = 0x0000000000000002 (2)
00:00:18.497 TimerMillies <integer> = 0x000000000000000a (10)
00:00:18.497 RawR3Enabled <integer> = 0x0000000000000001 (1)
00:00:18.497 RawR0Enabled <integer> = 0x0000000000000001 (1)
00:00:18.497 PATMEnabled <integer> = 0x0000000000000001 (1)
00:00:18.497 CSAMEnabled <integer> = 0x0000000000000001 (1)
00:00:18.497 HwVirtExtForced <integer> = 0x0000000000000001 (1)
00:00:18.497 EnableNestedPaging <integer> = 0x0000000000000001 (1)
00:00:18.497 EnableVPID <integer> = 0x0000000000000000 (0)
00:00:18.497 EnablePAE <integer> = 0x0000000000000001 (1)
00:00:18.497
00:00:18.497 [/HWVirtExt/] (level 1)
00:00:18.497 Enabled <integer> = 0x0000000000000001 (1)
00:00:18.497 64bitEnabled <integer> = 0x0000000000000001 (1)
00:00:18.497
00:00:18.497 [/PDM/] (level 1)
00:00:18.497
00:00:18.497 [/PDM/Drivers/] (level 2)
00:00:18.497
00:00:18.497 [/PDM/Drivers/VBoxC/] (level 3)
00:00:18.497 Path <string> = "/usr/lib/virtualbox/components/VBoxC" (cch=37)
00:00:18.497
00:00:18.497 [/Devices/] (level 1)
00:00:18.497
00:00:18.497 [/Devices/pcarch/] (level 2)
00:00:18.497
00:00:18.497 [/Devices/pcarch/0/] (level 3)
00:00:18.497 Trusted <integer> = 0x0000000000000001 (1)
00:00:18.497
00:00:18.497 [/Devices/pcarch/0/Config/] (level 4)
00:00:18.497
00:00:18.497 [/Devices/pcbios/] (level 2)
00:00:18.497
00:00:18.497 [/Devices/pcbios/0/] (level 3)
00:00:18.497 Trusted <integer> = 0x0000000000000001 (1)
00:00:18.497
00:00:18.497 [/Devices/pcbios/0/Config/] (level 4)
00:00:18.497 RamSize <integer> = 0x000000013f800000 (5360320512)
00:00:18.497 RamHoleSize <integer> = 0x0000000020000000 (536870912)
00:00:18.497 NumCPUs <integer> = 0x0000000000000002 (2)
00:00:18.497 HardDiskDevice <string> = "piix3ide" (cch=9)
00:00:18.497 FloppyDevice <string> = "i82078" (cch=7)
00:00:18.497 IOAPIC <integer> = 0x0000000000000001 (1)
00:00:18.497 PXEDebug <integer> = 0x0000000000000000 (0)
00:00:18.497 UUID <bytes> = "c2 fb 68 08 ca 29 42 4a a0 cc 86 50 19 ad ce 96" (cb=16)
00:00:18.497 BootDevice0 <string> = "DVD" (cch=4)
00:00:18.497 BootDevice1 <string> = "IDE" (cch=4)
00:00:18.497 BootDevice2 <string> = "NONE" (cch=5)
00:00:18.497 BootDevice3 <string> = "NONE" (cch=5)
00:00:18.497 SataHardDiskDevice <string> = "ahci" (cch=5)
00:00:18.497 SataPrimaryMasterLUN <integer> = 0x0000000000000000 (0)
00:00:18.497 SataPrimarySlaveLUN <integer> = 0x0000000000000001 (1)
00:00:18.497 SataSecondaryMasterLUN <integer> = 0x0000000000000002 (2)
00:00:18.497 SataSecondarySlaveLUN <integer> = 0x0000000000000003 (3)
00:00:18.497
00:00:18.497 [/Devices/8237A/] (level 2)
00:00:18.497
00:00:18.497 [/Devices/8237A/0/] (level 3)
00:00:18.497 Trusted <integer> = 0x0000000000000001 (1)
00:00:18.497
00:00:18.497 [/Devices/pci/] (level 2)
00:00:18.497
00:00:18.497 [/Devices/pci/0/] (level 3)
00:00:18.497 Trusted <integer> = 0x0000000000000001 (1)
00:00:18.497
00:00:18.497 [/Devices/pci/0/Config/] (level 4)
00:00:18.497 IOAPIC <integer> = 0x0000000000000001 (1)
00:00:18.497
00:00:18.497 [/Devices/pckbd/] (level 2)
00:00:18.497
00:00:18.497 [/Devices/pckbd/0/] (level 3)
00:00:18.497 Trusted <integer> = 0x0000000000000001 (1)
00:00:18.497
00:00:18.497 [/Devices/pckbd/0/Config/] (level 4)
00:00:18.497
00:00:18.497 [/Devices/pckbd/0/LUN#0/] (level 4)
00:00:18.497 Driver <string> = "KeyboardQueue" (cch=14)
00:00:18.497
00:00:18.497 [/Devices/pckbd/0/LUN#0/Config/] (level 5)
00:00:18.497 QueueSize <integer> = 0x0000000000000040 (64)
00:00:18.497
00:00:18.497 [/Devices/pckbd/0/LUN#0/AttachedDriver/] (level 5)
00:00:18.497 Driver <string> = "MainKeyboard" (cch=13)
00:00:18.497
00:00:18.497 [/Devices/pckbd/0/LUN#0/AttachedDriver/Config/] (level 6)
00:00:18.497 Object <integer> = 0x0000000002413810 (37828624)
00:00:18.497
00:00:18.497 [/Devices/pckbd/0/LUN#1/] (level 4)
00:00:18.497 Driver <string> = "MouseQueue" (cch=11)
00:00:18.497
00:00:18.497 [/Devices/pckbd/0/LUN#1/Config/] (level 5)
00:00:18.498 QueueSize <integer> = 0x0000000000000080 (128)
00:00:18.498
00:00:18.498 [/Devices/pckbd/0/LUN#1/AttachedDriver/] (level 5)
00:00:18.498 Driver <string> = "MainMouse" (cch=10)
00:00:18.498
00:00:18.498 [/Devices/pckbd/0/LUN#1/AttachedDriver/Config/] (level 6)
00:00:18.498 Object <integer> = 0x0000000002413960 (37828960)
00:00:18.498
00:00:18.498 [/Devices/i82078/] (level 2)
00:00:18.498
00:00:18.498 [/Devices/i82078/0/] (level 3)
00:00:18.498 Trusted <integer> = 0x0000000000000001 (1)
00:00:18.498
00:00:18.498 [/Devices/i82078/0/Config/] (level 4)
00:00:18.498 IRQ <integer> = 0x0000000000000006 (6)
00:00:18.498 DMA <integer> = 0x0000000000000002 (2)
00:00:18.498 MemMapped <integer> = 0x0000000000000000 (0)
00:00:18.498 IOBase <integer> = 0x00000000000003f0 (1008)
00:00:18.498
00:00:18.498 [/Devices/i82078/0/LUN#999/] (level 4)
00:00:18.498 Driver <string> = "MainStatus" (cch=11)
00:00:18.498
00:00:18.498 [/Devices/i82078/0/LUN#999/Config/] (level 5)
00:00:18.498 papLeds <integer> = 0x0000000002412de0 (37826016)
00:00:18.498 First <integer> = 0x0000000000000000 (0)
00:00:18.498 Last <integer> = 0x0000000000000000 (0)
00:00:18.498
00:00:18.498 [/Devices/i82078/0/LUN#0/] (level 4)
00:00:18.498 Driver <string> = "Block" (cch=6)
00:00:18.498
00:00:18.498 [/Devices/i82078/0/LUN#0/Config/] (level 5)
00:00:18.498 Type <string> = "Floppy 1.44" (cch=12)
00:00:18.498 Mountable <integer> = 0x0000000000000001 (1)
00:00:18.498
00:00:18.498 [/Devices/acpi/] (level 2)
00:00:18.498
00:00:18.498 [/Devices/acpi/0/] (level 3)
00:00:18.498 Trusted <integer> = 0x0000000000000001 (1)
00:00:18.498 PCIDeviceNo <integer> = 0x0000000000000007 (7)
00:00:18.498 PCIFunctionNo <integer> = 0x0000000000000000 (0)
00:00:18.498
00:00:18.498 [/Devices/acpi/0/Config/] (level 4)
00:00:18.498 RamSize <integer> = 0x000000013f800000 (5360320512)
00:00:18.498 RamHoleSize <integer> = 0x0000000020000000 (536870912)
00:00:18.498 NumCPUs <integer> = 0x0000000000000002 (2)
00:00:18.498 IOAPIC <integer> = 0x0000000000000001 (1)
00:00:18.498 FdcEnabled <integer> = 0x0000000000000001 (1)
00:00:18.498 HpetEnabled <integer> = 0x0000000000000000 (0)
00:00:18.498 ShowRtc <integer> = 0x0000000000000000 (0)
00:00:18.498 ShowCpu <integer> = 0x0000000000000001 (1)
00:00:18.498
00:00:18.498 [/Devices/acpi/0/LUN#0/] (level 4)
00:00:18.498 Driver <string> = "ACPIHost" (cch=9)
00:00:18.498
00:00:18.498 [/Devices/acpi/0/LUN#0/Config/] (level 5)
00:00:18.498
00:00:18.498 [/Devices/i8254/] (level 2)
00:00:18.498
00:00:18.498 [/Devices/i8254/0/] (level 3)
00:00:18.498
00:00:18.498 [/Devices/i8254/0/Config/] (level 4)
00:00:18.498
00:00:18.498 [/Devices/i8259/] (level 2)
00:00:18.498
00:00:18.498 [/Devices/i8259/0/] (level 3)
00:00:18.498 Trusted <integer> = 0x0000000000000001 (1)
00:00:18.498
00:00:18.498 [/Devices/i8259/0/Config/] (level 4)
00:00:18.498
00:00:18.498 [/Devices/apic/] (level 2)
00:00:18.498
00:00:18.498 [/Devices/apic/0/] (level 3)
00:00:18.498 Trusted <integer> = 0x0000000000000001 (1)
00:00:18.498
00:00:18.498 [/Devices/apic/0/Config/] (level 4)
00:00:18.498 IOAPIC <integer> = 0x0000000000000001 (1)
00:00:18.498 NumCPUs <integer> = 0x0000000000000002 (2)
00:00:18.498
00:00:18.498 [/Devices/ioapic/] (level 2)
00:00:18.498
00:00:18.498 [/Devices/ioapic/0/] (level 3)
00:00:18.498 Trusted <integer> = 0x0000000000000001 (1)
00:00:18.498
00:00:18.498 [/Devices/ioapic/0/Config/] (level 4)
00:00:18.498
00:00:18.498 [/Devices/mc146818/] (level 2)
00:00:18.498
00:00:18.498 [/Devices/mc146818/0/] (level 3)
00:00:18.498
00:00:18.498 [/Devices/mc146818/0/Config/] (level 4)
00:00:18.498
00:00:18.498 [/Devices/vga/] (level 2)
00:00:18.498
00:00:18.498 [/Devices/vga/0/] (level 3)
00:00:18.498 Trusted <integer> = 0x0000000000000001 (1)
00:00:18.498 PCIDeviceNo <integer> = 0x0000000000000002 (2)
00:00:18.498 PCIFunctionNo <integer> = 0x0000000000000000 (0)
00:00:18.498
00:00:18.498 [/Devices/vga/0/Config/] (level 4)
00:00:18.498 VRamSize <integer> = 0x0000000000c00000 (12582912)
00:00:18.498 FadeIn <integer> = 0x0000000000000001 (1)
00:00:18.498 FadeOut <integer> = 0x0000000000000001 (1)
00:00:18.498 LogoTime <integer> = 0x0000000000000000 (0)
00:00:18.498 LogoFile <string> = "" (cch=1)
00:00:18.498 ShowBootMenu <integer> = 0x0000000000000002 (2)
00:00:18.498 CustomVideoModes <integer> = 0x0000000000000000 (0)
00:00:18.499 HeightReduction <integer> = 0x0000000000000000 (0)
00:00:18.499
00:00:18.499 [/Devices/vga/0/LUN#0/] (level 4)
00:00:18.499 Driver <string> = "MainDisplay" (cch=12)
00:00:18.499
00:00:18.499 [/Devices/vga/0/LUN#0/Config/] (level 5)
00:00:18.499 Object <integer> = 0x0000000002413ab0 (37829296)
00:00:18.499
00:00:18.499 [/Devices/piix3ide/] (level 2)
00:00:18.499
00:00:18.499 [/Devices/piix3ide/0/] (level 3)
00:00:18.499 Trusted <integer> = 0x0000000000000001 (1)
00:00:18.499 PCIDeviceNo <integer> = 0x0000000000000001 (1)
00:00:18.499 PCIFunctionNo <integer> = 0x0000000000000001 (1)
00:00:18.499
00:00:18.499 [/Devices/piix3ide/0/Config/] (level 4)
00:00:18.499 Type <string> = "PIIX4" (cch=6)
00:00:18.499
00:00:18.499 [/Devices/piix3ide/0/LUN#999/] (level 4)
00:00:18.499 Driver <string> = "MainStatus" (cch=11)
00:00:18.499
00:00:18.499 [/Devices/piix3ide/0/LUN#999/Config/] (level 5)
00:00:18.499 papLeds <integer> = 0x0000000002412df0 (37826032)
00:00:18.499 First <integer> = 0x0000000000000000 (0)
00:00:18.499 Last <integer> = 0x0000000000000003 (3)
00:00:18.499
00:00:18.499 [/Devices/piix3ide/0/LUN#2/] (level 4)
00:00:18.499 Driver <string> = "Block" (cch=6)
00:00:18.499
00:00:18.499 [/Devices/piix3ide/0/LUN#2/Config/] (level 5)
00:00:18.499 Type <string> = "DVD" (cch=4)
00:00:18.499 Mountable <integer> = 0x0000000000000001 (1)
00:00:18.499
00:00:18.499 [/Devices/ahci/] (level 2)
00:00:18.499
00:00:18.499 [/Devices/ahci/0/] (level 3)
00:00:18.499 Trusted <integer> = 0x0000000000000001 (1)
00:00:18.499 PCIDeviceNo <integer> = 0x000000000000000d (13)
00:00:18.499 PCIFunctionNo <integer> = 0x0000000000000000 (0)
00:00:18.499
00:00:18.499 [/Devices/ahci/0/Config/] (level 4)
00:00:18.499 PortCount <integer> = 0x0000000000000002 (2)
00:00:18.499 PrimaryMaster <integer> = 0x0000000000000000 (0)
00:00:18.499 PrimarySlave <integer> = 0x0000000000000001 (1)
00:00:18.499 SecondaryMaster <integer> = 0x0000000000000002 (2)
00:00:18.499 SecondarySlave <integer> = 0x0000000000000003 (3)
00:00:18.499
00:00:18.499 [/Devices/ahci/0/LUN#999/] (level 4)
00:00:18.499 Driver <string> = "MainStatus" (cch=11)
00:00:18.499
00:00:18.499 [/Devices/ahci/0/LUN#999/Config/] (level 5)
00:00:18.499 papLeds <integer> = 0x0000000002412e10 (37826064)
00:00:18.499 First <integer> = 0x0000000000000000 (0)
00:00:18.499 Last <integer> = 0x0000000000000001 (1)
00:00:18.499
00:00:18.499 [/Devices/ahci/0/LUN#0/] (level 4)
00:00:18.499 Driver <string> = "Block" (cch=6)
00:00:18.499
00:00:18.499 [/Devices/ahci/0/LUN#0/Config/] (level 5)
00:00:18.499 Type <string> = "HardDisk" (cch=9)
00:00:18.499 Mountable <integer> = 0x0000000000000000 (0)
00:00:18.499
00:00:18.499 [/Devices/ahci/0/LUN#0/AttachedDriver/] (level 5)
00:00:18.499 Driver <string> = "VD" (cch=3)
00:00:18.499
00:00:18.499 [/Devices/ahci/0/LUN#0/AttachedDriver/Config/] (level 6)
00:00:18.499 Path <string> = "/mnt/vb-data/lamm-vb-server/lamm-vb-hdd1.vdi" (cch=45)
00:00:18.499 Format <string> = "VDI" (cch=4)
00:00:18.499
00:00:18.499 [/Devices/ahci/0/LUN#1/] (level 4)
00:00:18.499 Driver <string> = "Block" (cch=6)
00:00:18.499
00:00:18.499 [/Devices/ahci/0/LUN#1/Config/] (level 5)
00:00:18.499 Type <string> = "HardDisk" (cch=9)
00:00:18.499 Mountable <integer> = 0x0000000000000000 (0)
00:00:18.499
00:00:18.499 [/Devices/ahci/0/LUN#1/AttachedDriver/] (level 5)
00:00:18.499 Driver <string> = "VD" (cch=3)
00:00:18.499
00:00:18.499 [/Devices/ahci/0/LUN#1/AttachedDriver/Config/] (level 6)
00:00:18.499 Path <string> = "/mnt/vb-data/lamm-vb-server/lamm-vb-hdd2.vdi" (cch=45)
00:00:18.499 Format <string> = "VDI" (cch=4)
00:00:18.499
00:00:18.499 [/Devices/pcnet/] (level 2)
00:00:18.499
00:00:18.499 [/Devices/e1000/] (level 2)
00:00:18.499
00:00:18.499 [/Devices/e1000/0/] (level 3)
00:00:18.499 Trusted <integer> = 0x0000000000000001 (1)
00:00:18.499 PCIDeviceNo <integer> = 0x0000000000000011 (17)
00:00:18.499 PCIFunctionNo <integer> = 0x0000000000000000 (0)
00:00:18.499
00:00:18.499 [/Devices/e1000/0/Config/] (level 4)
00:00:18.499 AdapterType <integer> = 0x0000000000000002 (2)
00:00:18.499 MAC <bytes> = "08 00 27 62 90 de" (cb=6)
00:00:18.499 CableConnected <integer> = 0x0000000000000001 (1)
00:00:18.499 LineSpeed <integer> = 0x0000000000000000 (0)
00:00:18.499
00:00:18.499 [/Devices/e1000/0/LUN#999/] (level 4)
00:00:18.499 Driver <string> = "MainStatus" (cch=11)
00:00:18.499
00:00:18.499 [/Devices/e1000/0/LUN#999/Config/] (level 5)
00:00:18.499 papLeds <integer> = 0x0000000002412f80 (37826432)
00:00:18.499
00:00:18.499 [/Devices/e1000/0/LUN#0/] (level 4)
00:00:18.499 Driver <string> = "IntNet" (cch=7)
00:00:18.499
00:00:18.499 [/Devices/e1000/0/LUN#0/Config/] (level 5)
00:00:18.500 Trunk <string> = "eth0" (cch=5)
00:00:18.500 TrunkType <integer> = 0x0000000000000003 (3)
00:00:18.500 Network <string> = "HostInterfaceNetworking-eth0" (cch=29)
00:00:18.500
00:00:18.500 [/Devices/serial/] (level 2)
00:00:18.500
00:00:18.500 [/Devices/parallel/] (level 2)
00:00:18.500
00:00:18.500 [/Devices/VMMDev/] (level 2)
00:00:18.500
00:00:18.500 [/Devices/VMMDev/0/] (level 3)
00:00:18.500 Trusted <integer> = 0x0000000000000001 (1)
00:00:18.500 PCIDeviceNo <integer> = 0x0000000000000004 (4)
00:00:18.500 PCIFunctionNo <integer> = 0x0000000000000000 (0)
00:00:18.500
00:00:18.500 [/Devices/VMMDev/0/Config/] (level 4)
00:00:18.500
00:00:18.500 [/Devices/VMMDev/0/LUN#0/] (level 4)
00:00:18.500 Driver <string> = "MainVMMDev" (cch=11)
00:00:18.500
00:00:18.500 [/Devices/VMMDev/0/LUN#0/Config/] (level 5)
00:00:18.500 Object <integer> = 0x0000000002414390 (37831568)
00:00:18.500
00:00:18.500 [/Devices/VMMDev/0/LUN#999/] (level 4)
00:00:18.500 Driver <string> = "MainStatus" (cch=11)
00:00:18.500
00:00:18.500 [/Devices/VMMDev/0/LUN#999/Config/] (level 5)
00:00:18.500 papLeds <integer> = 0x0000000002412fc0 (37826496)
00:00:18.500 First <integer> = 0x0000000000000000 (0)
00:00:18.500 Last <integer> = 0x0000000000000000 (0)
00:00:18.500
00:00:18.500 [/Devices/AudioSniffer/] (level 2)
00:00:18.500
00:00:18.500 [/Devices/AudioSniffer/0/] (level 3)
00:00:18.500
00:00:18.500 [/Devices/AudioSniffer/0/Config/] (level 4)
00:00:18.500
00:00:18.500 [/Devices/AudioSniffer/0/LUN#0/] (level 4)
00:00:18.500 Driver <string> = "MainAudioSniffer" (cch=17)
00:00:18.500
00:00:18.500 [/Devices/AudioSniffer/0/LUN#0/Config/] (level 5)
00:00:18.500 Object <integer> = 0x0000000002414370 (37831536)
00:00:18.500
00:00:18.500 [/Devices/usb-ohci/] (level 2)
00:00:18.500
00:00:18.500 [/Devices/usb-ohci/0/] (level 3)
00:00:18.500 Trusted <integer> = 0x0000000000000001 (1)
00:00:18.500 PCIDeviceNo <integer> = 0x0000000000000006 (6)
00:00:18.500 PCIFunctionNo <integer> = 0x0000000000000000 (0)
00:00:18.500
00:00:18.500 [/Devices/usb-ohci/0/Config/] (level 4)
00:00:18.500
00:00:18.500 [/Devices/usb-ohci/0/LUN#0/] (level 4)
00:00:18.500 Driver <string> = "VUSBRootHub" (cch=12)
00:00:18.500
00:00:18.500 [/Devices/usb-ohci/0/LUN#0/Config/] (level 5)
00:00:18.500
00:00:18.500 [/Devices/usb-ohci/0/LUN#999/] (level 4)
00:00:18.500 Driver <string> = "MainStatus" (cch=11)
00:00:18.500
00:00:18.500 [/Devices/usb-ohci/0/LUN#999/Config/] (level 5)
00:00:18.500 papLeds <integer> = 0x0000000002412fc8 (37826504)
00:00:18.500 First <integer> = 0x0000000000000000 (0)
00:00:18.500 Last <integer> = 0x0000000000000000 (0)
00:00:18.500
00:00:18.500 [/Devices/usb-ehci/] (level 2)
00:00:18.500
00:00:18.500 [/Devices/usb-ehci/0/] (level 3)
00:00:18.500 Trusted <integer> = 0x0000000000000001 (1)
00:00:18.500 PCIDeviceNo <integer> = 0x000000000000000b (11)
00:00:18.500 PCIFunctionNo <integer> = 0x0000000000000000 (0)
00:00:18.500
00:00:18.500 [/Devices/usb-ehci/0/Config/] (level 4)
00:00:18.500
00:00:18.500 [/Devices/usb-ehci/0/LUN#0/] (level 4)
00:00:18.500 Driver <string> = "VUSBRootHub" (cch=12)
00:00:18.500
00:00:18.500 [/Devices/usb-ehci/0/LUN#0/Config/] (level 5)
00:00:18.500
00:00:18.500 [/Devices/usb-ehci/0/LUN#999/] (level 4)
00:00:18.500 Driver <string> = "MainStatus" (cch=11)
00:00:18.500
00:00:18.500 [/Devices/usb-ehci/0/LUN#999/Config/] (level 5)
00:00:18.500 papLeds <integer> = 0x0000000002412fd0 (37826512)
00:00:18.500 First <integer> = 0x0000000000000000 (0)
00:00:18.500 Last <integer> = 0x0000000000000000 (0)
00:00:18.500
00:00:18.500 [/TM/] (level 1)
00:00:18.500 UTCOffset <integer> = 0x0000000000000000 (0)
00:00:18.500
00:00:18.500 ********************* End of CFGM dump **********************
00:00:18.500 MM: cbHyperHeap=0x220000 (2228224)
The log is not complete, i post it in a second post.
Do you need more infromations? If yes, about what?
My guest settings (sorry is in german):
Re: VM Windows Server 2008 hang ups
Posted: 6. Oct 2009, 06:32
by edvl
The end of the log shows my poweroff command.
Here is the second part of the log file:
Code: Select all
00:00:18.500 ********************* End of CFGM dump **********************
00:00:18.500 MM: cbHyperHeap=0x220000 (2228224)
00:00:18.501 CPUMSetGuestCpuIdFeature: Enabled PAE
00:00:18.502 Logical host processors: 4, processor active mask: 000000000000000f
00:00:18.502 ************************* CPUID dump ************************
00:00:18.502 RAW Standard CPUIDs
00:00:18.502 Function eax ebx ecx edx
00:00:18.502 Gst: 00000000 00000005 756e6547 6c65746e 49656e69
00:00:18.502 Hst: 0000000d 756e6547 6c65746e 49656e69
00:00:18.502 Gst: 00000001 0001067a 00020800 00000001 178bf1ff
00:00:18.502 Hst: 0001067a 01040800 0c08e3fd bfebfbff
00:00:18.502 Gst: 00000002 05b0b101 005657f0 00000000 2cb4304e
00:00:18.502 Hst: 05b0b101 005657f0 00000000 2cb4304e
00:00:18.502 Gst: 00000003 00000000 00000000 00000000 00000000
00:00:18.502 Hst: 00000000 00000000 00000000 00000000
00:00:18.502 Gst: 00000004 04000000 00000000 00000000 00000000
00:00:18.502 Hst: 0c000121 01c0003f 0000003f 00000001
00:00:18.502 Gst: 00000005 00000000 00000000 00000000 00000000
00:00:18.502 Hst: 00000040 00000040 00000003 00022220
00:00:18.502 Name: GenuineIntel
00:00:18.502 Supports: 0-5
00:00:18.502 Family: 6 Extended: 0 Effective: 6
00:00:18.502 Model: 7 Extended: 1 Effective: 23
00:00:18.502 Stepping: 10
00:00:18.502 APIC ID: 0x00
00:00:18.502 Logical CPUs: 2
00:00:18.502 CLFLUSH Size: 8
00:00:18.502 Brand ID: 0x00
00:00:18.502 Mnemonic - Description = guest (host)
00:00:18.502 FPU - x87 FPU on Chip = 1 (1)
00:00:18.502 VME - Virtual 8086 Mode Enhancements = 1 (1)
00:00:18.502 DE - Debugging extensions = 1 (1)
00:00:18.502 PSE - Page Size Extension = 1 (1)
00:00:18.502 TSC - Time Stamp Counter = 1 (1)
00:00:18.502 MSR - Model Specific Registers = 1 (1)
00:00:18.502 PAE - Physical Address Extension = 1 (1)
00:00:18.502 MCE - Machine Check Exception = 1 (1)
00:00:18.502 CX8 - CMPXCHG8B instruction = 1 (1)
00:00:18.502 APIC - APIC On-Chip = 0 (1)
00:00:18.502 Reserved = 0 (0)
00:00:18.502 SEP - SYSENTER and SYSEXIT = 0 (1)
00:00:18.502 MTRR - Memory Type Range Registers = 1 (1)
00:00:18.502 PGE - PTE Global Bit = 1 (1)
00:00:18.502 MCA - Machine Check Architecture = 1 (1)
00:00:18.502 CMOV - Conditional Move Instructions = 1 (1)
00:00:18.502 PAT - Page Attribute Table = 1 (1)
00:00:18.502 PSE-36 - 36-bit Page Size Extention = 1 (1)
00:00:18.502 PSN - Processor Serial Number = 0 (0)
00:00:18.502 CLFSH - CLFLUSH Instruction. = 1 (1)
00:00:18.502 Reserved = 0 (0)
00:00:18.502 DS - Debug Store = 0 (1)
00:00:18.502 ACPI - Thermal Mon. & Soft. Clock Ctrl.= 0 (1)
00:00:18.502 MMX - Intel MMX Technology = 1 (1)
00:00:18.502 FXSR - FXSAVE and FXRSTOR Instructions = 1 (1)
00:00:18.502 SSE - SSE Support = 1 (1)
00:00:18.502 SSE2 - SSE2 Support = 1 (1)
00:00:18.502 SS - Self Snoop = 0 (1)
00:00:18.502 HTT - Hyper-Threading Technolog = 1 (1)
00:00:18.502 TM - Thermal Monitor = 0 (1)
00:00:18.502 30 - Reserved = 0 (0)
00:00:18.502 PBE - Pending Break Enable = 0 (1)
00:00:18.502 Supports SSE3 or not = 1 (1)
00:00:18.502 Reserved = 0 (0)
00:00:18.502 DS Area 64-bit layout = 0 (1)
00:00:18.502 Supports MONITOR/MWAIT = 0 (1)
00:00:18.502 CPL-DS - CPL Qualified Debug Store = 0 (1)
00:00:18.502 VMX - Virtual Machine Technology = 0 (1)
00:00:18.502 SMX - Safer Mode Extensions = 0 (1)
00:00:18.502 Enhanced SpeedStep Technology = 0 (1)
00:00:18.502 Terminal Monitor 2 = 0 (1)
00:00:18.502 Supports Supplemental SSE3 or not = 0 (1)
00:00:18.502 L1 Context ID = 0 (0)
00:00:18.502 Reserved = 0x0 (0x0)
00:00:18.502 CMPXCHG16B = 0 (1)
00:00:18.502 xTPR Update Control = 0 (1)
00:00:18.502 Perf/Debug Capability MSR = 0 (1)
00:00:18.502 Reserved = 0x0 (0x0)
00:00:18.502 Direct Cache Access = 0 (0)
00:00:18.502 Supports SSE4_1 or not = 0 (1)
00:00:18.502 Supports SSE4_2 or not = 0 (0)
00:00:18.502 Supports the x2APIC extensions = 0 (0)
00:00:18.502 Supports MOVBE = 0 (0)
00:00:18.502 Supports POPCNT = 0 (0)
00:00:18.502 Reserved = 0x0 (0x0)
00:00:18.502 Supports XSAVE = 0 (1)
00:00:18.502 Supports OSXSAVE = 0 (1)
00:00:18.502 Reserved = 0x0 (0x0)
00:00:18.502
00:00:18.502 RAW Extended CPUIDs
00:00:18.502 Function eax ebx ecx edx
00:00:18.502 Gst: 80000000 80000008 00000000 00000000 00000000
00:00:18.502 Hst: 80000008 00000000 00000000 00000000
00:00:18.502 Gst: 80000001 00000000 00000000 00000000 00000000
00:00:18.502 Hst: 00000000 00000000 00000001 20100800
00:00:18.502 Gst: 80000002 65746e49 2952286c 726f4320 4d542865
00:00:18.502 Hst: 65746e49 2952286c 726f4320 4d542865
00:00:18.516 Gst: 80000003 51203229 20646175 20555043 51202020
00:00:18.516 Hst: 51203229 20646175 20555043 51202020
00:00:18.516 Gst: 80000004 30353539 20402020 33382e32 007a4847
00:00:18.516 Hst: 30353539 20402020 33382e32 007a4847
00:00:18.516 Gst: 80000005 00000000 00000000 00000000 00000000
00:00:18.516 Hst: 00000000 00000000 00000000 00000000
00:00:18.516 Gst: 80000006 00000000 00000000 18008040 00000000
00:00:18.516 Hst: 00000000 00000000 18008040 00000000
00:00:18.516 Gst: 80000007 00000000 00000000 00000000 00000000
00:00:18.516 Hst: 00000000 00000000 00000000 00000000
00:00:18.516 Gst: 80000008 00003024 00000000 00000000 00000000
00:00:18.516 Hst: 00003024 00000000 00000000 00000000
00:00:18.516 Gst: 80000009 00000000 00000000 00000000 00000000*
00:00:18.516 Hst: 00000000 00000000 00000000 00000000
00:00:18.516 Ext Name:
00:00:18.516 Ext Supports: 0x80000000-0x80000008
00:00:18.516 Family: 0 Extended: 0 Effective: 0
00:00:18.516 Model: 0 Extended: 0 Effective: 0
00:00:18.516 Stepping: 0
00:00:18.516 Brand ID: 0x000
00:00:18.516 Mnemonic - Description = guest (host)
00:00:18.516 FPU - x87 FPU on Chip = 0 (0)
00:00:18.516 VME - Virtual 8086 Mode Enhancements = 0 (0)
00:00:18.516 DE - Debugging extensions = 0 (0)
00:00:18.516 PSE - Page Size Extension = 0 (0)
00:00:18.516 TSC - Time Stamp Counter = 0 (0)
00:00:18.516 MSR - K86 Model Specific Registers = 0 (0)
00:00:18.516 PAE - Physical Address Extension = 0 (0)
00:00:18.516 MCE - Machine Check Exception = 0 (0)
00:00:18.516 CX8 - CMPXCHG8B instruction = 0 (0)
00:00:18.516 APIC - APIC On-Chip = 0 (0)
00:00:18.516 10 - Reserved = 0 (0)
00:00:18.516 SEP - SYSCALL and SYSRET = 0 (1)
00:00:18.516 MTRR - Memory Type Range Registers = 0 (0)
00:00:18.516 PGE - PTE Global Bit = 0 (0)
00:00:18.516 MCA - Machine Check Architecture = 0 (0)
00:00:18.516 CMOV - Conditional Move Instructions = 0 (0)
00:00:18.516 PAT - Page Attribute Table = 0 (0)
00:00:18.516 PSE-36 - 36-bit Page Size Extention = 0 (0)
00:00:18.516 18 - Reserved = 0 (0)
00:00:18.516 19 - Reserved = 0 (0)
00:00:18.516 NX - No-Execute Page Protection = 0 (1)
00:00:18.516 DS - Debug Store = 0 (0)
00:00:18.516 AXMMX - AMD Extensions to MMX Instr. = 0 (0)
00:00:18.516 MMX - Intel MMX Technology = 0 (0)
00:00:18.516 FXSR - FXSAVE and FXRSTOR Instructions = 0 (0)
00:00:18.516 25 - AMD fast FXSAVE and FXRSTOR Instr.= 0 (0)
00:00:18.516 26 - 1 GB large page support = 0 (0)
00:00:18.516 27 - RDTSCP instruction = 0 (0)
00:00:18.516 28 - Reserved = 0 (0)
00:00:18.516 29 - AMD Long Mode = 0 (1)
00:00:18.516 30 - AMD Extensions to 3DNow = 0 (0)
00:00:18.516 31 - AMD 3DNow = 0 (0)
00:00:18.516 LahfSahf - LAHF/SAHF in 64-bit mode = 0 (1)
00:00:18.516 CmpLegacy - Core MP legacy mode (depr) = 0 (0)
00:00:18.516 SVM - AMD VM Extensions = 0 (0)
00:00:18.516 APIC registers starting at 0x400 = 0 (0)
00:00:18.516 AltMovCR8 - LOCK MOV CR0 means MOV CR8 = 0 (0)
00:00:18.516 Advanced bit manipulation = 0 (0)
00:00:18.516 SSE4A instruction support = 0 (0)
00:00:18.516 Misaligned SSE mode = 0 (0)
00:00:18.516 PREFETCH and PREFETCHW instruction = 0 (0)
00:00:18.516 OS visible workaround = 0 (0)
00:00:18.516 Instruction based sampling = 0 (0)
00:00:18.516 SSE5 support = 0 (0)
00:00:18.516 SKINIT, STGI, and DEV support = 0 (0)
00:00:18.516 Watchdog timer support. = 0 (0)
00:00:18.516 31:14 - Reserved = 0x0 (0x0)
00:00:18.516 Full Name: Intel(R) Core(TM)2 Quad CPU Q9550 @ 2.83GHz
00:00:18.516 TLB 2/4M Instr/Uni: res0 0 entries
00:00:18.516 TLB 2/4M Data: res0 0 entries
00:00:18.516 TLB 4K Instr/Uni: res0 0 entries
00:00:18.516 TLB 4K Data: res0 0 entries
00:00:18.516 L1 Instr Cache Line Size: 0 bytes
00:00:18.516 L1 Instr Cache Lines Per Tag: 0
00:00:18.516 L1 Instr Cache Associativity: res0
00:00:18.516 L1 Instr Cache Size: 0 KB
00:00:18.516 L1 Data Cache Line Size: 0 bytes
00:00:18.516 L1 Data Cache Lines Per Tag: 0
00:00:18.516 L1 Data Cache Associativity: res0
00:00:18.516 L1 Data Cache Size: 0 KB
00:00:18.516 L2 TLB 2/4M Instr/Uni: off 0 entries
00:00:18.516 L2 TLB 2/4M Data: off 0 entries
00:00:18.516 L2 TLB 4K Instr/Uni: off 0 entries
00:00:18.516 L2 TLB 4K Data: off 0 entries
00:00:18.516 L2 Cache Line Size: 0 bytes
00:00:18.516 L2 Cache Lines Per Tag: 0
00:00:18.516 L2 Cache Associativity: off
00:00:18.516 L2 Cache Size: 0 KB
00:00:18.516 APM Features:
00:00:18.516 Physical Address Width: 36 bits
00:00:18.516 Virtual Address Width: 48 bits
00:00:18.516 Physical Core Count: 0
00:00:18.516
00:00:18.516 RAW Centaur CPUIDs
00:00:18.516 Function eax ebx ecx edx
00:00:18.516 Gst: c0000000 00000000 00000000 00000000 00000000
00:00:18.516 Hst: 00000000 00000000 00000000 00000000
00:00:18.516 Gst: c0000001 00000000 00000000 00000000 00000000*
00:00:18.516 Hst: 00000000 00000000 00000000 00000000
00:00:18.516 Gst: c0000002 00000000 00000000 00000000 00000000*
00:00:18.516 Hst: 00000000 00000000 00000000 00000000
00:00:18.516 Gst: c0000003 00000000 00000000 00000000 00000000*
00:00:18.516 Hst: 00000000 00000000 00000000 00000000
00:00:18.516 Centaur Supports: 0xc0000000-0x00000000
00:00:18.516
00:00:18.516 ******************** End of CPUID dump **********************
00:00:18.517 Debug: HCPhysInterPD=00000000121be000 HCPhysInterPaePDPT=0000000017ddc000 HCPhysInterPaePML4=0000000012083000
00:00:18.517 Debug: apInterPTs={0000000017d17000,0000000017c6d000} apInterPaePTs={000000019e748000,000000023d42b000} apInterPaePDs={000000023dd43000,000000023d155000,000000021906c000,000000019e684000} pInterPaePDPT64=000000001214c000
00:00:18.597 TM: GIP - u32Mode=1 (SyncTSC) u32UpdateHz=83
00:00:18.629 TM: cTSCTicksPerSecond=0xa8a3cbc6 (2 829 306 822) fTSCVirtualized=true fTSCUseRealTSC=false
00:00:18.629 TM: fMaybeUseOffsettedHostTSC=true TSCTiedToExecution=false TSCNotTiedToHalt=false
00:00:18.629 CoreCode: R3=00007f87a21d1000 R0=ffffc2001414d000 RC=a10a8000 Phys=00000000b2215000 cb=0x1000
00:00:18.686 [SMP] BIOS with 2 CPUs
00:00:18.722 SUP: Loaded VBoxDDR0.r0 (/usr/lib/virtualbox/VBoxDDR0.r0) at 0xffffffffa0420860 - ModuleInit at 0000000000000000 and ModuleTerm at 0000000000000000
00:00:18.729 SUP: Loaded VBoxDD2R0.r0 (/usr/lib/virtualbox/VBoxDD2R0.r0) at 0xffffffffa0433c60 - ModuleInit at 0000000000000000 and ModuleTerm at 0000000000000000
00:00:18.729 Activating Local APIC
00:00:18.729 CPUMSetGuestCpuIdFeature: Enabled APIC
00:00:18.729 CPUMSetGuestCpuIdFeature: Disabled x2APIC
00:00:18.729 PIT: mode=3 count=0x10000 (65536) - 18.20 Hz (ch=0)
00:00:18.731 Shared Folders service loaded.
00:00:18.761 PIIX3 ATA: LUN#0: no unit
00:00:18.761 PIIX3 ATA: LUN#1: no unit
00:00:18.761 PIIX3 ATA: LUN#2: CD/DVD, total number of sectors 0, passthrough disabled
00:00:18.761 PIIX3 ATA: LUN#3: no unit
00:00:18.761 PIIX3 ATA: Ctl#0: finished processing RESET
00:00:18.861 PIIX3 ATA: Ctl#1: finished processing RESET
00:00:18.964 VDInit finished
00:00:18.964 AHCI: LUN#0: disk, PCHS=16383/16/63, total number of sectors 167772160
00:00:18.964 AHCI: LUN#0: using normal I/O
00:00:18.977 AHCI: LUN#1: disk, PCHS=16383/16/63, total number of sectors 169869312
00:00:18.977 AHCI: LUN#1: using normal I/O
00:00:18.977 AHCI ATA: LUN#0: disk, PCHS=16383/16/63, total number of sectors 167772160
00:00:18.977 AHCI ATA: LUN#1: disk, PCHS=16383/16/63, total number of sectors 169869312
00:00:18.977 ATA: Ctl: finished processing RESET
00:00:19.077 AHCI ATA: LUN#0: no unit
00:00:19.077 AHCI ATA: LUN#1: no unit
00:00:19.077 ATA: Ctl: finished processing RESET
00:00:19.177 ATA: Ctl: finished processing RESET
00:00:19.277 ATA: Ctl: finished processing RESET
00:00:19.377 IntNet#0: szNetwork={HostInterfaceNetworking-eth0} enmTrunkType=3 szTrunk={eth0} fFlags=0x0 cbRecv=223232 cbSend=36864
00:00:19.379 DevPcBios: SATA LUN#0 LCHS=1024/255/63
00:00:19.379 DevPcBios: SATA LUN#1 LCHS=1024/255/63
00:00:19.379 PGMR3InitFinalize: 4 MB PSE mask 0000000fffffffff
00:00:19.390 HWACCM: Host CR4=000406E0
00:00:19.390 HWACCM: MSR_IA32_FEATURE_CONTROL = 5
00:00:19.390 HWACCM: MSR_IA32_VMX_BASIC_INFO = 5a08000000000d
00:00:19.390 HWACCM: VMCS id = d
00:00:19.390 HWACCM: VMCS size = 800
00:00:19.390 HWACCM: VMCS physical address limit = None
00:00:19.390 HWACCM: VMCS memory type = 6
00:00:19.390 HWACCM: Dual monitor treatment = 1
00:00:19.390 HWACCM: MSR_IA32_VMX_PINBASED_CTLS = 3f00000016
00:00:19.390 HWACCM: VMX_VMCS_CTRL_PIN_EXEC_CONTROLS_EXT_INT_EXIT
00:00:19.390 HWACCM: VMX_VMCS_CTRL_PIN_EXEC_CONTROLS_NMI_EXIT
00:00:19.390 HWACCM: VMX_VMCS_CTRL_PIN_EXEC_CONTROLS_VIRTUAL_NMI
00:00:19.390 HWACCM: MSR_IA32_VMX_PROCBASED_CTLS = f7f9fffe0401e172
00:00:19.390 HWACCM: VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_IRQ_WINDOW_EXIT
00:00:19.390 HWACCM: VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_TSC_OFFSET
00:00:19.390 HWACCM: VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_HLT_EXIT
00:00:19.390 HWACCM: VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_INVLPG_EXIT
00:00:19.390 HWACCM: VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_MWAIT_EXIT
00:00:19.390 HWACCM: VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_RDPMC_EXIT
00:00:19.390 HWACCM: VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_RDTSC_EXIT
00:00:19.390 HWACCM: VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_CR3_LOAD_EXIT
00:00:19.390 HWACCM: VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_CR3_STORE_EXIT
00:00:19.390 HWACCM: VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_CR8_LOAD_EXIT
00:00:19.390 HWACCM: VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_CR8_STORE_EXIT
00:00:19.390 HWACCM: VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_USE_TPR_SHADOW
00:00:19.390 HWACCM: VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_NMI_WINDOW_EXIT
00:00:19.390 HWACCM: VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_MOV_DR_EXIT
00:00:19.390 HWACCM: VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_UNCOND_IO_EXIT
00:00:19.390 HWACCM: VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_USE_IO_BITMAPS
00:00:19.390 HWACCM: VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_USE_MSR_BITMAPS
00:00:19.390 HWACCM: VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_MONITOR_EXIT
00:00:19.390 HWACCM: VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_PAUSE_EXIT
00:00:19.390 HWACCM: VMX_VMCS_CTRL_PROC_EXEC_USE_SECONDARY_EXEC_CTRL
00:00:19.390 HWACCM: VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_CR3_LOAD_EXIT *must* be set
00:00:19.390 HWACCM: VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_CR3_STORE_EXIT *must* be set
00:00:19.390 HWACCM: MSR_IA32_VMX_PROCBASED_CTLS2 = 4100000000
00:00:19.390 HWACCM: VMX_VMCS_CTRL_PROC_EXEC2_VIRT_APIC
00:00:19.390 HWACCM: VMX_VMCS_CTRL_PROC_EXEC2_WBINVD_EXIT
00:00:19.390 HWACCM: MSR_IA32_VMX_ENTRY_CTLS = 3fff000011ff
00:00:19.390 HWACCM: VMX_VMCS_CTRL_ENTRY_CONTROLS_LOAD_DEBUG
00:00:19.390 HWACCM: VMX_VMCS_CTRL_ENTRY_CONTROLS_IA64_MODE
00:00:19.390 HWACCM: VMX_VMCS_CTRL_ENTRY_CONTROLS_ENTRY_SMM
00:00:19.390 HWACCM: VMX_VMCS_CTRL_ENTRY_CONTROLS_DEACTIVATE_DUALMON
00:00:19.390 HWACCM: VMX_VMCS_CTRL_ENTRY_CONTROLS_LOAD_GUEST_PERF_MSR
00:00:19.390 HWACCM: VMX_VMCS_CTRL_ENTRY_CONTROLS_LOAD_DEBUG *must* be set
00:00:19.390 HWACCM: MSR_IA32_VMX_EXIT_CTLS = 3ffff00036dff
00:00:19.390 HWACCM: VMX_VMCS_CTRL_EXIT_CONTROLS_SAVE_DEBUG
00:00:19.390 HWACCM: VMX_VMCS_CTRL_EXIT_CONTROLS_HOST_AMD64
00:00:19.390 HWACCM: VMX_VMCS_CTRL_EXIT_CONTROLS_ACK_EXTERNAL_IRQ
00:00:19.390 HWACCM: VMX_VMCS_CTRL_EXIT_CONTROLS_SAVE_DEBUG *must* be set
00:00:19.390 HWACCM: MSR_IA32_VMX_MISC = 403c0
00:00:19.390 HWACCM: MSR_IA32_VMX_MISC_PREEMPT_TSC_BIT 0
00:00:19.390 HWACCM: MSR_IA32_VMX_MISC_ACTIVITY_STATES 7
00:00:19.390 HWACCM: MSR_IA32_VMX_MISC_CR3_TARGET 4
00:00:19.390 HWACCM: MSR_IA32_VMX_MISC_MAX_MSR 200
00:00:19.390 HWACCM: MSR_IA32_VMX_MISC_MSEG_ID 0
00:00:19.390 HWACCM: MSR_IA32_VMX_CR0_FIXED0 = 80000021
00:00:19.390 HWACCM: MSR_IA32_VMX_CR0_FIXED1 = ffffffff
00:00:19.390 HWACCM: MSR_IA32_VMX_CR4_FIXED0 = 2000
00:00:19.390 HWACCM: MSR_IA32_VMX_CR4_FIXED1 = 467ff
00:00:19.390 HWACCM: MSR_IA32_VMX_VMCS_ENUM = 2c
00:00:19.390 HWACCM: TPR shadow physaddr = 000000001d501000
00:00:19.390 HWACCM: VCPU0: MSR bitmap physaddr = 0000000017d13000
00:00:19.390 HWACCM: VCPU0: VMCS physaddr = 000000001202b000
00:00:19.390 HWACCM: VCPU1: MSR bitmap physaddr = 000000001212c000
00:00:19.390 HWACCM: VCPU1: VMCS physaddr = 0000000017c84000
00:00:19.390 HWACCM: Real Mode TSS guest physaddr = 00000000f0400000
00:00:19.390 HWACCM: Non-Paging Mode EPT CR3 = 00000000f0403000
00:00:19.397 CPUMSetGuestCpuIdFeature: Enabled sysenter/exit
00:00:19.397 CPUMSetGuestCpuIdFeature: Enabled PAE
00:00:19.397 CPUMSetGuestCpuIdFeature: Enabled LONG MODE
00:00:19.397 CPUMSetGuestCpuIdFeature: Enabled syscall/ret
00:00:19.397 CPUMSetGuestCpuIdFeature: Enabled LAHF/SAHF
00:00:19.397 CPUMSetGuestCpuIdFeature: Enabled NXE
00:00:19.397 HWACCM: 32-bit and 64-bit guests supported.
00:00:19.397 HWACCM: VMX enabled!
00:00:19.404 VM: Halt method global1 (5)
00:00:19.404 Changing the VM state from 'CREATING' to 'CREATED'.
00:00:19.415 Changing the VM state from 'CREATED' to 'LOADING'.
00:00:26.457 SSM: File header: Format V1.2, VirtualBox Version 3.0.6 r52128, 64-bit host, cbGCPhys=8, cbGCPtr=8
00:00:26.457 PCI: PIIX3/0: register 60: 0b -> 80
00:00:26.457 PCI: PIIX3/0: register 61: 09 -> 8a
00:00:26.457 PCI: PIIX3/0: register 62: 0b -> 80
00:00:26.457 PCI: PIIX3/0: register 63: 09 -> 80
00:00:26.457 PCI: PIIX3/0: register ad: 00 -> ef
00:00:26.457 PCI: PIIX3/0: register de: 00 -> be
00:00:26.457 PCI: piix3ide/0: 16-bit field COMMAND: 7 -> 5
00:00:26.457 PCI: piix3ide/0: register 42: 00 -> 03
00:00:26.457 PCI: piix3ide/0: register 43: 80 -> e3
00:00:26.457 PCI: piix3ide/0: register 48: 00 -> 04
00:00:26.457 PCI: piix3ide/0: register 4b: 00 -> 02
00:00:26.457 PCI: vga/0: 16-bit field COMMAND: 2 -> 7
00:00:26.458 PCI: VMMDev/0: 8-bit field INTERRUPT_LINE: 9 -> 14
00:00:26.458 PCI: VMMDev/0: 16-bit field COMMAND: 3 -> 7
00:00:26.458 PCI: usb-ohci/0: 8-bit field INTERRUPT_LINE: 9 -> 16
00:00:26.458 PCI: usb-ohci/0: 16-bit field COMMAND: 2 -> 6
00:00:26.458 PCI: acpi/0: 16-bit field COMMAND: 1 -> 7
00:00:26.458 PCI: usb-ehci/0: 8-bit field INTERRUPT_LINE: b -> 13
00:00:26.458 PCI: usb-ehci/0: 16-bit field COMMAND: 2 -> 6
00:00:26.458 PCI: ahci/0: 8-bit field INTERRUPT_LINE: b -> 15
00:00:26.458 PCI: ahci/0: 16-bit field COMMAND: 3 -> 7
00:00:26.458 PCI: e1000/0: 8-bit field INTERRUPT_LINE: b -> 11
00:00:26.458 PCI: e1000/0: 16-bit field COMMAND: 3 -> 7
00:00:40.604 TM: cTSCTicksPerSecond=0xa8a2c621 (2 829 239 841) fTSCVirtualized=true fTSCUseRealTSC=false (state load)
00:00:40.610 PIT: mode=2 count=0x10000 (65536) - 18.20 Hz (ch=0) (restore)
00:00:40.610 RTC: period=0x20 (32) 1024 Hz (restore)
00:00:40.650 SharedFolders host service: connected, u32ClientID = 1
00:00:40.650 SharedFolders host service: loading state, u32ClientID = 1
00:00:40.650 SharedFolders host service: success
00:00:40.650 Starting host clipboard service
00:00:40.685 ClipConstructX11: X11 DISPLAY variable not set -- disabling shared clipboard
00:00:40.726 Guest Additions information report: additionsVersion = 0x00010004, osType = 0x00035000
00:00:40.760 Changing the VM state from 'LOADING' to 'SUSPENDED'.
00:00:40.760 Changing the VM state from 'SUSPENDED' to 'RUNNING'.
00:00:42.929 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=00007f87960fd000 w=1280 h=1024 bpp=32 cbLine=0x1400
00:00:43.025 VBVA: Enabled.
00:03:28.778 Console::powerDown(): A request to power off the VM has been issued (mMachineState=8, InUninit=0)
00:03:28.883 VRDP: TCP server closed.
00:03:28.889 SharedFolders host service: disconnected, u32ClientID = 1
00:03:28.889 Stopping the host clipboard service
00:03:28.951 ****************** Guest state at power off ******************
00:03:28.951 Guest CPUM (VCPU 0) state: se
00:03:28.951 rax=0000000000000000 rbx=00000000026929a0 rcx=0000000002569678 rdx=0000000000000000
00:03:28.951 rsi=0000000000000000 rdi=0000000000000000 r8 =0000000000000000 r9 =000007fffffdf000
00:03:28.951 r10=000007fefc18e340 r11=000000000b10f878 r12=0000000000000000 r13=00000000000003e8
00:03:28.951 r14=00000000000003e8 r15=0000000000000017
00:03:28.951 rip=0000000076f1cfdc rsp=000000000b10f660 rbp=000007fefc18e560 iopl=0 rf nv up ei pl nz na po nc
00:03:28.951 cs={0033 base=0000000000000000 limit=ffffffff flags=0000a0fb}
00:03:28.951 ds={002b base=0000000000000000 limit=ffffffff flags=0000c0f3}
00:03:28.951 es={002b base=0000000000000000 limit=ffffffff flags=0000c0f3}
00:03:28.951 fs={0053 base=00000000fffdb000 limit=00003c00 flags=000040f3}
00:03:28.951 gs={002b base=000007fffffd9000 limit=ffffffff flags=0000c0f3}
00:03:28.951 ss={002b base=0000000000000000 limit=ffffffff flags=0000c0f3}
00:03:28.951 cr0=00000000e0050031 cr2=000000000326f940 cr3=00000000c0536000 cr4=00000000000006f8
00:03:28.951 dr0=0000000000000000 dr1=0000000000000000 dr2=0000000000000000 dr3=0000000000000000
00:03:28.951 dr4=0000000000000000 dr5=0000000000000000 dr6=00000000ffff0ff0 dr7=0000000000000400
00:03:28.951 gdtr=fffff80003a79000:006f idtr=fffff80003a79070:0fff eflags=00010246
00:03:28.951 ldtr={0000 base=00000000 limit=00000000 flags=00000082}
00:03:28.951 tr ={0040 base=fffff80003a7a070 limit=00000067 flags=0000008b}
00:03:28.951 SysEnter={cs=0000 eip=0000000000000000 esp=0000000000000000}
00:03:28.951 FPU:
00:03:28.951 FCW=027f FSW=0000 FTW=00
00:03:28.951 res1=00 FOP=0000 FPUIP=00000000 CS=0000 Rsvrd1=0000
00:03:28.951 FPUDP=0000 DS=0000 Rsvrd2=0000 MXCSR=00001fa0 MXCSR_MASK=0000ffff
00:03:28.951 MSR:
00:03:28.951 EFER =0000000000000d01
00:03:28.951 PAT =0007010600070106
00:03:28.951 STAR =0023001000000000
00:03:28.951 CSTAR =fffff8000166a980
00:03:28.951 LSTAR =fffff8000166ac00
00:03:28.951 SFMASK =0000000000014700
00:03:28.951 KERNELGSBASE =fffff8000178a500
00:03:28.951 ***
00:03:28.951 Guest paging mode: AMD64+NX, changed 2 times, A20 enabled
00:03:28.951 Shadow paging mode: AMD64
00:03:28.951 Host paging mode: AMD64+G+NX
00:03:28.951 ***
00:03:28.951 Active Timers (pVM=00007f879b776000)
00:03:28.951 pTimerR3 offNext offPrev offSched Clock Time Expire State Description
00:03:28.951 00007f8799dfcfa0 00000000 00000000 00000000 Real 000000000433361977 000000000433361991 2-ACTIVE VGA Refresh Timer
00:03:28.951 00007f8799e1a500 ffffe9d0 00000000 00000000 Virt 000429897013621135 000429897029750788 2-ACTIVE USB2 Frame Timer (no sync)
00:03:28.951 00007f8799e18ed0 00000000 00001630 00000000 Virt 000429897013631526 000429897029752912 2-ACTIVE USB Frame Timer
00:03:28.951 00007f8799deaf40 fffffbb0 00000000 00000000 VrSy 000429897013493810 000429897013671876 2-ACTIVE MC146818 RTC/CMOS - Periodic
00:03:28.951 00007f8799deaaf0 000004d0 00000450 00000000 VrSy 000429897013503849 000429897019588739 2-ACTIVE i8254 Programmable Interval Timer
00:03:28.951 00007f8799deafc0 00030fb0 fffffb30 00000000 VrSy 000429897013513561 000429897990000000 2-ACTIVE MC146818 RTC/CMOS - Second
00:03:28.951 00007f8799e1bf70 00000000 fffcf050 00000000 VrSy 000429897013523391 000430930662859727 2-ACTIVE ACPI Timer
00:03:28.951 ***
00:03:28.951 Shadow GDT (GCAddr=fe4aa000):
00:03:28.951 ffd8 - 97380087 fd008940 - base=fd409738 limit=00000087 dpl=0 TSS32Avail Present 16-bit HyperTSSTrap08
00:03:28.951 ffe0 - 96b00087 fd008940 - base=fd4096b0 limit=00000087 dpl=0 TSS32Avail Present 16-bit HyperTSS
00:03:28.951 ffe8 - 0000ffff 00af9b00 - base=00000000 limit=ffffffff dpl=0 CodeER Accessed Present Page 16-bit HyperCS64
00:03:28.951 fff0 - 0000ffff 00cf9300 - base=00000000 limit=ffffffff dpl=0 DataRW Accessed Present Page 32-bit HyperDS
00:03:28.951 fff8 - 0000ffff 00cf9b00 - base=00000000 limit=ffffffff dpl=0 CodeER Accessed Present Page 32-bit HyperCS
00:03:28.951 ***
00:03:28.951 ************** End of Guest state at power off ***************
00:03:28.951 Changing the VM state from 'RUNNING' to 'OFF'.
00:03:29.036 Changing the VM state from 'OFF' to 'DESTROYING'.
00:03:29.036 ************************* Statistics *************************
00:03:29.036 /Devices/ATA0/Unit0/AtapiDMA 0 times
00:03:29.036 /Devices/ATA0/Unit0/AtapiPIO 0 times
00:03:29.036 /Devices/ATA0/Unit0/DMA 0 times
00:03:29.036 /Devices/ATA0/Unit0/PIO 0 times
00:03:29.036 /Devices/ATA0/Unit0/ReadBytes 0 bytes
00:03:29.036 /Devices/ATA0/Unit0/WrittenBytes 0 bytes
00:03:29.036 /Devices/ATA0/Unit1/AtapiDMA 0 times
00:03:29.036 /Devices/ATA0/Unit1/AtapiPIO 0 times
00:03:29.036 /Devices/ATA0/Unit1/DMA 0 times
00:03:29.036 /Devices/ATA0/Unit1/PIO 0 times
00:03:29.036 /Devices/ATA0/Unit1/ReadBytes 0 bytes
00:03:29.036 /Devices/ATA0/Unit1/WrittenBytes 0 bytes
00:03:29.036 /Devices/ATA1/Unit0/AtapiDMA 0 times
00:03:29.036 /Devices/ATA1/Unit0/AtapiPIO 164 times
00:03:29.036 /Devices/ATA1/Unit0/DMA 0 times
00:03:29.036 /Devices/ATA1/Unit0/PIO 0 times
00:03:29.036 /Devices/ATA1/Unit0/ReadBytes 0 bytes
00:03:29.036 /Devices/ATA1/Unit0/WrittenBytes 0 bytes
00:03:29.036 /Devices/ATA1/Unit1/AtapiDMA 0 times
00:03:29.036 /Devices/ATA1/Unit1/AtapiPIO 0 times
00:03:29.036 /Devices/ATA1/Unit1/DMA 0 times
00:03:29.036 /Devices/ATA1/Unit1/PIO 0 times
00:03:29.036 /Devices/ATA1/Unit1/ReadBytes 0 bytes
00:03:29.036 /Devices/ATA1/Unit1/WrittenBytes 0 bytes
00:03:29.036 /Devices/E1k0/ReceiveBytes 12489 bytes
00:03:29.036 /Devices/E1k0/TransmitBytes 16656 bytes
00:03:29.036 /Devices/SATA/Port0/DMA 0 times
00:03:29.036 /Devices/SATA/Port0/IORequestsPerSecond 0 times
00:03:29.036 /Devices/SATA/Port0/ReadBytes 0 bytes
00:03:29.036 /Devices/SATA/Port0/WrittenBytes 0 bytes
00:03:29.036 /Devices/SATA/Port1/DMA 0 times
00:03:29.036 /Devices/SATA/Port1/IORequestsPerSecond 0 times
00:03:29.036 /Devices/SATA/Port1/ReadBytes 0 bytes
00:03:29.036 /Devices/SATA/Port1/WrittenBytes 0 bytes
00:03:29.036 /Devices/SATA/Port10/DMA 0 times
00:03:29.036 /Devices/SATA/Port10/IORequestsPerSecond 0 times
00:03:29.036 /Devices/SATA/Port10/ReadBytes 0 bytes
00:03:29.036 /Devices/SATA/Port10/WrittenBytes 0 bytes
00:03:29.036 /Devices/SATA/Port11/DMA 0 times
00:03:29.036 /Devices/SATA/Port11/IORequestsPerSecond 0 times
00:03:29.036 /Devices/SATA/Port11/ReadBytes 0 bytes
00:03:29.036 /Devices/SATA/Port11/WrittenBytes 0 bytes
00:03:29.037 /Devices/SATA/Port12/DMA 0 times
00:03:29.037 /Devices/SATA/Port12/IORequestsPerSecond 0 times
00:03:29.037 /Devices/SATA/Port12/ReadBytes 0 bytes
00:03:29.037 /Devices/SATA/Port12/WrittenBytes 0 bytes
00:03:29.037 /Devices/SATA/Port13/DMA 0 times
00:03:29.037 /Devices/SATA/Port13/IORequestsPerSecond 0 times
00:03:29.037 /Devices/SATA/Port13/ReadBytes 0 bytes
00:03:29.037 /Devices/SATA/Port13/WrittenBytes 0 bytes
00:03:29.037 /Devices/SATA/Port14/DMA 0 times
00:03:29.037 /Devices/SATA/Port14/IORequestsPerSecond 0 times
00:03:29.037 /Devices/SATA/Port14/ReadBytes 0 bytes
00:03:29.037 /Devices/SATA/Port14/WrittenBytes 0 bytes
00:03:29.037 /Devices/SATA/Port15/DMA 0 times
00:03:29.037 /Devices/SATA/Port15/IORequestsPerSecond 0 times
00:03:29.037 /Devices/SATA/Port15/ReadBytes 0 bytes
00:03:29.037 /Devices/SATA/Port15/WrittenBytes 0 bytes
00:03:29.037 /Devices/SATA/Port16/DMA 0 times
00:03:29.037 /Devices/SATA/Port16/IORequestsPerSecond 0 times
00:03:29.037 /Devices/SATA/Port16/ReadBytes 0 bytes
00:03:29.037 /Devices/SATA/Port16/WrittenBytes 0 bytes
00:03:29.037 /Devices/SATA/Port17/DMA 0 times
00:03:29.037 /Devices/SATA/Port17/IORequestsPerSecond 0 times
00:03:29.037 /Devices/SATA/Port17/ReadBytes 0 bytes
00:03:29.037 /Devices/SATA/Port17/WrittenBytes 0 bytes
00:03:29.037 /Devices/SATA/Port18/DMA 0 times
00:03:29.037 /Devices/SATA/Port18/IORequestsPerSecond 0 times
00:03:29.037 /Devices/SATA/Port18/ReadBytes 0 bytes
00:03:29.037 /Devices/SATA/Port18/WrittenBytes 0 bytes
00:03:29.037 /Devices/SATA/Port19/DMA 0 times
00:03:29.037 /Devices/SATA/Port19/IORequestsPerSecond 0 times
00:03:29.037 /Devices/SATA/Port19/ReadBytes 0 bytes
00:03:29.037 /Devices/SATA/Port19/WrittenBytes 0 bytes
00:03:29.037 /Devices/SATA/Port2/DMA 0 times
00:03:29.037 /Devices/SATA/Port2/IORequestsPerSecond 0 times
00:03:29.037 /Devices/SATA/Port2/ReadBytes 0 bytes
00:03:29.037 /Devices/SATA/Port2/WrittenBytes 0 bytes
00:03:29.037 /Devices/SATA/Port20/DMA 0 times
00:03:29.037 /Devices/SATA/Port20/IORequestsPerSecond 0 times
00:03:29.037 /Devices/SATA/Port20/ReadBytes 0 bytes
00:03:29.037 /Devices/SATA/Port20/WrittenBytes 0 bytes
00:03:29.037 /Devices/SATA/Port21/DMA 0 times
00:03:29.037 /Devices/SATA/Port21/IORequestsPerSecond 0 times
00:03:29.037 /Devices/SATA/Port21/ReadBytes 0 bytes
00:03:29.037 /Devices/SATA/Port21/WrittenBytes 0 bytes
00:03:29.037 /Devices/SATA/Port22/DMA 0 times
00:03:29.037 /Devices/SATA/Port22/IORequestsPerSecond 0 times
00:03:29.037 /Devices/SATA/Port22/ReadBytes 0 bytes
00:03:29.037 /Devices/SATA/Port22/WrittenBytes 0 bytes
00:03:29.037 /Devices/SATA/Port23/DMA 0 times
00:03:29.037 /Devices/SATA/Port23/IORequestsPerSecond 0 times
00:03:29.037 /Devices/SATA/Port23/ReadBytes 0 bytes
00:03:29.037 /Devices/SATA/Port23/WrittenBytes 0 bytes
00:03:29.037 /Devices/SATA/Port24/DMA 0 times
00:03:29.037 /Devices/SATA/Port24/IORequestsPerSecond 0 times
00:03:29.037 /Devices/SATA/Port24/ReadBytes 0 bytes
00:03:29.037 /Devices/SATA/Port24/WrittenBytes 0 bytes
00:03:29.037 /Devices/SATA/Port25/DMA 0 times
00:03:29.037 /Devices/SATA/Port25/IORequestsPerSecond 0 times
00:03:29.037 /Devices/SATA/Port25/ReadBytes 0 bytes
00:03:29.037 /Devices/SATA/Port25/WrittenBytes 0 bytes
00:03:29.037 /Devices/SATA/Port26/DMA 0 times
00:03:29.037 /Devices/SATA/Port26/IORequestsPerSecond 0 times
00:03:29.037 /Devices/SATA/Port26/ReadBytes 0 bytes
00:03:29.037 /Devices/SATA/Port26/WrittenBytes 0 bytes
00:03:29.037 /Devices/SATA/Port27/DMA 0 times
00:03:29.037 /Devices/SATA/Port27/IORequestsPerSecond 0 times
00:03:29.037 /Devices/SATA/Port27/ReadBytes 0 bytes
00:03:29.037 /Devices/SATA/Port27/WrittenBytes 0 bytes
00:03:29.037 /Devices/SATA/Port28/DMA 0 times
00:03:29.037 /Devices/SATA/Port28/IORequestsPerSecond 0 times
00:03:29.037 /Devices/SATA/Port28/ReadBytes 0 bytes
00:03:29.037 /Devices/SATA/Port28/WrittenBytes 0 bytes
00:03:29.037 /Devices/SATA/Port29/DMA 0 times
00:03:29.037 /Devices/SATA/Port29/IORequestsPerSecond 0 times
00:03:29.037 /Devices/SATA/Port29/ReadBytes 0 bytes
00:03:29.037 /Devices/SATA/Port29/WrittenBytes 0 bytes
00:03:29.037 /Devices/SATA/Port3/DMA 0 times
00:03:29.037 /Devices/SATA/Port3/IORequestsPerSecond 0 times
00:03:29.037 /Devices/SATA/Port3/ReadBytes 0 bytes
00:03:29.037 /Devices/SATA/Port3/WrittenBytes 0 bytes
00:03:29.037 /Devices/SATA/Port4/DMA 0 times
00:03:29.037 /Devices/SATA/Port4/IORequestsPerSecond 0 times
00:03:29.037 /Devices/SATA/Port4/ReadBytes 0 bytes
00:03:29.037 /Devices/SATA/Port4/WrittenBytes 0 bytes
00:03:29.037 /Devices/SATA/Port5/DMA 0 times
00:03:29.037 /Devices/SATA/Port5/IORequestsPerSecond 0 times
00:03:29.037 /Devices/SATA/Port5/ReadBytes 0 bytes
00:03:29.037 /Devices/SATA/Port5/WrittenBytes 0 bytes
00:03:29.037 /Devices/SATA/Port6/DMA 0 times
00:03:29.037 /Devices/SATA/Port6/IORequestsPerSecond 0 times
00:03:29.037 /Devices/SATA/Port6/ReadBytes 0 bytes
00:03:29.037 /Devices/SATA/Port6/WrittenBytes 0 bytes
00:03:29.037 /Devices/SATA/Port7/DMA 0 times
00:03:29.037 /Devices/SATA/Port7/IORequestsPerSecond 0 times
00:03:29.037 /Devices/SATA/Port7/ReadBytes 0 bytes
00:03:29.037 /Devices/SATA/Port7/WrittenBytes 0 bytes
00:03:29.037 /Devices/SATA/Port8/DMA 0 times
00:03:29.037 /Devices/SATA/Port8/IORequestsPerSecond 0 times
00:03:29.037 /Devices/SATA/Port8/ReadBytes 0 bytes
00:03:29.037 /Devices/SATA/Port8/WrittenBytes 0 bytes
00:03:29.037 /Devices/SATA/Port9/DMA 0 times
00:03:29.037 /Devices/SATA/Port9/IORequestsPerSecond 0 times
00:03:29.037 /Devices/SATA/Port9/ReadBytes 0 bytes
00:03:29.037 /Devices/SATA/Port9/WrittenBytes 0 bytes
00:03:29.037 /GVMM/EMTs 2 calls
00:03:29.037 /GVMM/Sum/HaltBlocking 56631 calls
00:03:29.037 /GVMM/Sum/HaltCalls 56631 calls
00:03:29.037 /GVMM/Sum/HaltNotBlocking 0 calls
00:03:29.037 /GVMM/Sum/HaltTimeouts 4234 calls
00:03:29.037 /GVMM/Sum/HaltWakeUps 0 calls
00:03:29.037 /GVMM/Sum/PokeCalls 28342 calls
00:03:29.037 /GVMM/Sum/PokeNotBusy 1250 calls
00:03:29.037 /GVMM/Sum/PollCalls 85 calls
00:03:29.037 /GVMM/Sum/PollHalts 0 calls
00:03:29.037 /GVMM/Sum/PollWakeUps 0 calls
00:03:29.037 /GVMM/Sum/WakeUpCalls 55981 calls
00:03:29.037 /GVMM/Sum/WakeUpNotHalted 8232 calls
00:03:29.037 /GVMM/Sum/WakeUpWakeUps 0 calls
00:03:29.037 /GVMM/VM/HaltBlocking 56631 calls
00:03:29.037 /GVMM/VM/HaltCalls 56631 calls
00:03:29.037 /GVMM/VM/HaltNotBlocking 0 calls
00:03:29.037 /GVMM/VM/HaltTimeouts 4234 calls
00:03:29.037 /GVMM/VM/HaltWakeUps 0 calls
00:03:29.037 /GVMM/VM/PokeCalls 28342 calls
00:03:29.037 /GVMM/VM/PokeNotBusy 1250 calls
00:03:29.037 /GVMM/VM/PollCalls 85 calls
00:03:29.037 /GVMM/VM/PollHalts 0 calls
00:03:29.037 /GVMM/VM/PollWakeUps 0 calls
00:03:29.037 /GVMM/VM/WakeUpCalls 55981 calls
00:03:29.037 /GVMM/VM/WakeUpNotHalted 8232 calls
00:03:29.037 /GVMM/VM/WakeUpWakeUps 0 calls
00:03:29.037 /GVMM/VMs 1 calls
00:03:29.037 /MM/HyperHeap/cbFree 1760560 bytes
00:03:29.037 /MM/HyperHeap/cbHeap 2227904 bytes
00:03:29.037 /Net/IntNet0/Bytes/Received 13793 bytes
00:03:29.037 /Net/IntNet0/Bytes/Sent 16656 bytes
00:03:29.037 /Net/IntNet0/Packets/Lost 0 count
00:03:29.037 /Net/IntNet0/Packets/Received 155 count
00:03:29.037 /Net/IntNet0/Packets/Sent 230 count
00:03:29.037 /Net/IntNet0/YieldNok 0 count
00:03:29.037 /PDM/CritSects/AHCI/ContentionR3 0 times
00:03:29.037 /PDM/CritSects/AHCI/ContentionRZLock 0 times
00:03:29.037 /PDM/CritSects/AHCI/ContentionRZUnlock 0 times
00:03:29.037 /PDM/CritSects/ATA0/ContentionR3 0 times
00:03:29.037 /PDM/CritSects/ATA0/ContentionRZLock 0 times
00:03:29.037 /PDM/CritSects/ATA0/ContentionRZUnlock 0 times
00:03:29.037 /PDM/CritSects/ATA1/ContentionR3 0 times
00:03:29.037 /PDM/CritSects/ATA1/ContentionRZLock 0 times
00:03:29.037 /PDM/CritSects/ATA1/ContentionRZUnlock 0 times
00:03:29.037 /PDM/CritSects/E1000#0/ContentionR3 0 times
00:03:29.037 /PDM/CritSects/E1000#0/ContentionRZLock 0 times
00:03:29.037 /PDM/CritSects/E1000#0/ContentionRZUnlock 0 times
00:03:29.037 /PDM/CritSects/E1000#0RX/ContentionR3 0 times
00:03:29.037 /PDM/CritSects/E1000#0RX/ContentionRZLock 0 times
00:03:29.037 /PDM/CritSects/E1000#0RX/ContentionRZUnlock 0 times
00:03:29.037 /PDM/CritSects/EM-REM/ContentionR3 0 times
00:03:29.037 /PDM/CritSects/EM-REM/ContentionRZLock 1009 times
00:03:29.037 /PDM/CritSects/EM-REM/ContentionRZUnlock 0 times
00:03:29.037 /PDM/CritSects/EmulatedATA0/ContentionR3 0 times
00:03:29.037 /PDM/CritSects/EmulatedATA0/ContentionRZLock 0 times
00:03:29.037 /PDM/CritSects/EmulatedATA0/ContentionRZUnlock 0 times
00:03:29.037 /PDM/CritSects/EmulatedATA1/ContentionR3 0 times
00:03:29.037 /PDM/CritSects/EmulatedATA1/ContentionRZLock 0 times
00:03:29.037 /PDM/CritSects/EmulatedATA1/ContentionRZUnlock 0 times
00:03:29.037 /PDM/CritSects/IOM EMT Lock/ContentionR3 0 times
00:03:29.037 /PDM/CritSects/IOM EMT Lock/ContentionRZLock 627 times
00:03:29.037 /PDM/CritSects/IOM EMT Lock/ContentionRZUnlock 0 times
00:03:29.037 /PDM/CritSects/MM-HYPER/ContentionR3 0 times
00:03:29.037 /PDM/CritSects/MM-HYPER/ContentionRZLock 0 times
00:03:29.037 /PDM/CritSects/MM-HYPER/ContentionRZUnlock 0 times
00:03:29.037 /PDM/CritSects/PDM/ContentionR3 0 times
00:03:29.037 /PDM/CritSects/PDM/ContentionRZLock 11 times
00:03:29.037 /PDM/CritSects/PDM/ContentionRZUnlock 19 times
00:03:29.037 /PDM/CritSects/PGM/ContentionR3 0 times
00:03:29.037 /PDM/CritSects/PGM/ContentionRZLock 59243 times
00:03:29.037 /PDM/CritSects/PGM/ContentionRZUnlock 0 times
00:03:29.037 /PDM/CritSects/PS2KM#0/ContentionR3 0 times
00:03:29.037 /PDM/CritSects/PS2KM#0/ContentionRZLock 0 times
00:03:29.037 /PDM/CritSects/PS2KM#0/ContentionRZUnlock 0 times
00:03:29.038 /PDM/CritSects/REM-Register/ContentionR3 0 times
00:03:29.038 /PDM/CritSects/REM-Register/ContentionRZLock 0 times
00:03:29.038 /PDM/CritSects/REM-Register/ContentionRZUnlock 0 times
00:03:29.038 /PDM/CritSects/TM Timer Lock/ContentionR3 9624 times
00:03:29.038 /PDM/CritSects/TM Timer Lock/ContentionRZLock 0 times
00:03:29.038 /PDM/CritSects/TM Timer Lock/ContentionRZUnlock 0 times
00:03:29.038 /PDM/CritSects/TM VirtualSync Lock/ContentionR3 0 times
00:03:29.038 /PDM/CritSects/TM VirtualSync Lock/ContentionRZLock 4414 times
00:03:29.038 /PDM/CritSects/TM VirtualSync Lock/ContentionRZUnlock 0 times
00:03:29.038 /PDM/CritSects/VGA/ContentionR3 0 times
00:03:29.038 /PDM/CritSects/VGA/ContentionRZLock 0 times
00:03:29.038 /PDM/CritSects/VGA/ContentionRZUnlock 0 times
00:03:29.038 /PDM/CritSects/VMMDev/ContentionR3 0 times
00:03:29.038 /PDM/CritSects/VMMDev/ContentionRZLock 0 times
00:03:29.038 /PDM/CritSects/VMMDev/ContentionRZUnlock 0 times
00:03:29.038 /PGM/CPU0/cGuestModeChanges 2 times
00:03:29.038 /PGM/CPU1/cGuestModeChanges 2 times
00:03:29.038 /PGM/ChunkR3Map/c 5113 times
00:03:29.038 /PGM/ChunkR3Map/cMax 4294967295 times
00:03:29.038 /PGM/Page/cAllPages 1312881 times
00:03:29.038 /PGM/Page/cHandyPages 125 times
00:03:29.038 /PGM/Page/cPrivatePages 1312743 times
00:03:29.038 /PGM/Page/cSharedPages 0 times
00:03:29.038 /PGM/Page/cZeroPages 138 times
00:03:29.038 /PGM/cRelocations 0 times
00:03:29.038 /PROF/CPU0/EM/ForcedActions 537185 times
00:03:29.038 /PROF/CPU0/EM/Halted 34802 times
00:03:29.038 /PROF/CPU0/EM/RAWTotal 0 times
00:03:29.038 /PROF/CPU0/EM/REMTotal 0 times
00:03:29.038 /PROF/CPU0/EM/Total 471901480311 ticks/call (471901480311 ticks, 1 times, max 471901480311, min 471901480311)
00:03:29.038 /PROF/CPU1/EM/ForcedActions 423874 times
00:03:29.038 /PROF/CPU1/EM/Halted 18782 times
00:03:29.038 /PROF/CPU1/EM/RAWTotal 0 times
00:03:29.038 /PROF/CPU1/EM/REMTotal 0 times
00:03:29.038 /PROF/CPU1/EM/Total 471661894925 ticks/call (471661894925 ticks, 1 times, max 471661894925, min 471661894925)
00:03:29.038 /PROF/VM/CPU0/Halt/Block 9640310 ticks/call (336263665404 ticks, 34881 times, max 145438672, min 6154)
00:03:29.038 /PROF/VM/CPU0/Halt/Timers 431 ticks/call ( 30051763 ticks, 69677 times, max 18487, min 111)
00:03:29.038 /PROF/VM/CPU0/Halt/Yield 0 ticks/call ( 0 ticks, 0 times, max 0, min -1)
00:03:29.038 /PROF/VM/CPU1/Halt/Block 2207620 ticks/call ( 47947302685 ticks, 21719 times, max 20303296, min 2958)
00:03:29.038 /PROF/VM/CPU1/Halt/Timers 6457 ticks/call ( 269534483 ticks, 41740 times, max 85230, min 1326)
00:03:29.038 /PROF/VM/CPU1/Halt/Yield 5268 ticks/call ( 447787 ticks, 85 times, max 8033, min 3868)
00:03:29.038 /REM/TbFlushCount 0 times
00:03:29.038 /REM/TbPhysInvldCount 1696 times
00:03:29.038 /REM/TlbFlushCount 210246 times
00:03:29.038 /TM/R0/1nsSteps 1608462 times
00:03:29.038 /TM/R3/1nsSteps 1642208 times
00:03:29.038 /TM/TSC/offCPU0 113991687849 ticks
00:03:29.038 /TM/TSC/offCPU1 108038696907 ticks
00:03:29.038 /TM/VirtualSync/CurrentOffset 147769 ns
00:03:29.038 /VUSB/0/cUrbsInPool 0 count
00:03:29.038 /VUSB/1/cUrbsInPool 0 count
00:03:29.038 ********************* End of statistics **********************
00:03:29.068 Changing the VM state from 'DESTROYING' to 'TERMINATED'.
Do you see something strange?
Do you need the VBox.log.2 also?
Thanks for looking!
Re: VM Windows Server 2008 hang ups
Posted: 6. Oct 2009, 15:01
by Perryg
Typically you should not give more than 50% of the available ram to the guest. While VBox will allow you to in the 64 bit version it can still cause this problem.
Try setting the active guest RAM to 1/2 of the available RAM as stated in the log file entry below.
Host RAM: 8008MB RAM, available: 7733MB
Re: VM Windows Server 2008 hang ups
Posted: 7. Oct 2009, 06:26
by edvl
The guest is running now with 3800MB.
The next sunday will show, if the problem is now solved.
Thanks for analysing!
Re: VM Windows Server 2008 hang ups
Posted: 19. Oct 2009, 13:47
by edvl
Hello Perryg,
the VM is running now over 2 weeks without a problem.
It seems to be the problem if i use more then 50% of RAM.
Thanks!