Virtual Box 3.04 slow and high CPU load on Window XP SP3

Discussions about using Windows guests in VirtualBox.
Post Reply
kmlhk79
Posts: 3
Joined: 6. Aug 2009, 06:11
Primary OS: Ubuntu other
VBox Version: OSE Debian
Guest OSses: winxp

Virtual Box 3.04 slow and high CPU load on Window XP SP3

Post by kmlhk79 »

Dear All:

I have just upgraded form Virtualbox 2.24 to 3.04.

My Host is ubuntu 9.04
Guest is Windows XP SP3.
Hardware : Intel Celeron 2.8G 32 bit CPU, 2G DDR2 667 RAM

I found the following problem under VirtualBox 3.04:

1. Network access under (Guest using NAT) are very slow . It took me more than 30 min to download 3G byte vdi image file form my file server via my 100M LAN.

Hight CPU load, always under 100% and slow response (i.e. freeze ) when

a. Transfer files form the network.

b. Playing mp3 by window media player

c. Playing Flash video (i.e. video form Youtube ) are unacceptable slow and almost freeze.

It was working fine in Virtualbox 2.24.

Is it a bug on Virtualbox 3.04 ?

Any other users had that problem on 3.04 ?

K. M. Lau

------------------------------------------------------------------------------------------------
Date : 11 August 2009

It is bad to report to all of you that the slow and high CPU load on Window XP Sp3 is not only happen on Ubuntu 9.04.

I had run a test case on Windows Vista host with Window XP Sp3 as guest .

Below are my test case for your reference:

My Host is windows Vista business SP1 64bit
Guest is Windows XP SP3 32 bit
Hardware : HP 6515B notebook with AMD 2.2 G 64bit CPU and 3 G byte DDR 667 Ram.

Watch online video with windows media player V9.0

The video was slow and interrupted .

The same online video can be played smoothly under Virtual Box 2.xx .

Therefore , I can confirm to all you that , Virtualbox 3.04 is poison to us.

Please forback to 2.xx until Sun fix it.

K. M. Lau
Last edited by kmlhk79 on 11. Aug 2009, 11:40, edited 2 times in total.
Top
sandervl
Volunteer
Posts: 1064
Joined: 10. May 2007, 10:27
Primary OS: MS Windows Vista
VBox Version: VirtualBox+Oracle ExtPack
Guest OSses: Windows, Linux, Solaris

Re: Virtual Box 3.04 slow and high CPU load on Window XP SP3

Post by sandervl »

Could you open a ticket on our bug tracker and include a VBox.log?
If playing mp3 refers to steaming mp3, then it seems all related to NAT networking.
Top
Hachiman
Posts: 94
Joined: 18. Dec 2008, 21:04
Primary OS: Mac OS X other
VBox Version: VirtualBox+Oracle ExtPack
Guest OSses: Linux, Solaris, NetBSD

Re: Virtual Box 3.04 slow and high CPU load on Window XP SP3

Post by Hachiman »

please add your observation to http://www.virtualbox.org/ticket/4343
--
Kind regards
Sr. Software Engineer
Oracle Corporation
Vasily Levchenko

PS. Happy VBoxing.
Top
kmlhk79
Posts: 3
Joined: 6. Aug 2009, 06:11
Primary OS: Ubuntu other
VBox Version: OSE Debian
Guest OSses: winxp

Re: Virtual Box 3.04 slow and high CPU load on Window XP SP3

Post by kmlhk79 »

Dear All:

I did a test case, It seems that The VBox 3.04 is not only slow on the network access.

My testing case as follow:

1. Hardware : Intel Celeron D 2.8G 32 bit CPU, 2G DDR2 667 RAM
2. Virtual Box 3.04
3. Host : Ubuntu 9.04 i386 32bit
4. Guest : Win XP Sp3 32bit.
5. Vshare Folder Mounted as drive Z in Win xp Guest. The Vshare folder is a local folder on the Ubuntu Host.

Testing case 1 :

I had put an MP3 music file in the Vshare folder for testing. Assume that the VBox guest will access the Vshare folder like a network drive.

I played the MP3 music file (2.8M byte) by window media player v9.0 .

The window media player 9.0 was freeze and no response for 1 minutes. The CPU load was 100%. as observed from the host system monitor panel . Than the mp3 music was loaded and played. I stop the window media player.

Testing case 2 :
I had put the same MP3 music file in the Guest folder for testing. Assume that the VBox guest see it as local drive.

I played the MP3 music file by window media player v9.0 .

The window media player 9.0 load and play the Mp3 file immediately. The CPU load was 70%. It was still high but was much better than to access the Vshare folder.

It seems that the over all performance of Vbox 3.04 is much slower than Vbox 2.x . It is not acceptable if you play an Mp3 file with 70% CPU load ( PS : running on an Intel Celeron D 2.8G 32 bit single core CPU, no VT-X ). I am going to be mad.

New version should work better , isn't it ?

What were changed in VB 3.04 ?

Hope the VBox development team can solve the problem ASAP.

The VBlog file info was attached for your debugging purpose:

00:00:02.245 VirtualBox 3.0.4 r50677 linux.x86 (Aug 4 2009 18:05:36) release log
00:00:02.245 Log opened 2009-08-06T15:44:38.772047000Z
00:00:02.245 OS Product: Linux
00:00:02.245 OS Release: 2.6.28-14-generic
00:00:02.245 OS Version: #47-Ubuntu SMP Sat Jul 25 00:28:35 UTC 2009
00:00:02.245 Host RAM: 1886MB RAM, available: 1600MB
00:00:02.245 Executable: /usr/lib/virtualbox/VirtualBox
00:00:02.245 Process ID: 7356
00:00:02.245 Package type: LINUX_32BITS_UBUNTU_9_04
00:00:02.284 SUP: Loaded VMMR0.r0 (/usr/lib/virtualbox/VMMR0.r0) at 0xf91ee060 - ModuleInit at 00000000f9200c20 and ModuleTerm at 00000000f9200be0
00:00:02.284 SUP: VMMR0EntryEx located at 00000000f9200ab0, VMMR0EntryFast at 00000000f91ffca0 and VMMR0EntryInt at 00000000f91ffac0
00:00:02.359 VBoxSharedClipboard mode: Bidirectional
00:00:02.674 ************************* CFGM dump *************************
00:00:02.674 pRoot=09fcd300:{/}
00:00:02.674 [/] (level 0)
00:00:02.674 Name <string> = "cwxp090805" (cch=11)
00:00:02.674 UUID <bytes> = "ac c9 1b 7c fd c5 d0 4b 9f b2 77 1d 0e f0 ee f5" (cb=16)
00:00:02.674 RamSize <integer> = 0x0000000018000000 (402653184)
00:00:02.674 RamHoleSize <integer> = 0x0000000020000000 (536870912)
00:00:02.674 NumCPUs <integer> = 0x0000000000000001 (1)
00:00:02.674 TimerMillies <integer> = 0x000000000000000a (10)
00:00:02.674 RawR3Enabled <integer> = 0x0000000000000001 (1)
00:00:02.674 RawR0Enabled <integer> = 0x0000000000000001 (1)
00:00:02.674 PATMEnabled <integer> = 0x0000000000000001 (1)
00:00:02.674 CSAMEnabled <integer> = 0x0000000000000001 (1)
00:00:02.674 HwVirtExtForced <integer> = 0x0000000000000000 (0)
00:00:02.674 EnableNestedPaging <integer> = 0x0000000000000000 (0)
00:00:02.674 EnableVPID <integer> = 0x0000000000000000 (0)
00:00:02.674 EnablePAE <integer> = 0x0000000000000000 (0)
00:00:02.674
00:00:02.674 [/HWVirtExt/] (level 1)
00:00:02.674 Enabled <integer> = 0x0000000000000001 (1)
00:00:02.675 64bitEnabled <integer> = 0x0000000000000000 (0)
00:00:02.675
00:00:02.675 [/PDM/] (level 1)
00:00:02.675
00:00:02.675 [/PDM/Drivers/] (level 2)
00:00:02.675
00:00:02.675 [/PDM/Drivers/VBoxC/] (level 3)
00:00:02.675 Path <string> = "/usr/lib/virtualbox/components/VBoxC" (cch=37)
00:00:02.675
00:00:02.675 [/Devices/] (level 1)
00:00:02.675
00:00:02.675 [/Devices/pcarch/] (level 2)
00:00:02.675
00:00:02.675 [/Devices/pcarch/0/] (level 3)
00:00:02.675 Trusted <integer> = 0x0000000000000001 (1)
00:00:02.675
00:00:02.675 [/Devices/pcarch/0/Config/] (level 4)
00:00:02.675
00:00:02.675 [/Devices/pcbios/] (level 2)
00:00:02.675
00:00:02.675 [/Devices/pcbios/0/] (level 3)
00:00:02.675 Trusted <integer> = 0x0000000000000001 (1)
00:00:02.675
00:00:02.675 [/Devices/pcbios/0/Config/] (level 4)
00:00:02.675 RamSize <integer> = 0x0000000018000000 (402653184)
00:00:02.675 RamHoleSize <integer> = 0x0000000020000000 (536870912)
00:00:02.675 NumCPUs <integer> = 0x0000000000000001 (1)
00:00:02.675 HardDiskDevice <string> = "piix3ide" (cch=9)
00:00:02.675 FloppyDevice <string> = "i82078" (cch=7)
00:00:02.675 IOAPIC <integer> = 0x0000000000000000 (0)
00:00:02.675 PXEDebug <integer> = 0x0000000000000000 (0)
00:00:02.675 UUID <bytes> = "ac c9 1b 7c fd c5 d0 4b 9f b2 77 1d 0e f0 ee f5" (cb=16)
00:00:02.675 BootDevice0 <string> = "DVD" (cch=4)
00:00:02.675 BootDevice1 <string> = "IDE" (cch=4)
00:00:02.675 BootDevice2 <string> = "NONE" (cch=5)
00:00:02.675 BootDevice3 <string> = "NONE" (cch=5)
00:00:02.675
00:00:02.675 [/Devices/8237A/] (level 2)
00:00:02.675
00:00:02.675 [/Devices/8237A/0/] (level 3)
00:00:02.675 Trusted <integer> = 0x0000000000000001 (1)
00:00:02.675
00:00:02.675 [/Devices/pci/] (level 2)
00:00:02.675
00:00:02.675 [/Devices/pci/0/] (level 3)
00:00:02.675 Trusted <integer> = 0x0000000000000001 (1)
00:00:02.675
00:00:02.675 [/Devices/pci/0/Config/] (level 4)
00:00:02.675 IOAPIC <integer> = 0x0000000000000000 (0)
00:00:02.675
00:00:02.675 [/Devices/pckbd/] (level 2)
00:00:02.675
00:00:02.675 [/Devices/pckbd/0/] (level 3)
00:00:02.675 Trusted <integer> = 0x0000000000000001 (1)
00:00:02.675
00:00:02.675 [/Devices/pckbd/0/Config/] (level 4)
00:00:02.675
00:00:02.675 [/Devices/pckbd/0/LUN#0/] (level 4)
00:00:02.675 Driver <string> = "KeyboardQueue" (cch=14)
00:00:02.675
00:00:02.675 [/Devices/pckbd/0/LUN#0/Config/] (level 5)
00:00:02.675 QueueSize <integer> = 0x0000000000000040 (64)
00:00:02.676
00:00:02.676 [/Devices/pckbd/0/LUN#0/AttachedDriver/] (level 5)
00:00:02.676 Driver <string> = "MainKeyboard" (cch=13)
00:00:02.676
00:00:02.676 [/Devices/pckbd/0/LUN#0/AttachedDriver/Config/] (level 6)
00:00:02.676 Object <integer> = 0x0000000009f9b0d0 (167358672)
00:00:02.676
00:00:02.676 [/Devices/pckbd/0/LUN#1/] (level 4)
00:00:02.676 Driver <string> = "MouseQueue" (cch=11)
00:00:02.676
00:00:02.676 [/Devices/pckbd/0/LUN#1/Config/] (level 5)
00:00:02.676 QueueSize <integer> = 0x0000000000000080 (128)
00:00:02.676
00:00:02.676 [/Devices/pckbd/0/LUN#1/AttachedDriver/] (level 5)
00:00:02.676 Driver <string> = "MainMouse" (cch=10)
00:00:02.676
00:00:02.676 [/Devices/pckbd/0/LUN#1/AttachedDriver/Config/] (level 6)
00:00:02.676 Object <integer> = 0x0000000009f9b1a8 (167358888)
00:00:02.676
00:00:02.676 [/Devices/i82078/] (level 2)
00:00:02.676
00:00:02.676 [/Devices/i82078/0/] (level 3)
00:00:02.676 Trusted <integer> = 0x0000000000000001 (1)
00:00:02.676
00:00:02.676 [/Devices/i82078/0/Config/] (level 4)
00:00:02.676 IRQ <integer> = 0x0000000000000006 (6)
00:00:02.676 DMA <integer> = 0x0000000000000002 (2)
00:00:02.676 MemMapped <integer> = 0x0000000000000000 (0)
00:00:02.676 IOBase <integer> = 0x00000000000003f0 (1008)
00:00:02.676
00:00:02.676 [/Devices/i82078/0/LUN#999/] (level 4)
00:00:02.676 Driver <string> = "MainStatus" (cch=11)
00:00:02.676
00:00:02.676 [/Devices/i82078/0/LUN#999/Config/] (level 5)
00:00:02.676 papLeds <integer> = 0x0000000009f9aa94 (167357076)
00:00:02.676 First <integer> = 0x0000000000000000 (0)
00:00:02.676 Last <integer> = 0x0000000000000000 (0)
00:00:02.676
00:00:02.676 [/Devices/i82078/0/LUN#0/] (level 4)
00:00:02.676 Driver <string> = "Block" (cch=6)
00:00:02.676
00:00:02.676 [/Devices/i82078/0/LUN#0/Config/] (level 5)
00:00:02.676 Type <string> = "Floppy 1.44" (cch=12)
00:00:02.676 Mountable <integer> = 0x0000000000000001 (1)
00:00:02.676
00:00:02.676 [/Devices/acpi/] (level 2)
00:00:02.676
00:00:02.676 [/Devices/acpi/0/] (level 3)
00:00:02.676 Trusted <integer> = 0x0000000000000001 (1)
00:00:02.676 PCIDeviceNo <integer> = 0x0000000000000007 (7)
00:00:02.676 PCIFunctionNo <integer> = 0x0000000000000000 (0)
00:00:02.676
00:00:02.676 [/Devices/acpi/0/Config/] (level 4)
00:00:02.676 RamSize <integer> = 0x0000000018000000 (402653184)
00:00:02.676 RamHoleSize <integer> = 0x0000000020000000 (536870912)
00:00:02.676 NumCPUs <integer> = 0x0000000000000001 (1)
00:00:02.676 IOAPIC <integer> = 0x0000000000000000 (0)
00:00:02.676 FdcEnabled <integer> = 0x0000000000000001 (1)
00:00:02.676 HpetEnabled <integer> = 0x0000000000000000 (0)
00:00:02.676 ShowRtc <integer> = 0x0000000000000000 (0)
00:00:02.676 ShowCpu <integer> = 0x0000000000000000 (0)
00:00:02.676
00:00:02.676 [/Devices/acpi/0/LUN#0/] (level 4)
00:00:02.677 Driver <string> = "ACPIHost" (cch=9)
00:00:02.677
00:00:02.677 [/Devices/acpi/0/LUN#0/Config/] (level 5)
00:00:02.677
00:00:02.677 [/Devices/i8254/] (level 2)
00:00:02.677
00:00:02.677 [/Devices/i8254/0/] (level 3)
00:00:02.677
00:00:02.677 [/Devices/i8254/0/Config/] (level 4)
00:00:02.677
00:00:02.677 [/Devices/i8259/] (level 2)
00:00:02.677
00:00:02.677 [/Devices/i8259/0/] (level 3)
00:00:02.677 Trusted <integer> = 0x0000000000000001 (1)
00:00:02.677
00:00:02.677 [/Devices/i8259/0/Config/] (level 4)
00:00:02.677
00:00:02.677 [/Devices/apic/] (level 2)
00:00:02.677
00:00:02.677 [/Devices/apic/0/] (level 3)
00:00:02.677 Trusted <integer> = 0x0000000000000001 (1)
00:00:02.677
00:00:02.677 [/Devices/apic/0/Config/] (level 4)
00:00:02.677 IOAPIC <integer> = 0x0000000000000000 (0)
00:00:02.677 NumCPUs <integer> = 0x0000000000000001 (1)
00:00:02.677
00:00:02.677 [/Devices/mc146818/] (level 2)
00:00:02.677
00:00:02.677 [/Devices/mc146818/0/] (level 3)
00:00:02.677
00:00:02.677 [/Devices/mc146818/0/Config/] (level 4)
00:00:02.677
00:00:02.677 [/Devices/vga/] (level 2)
00:00:02.677
00:00:02.677 [/Devices/vga/0/] (level 3)
00:00:02.677 Trusted <integer> = 0x0000000000000001 (1)
00:00:02.677 PCIDeviceNo <integer> = 0x0000000000000002 (2)
00:00:02.677 PCIFunctionNo <integer> = 0x0000000000000000 (0)
00:00:02.677
00:00:02.677 [/Devices/vga/0/Config/] (level 4)
00:00:02.677 VRamSize <integer> = 0x0000000000c00000 (12582912)
00:00:02.677 FadeIn <integer> = 0x0000000000000001 (1)
00:00:02.677 FadeOut <integer> = 0x0000000000000001 (1)
00:00:02.677 LogoTime <integer> = 0x0000000000000000 (0)
00:00:02.677 LogoFile <string> = "" (cch=1)
00:00:02.677 ShowBootMenu <integer> = 0x0000000000000002 (2)
00:00:02.677 CustomVideoModes <integer> = 0x0000000000000000 (0)
00:00:02.677 HeightReduction <integer> = 0x0000000000000000 (0)
00:00:02.677
00:00:02.677 [/Devices/vga/0/LUN#0/] (level 4)
00:00:02.677 Driver <string> = "MainDisplay" (cch=12)
00:00:02.677
00:00:02.677 [/Devices/vga/0/LUN#0/Config/] (level 5)
00:00:02.677 Object <integer> = 0x0000000009f9b288 (167359112)
00:00:02.678
00:00:02.678 [/Devices/piix3ide/] (level 2)
00:00:02.678
00:00:02.678 [/Devices/piix3ide/0/] (level 3)
00:00:02.678 Trusted <integer> = 0x0000000000000001 (1)
00:00:02.678 PCIDeviceNo <integer> = 0x0000000000000001 (1)
00:00:02.678 PCIFunctionNo <integer> = 0x0000000000000001 (1)
00:00:02.678
00:00:02.678 [/Devices/piix3ide/0/Config/] (level 4)
00:00:02.678 Type <string> = "PIIX4" (cch=6)
00:00:02.678
00:00:02.678 [/Devices/piix3ide/0/LUN#999/] (level 4)
00:00:02.678 Driver <string> = "MainStatus" (cch=11)
00:00:02.678
00:00:02.678 [/Devices/piix3ide/0/LUN#999/Config/] (level 5)
00:00:02.678 papLeds <integer> = 0x0000000009f9aa9c (167357084)
00:00:02.678 First <integer> = 0x0000000000000000 (0)
00:00:02.678 Last <integer> = 0x0000000000000003 (3)
00:00:02.678
00:00:02.678 [/Devices/piix3ide/0/LUN#2/] (level 4)
00:00:02.678 Driver <string> = "Block" (cch=6)
00:00:02.678
00:00:02.678 [/Devices/piix3ide/0/LUN#2/Config/] (level 5)
00:00:02.678 Type <string> = "DVD" (cch=4)
00:00:02.678 Mountable <integer> = 0x0000000000000001 (1)
00:00:02.678
00:00:02.678 [/Devices/piix3ide/0/LUN#2/AttachedDriver/] (level 5)
00:00:02.678 Driver <string> = "MediaISO" (cch=9)
00:00:02.678
00:00:02.678 [/Devices/piix3ide/0/LUN#2/AttachedDriver/Config/] (level 6)
00:00:02.678 Path <string> = "/usr/share/virtualbox/VBoxGuestAdditions.iso" (cch=45)
00:00:02.678
00:00:02.678 [/Devices/piix3ide/0/LUN#0/] (level 4)
00:00:02.678 Driver <string> = "Block" (cch=6)
00:00:02.678
00:00:02.678 [/Devices/piix3ide/0/LUN#0/Config/] (level 5)
00:00:02.678 Type <string> = "HardDisk" (cch=9)
00:00:02.678 Mountable <integer> = 0x0000000000000000 (0)
00:00:02.678
00:00:02.678 [/Devices/piix3ide/0/LUN#0/AttachedDriver/] (level 5)
00:00:02.678 Driver <string> = "VD" (cch=3)
00:00:02.678
00:00:02.678 [/Devices/piix3ide/0/LUN#0/AttachedDriver/Config/] (level 6)
00:00:02.678 Path <string> = "/home/kmlid4/vdi/cwxp090805.vdi" (cch=32)
00:00:02.678 Format <string> = "VDI" (cch=4)
00:00:02.678
00:00:02.678 [/Devices/pcnet/] (level 2)
00:00:02.678
00:00:02.678 [/Devices/pcnet/0/] (level 3)
00:00:02.678 Trusted <integer> = 0x0000000000000001 (1)
00:00:02.678 PCIDeviceNo <integer> = 0x0000000000000003 (3)
00:00:02.678 PCIFunctionNo <integer> = 0x0000000000000000 (0)
00:00:02.678
00:00:02.678 [/Devices/pcnet/0/Config/] (level 4)
00:00:02.679 Am79C973 <integer> = 0x0000000000000001 (1)
00:00:02.679 MAC <bytes> = "08 00 27 b8 62 1b" (cb=6)
00:00:02.679 CableConnected <integer> = 0x0000000000000001 (1)
00:00:02.679 LineSpeed <integer> = 0x0000000000000000 (0)
00:00:02.679
00:00:02.679 [/Devices/pcnet/0/LUN#999/] (level 4)
00:00:02.679 Driver <string> = "MainStatus" (cch=11)
00:00:02.679
00:00:02.679 [/Devices/pcnet/0/LUN#999/Config/] (level 5)
00:00:02.679 papLeds <integer> = 0x0000000009f9ab64 (167357284)
00:00:02.679
00:00:02.679 [/Devices/pcnet/0/LUN#0/] (level 4)
00:00:02.679 Driver <string> = "NAT" (cch=4)
00:00:02.679
00:00:02.679 [/Devices/pcnet/0/LUN#0/Config/] (level 5)
00:00:02.679 TFTPPrefix <string> = "/home/kmlid4/.VirtualBox/TFTP" (cch=30)
00:00:02.679 BootFile <string> = "cwxp090805.pxe" (cch=15)
00:00:02.679
00:00:02.679 [/Devices/e1000/] (level 2)
00:00:02.679
00:00:02.679 [/Devices/serial/] (level 2)
00:00:02.679
00:00:02.679 [/Devices/parallel/] (level 2)
00:00:02.679
00:00:02.679 [/Devices/VMMDev/] (level 2)
00:00:02.679
00:00:02.679 [/Devices/VMMDev/0/] (level 3)
00:00:02.679 Trusted <integer> = 0x0000000000000001 (1)
00:00:02.679 PCIDeviceNo <integer> = 0x0000000000000004 (4)
00:00:02.679 PCIFunctionNo <integer> = 0x0000000000000000 (0)
00:00:02.679
00:00:02.679 [/Devices/VMMDev/0/Config/] (level 4)
00:00:02.679
00:00:02.679 [/Devices/VMMDev/0/LUN#0/] (level 4)
00:00:02.679 Driver <string> = "MainVMMDev" (cch=11)
00:00:02.679
00:00:02.679 [/Devices/VMMDev/0/LUN#0/Config/] (level 5)
00:00:02.679 Object <integer> = 0x0000000009f9b908 (167360776)
00:00:02.679
00:00:02.679 [/Devices/VMMDev/0/LUN#999/] (level 4)
00:00:02.679 Driver <string> = "MainStatus" (cch=11)
00:00:02.679
00:00:02.679 [/Devices/VMMDev/0/LUN#999/Config/] (level 5)
00:00:02.679 papLeds <integer> = 0x0000000009f9ab84 (167357316)
00:00:02.679 First <integer> = 0x0000000000000000 (0)
00:00:02.679 Last <integer> = 0x0000000000000000 (0)
00:00:02.679
00:00:02.679 [/Devices/AudioSniffer/] (level 2)
00:00:02.679
00:00:02.679 [/Devices/AudioSniffer/0/] (level 3)
00:00:02.679
00:00:02.679 [/Devices/AudioSniffer/0/Config/] (level 4)
00:00:02.679
00:00:02.679 [/Devices/AudioSniffer/0/LUN#0/] (level 4)
00:00:02.679 Driver <string> = "MainAudioSniffer" (cch=17)
00:00:02.679
00:00:02.679 [/Devices/AudioSniffer/0/LUN#0/Config/] (level 5)
00:00:02.679 Object <integer> = 0x0000000009f9b928 (167360808)
00:00:02.679
00:00:02.679 [/Devices/ichac97/] (level 2)
00:00:02.680
00:00:02.680 [/Devices/ichac97/0/] (level 3)
00:00:02.680 Trusted <integer> = 0x0000000000000001 (1)
00:00:02.680 PCIDeviceNo <integer> = 0x0000000000000005 (5)
00:00:02.680 PCIFunctionNo <integer> = 0x0000000000000000 (0)
00:00:02.680
00:00:02.680 [/Devices/ichac97/0/Config/] (level 4)
00:00:02.680
00:00:02.680 [/Devices/ichac97/0/LUN#0/] (level 4)
00:00:02.680 Driver <string> = "AUDIO" (cch=6)
00:00:02.680
00:00:02.680 [/Devices/ichac97/0/LUN#0/Config/] (level 5)
00:00:02.680 AudioDriver <string> = "pulse" (cch=6)
00:00:02.680 StreamName <string> = "cwxp090805" (cch=11)
00:00:02.680
00:00:02.680 [/Devices/usb-ohci/] (level 2)
00:00:02.680
00:00:02.680 [/Devices/usb-ohci/0/] (level 3)
00:00:02.680 Trusted <integer> = 0x0000000000000001 (1)
00:00:02.680 PCIDeviceNo <integer> = 0x0000000000000006 (6)
00:00:02.680 PCIFunctionNo <integer> = 0x0000000000000000 (0)
00:00:02.680
00:00:02.680 [/Devices/usb-ohci/0/Config/] (level 4)
00:00:02.680
00:00:02.680 [/Devices/usb-ohci/0/LUN#0/] (level 4)
00:00:02.680 Driver <string> = "VUSBRootHub" (cch=12)
00:00:02.680
00:00:02.680 [/Devices/usb-ohci/0/LUN#0/Config/] (level 5)
00:00:02.680
00:00:02.680 [/Devices/usb-ohci/0/LUN#999/] (level 4)
00:00:02.680 Driver <string> = "MainStatus" (cch=11)
00:00:02.680
00:00:02.680 [/Devices/usb-ohci/0/LUN#999/Config/] (level 5)
00:00:02.680 papLeds <integer> = 0x0000000009f9ab88 (167357320)
00:00:02.680 First <integer> = 0x0000000000000000 (0)
00:00:02.680 Last <integer> = 0x0000000000000000 (0)
00:00:02.680
00:00:02.680 [/Devices/usb-ehci/] (level 2)
00:00:02.680
00:00:02.680 [/Devices/usb-ehci/0/] (level 3)
00:00:02.680 Trusted <integer> = 0x0000000000000001 (1)
00:00:02.680 PCIDeviceNo <integer> = 0x000000000000000b (11)
00:00:02.680 PCIFunctionNo <integer> = 0x0000000000000000 (0)
00:00:02.680
00:00:02.680 [/Devices/usb-ehci/0/Config/] (level 4)
00:00:02.680
00:00:02.680 [/Devices/usb-ehci/0/LUN#0/] (level 4)
00:00:02.680 Driver <string> = "VUSBRootHub" (cch=12)
00:00:02.680
00:00:02.680 [/Devices/usb-ehci/0/LUN#0/Config/] (level 5)
00:00:02.680
00:00:02.680 [/Devices/usb-ehci/0/LUN#999/] (level 4)
00:00:02.680 Driver <string> = "MainStatus" (cch=11)
00:00:02.680
00:00:02.680 [/Devices/usb-ehci/0/LUN#999/Config/] (level 5)
00:00:02.680 papLeds <integer> = 0x0000000009f9ab8c (167357324)
00:00:02.680 First <integer> = 0x0000000000000000 (0)
00:00:02.680 Last <integer> = 0x0000000000000000 (0)
00:00:02.680
00:00:02.680 [/TM/] (level 1)
00:00:02.681 UTCOffset <integer> = 0x0000000000000000 (0)
00:00:02.681
00:00:02.681 ********************* End of CFGM dump **********************
00:00:02.681 MM: cbHyperHeap=0x140000 (1310720)
00:00:02.683 Logical host processors: 1, processor active mask: 0000000000000001
00:00:02.683 ************************* CPUID dump ************************
00:00:02.683 RAW Standard CPUIDs
00:00:02.683 Function eax ebx ecx edx
00:00:02.683 Gst: 00000000 00000002 756e6547 6c65746e 49656e69
00:00:02.683 Hst: 00000005 756e6547 6c65746e 49656e69
00:00:02.683 Gst: 00000001 00000f34 00000800 00000009 078bf1bf
00:00:02.683 Hst: 00000f34 00010800 0000441d bfebfbff
00:00:02.683 Gst: 00000002 605b5101 00000000 00000000 003c7040
00:00:02.683 Hst: 605b5101 00000000 00000000 003c7040
00:00:02.683 Gst: 00000003 00000040 00000040 00000000 00000000*
00:00:02.683 Hst: 00000000 00000000 00000000 00000000
00:00:02.683 Gst: 00000004 00000000 00000040 00000000 00000000*
00:00:02.683 Hst: 00000121 01c0003f 0000001f 00000000
00:00:02.683 Gst: 00000005 00000040 00000040 00000000 00000000*
00:00:02.683 Hst: 00000040 00000040 00000000 00000000
00:00:02.683 Name: GenuineIntel
00:00:02.683 Supports: 0-2
00:00:02.683 Family: 15 Extended: 0 Effective: 15
00:00:02.683 Model: 3 Extended: 0 Effective: 3
00:00:02.683 Stepping: 4
00:00:02.683 APIC ID: 0x00
00:00:02.683 Logical CPUs: 0
00:00:02.683 CLFLUSH Size: 8
00:00:02.683 Brand ID: 0x00
00:00:02.683 Mnemonic - Description = guest (host)
00:00:02.683 FPU - x87 FPU on Chip = 1 (1)
00:00:02.683 VME - Virtual 8086 Mode Enhancements = 1 (1)
00:00:02.683 DE - Debugging extensions = 1 (1)
00:00:02.683 PSE - Page Size Extension = 1 (1)
00:00:02.683 TSC - Time Stamp Counter = 1 (1)
00:00:02.683 MSR - Model Specific Registers = 1 (1)
00:00:02.683 PAE - Physical Address Extension = 0 (1)
00:00:02.683 MCE - Machine Check Exception = 1 (1)
00:00:02.683 CX8 - CMPXCHG8B instruction = 1 (1)
00:00:02.683 APIC - APIC On-Chip = 0 (1)
00:00:02.683 Reserved = 0 (0)
00:00:02.683 SEP - SYSENTER and SYSEXIT = 0 (1)
00:00:02.683 MTRR - Memory Type Range Registers = 1 (1)
00:00:02.683 PGE - PTE Global Bit = 1 (1)
00:00:02.683 MCA - Machine Check Architecture = 1 (1)
00:00:02.683 CMOV - Conditional Move Instructions = 1 (1)
00:00:02.683 PAT - Page Attribute Table = 1 (1)
00:00:02.683 PSE-36 - 36-bit Page Size Extention = 1 (1)
00:00:02.683 PSN - Processor Serial Number = 0 (0)
00:00:02.683 CLFSH - CLFLUSH Instruction. = 1 (1)
00:00:02.683 Reserved = 0 (0)
00:00:02.683 DS - Debug Store = 0 (1)
00:00:02.683 ACPI - Thermal Mon. & Soft. Clock Ctrl.= 0 (1)
00:00:02.683 MMX - Intel MMX Technology = 1 (1)
00:00:02.683 FXSR - FXSAVE and FXRSTOR Instructions = 1 (1)
00:00:02.683 SSE - SSE Support = 1 (1)
00:00:02.683 SSE2 - SSE2 Support = 1 (1)
00:00:02.683 SS - Self Snoop = 0 (1)
00:00:02.683 HTT - Hyper-Threading Technolog = 0 (1)
00:00:02.683 TM - Thermal Monitor = 0 (1)
00:00:02.683 30 - Reserved = 0 (0)
00:00:02.683 PBE - Pending Break Enable = 0 (1)
00:00:02.683 Supports SSE3 or not = 1 (1)
00:00:02.683 Reserved = 0 (2)
00:00:02.683 Supports MONITOR/MWAIT = 1 (1)
00:00:02.683 CPL-DS - CPL Qualified Debug Store = 0 (1)
00:00:02.683 VMX - Virtual Machine Technology = 0 (0)
00:00:02.683 Reserved = 0 (0)
00:00:02.683 Enhanced SpeedStep Technology = 0 (0)
00:00:02.683 Terminal Monitor 2 = 0 (0)
00:00:02.683 Supports Supplemental SSE3 or not = 0 (0)
00:00:02.683 L1 Context ID = 0 (1)
00:00:02.683 Reserved = 0x0 (0x0)
00:00:02.683 CMPXCHG16B = 0 (0)
00:00:02.683 xTPR Update Control = 0 (1)
00:00:02.683 Reserved = 0x0 (0x0)
00:00:02.683
00:00:02.683 RAW Extended CPUIDs
00:00:02.683 Function eax ebx ecx edx
00:00:02.683 Gst: 80000000 80000008 00000000 00000000 00000000
00:00:02.683 Hst: 80000008 00000000 00000000 00000000
00:00:02.683 Gst: 80000001 00000000 00000000 00000000 00000000
00:00:02.683 Hst: 00000000 00000000 00000000 00000000
00:00:02.683 Gst: 80000002 20202020 20202020 20202020 20202020
00:00:02.683 Hst: 20202020 20202020 20202020 20202020
00:00:02.683 Gst: 80000003 65746e49 2952286c 6c654320 6e6f7265
00:00:02.683 Hst: 65746e49 2952286c 6c654320 6e6f7265
00:00:02.683 Gst: 80000004 20295228 20555043 30382e32 007a4847
00:00:02.683 Hst: 20295228 20555043 30382e32 007a4847
00:00:02.683 Gst: 80000005 00000000 00000000 00000000 00000000
00:00:02.683 Hst: 00000000 00000000 00000000 00000000
00:00:02.683 Gst: 80000006 00000000 00000000 01004040 00000000
00:00:02.683 Hst: 00000000 00000000 01004040 00000000
00:00:02.683 Gst: 80000007 00000000 00000000 00000000 00000000
00:00:02.683 Hst: 00000000 00000000 00000000 00000000
00:00:02.683 Gst: 80000008 00002024 00000000 00000000 00000000
00:00:02.683 Hst: 00002024 00000000 00000000 00000000
00:00:02.683 Gst: 80000009 00000040 00000040 00000000 00000000*
00:00:02.683 Hst: 00000040 00000040 00000000 00000000
00:00:02.683 Ext Name:
00:00:02.683 Ext Supports: 0x80000000-0x80000008
00:00:02.683 Family: 0 Extended: 0 Effective: 0
00:00:02.683 Model: 0 Extended: 0 Effective: 0
00:00:02.683 Stepping: 0
00:00:02.683 Brand ID: 0x000
00:00:02.683 Mnemonic - Description = guest (host)
00:00:02.683 FPU - x87 FPU on Chip = 0 (0)
00:00:02.683 VME - Virtual 8086 Mode Enhancements = 0 (0)
00:00:02.683 DE - Debugging extensions = 0 (0)
00:00:02.683 PSE - Page Size Extension = 0 (0)
00:00:02.683 TSC - Time Stamp Counter = 0 (0)
00:00:02.683 MSR - K86 Model Specific Registers = 0 (0)
00:00:02.683 PAE - Physical Address Extension = 0 (0)
00:00:02.683 MCE - Machine Check Exception = 0 (0)
00:00:02.683 CX8 - CMPXCHG8B instruction = 0 (0)
00:00:02.683 APIC - APIC On-Chip = 0 (0)
00:00:02.683 10 - Reserved = 0 (0)
00:00:02.683 SEP - SYSCALL and SYSRET = 0 (0)
00:00:02.683 MTRR - Memory Type Range Registers = 0 (0)
00:00:02.683 PGE - PTE Global Bit = 0 (0)
00:00:02.683 MCA - Machine Check Architecture = 0 (0)
00:00:02.684 CMOV - Conditional Move Instructions = 0 (0)
00:00:02.684 PAT - Page Attribute Table = 0 (0)
00:00:02.684 PSE-36 - 36-bit Page Size Extention = 0 (0)
00:00:02.684 18 - Reserved = 0 (0)
00:00:02.684 19 - Reserved = 0 (0)
00:00:02.684 NX - No-Execute Page Protection = 0 (0)
00:00:02.684 DS - Debug Store = 0 (0)
00:00:02.684 AXMMX - AMD Extensions to MMX Instr. = 0 (0)
00:00:02.684 MMX - Intel MMX Technology = 0 (0)
00:00:02.684 FXSR - FXSAVE and FXRSTOR Instructions = 0 (0)
00:00:02.684 25 - AMD fast FXSAVE and FXRSTOR Instr.= 0 (0)
00:00:02.684 26 - 1 GB large page support = 0 (0)
00:00:02.684 27 - RDTSCP instruction = 0 (0)
00:00:02.684 28 - Reserved = 0 (0)
00:00:02.684 29 - AMD Long Mode = 0 (0)
00:00:02.684 30 - AMD Extensions to 3DNow = 0 (0)
00:00:02.684 31 - AMD 3DNow = 0 (0)
00:00:02.684 LahfSahf - LAHF/SAHF in 64-bit mode = 0 (0)
00:00:02.684 CmpLegacy - Core MP legacy mode (depr) = 0 (0)
00:00:02.684 SVM - AMD VM Extensions = 0 (0)
00:00:02.684 APIC registers starting at 0x400 = 0 (0)
00:00:02.684 AltMovCR8 - LOCK MOV CR0 means MOV CR8 = 0 (0)
00:00:02.684 Advanced bit manipulation = 0 (0)
00:00:02.684 SSE4A instruction support = 0 (0)
00:00:02.684 Misaligned SSE mode = 0 (0)
00:00:02.684 PREFETCH and PREFETCHW instruction = 0 (0)
00:00:02.684 OS visible workaround = 0 (0)
00:00:02.684 Instruction based sampling = 0 (0)
00:00:02.684 SSE5 support = 0 (0)
00:00:02.684 SKINIT, STGI, and DEV support = 0 (0)
00:00:02.684 Watchdog timer support. = 0 (0)
00:00:02.684 31:14 - Reserved = 0x0 (0x0)
00:00:02.684 Full Name: Intel(R) Celeron(R) CPU 2.80GHz
00:00:02.684 TLB 2/4M Instr/Uni: res0 0 entries
00:00:02.684 TLB 2/4M Data: res0 0 entries
00:00:02.684 TLB 4K Instr/Uni: res0 0 entries
00:00:02.684 TLB 4K Data: res0 0 entries
00:00:02.684 L1 Instr Cache Line Size: 0 bytes
00:00:02.684 L1 Instr Cache Lines Per Tag: 0
00:00:02.684 L1 Instr Cache Associativity: res0
00:00:02.684 L1 Instr Cache Size: 0 KB
00:00:02.684 L1 Data Cache Line Size: 0 bytes
00:00:02.684 L1 Data Cache Lines Per Tag: 0
00:00:02.684 L1 Data Cache Associativity: res0
00:00:02.684 L1 Data Cache Size: 0 KB
00:00:02.684 L2 TLB 2/4M Instr/Uni: off 0 entries
00:00:02.684 L2 TLB 2/4M Data: off 0 entries
00:00:02.684 L2 TLB 4K Instr/Uni: off 0 entries
00:00:02.684 L2 TLB 4K Data: off 0 entries
00:00:02.684 L2 Cache Line Size: 0 bytes
00:00:02.684 L2 Cache Lines Per Tag: 0
00:00:02.684 L2 Cache Associativity: off
00:00:02.684 L2 Cache Size: 0 KB
00:00:02.684 APM Features:
00:00:02.684 Physical Address Width: 36 bits
00:00:02.684 Virtual Address Width: 32 bits
00:00:02.684 Physical Core Count: 0
00:00:02.684
00:00:02.684 RAW Centaur CPUIDs
00:00:02.684 Function eax ebx ecx edx
00:00:02.684 Gst: c0000000 00000040 00000040 00000000 00000000
00:00:02.684 Hst: 00000040 00000040 00000000 00000000
00:00:02.684 Gst: c0000001 00000040 00000040 00000000 00000000
00:00:02.684 Hst: 00000040 00000040 00000000 00000000
00:00:02.684 Gst: c0000002 00000040 00000040 00000000 00000000
00:00:02.684 Hst: 00000040 00000040 00000000 00000000
00:00:02.684 Gst: c0000003 00000040 00000040 00000000 00000000
00:00:02.684 Hst: 00000040 00000040 00000000 00000000
00:00:02.684 Centaur Supports: 0xc0000000-0x00000040
00:00:02.684 Mnemonic - Description = guest (host)
00:00:02.684 AIS - Alternate Instruction Set = 0 (0)
00:00:02.684 AIS-E - AIS enabled = 0 (0)
00:00:02.684 RNG - Random Number Generator = 0 (0)
00:00:02.684 RNG-E - RNG enabled = 0 (0)
00:00:02.684 LH - LongHaul MSR 0000_110Ah = 0 (0)
00:00:02.684 FEMMS - FEMMS = 0 (0)
00:00:02.684 ACE - Advanced Cryptography Engine = 0 (0)
00:00:02.684 ACE-E - ACE enabled = 0 (0)
00:00:02.684 ACE2 - Advanced Cryptography Engine 2 = 0 (0)
00:00:02.684 ACE2-E - ACE enabled = 0 (0)
00:00:02.684 PHE - Hash Engine = 0 (0)
00:00:02.684 PHE-E - PHE enabled = 0 (0)
00:00:02.684 PMM - Montgomery Multiplier = 0 (0)
00:00:02.684 PMM-E - PMM enabled = 0 (0)
00:00:02.684
00:00:02.684
00:00:02.684 ******************** End of CPUID dump **********************
00:00:02.688 REM: VBoxREM32
00:00:02.742 TM: GIP - u32Mode=1 (SyncTSC) u32UpdateHz=83
00:00:02.774 TM: cTSCTicksPerSecond=0xa622f1d8 (2 787 307 992) fTSCVirtualized=true fTSCUseRealTSC=false
00:00:02.774 TM: fMaybeUseOffsettedHostTSC=true TSCTiedToExecution=false TSCNotTiedToHalt=false
00:00:02.797 CoreCode: R3=b3886000 R0=f93a2000 RC=a032e000 Phys=000000002be52000 cb=0x2000
00:00:02.827 [SMP] BIOS with 1 CPUs
00:00:02.843 SUP: Loaded VBoxDDR0.r0 (/usr/lib/virtualbox/VBoxDDR0.r0) at 0xf93ec060 - ModuleInit at 0000000000000000 and ModuleTerm at 0000000000000000
00:00:02.864 SUP: Loaded VBoxDD2R0.r0 (/usr/lib/virtualbox/VBoxDD2R0.r0) at 0xf9416060 - ModuleInit at 0000000000000000 and ModuleTerm at 0000000000000000
00:00:02.864 Activating Local APIC
00:00:02.864 CPUMSetGuestCpuIdFeature: Enabled APIC
00:00:02.864 CPUMSetGuestCpuIdFeature: Disabled x2APIC
00:00:02.864 PIT: mode=3 count=0x10000 (65536) - 18.20 Hz (ch=0)
00:00:02.898 Shared Folders service loaded.
00:00:03.007 VDInit finished
00:00:03.008 PIIX3 ATA: LUN#0: disk, PCHS=16383/16/63, total number of sectors 20971520
00:00:03.008 PIIX3 ATA: LUN#1: no unit
00:00:03.008 PIIX3 ATA: LUN#2: CD/DVD, total number of sectors 15811, passthrough disabled
00:00:03.009 PIIX3 ATA: LUN#3: no unit
00:00:03.009 PIIX3 ATA: Ctl#0: finished processing RESET
00:00:03.009 PIIX3 ATA: Ctl#1: finished processing RESET
00:00:03.012 NAT: value of BindIP has been ignored
00:00:03.013 Audio: Trying driver 'pulse'.
00:00:03.021 Audio: set_record_source ars=0 als=0 (not implemented)
00:00:03.021 Pulse: open PCM_IN rate=44100Hz channels=2 format=s16le
00:00:03.055 Pulse: buffer settings: max=26460 tlength=17640 prebuf=15876 minreq=1764
00:00:03.055 Pulse: open PCM_OUT rate=44100Hz channels=2 format=s16le
00:00:03.149 Pulse: buffer settings: max=26460 tlength=17640 prebuf=15876 minreq=1764
00:00:03.152 DevPcBios: ATA LUN#0 LCHS=1024/255/63
00:00:03.152 PGMR3InitFinalize: 4 MB PSE mask 0000000fffffffff
00:00:03.175 HWACCM: No VT-x or AMD-V CPU extension found. Reason VERR_VMX_NO_VMX
00:00:03.175 HWACCM: VMX MSR_IA32_FEATURE_CONTROL=0
00:00:03.195 VM: Halt method global1 (5)
00:00:03.195 Changing the VM state from 'CREATING' to 'CREATED'.
00:00:03.196 SharedFolders host service: adding host mapping.
00:00:03.196 Host path /home/kmlid4/vdishare, map name vdishare, writable 1
00:00:03.196 SharedFolders host service: add mapping result VINF_SUCCESS
00:00:03.198 Changing the VM state from 'CREATED' to 'RUNNING'.
00:00:03.412 Guest Log: BIOS: VirtualBox 3.0.4
00:00:03.412 PIT: mode=2 count=0x10000 (65536) - 18.20 Hz (ch=0)
00:00:03.610 PIIX3 ATA: Ctl#0: RESET, DevSel=0 AIOIf=0 CmdIf0=0x00 (-1 usec ago) CmdIf1=0x00 (-1 usec ago)
00:00:03.615 PIIX3 ATA: Ctl#0: finished processing RESET
00:00:03.618 Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63
00:00:03.619 PIIX3 ATA: Ctl#1: RESET, DevSel=0 AIOIf=0 CmdIf0=0x00 (-1 usec ago) CmdIf1=0x00 (-1 usec ago)
00:00:03.635 PIIX3 ATA: Async I/O thread probably stuck in operation, interrupting
00:00:03.635 PIIX3 ATA: Ctl#1: finished processing RESET
00:00:03.636 PIT: mode=2 count=0x48d3 (18643) - 64.00 Hz (ch=0)
00:00:03.695 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=b21bb000 w=640 h=480 bpp=32 cbLine=0xA00
00:00:06.130 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=00000000 w=720 h=400 bpp=0 cbLine=0x0
00:00:06.140 PIT: mode=2 count=0x10000 (65536) - 18.20 Hz (ch=0)
00:00:06.154 Guest Log: BIOS: CDROM boot failure code : 0004
00:00:06.155 Guest Log: BIOS: Boot from CD-ROM failed
00:00:06.157 Guest Log: BIOS: Booting from Hard Disk...
00:00:06.804 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=b21bb000 w=640 h=480 bpp=0 cbLine=0x140
00:00:07.056 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
00:00:07.069 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
00:00:07.070 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
00:00:07.071 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
00:00:07.071 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
00:00:07.072 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
00:00:07.073 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
00:00:11.287 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=00000000 w=720 h=400 bpp=0 cbLine=0x0
00:00:11.719 PIT: mode=2 count=0x2ead (11949) - 99.85 Hz (ch=0)
00:00:12.144 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=b21bb000 w=640 h=480 bpp=0 cbLine=0x140
00:00:15.445 PIIX3 ATA: LUN#0: IDLE IMMEDIATE, CmdIf=0xef (-1 usec ago)
00:00:15.445 PIIX3 ATA: LUN#0: aborting current command
00:00:16.641 Guest Additions information report: additionsVersion = 0x00010004 osType = 0x00033000
00:00:16.758 Guest reported fixed hypervisor window at 0xf6800000 (size = 0x800000, rc = VINF_SUCCESS)
00:00:20.882 Guest requests mouse pointer integration
00:00:21.411 EHCI: Hardware reset
00:00:21.412 EHCI: USB Operational
00:00:21.622 OHCI: Software reset
00:00:21.622 OHCI: USB Reset
00:00:21.622 OHCI: USB Operational
00:00:21.711 PCNet#0: Init: ss32=1 GCRDRA=0x029bc420[64] GCTDRA=0x029bc020[64]
00:00:22.729 Audio: set_record_source ars=0 als=0 (not implemented)
00:00:22.732 Audio: set_record_source ars=0 als=0 (not implemented)
00:00:22.900 SharedFolders host service: connected, u32ClientID = 1
00:00:23.977 EHCI: USB Suspended
00:00:25.207 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=b21bb000 w=904 h=892 bpp=32 cbLine=0xE20
00:00:25.257 VBVA: Enabled.
00:00:25.742 OHCI: USB Suspended
00:00:27.991 PCNet#0: Init: ss32=1 GCRDRA=0x029bc420[64] GCTDRA=0x029bc020[64]
00:00:30.846 NAT: DHCP offered IP address 10.0.2.15
00:00:33.925 PCNet#0: Init: ss32=1 GCRDRA=0x029bc420[64] GCTDRA=0x029bc020[64]
00:00:49.395 Guest Log: VBOXNP: DLL loaded.
00:00:49.584 SharedFolders host service: request to map folder vdishare
00:00:49.584 SharedFolders host service: map operation result VINF_SUCCESS.
00:00:49.584 Mapped to handle 0.
00:00:50.234 Audio: set_record_source ars=0 als=0 (not implemented)
00:00:50.235 Audio: set_record_source ars=0 als=0 (not implemented)
00:00:53.191 SharedFolders host service: request to map folder vdishare
00:00:53.191 SharedFolders host service: map operation result VINF_SUCCESS.
00:00:53.191 Mapped to handle 0.
00:00:56.248 Guest Log: VBoxTray: Started.
00:00:56.468 Starting host clipboard service
00:00:56.468 Initializing X11 clipboard backend
00:00:56.472 Shared clipboard: starting shared clipboard thread
00:00:56.503 Guest Additions capability report: (0x5) seamless: yes, hostWindowMapping: no, graphics: yes
00:00:58.195 PCNet#0: Init: ss32=1 GCRDRA=0x029bc420[64] GCTDRA=0x029bc020[64]
00:01:04.145 SharedFolders host service: request to unmap folder handle 0
00:01:04.146 SharedFolders host service: unmap operation result VINF_SUCCESS.
00:01:10.305 PIT: mode=2 count=0x4ad (1197) - 996.81 Hz (ch=0)
00:01:24.363 Guest Log: VBOXNP: DLL loaded.
00:01:52.302 Guest Log: VBOXNP: DLL loaded.
00:02:13.500 SharedFolders host service: request to map folder vdishare
00:02:13.500 SharedFolders host service: map operation result VINF_SUCCESS.
00:02:13.500 Mapped to handle 0.
00:02:23.622 SharedFolders host service: request to unmap folder handle 0
00:02:23.622 SharedFolders host service: unmap operation result VINF_SUCCESS.
00:02:23.676 SharedFolders host service: request to map folder vdishare
00:02:23.676 SharedFolders host service: map operation result VINF_SUCCESS.
00:02:23.676 Mapped to handle 0.
00:02:33.298 SharedFolders host service: request to unmap folder handle 0
00:02:33.298 SharedFolders host service: unmap operation result VINF_SUCCESS.
00:02:33.299 SharedFolders host service: request to map folder vdishare
00:02:33.300 SharedFolders host service: map operation result VINF_SUCCESS.
00:02:33.300 Mapped to handle 0.
00:02:33.743 SharedFolders host service: request to unmap folder handle 0
00:02:33.743 SharedFolders host service: unmap operation result VINF_SUCCESS.
00:02:35.569 SharedFolders host service: request to map folder vdishare
00:02:35.569 SharedFolders host service: map operation result VINF_SUCCESS.
00:02:35.569 Mapped to handle 0.
00:02:40.127 SharedFolders host service: request to unmap folder handle 0
00:02:40.127 SharedFolders host service: unmap operation result VINF_SUCCESS.
00:02:40.128 SharedFolders host service: request to map folder vdishare
00:02:40.128 SharedFolders host service: map operation result VINF_SUCCESS.
00:02:40.128 Mapped to handle 0.
00:02:45.614 SharedFolders host service: request to unmap folder handle 0
00:02:45.614 SharedFolders host service: unmap operation result VINF_SUCCESS.
00:02:46.911 SharedFolders host service: request to map folder vdishare
00:02:46.911 SharedFolders host service: map operation result VINF_SUCCESS.
00:02:46.911 Mapped to handle 0.
00:03:46.346 SharedFolders host service: request to unmap folder handle 0
00:03:46.346 SharedFolders host service: unmap operation result VINF_SUCCESS.
00:07:24.544 SharedFolders host service: request to map folder vdishare
00:07:24.544 SharedFolders host service: map operation result VINF_SUCCESS.
00:07:24.544 Mapped to handle 0.
00:07:34.634 SharedFolders host service: request to unmap folder handle 0
00:07:34.635 SharedFolders host service: unmap operation result VINF_SUCCESS.
00:09:41.684 SharedFolders host service: request to map folder vdishare
00:09:41.684 SharedFolders host service: map operation result VINF_SUCCESS.
00:09:41.684 Mapped to handle 0.
00:09:42.007 PIT: mode=2 count=0x2ead (11949) - 99.85 Hz (ch=0)
00:09:42.124 Guest Log: VBOXNP: DLL unloaded.
00:09:48.714 PIT: mode=2 count=0x4ad (1197) - 996.81 Hz (ch=0)
00:09:52.436 SharedFolders host service: request to unmap folder handle 0
00:09:52.436 SharedFolders host service: unmap operation result VINF_SUCCESS.
00:09:55.934 Guest Log: VBOXNP: DLL loaded.
00:11:47.087 SharedFolders host service: request to map folder vdishare
00:11:47.087 SharedFolders host service: map operation result VINF_SUCCESS.
00:11:47.087 Mapped to handle 0.
00:11:49.393 SharedFolders host service: request to unmap folder handle 0
00:11:49.393 SharedFolders host service: unmap operation result VINF_SUCCESS.
00:11:49.394 SharedFolders host service: request to map folder vdishare
00:11:49.394 SharedFolders host service: map operation result VINF_SUCCESS.
00:11:49.394 Mapped to handle 0.
00:11:57.157 SharedFolders host service: request to unmap folder handle 0
00:11:57.157 SharedFolders host service: unmap operation result VINF_SUCCESS.
00:13:30.327 SharedFolders host service: request to map folder vdishare
00:13:30.327 SharedFolders host service: map operation result VINF_SUCCESS.
00:13:30.327 Mapped to handle 0.
00:13:40.414 SharedFolders host service: request to unmap folder handle 0
00:13:40.414 SharedFolders host service: unmap operation result VINF_SUCCESS.
00:13:40.925 SharedFolders host service: request to map folder vdishare
00:13:40.925 SharedFolders host service: map operation result VINF_SUCCESS.
00:13:40.925 Mapped to handle 0.
00:13:50.400 SharedFolders host service: request to unmap folder handle 0
00:13:50.400 SharedFolders host service: unmap operation result VINF_SUCCESS.
00:13:50.402 SharedFolders host service: request to map folder vdishare
00:13:50.402 SharedFolders host service: map operation result VINF_SUCCESS.
00:13:50.402 Mapped to handle 0.
00:13:50.826 SharedFolders host service: request to unmap folder handle 0
00:13:50.826 SharedFolders host service: unmap operation result VINF_SUCCESS.
00:13:52.668 SharedFolders host service: request to map folder vdishare
00:13:52.668 SharedFolders host service: map operation result VINF_SUCCESS.
00:13:52.668 Mapped to handle 0.
00:13:57.217 SharedFolders host service: request to unmap folder handle 0
00:13:57.217 SharedFolders host service: unmap operation result VINF_SUCCESS.
00:13:57.219 SharedFolders host service: request to map folder vdishare
00:13:57.219 SharedFolders host service: map operation result VINF_SUCCESS.
00:13:57.219 Mapped to handle 0.
00:14:02.715 SharedFolders host service: request to unmap folder handle 0
00:14:02.715 SharedFolders host service: unmap operation result VINF_SUCCESS.
00:14:03.997 SharedFolders host service: request to map folder vdishare
00:14:03.997 SharedFolders host service: map operation result VINF_SUCCESS.
00:14:03.997 Mapped to handle 0.
00:21:40.247 PIT: mode=2 count=0x2ead (11949) - 99.85 Hz (ch=0)
00:21:40.568 Guest Log: VBOXNP: DLL unloaded.
00:21:51.175 SharedFolders host service: request to unmap folder handle 0
00:21:51.175 SharedFolders host service: unmap operation result VINF_SUCCESS.
00:24:37.634 SharedFolders host service: request to map folder vdishare
00:24:37.634 SharedFolders host service: map operation result VINF_SUCCESS.
00:24:37.634 Mapped to handle 0.
00:24:44.809 Stopping the host clipboard service
00:24:44.809 ClipStopX11: stopping the shared clipboard X11 backend
00:24:44.809 Shared clipboard: shared clipboard thread terminated successfully
00:24:46.552 SharedFolders host service: request to unmap folder handle 0
00:24:46.552 SharedFolders host service: unmap operation result VINF_SUCCESS.
00:24:46.553 SharedFolders host service: request to unmap folder handle 0
00:24:46.553 SharedFolders host service: unmap operation result VINF_SUCCESS.
00:24:52.890 PCNet#0: Init: ss32=1 GCRDRA=0x029bc420[64] GCTDRA=0x029bc020[64]
00:24:55.842 PIIX3 ATA: LUN#0: IDLE IMMEDIATE, CmdIf=0xe7 (-1 usec ago)
00:24:55.842 PIIX3 ATA: LUN#0: aborting current command
00:24:57.055 Guest requests the VM to be turned off
00:24:57.055 ****************** Guest state at power off ******************
00:24:57.055 Guest CPUM (VCPU 0) state: se
00:24:57.055 eax=02b6406c ebx=823b06a0 ecx=00000000 edx=0000d040 esi=8276406c edi=00000000
00:24:57.055 eip=f794fed1 esp=f7afec94 ebp=f7afec9c iopl=0 rf nv up ei pl nz na po nc
00:24:57.055 cs={0008 base=0000000000000000 limit=ffffffff flags=0000c09a} dr0=00000000 dr1=00000000
00:24:57.055 ds={0023 base=0000000000000000 limit=ffffffff flags=0000c0f3} dr2=00000000 dr3=00000000
00:24:57.055 es={0023 base=0000000000000000 limit=ffffffff flags=0000c0f3} dr4=00000000 dr5=00000000
00:24:57.055 fs={0030 base=00000000ffdff000 limit=00001fff flags=0000c093} dr6=ffff0ff0 dr7=00000400
00:24:57.055 gs={0000 base=0000000000000000 limit=0000ffff flags=00000092} cr0=e001003b cr2=f797f000
00:24:57.055 ss={0010 base=0000000000000000 limit=ffffffff flags=0000c093} cr3=00039000 cr4=000006d9
00:24:57.055 gdtr=000000008003f000:03ff idtr=000000008003f400:07ff eflags=00010246
00:24:57.055 ldtr={0000 base=00000000 limit=00000000 flags=00000082}
00:24:57.055 tr ={0028 base=80042000 limit=000020ab flags=0000008b}
00:24:57.055 SysEnter={cs=0000 eip=00000000 esp=00000000}
00:24:57.055 FPU:
00:24:57.055 FCW=027f FSW=0000 FTW=00
00:24:57.055 res1=00 FOP=0000 FPUIP=00000000 CS=0000 Rsvrd1=0000
00:24:57.055 FPUDP=0000 DS=0000 Rsvrd2=0000 MXCSR=00001f80 MXCSR_MASK=0000ffff
00:24:57.055 MSR:
00:24:57.055 EFER =0000000000000000
00:24:57.055 PAT =0007010600070106
00:24:57.055 STAR =0000000000000000
00:24:57.055 CSTAR =0000000000000000
00:24:57.055 LSTAR =0000000000000000
00:24:57.055 SFMASK =0000000000000000
00:24:57.055 KERNELGSBASE =0000000000000000
00:24:57.055 ***
00:24:57.055 Guest paging mode: 32-bit, changed 1988 times, A20 enabled
00:24:57.055 Shadow paging mode: 32-bit
00:24:57.055 Host paging mode: 32-bit+G
00:24:57.055 ***
00:24:57.055 Active Timers (pVM=b4b95000)
00:24:57.055 pTimerR3 offNext offPrev offSched Clock Time Expire State Description
00:24:57.055 b48e5050 00008280 00000000 00000000 Real 000000000007567017 000000000007567029 2-ACTIVE VGA Refresh Timer
00:24:57.055 b48ed2d0 00000000 ffff7d80 00000000 Real 000000000007567017 000000000007567034 2-ACTIVE EMT Yielder
00:24:57.055 b48e9650 00000000 00000000 00000000 Virt 000001493748065358 000001493748481384 2-ACTIVE Audio timer
00:24:57.055 b48d35b0 000003a0 00000000 00000000 VrSy 000001493748089636 000001493751265575 2-ACTIVE i8254 Programmable Interval Timer
00:24:57.055 b48d3950 00018fa0 fffffc60 00000000 VrSy 000001493748113672 000001493990000000 2-ACTIVE MC146818 RTC/CMOS - Second
00:24:57.055 b48ec8f0 00000000 fffe7060 00000000 VrSy 000001493748137305 000002399728063202 2-ACTIVE ACPI Timer
00:24:57.055 ***
00:24:57.055 Shadow GDT (GCAddr=f6b31000):
00:24:57.055 0008 - 0000ffff 00cfbb00 - base=00000000 limit=ffffffff dpl=1 CodeER Accessed Present Page 32-bit
00:24:57.055 0010 - 0000ffff 00cfb300 - base=00000000 limit=ffffffff dpl=1 DataRW Accessed Present Page 32-bit
00:24:57.055 0018 - 0000ffff 00cffb00 - base=00000000 limit=ffffffff dpl=3 CodeER Accessed Present Page 32-bit
00:24:57.055 0020 - 0000ffff 00cff300 - base=00000000 limit=ffffffff dpl=3 DataRW Accessed Present Page 32-bit
00:24:57.055 0030 - f0000001 ffc0b3df - base=ffdff000 limit=00001fff dpl=1 DataRW Accessed Present Page 32-bit
00:24:57.055 0038 - 00000fff 0040f300 - base=00000000 limit=00000fff dpl=3 DataRW Accessed Present 32-bit
00:24:57.055 0040 - 0400ffff 0000f300 - base=00000400 limit=0000ffff dpl=3 DataRW Accessed Present 16-bit
00:24:57.055 0060 - 2f40ffff 0000b302 - base=00022f40 limit=0000ffff dpl=1 DataRW Accessed Present 16-bit
00:24:57.055 0068 - 80003fff 0000b30b - base=000b8000 limit=00003fff dpl=1 DataRW Accessed Present 16-bit
00:24:57.055 0070 - 700003ff ff00b3ff - base=ffff7000 limit=000003ff dpl=1 DataRW Accessed Present 16-bit
00:24:57.055 0078 - 0000ffff 8000bb40 - base=80400000 limit=0000ffff dpl=1 CodeER Accessed Present 16-bit
00:24:57.055 0080 - 0000ffff 8000b340 - base=80400000 limit=0000ffff dpl=1 DataRW Accessed Present 16-bit
00:24:57.055 0088 - 00000000 0000b300 - base=00000000 limit=00000000 dpl=1 DataRW Accessed Present 16-bit
00:24:57.055 00e8 - 0000ffff 0000b300 - base=00000000 limit=0000ffff dpl=1 DataRW Accessed Present 16-bit
00:24:57.055 00f0 - 9b2884c7 8003b94d - base=804d9b28 limit=000384c7 dpl=1 CodeEO Accessed Present 16-bit
00:24:57.055 00f8 - 0000ffff 0000b300 - base=00000000 limit=0000ffff dpl=1 DataRW Accessed Present 16-bit
00:24:57.055 ffd8 - 79380087 f6008980 - base=f6807938 limit=00000087 dpl=0 TSS32Avail Present 16-bit HyperTSSTrap08
00:24:57.055 ffe0 - 78b00087 f6008b80 - base=f68078b0 limit=00000087 dpl=0 TSS32Busy Present 16-bit HyperTSS
00:24:57.055 ffe8 - 0000ffff 00af9b00 - base=00000000 limit=ffffffff dpl=0 CodeER Accessed Present Page 16-bit HyperCS64
00:24:57.055 fff0 - 0000ffff 00cf9300 - base=00000000 limit=ffffffff dpl=0 DataRW Accessed Present Page 32-bit HyperDS
00:24:57.055 fff8 - 0000ffff 00cf9b00 - base=00000000 limit=ffffffff dpl=0 CodeER Accessed Present Page 32-bit HyperCS
00:24:57.055 ***
00:24:57.055 ************** End of Guest state at power off ***************
00:24:57.055 Changing the VM state from 'RUNNING' to 'OFF'.
00:24:57.189 Console::powerDown(): A request to power off the VM has been issued (mMachineState=8, InUninit=0)
00:24:57.204 SharedFolders host service: disconnected, u32ClientID = 1
00:24:57.207 Changing the VM state from 'OFF' to 'DESTROYING'.
00:24:57.811 ************************* Statistics *************************
00:24:57.811 /Devices/ATA0/Unit0/AtapiDMA 0 times
00:24:57.811 /Devices/ATA0/Unit0/AtapiPIO 0 times
00:24:57.811 /Devices/ATA0/Unit0/DMA 9020 times
00:24:57.811 /Devices/ATA0/Unit0/PIO 2353 times
00:24:57.811 /Devices/ATA0/Unit0/ReadBytes 168521216 bytes
00:24:57.811 /Devices/ATA0/Unit0/WrittenBytes 53429760 bytes
00:24:57.811 /Devices/ATA0/Unit1/AtapiDMA 0 times
00:24:57.811 /Devices/ATA0/Unit1/AtapiPIO 0 times
00:24:57.811 /Devices/ATA0/Unit1/DMA 0 times
00:24:57.811 /Devices/ATA0/Unit1/PIO 0 times
00:24:57.811 /Devices/ATA0/Unit1/ReadBytes 0 bytes
00:24:57.811 /Devices/ATA0/Unit1/WrittenBytes 0 bytes
00:24:57.811 /Devices/ATA1/Unit0/AtapiDMA 0 times
00:24:57.811 /Devices/ATA1/Unit0/AtapiPIO 1520 times
00:24:57.811 /Devices/ATA1/Unit0/DMA 0 times
00:24:57.811 /Devices/ATA1/Unit0/PIO 0 times
00:24:57.811 /Devices/ATA1/Unit0/ReadBytes 116736 bytes
00:24:57.811 /Devices/ATA1/Unit0/WrittenBytes 0 bytes
00:24:57.811 /Devices/ATA1/Unit1/AtapiDMA 0 times
00:24:57.811 /Devices/ATA1/Unit1/AtapiPIO 0 times
00:24:57.811 /Devices/ATA1/Unit1/DMA 0 times
00:24:57.811 /Devices/ATA1/Unit1/PIO 0 times
00:24:57.811 /Devices/ATA1/Unit1/ReadBytes 0 bytes
00:24:57.811 /Devices/ATA1/Unit1/WrittenBytes 0 bytes
00:24:57.811 /Devices/PCNet0/ReceiveBytes 4849 bytes
00:24:57.811 /Devices/PCNet0/TransmitBytes 45584 bytes
00:24:57.811 /GVMM/EMTs 1 calls
00:24:57.811 /GVMM/Sum/HaltBlocking 400202 calls
00:24:57.811 /GVMM/Sum/HaltCalls 91547995 calls
00:24:57.811 /GVMM/Sum/HaltNotBlocking 91147793 calls
00:24:57.811 /GVMM/Sum/HaltTimeouts 169280 calls
00:24:57.811 /GVMM/Sum/HaltWakeUps 0 calls
00:24:57.811 /GVMM/Sum/PokeCalls 0 calls
00:24:57.812 /GVMM/Sum/PokeNotBusy 0 calls
00:24:57.812 /GVMM/Sum/PollCalls 5815 calls
00:24:57.812 /GVMM/Sum/PollHalts 0 calls
00:24:57.812 /GVMM/Sum/PollWakeUps 0 calls
00:24:57.812 /GVMM/Sum/WakeUpCalls 1965801 calls
00:24:57.812 /GVMM/Sum/WakeUpNotHalted 1931005 calls
00:24:57.812 /GVMM/Sum/WakeUpWakeUps 0 calls
00:24:57.812 /GVMM/VM/HaltBlocking 400202 calls
00:24:57.812 /GVMM/VM/HaltCalls 91547995 calls
00:24:57.812 /GVMM/VM/HaltNotBlocking 91147793 calls
00:24:57.812 /GVMM/VM/HaltTimeouts 169280 calls
00:24:57.812 /GVMM/VM/HaltWakeUps 0 calls
00:24:57.812 /GVMM/VM/PokeCalls 0 calls
00:24:57.812 /GVMM/VM/PokeNotBusy 0 calls
00:24:57.812 /GVMM/VM/PollCalls 5815 calls
00:24:57.812 /GVMM/VM/PollHalts 0 calls
00:24:57.812 /GVMM/VM/PollWakeUps 0 calls
00:24:57.812 /GVMM/VM/WakeUpCalls 1965801 calls
00:24:57.812 /GVMM/VM/WakeUpNotHalted 1931005 calls
00:24:57.812 /GVMM/VM/WakeUpWakeUps 0 calls
00:24:57.812 /GVMM/VMs 1 calls
00:24:57.812 /MM/HyperHeap/cbFree 833984 bytes
00:24:57.812 /MM/HyperHeap/cbHeap 1310464 bytes
00:24:57.812 /PDM/CritSects/ATA0/ContentionR3 0 times
00:24:57.812 /PDM/CritSects/ATA0/ContentionRZLock 0 times
00:24:57.812 /PDM/CritSects/ATA0/ContentionRZUnlock 0 times
00:24:57.812 /PDM/CritSects/ATA1/ContentionR3 0 times
00:24:57.812 /PDM/CritSects/ATA1/ContentionRZLock 0 times
00:24:57.812 /PDM/CritSects/ATA1/ContentionRZUnlock 0 times
00:24:57.812 /PDM/CritSects/EM-REM/ContentionR3 0 times
00:24:57.812 /PDM/CritSects/EM-REM/ContentionRZLock 0 times
00:24:57.812 /PDM/CritSects/EM-REM/ContentionRZUnlock 0 times
00:24:57.812 /PDM/CritSects/IOM EMT Lock/ContentionR3 0 times
00:24:57.812 /PDM/CritSects/IOM EMT Lock/ContentionRZLock 0 times
00:24:57.812 /PDM/CritSects/IOM EMT Lock/ContentionRZUnlock 0 times
00:24:57.812 /PDM/CritSects/MM-HYPER/ContentionR3 0 times
00:24:57.812 /PDM/CritSects/MM-HYPER/ContentionRZLock 0 times
00:24:57.812 /PDM/CritSects/MM-HYPER/ContentionRZUnlock 0 times
00:24:57.812 /PDM/CritSects/PCNet#0/ContentionR3 0 times
00:24:57.812 /PDM/CritSects/PCNet#0/ContentionRZLock 0 times
00:24:57.812 /PDM/CritSects/PCNet#0/ContentionRZUnlock 0 times
00:24:57.812 /PDM/CritSects/PDM/ContentionR3 0 times
00:24:57.812 /PDM/CritSects/PDM/ContentionRZLock 0 times
00:24:57.812 /PDM/CritSects/PDM/ContentionRZUnlock 0 times
00:24:57.812 /PDM/CritSects/PGM/ContentionR3 0 times
00:24:57.812 /PDM/CritSects/PGM/ContentionRZLock 0 times
Top
D8TA
Posts: 1
Joined: 7. Aug 2009, 14:03
Primary OS: Ubuntu other
VBox Version: OSE other
Guest OSses: Windows

Re: Virtual Box 3.04 slow and high CPU load on Window XP SP3

Post by D8TA »

I hadn't had any problems with VB 3 running on openSUSE. Once I installed Ubuntu I am having this exact problem. When I looked at the trouble ticket this has been a problem since the release of version 3? When can a patch be sent out to correct this behavior? It is really quite annoying and I am surprised this was overlooked during testing.

Going back to VB version 2.2.4 and all is well again. Will wait to use 3 until this bug is worked out.
Top
Kakao
Posts: 21
Joined: 29. Jul 2009, 20:29
Primary OS: MS Windows 7
VBox Version: VirtualBox+Oracle ExtPack
Guest OSses: Ubuntu 11.10, 12.04 and Centos 6

Re: Virtual Box 3.04 slow and high CPU load on Window XP SP3

Post by Kakao »

Fedora 10 64 bits host with a 4 core AMD cpu.

XP SP3 32 bits guest is very sluggish the host cpu is at 100% most of the time. If I set the number of cpus to 2 then the host goes to 200% but still equally sluggish.

Flash is a slide show.
Top
fyi
Posts: 25
Joined: 20. Jan 2009, 07:58
Primary OS: Debian Lenny
VBox Version: VirtualBox+Oracle ExtPack
Guest OSses: XP sp3, Debian, others

Re: Virtual Box 3.04 slow and high CPU load on Window XP SP3

Post by fyi »

Slow here too.. cpu pins to ~50% of host far more, the cpu fan is working significantly harder with an appliance open.
- i'm using 3.0.4 with respective guest additions version.
- tried recompiled the vbox kernel modules again
- logged a lot in my kern, syslog and messages, also when i press the left ctrl or left alt key... (only when the appliance is running)

Code: Select all

----
Aug 16 03:38:51  kernel: [28212.716870] Pid: 6494, comm: VirtualBox Tainted: P  2.6.29.5-rt22 #1
Aug 16 03:38:51  kernel: [28212.716872] Call Trace:
Aug 16 03:38:51  kernel: [28212.716879]  [<ffffffff8048598b>] ? rt_spin_lock+0x27/0x6e
Aug 16 03:38:51  kernel: [28212.716890]  [<ffffffffa042222f>] ? RTHandleTableLookupWithCtx+0x72/0xf4 [vboxdrv]
Aug 16 03:38:51  kernel: [28212.716897]  [<ffffffffa043fbb0>] ? g_abExecMemory+0x1a010/0x180000 [vboxdrv]
Aug 16 03:38:51  kernel: [28212.716904]  [<ffffffffa0417a82>] ? SUPSemEventSignal+0x48/0x88 [vboxdrv]
Aug 16 03:38:51  kernel: [28212.716911]  [<ffffffffa043fbb0>] ? g_abExecMemory+0x1a010/0x180000 [vboxdrv]
Aug 16 03:38:51  kernel: [28212.716917]  [<ffffffffa0456ca6>] ? g_abExecMemory+0x31106/0x180000 [vboxdrv]
Aug 16 03:38:51  kernel: [28212.716924]  [<ffffffffa0441479>] ? g_abExecMemory+0x1b8d9/0x180000 [vboxdrv]
Aug 16 03:38:51  kernel: [28212.716930]  [<ffffffffa0442926>] ? g_abExecMemory+0x1cd86/0x180000 [vboxdrv]
Aug 16 03:38:51  kernel: [28212.716937]  [<ffffffffa0433ff5>] ? g_abExecMemory+0xe455/0x180000 [vboxdrv]
Aug 16 03:38:51  kernel: [28212.716944]  [<ffffffffa043208a>] ? g_abExecMemory+0xc4ea/0x180000 [vboxdrv]
Aug 16 03:38:51  kernel: [28212.716950]  [<ffffffffa042cfb7>] ? g_abExecMemory+0x7417/0x180000 [vboxdrv]
Aug 16 03:38:51  kernel: [28212.716957]  [<ffffffffa0468c01>] ? g_abExecMemory+0x43061/0x180000 [vboxdrv]
Aug 16 03:38:51  kernel: [28212.716964]  [<ffffffffa043694b>] ? g_abExecMemory+0x10dab/0x180000 [vboxdrv]
Aug 16 03:38:51  kernel: [28212.716967]  [<ffffffff80261b71>] ? do_futex+0x4a3/0x88c
Aug 16 03:38:51  kernel: [28212.716974]  [<ffffffffa041643b>] ? supdrvIOCtlFast+0x3f/0x51 [vboxdrv]
Aug 16 03:38:51  kernel: [28212.716981]  [<ffffffffa0416155>] ? VBoxDrvLinuxIOCtl+0x3b/0x191 [vboxdrv]
Aug 16 03:38:51  kernel: [28212.716985]  [<ffffffff802d01fa>] ? vfs_ioctl+0x21/0x6c
Aug 16 03:38:51  kernel: [28212.716987]  [<ffffffff802d06ee>] ? do_vfs_ioctl+0x4a9/0x4e7
Aug 16 03:38:51  kernel: [28212.716990]  [<ffffffff802d077d>] ? sys_ioctl+0x51/0x70
Aug 16 03:38:51  kernel: [28212.716993]  [<ffffffff8020bbc2>] ? system_call_fastpath+0x16/0x1b
----
Top
baf
Volunteer
Posts: 829
Joined: 27. Sep 2008, 06:18
Primary OS: Mac OS X Leopard
VBox Version: VirtualBox+Oracle ExtPack
Guest OSses: linux,xp,win7
Location: Luleå or Skellefteå, Sweden

Re: Virtual Box 3.04 slow and high CPU load on Window XP SP3

Post by baf »

Try creating a new junk machine and run that at the same time. This has helped some persons.
That machine may me empty just pause it directly after start.
Some say: "You learn as long as you live".
My way: "You live as long as you learn".
Top
jmelan
Posts: 2
Joined: 17. Aug 2009, 00:27
Primary OS: OpenSolaris other
VBox Version: OSE other
Guest OSses: windows xp

Re: Virtual Box 3.04 slow and high CPU load on Window XP SP3

Post by jmelan »

just wanted to add my experience here:

OpenSolaris b117 host
Q6600 processor, 4gb ram

Win XP SP3 guest
2 processors selected in VBox options
ACPI multiprocessor PC in Device Manager
SATA boot disk

XP is extremely slow, takes ~2 minutes to startup, internet explorer is crawling

Should i change the HAL back to uniprocessor, or is there a fix expected sometime soon?
Top
fyi
Posts: 25
Joined: 20. Jan 2009, 07:58
Primary OS: Debian Lenny
VBox Version: VirtualBox+Oracle ExtPack
Guest OSses: XP sp3, Debian, others

Re: Virtual Box 3.04 slow and high CPU load on Window XP SP3

Post by fyi »

baf wrote:Try creating a new junk machine and run that at the same time. This has helped some persons.
That machine may me empty just pause it directly after start.
Opening a second vm instance didn't make any difference. I also went to the hassle of hacking in a multiprocessor ACPI HAL for the guest vm, the guest then showed 2 processors in task manager, but to no avail - the performance took another hit.
So now i've reverted back to the original HAL again, rather disappointed I'm only getting 50% of the processing power i could have.
Sony Vaio SZ79SN/C, 2.6.31.12-rt21, VTx. Core2Duo T9300 2 x 2.50GHz, 800MHz FSB, 6MB L2, GM965, NVIDIA 8400M GS / X3100, 4GB DDR2 667, Seagate GForce, 13.3 WXGA TFT White LED, Intel 4965AGN, 1.76kg
Top
fyi
Posts: 25
Joined: 20. Jan 2009, 07:58
Primary OS: Debian Lenny
VBox Version: VirtualBox+Oracle ExtPack
Guest OSses: XP sp3, Debian, others

Re: Virtual Box 3.04 slow and high CPU load on Window XP SP3

Post by fyi »

Code: Select all

    00:00:00.622 VirtualBox 3.0.4 r50677 linux.amd64 (Aug  4 2009 18:49:33) release log
    00:00:00.622 Log opened 2009-08-16T12:19:36.182897000Z
    00:00:00.622 OS Product: Linux
    00:00:00.622 OS Release: 2.6.29.5-rt22
    00:00:00.622 OS Version: #1 SMP PREEMPT RT
    00:00:00.622 Host RAM: 3968MB RAM, available: 3579MB
    00:00:00.622 Executable: /usr/lib/virtualbox/VirtualBox
    00:00:00.622 Process ID: 4645
    00:00:00.622 Package type: LINUX_64BITS_DEBIAN_5_0
    00:00:00.645 SUP: Loaded VMMR0.r0 (/usr/lib/virtualbox/VMMR0.r0) at 0xffffffffa03fbc80 - ModuleInit at ffffffffa040d690 and ModuleTerm at ffffffffa040d650
    00:00:00.645 SUP: VMMR0EntryEx located at ffffffffa040d560, VMMR0EntryFast at ffffffffa040c770 and VMMR0EntryInt at ffffffffa040c500
    00:00:00.698 VBoxSharedClipboard mode: Bidirectional
    00:00:00.873 OpenGL Info: Render SPU: GL_VENDOR:   NVIDIA Corporation
    00:00:00.873 OpenGL Info: Render SPU: GL_RENDERER: GeForce 8400M GS/PCI/SSE2
    00:00:00.873 OpenGL Info: Render SPU: GL_VERSION:  3.1.0 NVIDIA 190.18
    00:00:00.873 OpenGL Info: Render SPU: GL_EXTENSIONS: GL_ARB_color_buffer_float GL_ARB_compatibility GL_ARB_copy_buffer GL_ARB_depth_buffer_float GL_ARB_depth_texture GL_ARB_draw_buffers GL_ARB_draw_instanced GL_ARB_fragment_program GL_ARB_fragment_program_shadow GL_ARB_fragment_shader GL_ARB_half_float_pixel GL_ARB_half_float_vertex GL_ARB_framebuffer_object GL_ARB_geometry_shader4 GL_ARB_imaging GL_ARB_map_buffer_range GL_ARB_multisample GL_ARB_multitexture GL_ARB_occlusion_query GL_ARB_pixel_buffer_object GL_ARB_point_parameters GL_ARB_point_sprite GL_ARB_shader_objects GL_ARB_shading_language_100 GL_ARB_shadow GL_ARB_texture_border_clamp GL_ARB_texture_buffer_object GL_ARB_texture_compression GL_ARB_texture_cube_map GL_ARB_texture_env_add GL_ARB_texture_env_combine GL_ARB_texture_env_dot3 GL_ARB_texture_float GL_ARB_texture_mirrored_repeat GL_ARB_texture_non_power_of_two GL_ARB_texture_rectangle GL_ARB_texture_rg GL_ARB_transpose_matrix GL_ARB_vertex_array_object GL_ARB_vertex_buffer_object GL_ARB_vertex_program GL_ARB_vertex_shader GL_ARB_window_pos GL_ATI_draw_buffers GL_ATI_texture_float GL_ATI_texture_mirror_once GL_S3_s3tc GL_EXT_texture_env_add GL_EXT_abgr GL_EXT_bgra GL_EXT_blend_color GL_EXT_blend_equation_separate GL_EXT_blend_func_separate GL_EXT_blend_minmax GL_EXT_blend_subtract GL_EXT_compiled_vertex_array GL_EXT_Cg_shader GL_EXT_bindable_uniform GL_EXT_depth_bounds_test GL_EXT_direct_state_access GL_EXT_draw_buffers2 GL_EXT_draw_instanced GL_EXT_draw_range_elements GL_EXT_fog_coord GL_EXT_framebuffer_blit GL_EXT_framebuffer_multisample GL_EXT_framebuffer_object GL_EXTX_framebuffer_mixed_formats GL_EXT_framebuffer_sRGB GL_EXT_geometry_shader4 GL_EXT_gpu_program_parameters GL_EXT_gpu_shader4 GL_EXT_multi_draw_arrays GL_EXT_packed_depth_stencil GL_EXT_packed_float GL_EXT_packed_pixels GL_EXT_pixel_buffer_object GL_EXT_point_parameters GL_EXT_provoking_vertex GL_EXT_rescale_normal GL_EXT_secondary_color GL_EXT_separate_shader_objects GL_EXT_separate_specular_color GL_EXT_shadow_funcs GL_EXT_stencil_two_side GL_EXT_stencil_wrap GL_EXT_texture3D GL_EXT_texture_array GL_EXT_texture_buffer_object GL_EXT_texture_compression_latc GL_EXT_texture_compression_rgtc GL_EXT_texture_compression_s3tc GL_EXT_texture_cube_map GL_EXT_texture_edge_clamp GL_EXT_texture_env_combine GL_EXT_texture_env_dot3 GL_EXT_texture_filter_anisotropic GL_EXT_texture_integer GL_EXT_texture_lod GL_EXT_texture_lod_bias GL_EXT_texture_mirror_clamp GL_EXT_texture_object GL_EXT_texture_sRGB GL_EXT_texture_swizzle GL_EXT_texture_shared_exponent GL_EXT_timer_query GL_EXT_vertex_array GL_EXT_vertex_array_bgra GL_IBM_rasterpos_clip GL_IBM_texture_mirrored_repeat GL_KTX_buffer_region GL_NV_blend_square GL_NV_copy_depth_to_color GL_NV_copy_image GL_NV_depth_buffer_float GL_NV_conditional_render GL_NV_depth_clamp GL_NV_explicit_multisample GL_NV_fence GL_NV_float_buffer GL_NV_fog_distance GL_NV_fragment_program GL_NV_fragment_program_option GL_NV_fragment_program2 GL_NV_framebuffer_multisample_coverage GL_NV_geometry_shader4 GL_NV_gpu_program4 GL_NV_half_float GL_NV_light_max_exponent GL_NV_multisample_coverage GL_NV_multisample_filter_hint GL_NV_occlusion_query GL_NV_packed_depth_stencil GL_NV_parameter_buffer_object GL_NV_parameter_buffer_object2 GL_NV_pixel_data_range GL_NV_point_sprite GL_NV_primitive_restart GL_NV_register_combiners GL_NV_register_combiners2 GL_NV_texgen_reflection GL_NV_texture_compression_vtc GL_NV_texture_env_combine4 GL_NV_texture_expand_normal GL_NV_texture_rectangle GL_NV_texture_shader GL_NV_texture_shader2 GL_NV_texture_shader3 GL_NV_transform_feedback GL_NV_vertex_array_range GL_NV_vertex_array_range2 GL_NV_vertex_program GL_NV_vertex_program1_1 GL_NV_vertex_program2 GL_NV_vertex_program2_option GL_NV_vertex_program3 GL_NVX_conditional_render GL_NV_vertex_buffer_unified_memory GL_NV_shader_buffer_load GL_SGIS_generate_mipmap GL_SGIS_texture_lod GL_SGIX_depth_texture GL_SGIX_shadow GL_SUN_slice_accum
    00:00:00.875 Shared crOpenGL service loaded.
    00:00:00.956 ************************* CFGM dump *************************
    00:00:00.956 pRoot=00000000022b4a20:{/}
    00:00:00.956 [/] (level 0)
    00:00:00.956   Name               <string>  = "xp" (cch=3)

    00:00:00.956   RamSize            <integer> = 0x0000000040000000 (1073741824)
    00:00:00.956   RamHoleSize        <integer> = 0x0000000020000000 (536870912)
    00:00:00.956   NumCPUs            <integer> = 0x0000000000000002 (2)
    00:00:00.956   TimerMillies       <integer> = 0x000000000000000a (10)
    00:00:00.956   RawR3Enabled       <integer> = 0x0000000000000001 (1)
    00:00:00.956   RawR0Enabled       <integer> = 0x0000000000000001 (1)
    00:00:00.956   PATMEnabled        <integer> = 0x0000000000000001 (1)
    00:00:00.956   CSAMEnabled        <integer> = 0x0000000000000001 (1)
    00:00:00.956   HwVirtExtForced    <integer> = 0x0000000000000001 (1)
    00:00:00.956   EnableNestedPaging <integer> = 0x0000000000000000 (0)
    00:00:00.956   EnableVPID         <integer> = 0x0000000000000000 (0)
    00:00:00.956   EnablePAE          <integer> = 0x0000000000000000 (0)
    00:00:00.956
    00:00:00.979 MM: cbHyperHeap=0x220000 (2228224)
    00:00:00.985 Logical host processors: 2, processor active mask: 0000000000000003
    00:00:00.985 ************************* CPUID dump ************************
    00:00:00.985          RAW Standard CPUIDs
    00:00:00.985      Function  eax      ebx      ecx      edx
    00:00:00.985 Gst: 00000000  00000002 756e6547 6c65746e 49656e69
    00:00:00.985 Hst:           0000000a 756e6547 6c65746e 49656e69
    00:00:00.985 Gst: 00000001  00010676 00020800 00000001 178bf1bf
    00:00:00.985 Hst:           00010676 01020800 0008e3bd bfebfbff
    00:00:00.985 Gst: 00000002  05b0b101 005657f0 00000000 2cb4304e
    00:00:00.985 Hst:           05b0b101 005657f0 00000000 2cb4304e
    00:00:00.985 Gst: 00000003  07280202 00000000 00000000 00000503*
    00:00:00.985 Hst:           00000000 00000000 00000000 00000000
    00:00:00.985 Gst: 00000004  00000000 00000000 00000000 00000503*
    00:00:00.985 Hst:           04000121 01c0003f 0000003f 00000001
    00:00:00.985 Gst: 00000005  07280202 00000000 00000000 00000503*
    00:00:00.985 Hst:           00000040 00000040 00000003 03122220
    00:00:00.985 Name:                            GenuineIntel
    00:00:00.985 Supports:                        0-2
    00:00:00.985 Family:                          6     Extended: 0    Effective: 6
    00:00:00.985 Model:                           7     Extended: 1    Effective: 23
    00:00:00.985 Stepping:                        6
    00:00:00.985 APIC ID:                         0x00
    00:00:00.985 Logical CPUs:                    2
    00:00:00.985 CLFLUSH Size:                    8
    00:00:00.985 Brand ID:                        0x00
    00:00:00.985 Mnemonic - Description                 = guest (host)
    00:00:00.985 FPU - x87 FPU on Chip                  = 1 (1)
    00:00:00.985 VME - Virtual 8086 Mode Enhancements   = 1 (1)
    00:00:00.985 DE - Debugging extensions              = 1 (1)
    00:00:00.985 PSE - Page Size Extension              = 1 (1)
    00:00:00.985 TSC - Time Stamp Counter               = 1 (1)
    00:00:00.985 MSR - Model Specific Registers         = 1 (1)
    00:00:00.985 PAE - Physical Address Extension       = 0 (1)
    00:00:00.985 MCE - Machine Check Exception          = 1 (1)
    00:00:00.985 CX8 - CMPXCHG8B instruction            = 1 (1)
    00:00:00.985 APIC - APIC On-Chip                    = 0 (1)
    00:00:00.985 Reserved                               = 0 (0)
    00:00:00.985 SEP - SYSENTER and SYSEXIT             = 0 (1)
    00:00:00.985 MTRR - Memory Type Range Registers     = 1 (1)
    00:00:00.985 PGE - PTE Global Bit                   = 1 (1)
    00:00:00.985 MCA - Machine Check Architecture       = 1 (1)
    00:00:00.985 CMOV - Conditional Move Instructions   = 1 (1)
    00:00:00.985 PAT - Page Attribute Table             = 1 (1)
    00:00:00.985 PSE-36 - 36-bit Page Size Extention    = 1 (1)
    00:00:00.985 PSN - Processor Serial Number          = 0 (0)
    00:00:00.985 CLFSH - CLFLUSH Instruction.           = 1 (1)
    00:00:00.985 Reserved                               = 0 (0)
    00:00:00.985 DS - Debug Store                       = 0 (1)
    00:00:00.985 ACPI - Thermal Mon. & Soft. Clock Ctrl.= 0 (1)
    00:00:00.985 MMX - Intel MMX Technology             = 1 (1)
    00:00:00.985 FXSR - FXSAVE and FXRSTOR Instructions = 1 (1)
    00:00:00.985 SSE - SSE Support                      = 1 (1)
    00:00:00.985 SSE2 - SSE2 Support                    = 1 (1)
    00:00:00.985 SS - Self Snoop                        = 0 (1)
    00:00:00.985 HTT - Hyper-Threading Technolog        = 1 (1)
    00:00:00.985 TM - Thermal Monitor                   = 0 (1)
    00:00:00.985 30 - Reserved                          = 0 (0)
    00:00:00.985 PBE - Pending Break Enable             = 0 (1)
    00:00:00.985 Supports SSE3 or not                   = 1 (1)
    00:00:00.985 Reserved                               = 0 (2)
    00:00:00.985 Supports MONITOR/MWAIT                 = 0 (1)
    00:00:00.985 CPL-DS - CPL Qualified Debug Store     = 0 (1)
    00:00:00.985 VMX - Virtual Machine Technology       = 0 (1)
    00:00:00.985 Reserved                               = 0 (0)
    00:00:00.985 Enhanced SpeedStep Technology          = 0 (1)
    00:00:00.985 Terminal Monitor 2                     = 0 (1)
    00:00:00.985 Supports Supplemental SSE3 or not      = 0 (1)
    00:00:00.985 L1 Context ID                          = 0 (0)
    00:00:00.985 Reserved                               = 0x0 (0x0)
    00:00:00.985 CMPXCHG16B                             = 0 (1)
    00:00:00.985 xTPR Update Control                    = 0 (1)
    00:00:00.985 Reserved                               = 0x0 (0x11)
    00:00:00.985
    00:00:00.985          RAW Extended CPUIDs
    00:00:00.985      Function  eax      ebx      ecx      edx
    00:00:00.985 Gst: 80000000  80000008 00000000 00000000 00000000
    00:00:00.985 Hst:           80000008 00000000 00000000 00000000
    00:00:00.985 Gst: 80000001  00000000 00000000 00000000 00000000
    00:00:00.985 Hst:           00000000 00000000 00000001 20100800
    00:00:00.985 Gst: 80000002  65746e49 2952286c 726f4320 4d542865
    00:00:00.985 Hst:           65746e49 2952286c 726f4320 4d542865
    00:00:00.985 Gst: 80000003  44203229 43206f75 20205550 54202020
    00:00:00.985 Hst:           44203229 43206f75 20205550 54202020
    00:00:00.985 Gst: 80000004  30303339 20402020 30352e32 007a4847
    00:00:00.985 Hst:           30303339 20402020 30352e32 007a4847
    00:00:00.985 Gst: 80000005  00000000 00000000 00000000 00000000
    00:00:00.985 Hst:           00000000 00000000 00000000 00000000
    00:00:00.985 Gst: 80000006  00000000 00000000 18008040 00000000
    00:00:00.985 Hst:           00000000 00000000 18008040 00000000
    00:00:00.985 Gst: 80000007  00000000 00000000 00000000 00000000
    00:00:00.985 Hst:           00000000 00000000 00000000 00000000
    00:00:00.985 Gst: 80000008  00003024 00000000 00000000 00000000
    00:00:00.985 Hst:           00003024 00000000 00000000 00000000
    00:00:00.985 Gst: 80000009  07280202 00000000 00000000 00000503*
    00:00:00.985 Hst:           07280202 00000000 00000000 00000503
    00:00:00.985 Ext Name:                       
    00:00:00.985 Ext Supports:                    0x80000000-0x80000008
    00:00:00.985 Family:                          0     Extended: 0    Effective: 0
    00:00:00.985 Model:                           0     Extended: 0    Effective: 0
    00:00:00.985 Stepping:                        0
    00:00:00.985 Brand ID:                        0x000
    00:00:00.985 Mnemonic - Description                 = guest (host)
    00:00:00.985 FPU - x87 FPU on Chip                  = 0 (0)
    00:00:00.985 VME - Virtual 8086 Mode Enhancements   = 0 (0)
    00:00:00.985 DE - Debugging extensions              = 0 (0)
    00:00:00.985 PSE - Page Size Extension              = 0 (0)
    00:00:00.985 TSC - Time Stamp Counter               = 0 (0)
    00:00:00.985 MSR - K86 Model Specific Registers     = 0 (0)
    00:00:00.985 PAE - Physical Address Extension       = 0 (0)
    00:00:00.985 MCE - Machine Check Exception          = 0 (0)
    00:00:00.985 CX8 - CMPXCHG8B instruction            = 0 (0)
    00:00:00.985 APIC - APIC On-Chip                    = 0 (0)
    00:00:00.985 10 - Reserved                          = 0 (0)
    00:00:00.985 SEP - SYSCALL and SYSRET               = 0 (1)
    00:00:00.985 MTRR - Memory Type Range Registers     = 0 (0)
    00:00:00.985 PGE - PTE Global Bit                   = 0 (0)
    00:00:00.985 MCA - Machine Check Architecture       = 0 (0)
    00:00:00.985 CMOV - Conditional Move Instructions   = 0 (0)
    00:00:00.985 PAT - Page Attribute Table             = 0 (0)
    00:00:00.985 PSE-36 - 36-bit Page Size Extention    = 0 (0)
    00:00:00.985 18 - Reserved                          = 0 (0)
    00:00:00.985 19 - Reserved                          = 0 (0)
    00:00:00.985 NX - No-Execute Page Protection        = 0 (1)
    00:00:00.985 DS - Debug Store                       = 0 (0)
    00:00:00.985 AXMMX - AMD Extensions to MMX Instr.   = 0 (0)
    00:00:00.985 MMX - Intel MMX Technology             = 0 (0)
    00:00:00.985 FXSR - FXSAVE and FXRSTOR Instructions = 0 (0)
    00:00:00.985 25 - AMD fast FXSAVE and FXRSTOR Instr.= 0 (0)
    00:00:00.985 26 - 1 GB large page support           = 0 (0)
    00:00:00.985 27 - RDTSCP instruction                = 0 (0)
    00:00:00.985 28 - Reserved                          = 0 (0)
    00:00:00.985 29 - AMD Long Mode                     = 0 (1)
    00:00:00.985 30 - AMD Extensions to 3DNow           = 0 (0)
    00:00:00.985 31 - AMD 3DNow                         = 0 (0)
    00:00:00.985 LahfSahf - LAHF/SAHF in 64-bit mode    = 0 (1)
    00:00:00.985 CmpLegacy - Core MP legacy mode (depr) = 0 (0)
    00:00:00.985 SVM - AMD VM Extensions                = 0 (0)
    00:00:00.985 APIC registers starting at 0x400       = 0 (0)
    00:00:00.985 AltMovCR8 - LOCK MOV CR0 means MOV CR8 = 0 (0)
    00:00:00.985 Advanced bit manipulation              = 0 (0)
    00:00:00.985 SSE4A instruction support              = 0 (0)
    00:00:00.986 Misaligned SSE mode                    = 0 (0)
    00:00:00.986 PREFETCH and PREFETCHW instruction     = 0 (0)
    00:00:00.986 OS visible workaround                  = 0 (0)
    00:00:00.986 Instruction based sampling             = 0 (0)
    00:00:00.986 SSE5 support                           = 0 (0)
    00:00:00.986 SKINIT, STGI, and DEV support          = 0 (0)
    00:00:00.986 Watchdog timer support.                = 0 (0)
    00:00:00.986 31:14 - Reserved                       = 0x0 (0x0)
    00:00:00.986 Full Name:                       Intel(R) Core(TM)2 Duo CPU     T9300  @ 2.50GHz
    00:00:00.986 TLB 2/4M Instr/Uni:              res0     0 entries
    00:00:00.986 TLB 2/4M Data:                   res0     0 entries
    00:00:00.986 TLB 4K Instr/Uni:                res0     0 entries
    00:00:00.986 TLB 4K Data:                     res0     0 entries
    00:00:00.986 L1 Instr Cache Line Size:        0 bytes
    00:00:00.986 L1 Instr Cache Lines Per Tag:    0
    00:00:00.986 L1 Instr Cache Associativity:    res0 
    00:00:00.986 L1 Instr Cache Size:             0 KB
    00:00:00.986 L1 Data Cache Line Size:         0 bytes
    00:00:00.986 L1 Data Cache Lines Per Tag:     0
    00:00:00.986 L1 Data Cache Associativity:     res0 
    00:00:00.986 L1 Data Cache Size:              0 KB
    00:00:00.986 L2 TLB 2/4M Instr/Uni:           off       0 entries
    00:00:00.986 L2 TLB 2/4M Data:                off       0 entries
    00:00:00.986 L2 TLB 4K Instr/Uni:             off       0 entries
    00:00:00.986 L2 TLB 4K Data:                  off       0 entries
    00:00:00.986 L2 Cache Line Size:              0 bytes
    00:00:00.986 L2 Cache Lines Per Tag:          0
    00:00:00.986 L2 Cache Associativity:          off   
    00:00:00.986 L2 Cache Size:                   0 KB
    00:00:00.986 APM Features:                   
    00:00:00.986 Physical Address Width:          36 bits
    00:00:00.986 Virtual Address Width:           48 bits
    00:00:00.986 Physical Core Count:             0
    00:00:00.986
    00:00:00.986          RAW Centaur CPUIDs
    00:00:00.986      Function  eax      ebx      ecx      edx
    00:00:00.986 Gst: c0000000  07280202 00000000 00000000 00000503
    00:00:00.986 Hst:           07280202 00000000 00000000 00000503
    00:00:00.986 Gst: c0000001  07280202 00000000 00000000 00000503
    00:00:00.986 Hst:           07280202 00000000 00000000 00000503
    00:00:00.986 Gst: c0000002  07280202 00000000 00000000 00000503
    00:00:00.986 Hst:           07280202 00000000 00000000 00000503
    00:00:00.986 Gst: c0000003  07280202 00000000 00000000 00000503
    00:00:00.986 Hst:           07280202 00000000 00000000 00000503
    00:00:00.986 Centaur Supports:                0xc0000000-0x07280202
    00:00:00.986 Mnemonic - Description                 = guest (host)
    00:00:00.986 AIS - Alternate Instruction Set        = 0 (1)
    00:00:00.986 AIS-E - AIS enabled                    = 0 (1)
    00:00:00.986 RNG - Random Number Generator          = 0 (0)
    00:00:00.986 RNG-E - RNG enabled                    = 0 (0)
    00:00:00.986 LH - LongHaul MSR 0000_110Ah           = 0 (0)
    00:00:00.986 FEMMS - FEMMS                          = 0 (0)
    00:00:00.986 ACE - Advanced Cryptography Engine     = 0 (0)
    00:00:00.986 ACE-E - ACE enabled                    = 0 (0)
    00:00:00.986 ACE2 - Advanced Cryptography Engine 2  = 0 (1)
    00:00:00.986 ACE2-E - ACE enabled                   = 0 (0)
    00:00:00.986 PHE - Hash Engine                      = 0 (1)
    00:00:00.986 PHE-E - PHE enabled                    = 0 (0)
    00:00:00.986 PMM - Montgomery Multiplier            = 0 (0)
    00:00:00.986 PMM-E - PMM enabled                    = 0 (0)
    00:00:00.986
    00:00:00.986
    00:00:00.986 ******************** End of CPUID dump **********************
    00:00:00.988 Debug: HCPhysInterPD=0000000037965000 HCPhysInterPaePDPT=00000000b8853000 HCPhysInterPaePML4=00000000378cb000
    00:00:00.988 Debug: apInterPTs={0000000037960000,00000000b8979000} apInterPaePTs={0000000123052000,0000000123053000} apInterPaePDs={0000000123054000,0000000123055000,0000000123056000,0000000123057000} pInterPaePDPT64=00000000b8920000
    00:00:00.999 TM: GIP - u32Mode=1 (SyncTSC) u32UpdateHz=99
    00:00:01.031 TM: cTSCTicksPerSecond=0x9162282c (2 439 129 132) fTSCVirtualized=true  fTSCUseRealTSC=false
    00:00:01.031 TM: fMaybeUseOffsettedHostTSC=true  TSCTiedToExecution=false TSCNotTiedToHalt=false
    00:00:01.032 CoreCode: R3=00007fdc30c96000 R0=ffffc2001144d000 RC=a0699000 Phys=000000003799d000 cb=0x1000
    00:00:01.036 [SMP] BIOS with 2 CPUs
    00:00:01.041 SUP: Loaded VBoxDDR0.r0 (/usr/lib/virtualbox/VBoxDDR0.r0) at 0xffffffffa046c040 - ModuleInit at 0000000000000000 and ModuleTerm at 0000000000000000
    00:00:01.042 SUP: Loaded VBoxDD2R0.r0 (/usr/lib/virtualbox/VBoxDD2R0.r0) at 0xffffffffa047f2a0 - ModuleInit at 0000000000000000 and ModuleTerm at 0000000000000000
    00:00:01.042 Activating Local APIC
    00:00:01.042 CPUMSetGuestCpuIdFeature: Enabled APIC
    00:00:01.042 CPUMSetGuestCpuIdFeature: Disabled x2APIC
    00:00:01.043 PIT: mode=3 count=0x10000 (65536) - 18.20 Hz (ch=0)
    00:00:01.046 Shared Folders service loaded.
    00:00:01.076 PIIX3 ATA: LUN#0: no unit
    00:00:01.076 PIIX3 ATA: LUN#1: no unit
    00:00:01.076 PIIX3 ATA: LUN#2: CD/DVD, total number of sectors 15811, passthrough disabled
    00:00:01.076 PIIX3 ATA: LUN#3: no unit
    00:00:01.076 PIIX3 ATA: Ctl#0: finished processing RESET
    00:00:01.176 PIIX3 ATA: Ctl#1: finished processing RESET
    00:00:01.277 VDInit finished
    00:00:01.278 AHCI: LUN#0: disk, PCHS=16383/16/63, total number of sectors 167772160
    00:00:01.278 AHCI: LUN#0: using normal I/O
    00:00:01.278 AHCI ATA: LUN#0: disk, PCHS=16383/16/63, total number of sectors 167772160
    00:00:01.278 AHCI ATA: LUN#1: no unit
    00:00:01.278 ATA: Ctl: finished processing RESET
    00:00:01.379 AHCI ATA: LUN#0: no unit
    00:00:01.379 AHCI ATA: LUN#1: no unit
    00:00:01.379 ATA: Ctl: finished processing RESET
    00:00:01.479 ATA: Ctl: finished processing RESET
    00:00:01.579 ATA: Ctl: finished processing RESET
    00:00:01.679 NAT: adding domain name xxxxx to search list
    00:00:01.679 NAT: value of BindIP has been ignored
    00:00:01.680 Audio: Trying driver 'alsa'.
    00:00:01.695 Audio: set_record_source ars=0 als=0 (not implemented)
    00:00:01.704 ALSA: ADC frequency 44100Hz, period size 940, buffer size 3763
    00:00:01.711 ALSA: DAC frequency 44100Hz, period size 940, buffer size 3763
    00:00:01.713 DevPcBios: SATA LUN#0 LCHS=1024/255/63
    00:00:01.713 PGMR3InitFinalize: 4 MB PSE mask 0000000fffffffff
    00:00:01.722 HWACCM: Host CR4=000006E0
    00:00:01.722 HWACCM: MSR_IA32_FEATURE_CONTROL      = 5
    00:00:01.722 HWACCM: MSR_IA32_VMX_BASIC_INFO       = 5a08000000000d
    00:00:01.722 HWACCM: VMCS id                       = d
    00:00:01.722 HWACCM: VMCS size                     = 800
    00:00:01.722 HWACCM: VMCS physical address limit   = None
    00:00:01.722 HWACCM: VMCS memory type              = 6
    00:00:01.722 HWACCM: Dual monitor treatment        = 1
    00:00:01.722 HWACCM: MSR_IA32_VMX_PINBASED_CTLS    = 3f00000016
    00:00:01.722 HWACCM:    VMX_VMCS_CTRL_PIN_EXEC_CONTROLS_EXT_INT_EXIT
    00:00:01.722 HWACCM:    VMX_VMCS_CTRL_PIN_EXEC_CONTROLS_NMI_EXIT
    00:00:01.722 HWACCM:    VMX_VMCS_CTRL_PIN_EXEC_CONTROLS_VIRTUAL_NMI
    00:00:01.722 HWACCM: MSR_IA32_VMX_PROCBASED_CTLS   = f7f9fffe0401e172
    00:00:01.722 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_IRQ_WINDOW_EXIT
    00:00:01.722 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_TSC_OFFSET
    00:00:01.722 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_HLT_EXIT
    00:00:01.722 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_INVLPG_EXIT
    00:00:01.722 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_MWAIT_EXIT
    00:00:01.722 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_RDPMC_EXIT
    00:00:01.722 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_RDTSC_EXIT
    00:00:01.722 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_CR3_LOAD_EXIT
    00:00:01.722 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_CR3_STORE_EXIT
    00:00:01.722 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_CR8_LOAD_EXIT
    00:00:01.722 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_CR8_STORE_EXIT
    00:00:01.722 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_USE_TPR_SHADOW
    00:00:01.722 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_NMI_WINDOW_EXIT
    00:00:01.722 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_MOV_DR_EXIT
    00:00:01.722 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_UNCOND_IO_EXIT
    00:00:01.722 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_USE_IO_BITMAPS
    00:00:01.722 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_USE_MSR_BITMAPS
    00:00:01.722 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_MONITOR_EXIT
    00:00:01.722 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_PAUSE_EXIT
    00:00:01.722 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_USE_SECONDARY_EXEC_CTRL
    00:00:01.722 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_CR3_LOAD_EXIT *must* be set
    00:00:01.722 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_CR3_STORE_EXIT *must* be set
    00:00:01.722 HWACCM: MSR_IA32_VMX_PROCBASED_CTLS2  = 4100000000
    00:00:01.722 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC2_VIRT_APIC
    00:00:01.722 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC2_WBINVD_EXIT
    00:00:01.722 HWACCM: MSR_IA32_VMX_ENTRY_CTLS       = 3fff000011ff
    00:00:01.722 HWACCM:    VMX_VMCS_CTRL_ENTRY_CONTROLS_LOAD_DEBUG
    00:00:01.722 HWACCM:    VMX_VMCS_CTRL_ENTRY_CONTROLS_IA64_MODE
    00:00:01.722 HWACCM:    VMX_VMCS_CTRL_ENTRY_CONTROLS_ENTRY_SMM
    00:00:01.722 HWACCM:    VMX_VMCS_CTRL_ENTRY_CONTROLS_DEACTIVATE_DUALMON
    00:00:01.722 HWACCM:    VMX_VMCS_CTRL_ENTRY_CONTROLS_LOAD_GUEST_PERF_MSR
    00:00:01.722 HWACCM:    VMX_VMCS_CTRL_ENTRY_CONTROLS_LOAD_DEBUG *must* be set
    00:00:01.722 HWACCM: MSR_IA32_VMX_EXIT_CTLS        = 3ffff00036dff
    00:00:01.722 HWACCM:    VMX_VMCS_CTRL_EXIT_CONTROLS_SAVE_DEBUG
    00:00:01.722 HWACCM:    VMX_VMCS_CTRL_EXIT_CONTROLS_HOST_AMD64
    00:00:01.722 HWACCM:    VMX_VMCS_CTRL_EXIT_CONTROLS_ACK_EXTERNAL_IRQ
    00:00:01.722 HWACCM:    VMX_VMCS_CTRL_EXIT_CONTROLS_SAVE_DEBUG *must* be set
    00:00:01.722 HWACCM: MSR_IA32_VMX_MISC             = 403c0
    00:00:01.722 HWACCM:    MSR_IA32_VMX_MISC_PREEMPT_TSC_BIT 0
    00:00:01.722 HWACCM:    MSR_IA32_VMX_MISC_ACTIVITY_STATES 7
    00:00:01.722 HWACCM:    MSR_IA32_VMX_MISC_CR3_TARGET      4
    00:00:01.722 HWACCM:    MSR_IA32_VMX_MISC_MAX_MSR         200
    00:00:01.722 HWACCM:    MSR_IA32_VMX_MISC_MSEG_ID         0
    00:00:01.722 HWACCM: MSR_IA32_VMX_CR0_FIXED0       = 80000021
    00:00:01.722 HWACCM: MSR_IA32_VMX_CR0_FIXED1       = ffffffff
    00:00:01.722 HWACCM: MSR_IA32_VMX_CR4_FIXED0       = 2000
    00:00:01.722 HWACCM: MSR_IA32_VMX_CR4_FIXED1       = 27ff
    00:00:01.722 HWACCM: MSR_IA32_VMX_VMCS_ENUM        = 2c
    00:00:01.722 HWACCM: TPR shadow physaddr           = 000000008b944000
    00:00:01.722 HWACCM: MSR bitmap physaddr           = 00000000379d7000
    00:00:01.722 HWACCM: VMCS physaddr VCPU0           = 00000000ad9eb000
    00:00:01.722 HWACCM: VMCS physaddr VCPU1           = 00000000725df000
    00:00:01.722 HWACCM: Real Mode TSS guest physaddr  = 00000000f0800000
    00:00:01.722 HWACCM: Non-Paging Mode EPT CR3       = 00000000f0803000
    00:00:01.722 CPUMSetGuestCpuIdFeature: Enabled sysenter/exit
    00:00:01.722 CPUMSetGuestCpuIdFeature: Enabled PAE
    00:00:01.722 CPUMSetGuestCpuIdFeature: Enabled LONG MODE
    00:00:01.722 CPUMSetGuestCpuIdFeature: Enabled syscall/ret
    00:00:01.722 CPUMSetGuestCpuIdFeature: Enabled LAHF/SAHF
    00:00:01.722 CPUMSetGuestCpuIdFeature: Enabled NXE
    00:00:01.722 HWACCM: 32-bit and 64-bit guests supported.
    00:00:01.722 HWACCM: VMX enabled!
    00:00:01.730 VM: Halt method global1 (5)
    00:00:01.730 Changing the VM state from 'CREATING' to 'CREATED'.
    00:00:01.730 SharedFolders host service: adding host mapping.
    00:00:01.730     Host path /xxxx/xxxxx/xxxxx, map name airlock, writable 1
    00:00:01.730 SharedFolders host service: add mapping result VINF_SUCCESS
    00:00:01.731 Changing the VM state from 'CREATED' to 'RUNNING'.
    00:00:01.745 Guest Log: BIOS: VirtualBox 3.0.4
    00:00:01.745 PIT: mode=2 count=0x10000 (65536) - 18.20 Hz (ch=0)
    00:00:01.842 PIIX3 ATA: Ctl#1: RESET, DevSel=0 AIOIf=0 CmdIf0=0x00 (-1 usec ago) CmdIf1=0x00 (-1 usec ago)
    00:00:01.842 PIIX3 ATA: Ctl#1: finished processing RESET
    00:00:01.843 ATA: Ctl: RESET, DevSel=0 AIOIf=0 CmdIf0=0x00 (-1 usec ago) CmdIf1=0x00 (-1 usec ago)
    00:00:01.843 ATA: Ctl: finished processing RESET
    00:00:01.843 Guest Log: BIOS: ata2-0: PCHS=16383/16/63 LCHS=1024/255/63
    00:00:01.843 PIT: mode=2 count=0x48d3 (18643) - 64.00 Hz (ch=0)
    00:00:01.862 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=00007fdc2bde4000 w=640 h=480 bpp=32 cbLine=0xA00
    00:00:04.322 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=0000000000000000 w=720 h=400 bpp=0 cbLine=0x0
    00:00:04.351 PIT: mode=2 count=0x10000 (65536) - 18.20 Hz (ch=0)
    00:00:04.351 Guest Log: BIOS: CDROM boot failure code : 0004
    00:00:04.352 Guest Log: BIOS: Boot from CD-ROM failed
    00:00:04.352 Guest Log: BIOS: Booting from Hard Disk...
    00:00:04.601 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
    00:00:04.602 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
    00:00:04.602 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
    00:00:04.602 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
    00:00:04.603 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
    00:00:04.603 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
    00:00:04.604 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
    00:00:07.045 PIT: mode=2 count=0x2ead (11949) - 99.85 Hz (ch=0)
    00:00:07.262 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=00007fdc2bde4000 w=640 h=480 bpp=0 cbLine=0x140
    00:00:09.175 Guest Additions information report: additionsVersion = 0x00010004  osType = 0x00033000
    00:00:09.176 Guest reported fixed hypervisor window at 0x00000000f6000000 (size = 0xc00000, rc = VINF_SUCCESS)
    00:00:11.152 Guest requests mouse pointer integration
    00:00:11.930 EHCI: Hardware reset
    00:00:11.930 EHCI: USB Operational
    00:00:12.025 OHCI: Software reset
    00:00:12.025 OHCI: USB Reset
    00:00:12.083 OHCI: USB Operational
    00:00:13.287 Audio: set_record_source ars=0 als=0 (not implemented)
    00:00:13.288 Audio: set_record_source ars=0 als=0 (not implemented)
    00:00:13.299 PCNet#0: Init: ss32=1 GCRDRA=0x05b26420[64] GCTDRA=0x05b26020[64]
    00:00:13.358 SharedFolders host service: connected, u32ClientID = 1
    00:00:13.697 PCNet#0: Init: ss32=1 GCRDRA=0x05b26420[64] GCTDRA=0x05b26020[64]
    00:00:14.666 EHCI: USB Suspended
    00:00:14.686 OHCI: USB Suspended
    00:00:15.322 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=00007fdc2bde4000 w=1248 h=679 bpp=32 cbLine=0x1380
    00:00:15.387 VBVA: Enabled.
    00:00:18.673 NAT: DHCP offered IP address 10.0.2.15
    00:00:18.944 Guest Log: VBOXNP: DLL loaded.
    00:00:18.967 SharedFolders host service: request to map folder airlock
    00:00:18.967 SharedFolders host service: map operation result VINF_SUCCESS.
    00:00:18.967     Mapped to handle 0.
    00:00:19.664 PCNet#0: Init: ss32=1 GCRDRA=0x05b26420[64] GCTDRA=0x05b26020[64]
    00:00:22.990 Guest Log: VBoxTray: Started.
    00:00:23.446 Starting host clipboard service
    00:00:23.446 Initializing X11 clipboard backend
    00:00:23.482 Shared clipboard: starting shared clipboard thread
    00:00:23.552 Guest Additions capability report: (0x5) seamless: yes, hostWindowMapping: no, graphics: yes
    00:00:23.861 Audio: set_record_source ars=0 als=0 (not implemented)
    00:00:23.861 Audio: set_record_source ars=0 als=0 (not implemented)
    00:00:26.203 PIT: mode=2 count=0x174c (5964) - 200.06 Hz (ch=0)
    00:00:26.233 PIT: mode=2 count=0x2ead (11949) - 99.85 Hz (ch=0)
    00:00:33.082 PCNet#0: Init: ss32=1 GCRDRA=0x05b26420[64] GCTDRA=0x05b26020[64]
    00:01:20.452 Guest Log: VBOXNP: DLL loaded.
    00:02:11.084 PIT: mode=2 count=0x174c (5964) - 200.06 Hz (ch=0)
    00:02:11.114 PIT: mode=2 count=0x2ead (11949) - 99.85 Hz (ch=0)
    00:03:12.023 Stopping the host clipboard service
    00:03:12.023 ClipStopX11: stopping the shared clipboard X11 backend
    00:03:12.023 Shared clipboard: shared clipboard thread terminated successfully
    00:03:13.280 SharedFolders host service: request to unmap folder handle 0
    00:03:13.280 SharedFolders host service: unmap operation result VINF_SUCCESS.
    00:03:15.880 PCNet#0: Init: ss32=1 GCRDRA=0x05b26420[64] GCTDRA=0x05b26020[64]
    00:03:20.738 Guest requests the VM to be turned off
    00:03:20.738 ****************** Guest state at power off ******************
    00:03:20.738 Guest CPUM (VCPU 0) state: se
    00:03:20.738 eax=05e1b06c ebx=8588a638 ecx=00000000 edx=0000d040 esi=85e1b06c edi=00000000
    00:03:20.738 eip=f787eed2 esp=f7a31c94 ebp=f7a31c9c iopl=0         nv up ei pl nz na po nc
    00:03:20.738 cs={0008 base=0000000000000000 limit=ffffffff flags=0000c09b} dr0=00000000 dr1=00000000
    00:03:20.738 ds={0023 base=0000000000000000 limit=ffffffff flags=0000c0f3} dr2=00000000 dr3=00000000
    00:03:20.738 es={0023 base=0000000000000000 limit=ffffffff flags=0000c0f3} dr4=00000000 dr5=00000000
    00:03:20.738 fs={0030 base=00000000ffdff000 limit=00001fff flags=0000c093} dr6=ffff0ff0 dr7=00000400
    00:03:20.738 gs={0000 base=0000000000000000 limit=ffffffff flags=0001c000} cr0=e001003b cr2=f0d43000
    00:03:20.738 ss={0010 base=0000000000000000 limit=ffffffff flags=0000c093} cr3=003c4000 cr4=000006f9
    00:03:20.738 gdtr=000000008003f000:03ff  idtr=000000008003f400:07ff  eflags=00000246
    00:03:20.738 ldtr={0000 base=00000000 limit=00000000 flags=00000082}
    00:03:20.738 tr  ={0028 base=80042000 limit=000020ab flags=0000008b}
    00:03:20.738 SysEnter={cs=0008 eip=8053d550 esp=f7a16000}
    00:03:20.738 FPU:
    00:03:20.738 FCW=027f FSW=0000 FTW=00
    00:03:20.738 res1=00 FOP=0000 FPUIP=00000000 CS=0000 Rsvrd1=0000
    00:03:20.738 FPUDP=0000 DS=0000 Rsvrd2=0000 MXCSR=00001f80 MXCSR_MASK=0000ffff
    00:03:20.738 MSR:
    00:03:20.738 EFER         =0000000000000800
    00:03:20.738 PAT          =0007010600070106
    00:03:20.738 STAR         =0000000000000000
    00:03:20.738 CSTAR        =0000000000000000
    00:03:20.739 LSTAR        =0000000000000000
    00:03:20.739 SFMASK       =0000000000000000
    00:03:20.739 KERNELGSBASE =0000000000000000
    00:03:20.739 ***
    00:03:20.739 Guest paging mode:  PAE+NX, changed 2158 times, A20 enabled
    00:03:20.739 Shadow paging mode: PAE
    00:03:20.739 Host paging mode:   AMD64+G+NX
    00:03:20.739 ***
    00:03:20.739 Active Timers (pVM=00007fdc348c6000)
    00:03:20.739 pTimerR3         offNext  offPrev  offSched Clock Time               Expire             State                     Description
    00:03:20.739 00007fdc31acbcd0 0001e4c0 00000000 00000000 Real  000000000010187175 000000000010187188 2-ACTIVE                  VGA Refresh Timer
    00:03:20.739 00007fdc31aea190 00000000 fffe1b40 00000000 Real  000000000010187175 000000000010187195 2-ACTIVE                  EMT Yielder
    00:03:20.739 00007fdc31ae48c0 00000000 00000000 00000000 Virt  000000198996509969 000000199000472379 2-ACTIVE                  Audio timer
    00:03:20.739 00007fdc31ac5820 000004d0 00000000 00000000 VrSy  000000198996520337 000000199001872273 2-ACTIVE                  i8254 Programmable Interval Timer
    00:03:20.739 00007fdc31ac5cf0 000232f0 fffffb30 00000000 VrSy  000000198996530967 000000199990000000 2-ACTIVE                  MC146818 RTC/CMOS - Second
    00:03:20.739 00007fdc31ae8fe0 00000000 fffdcd10 00000000 VrSy  000000198996541349 000001199864031601 2-ACTIVE                  ACPI Timer
    00:03:20.739 ***
    00:03:20.739 Shadow GDT (GCAddr=ff69b000):
    00:03:20.739 ffd8 - 79380087 ff008900 - base=ff007938 limit=00000087 dpl=0 TSS32Avail Present 16-bit  HyperTSSTrap08
    00:03:20.739 ffe0 - 78b00087 ff008900 - base=ff0078b0 limit=00000087 dpl=0 TSS32Avail Present 16-bit  HyperTSS
    00:03:20.739 ffe8 - 0000ffff 00af9b00 - base=00000000 limit=ffffffff dpl=0 CodeER Accessed Present Page 16-bit  HyperCS64
    00:03:20.739 fff0 - 0000ffff 00cf9300 - base=00000000 limit=ffffffff dpl=0 DataRW Accessed Present Page 32-bit  HyperDS
    00:03:20.739 fff8 - 0000ffff 00cf9b00 - base=00000000 limit=ffffffff dpl=0 CodeER Accessed Present Page 32-bit  HyperCS
    00:03:20.739 ***
    00:03:20.739 ************** End of Guest state at power off ***************
    00:03:20.739 Changing the VM state from 'RUNNING' to 'OFF'.
    00:03:20.739 Console::powerDown(): A request to power off the VM has been issued (mMachineState=8, InUninit=0)
    00:03:20.747 SharedFolders host service: disconnected, u32ClientID = 1
    00:03:20.770 Changing the VM state from 'OFF' to 'DESTROYING'.
    00:03:20.975 ************************* Statistics *************************
    00:03:20.975 /Devices/ATA0/Unit0/AtapiDMA            0 times
    00:03:20.975 /Devices/ATA0/Unit0/AtapiPIO            0 times
    00:03:20.975 /Devices/ATA0/Unit0/DMA                 0 times
    00:03:20.975 /Devices/ATA0/Unit0/PIO                 0 times
    00:03:20.976 /Devices/ATA0/Unit0/ReadBytes           0 bytes
    00:03:20.976 /Devices/ATA0/Unit0/WrittenBytes        0 bytes
    00:03:20.976 /Devices/ATA0/Unit1/AtapiDMA            0 times
    00:03:20.976 /Devices/ATA0/Unit1/AtapiPIO            0 times
    00:03:20.976 /Devices/ATA0/Unit1/DMA                 0 times
    00:03:20.976 /Devices/ATA0/Unit1/PIO                 0 times
    00:03:20.976 /Devices/ATA0/Unit1/ReadBytes           0 bytes
    00:03:20.976 /Devices/ATA0/Unit1/WrittenBytes        0 bytes
    00:03:20.976 /Devices/ATA1/Unit0/AtapiDMA           27 times
    00:03:20.976 /Devices/ATA1/Unit0/AtapiPIO          227 times
    00:03:20.976 /Devices/ATA1/Unit0/DMA                 0 times
    00:03:20.976 /Devices/ATA1/Unit0/PIO                 0 times
    00:03:20.976 /Devices/ATA1/Unit0/ReadBytes       34816 bytes
    00:03:20.976 /Devices/ATA1/Unit0/WrittenBytes        0 bytes
    00:03:20.976 /Devices/ATA1/Unit1/AtapiDMA            0 times
    00:03:20.976 /Devices/ATA1/Unit1/AtapiPIO            0 times
    00:03:20.976 /Devices/ATA1/Unit1/DMA                 0 times
    00:03:20.976 /Devices/ATA1/Unit1/PIO                 0 times
    00:03:20.976 /Devices/ATA1/Unit1/ReadBytes           0 bytes
    00:03:20.976 /Devices/ATA1/Unit1/WrittenBytes        0 bytes
    00:03:20.976 /Devices/PCNet0/ReceiveBytes          934 bytes
    00:03:20.976 /Devices/PCNet0/TransmitBytes        2211 bytes
    00:03:20.976 /Devices/SATA/Port0/DMA              9675 times
    00:03:20.976 /Devices/SATA/Port0/IORequestsPerSecond        0 times
    00:03:20.976 /Devices/SATA/Port0/ReadBytes    284759040 bytes
    00:03:20.976 /Devices/SATA/Port0/WrittenBytes 38614016 bytes
    00:03:20.976 /Devices/SATA/Port1/DMA                 0 times
    00:03:20.976 /Devices/SATA/Port1/IORequestsPerSecond        0 times
    00:03:20.976 /Devices/SATA/Port1/ReadBytes           0 bytes
    00:03:20.976 /Devices/SATA/Port1/WrittenBytes        0 bytes
    00:03:20.976 /Devices/SATA/Port10/DMA                0 times
    00:03:20.976 /Devices/SATA/Port10/IORequestsPerSecond        0 times
    00:03:20.976 /Devices/SATA/Port10/ReadBytes          0 bytes
    00:03:20.976 /Devices/SATA/Port10/WrittenBytes        0 bytes
    00:03:20.976 /Devices/SATA/Port11/DMA                0 times
    00:03:20.976 /Devices/SATA/Port11/IORequestsPerSecond        0 times
    00:03:20.976 /Devices/SATA/Port11/ReadBytes          0 bytes
    00:03:20.976 /Devices/SATA/Port11/WrittenBytes        0 bytes
    00:03:20.976 /Devices/SATA/Port12/DMA                0 times
    00:03:20.976 /Devices/SATA/Port12/IORequestsPerSecond        0 times
    00:03:20.976 /Devices/SATA/Port12/ReadBytes          0 bytes
    00:03:20.976 /Devices/SATA/Port12/WrittenBytes        0 bytes
    00:03:20.976 /Devices/SATA/Port13/DMA                0 times
    00:03:20.976 /Devices/SATA/Port13/IORequestsPerSecond        0 times
    00:03:20.976 /Devices/SATA/Port13/ReadBytes          0 bytes
    00:03:20.976 /Devices/SATA/Port13/WrittenBytes        0 bytes
    00:03:20.976 /Devices/SATA/Port14/DMA                0 times
    00:03:20.976 /Devices/SATA/Port14/IORequestsPerSecond        0 times
    00:03:20.976 /Devices/SATA/Port14/ReadBytes          0 bytes
    00:03:20.976 /Devices/SATA/Port14/WrittenBytes        0 bytes
    00:03:20.976 /Devices/SATA/Port15/DMA                0 times
    00:03:20.976 /Devices/SATA/Port15/IORequestsPerSecond        0 times
    00:03:20.976 /Devices/SATA/Port15/ReadBytes          0 bytes
    00:03:20.976 /Devices/SATA/Port15/WrittenBytes        0 bytes
    00:03:20.976 /Devices/SATA/Port16/DMA                0 times
    00:03:20.976 /Devices/SATA/Port16/IORequestsPerSecond        0 times
    00:03:20.976 /Devices/SATA/Port16/ReadBytes          0 bytes
    00:03:20.976 /Devices/SATA/Port16/WrittenBytes        0 bytes
    00:03:20.976 /Devices/SATA/Port17/DMA                0 times
    00:03:20.976 /Devices/SATA/Port17/IORequestsPerSecond        0 times
    00:03:20.976 /Devices/SATA/Port17/ReadBytes          0 bytes
    00:03:20.976 /Devices/SATA/Port17/WrittenBytes        0 bytes
    00:03:20.976 /Devices/SATA/Port18/DMA                0 times
    00:03:20.976 /Devices/SATA/Port18/IORequestsPerSecond        0 times
    00:03:20.976 /Devices/SATA/Port18/ReadBytes          0 bytes
    00:03:20.976 /Devices/SATA/Port18/WrittenBytes        0 bytes
    00:03:20.976 /Devices/SATA/Port19/DMA                0 times
    00:03:20.976 /Devices/SATA/Port19/IORequestsPerSecond        0 times
    00:03:20.976 /Devices/SATA/Port19/ReadBytes          0 bytes
    00:03:20.976 /Devices/SATA/Port19/WrittenBytes        0 bytes
    00:03:20.976 /Devices/SATA/Port2/DMA                 0 times
    00:03:20.976 /Devices/SATA/Port2/IORequestsPerSecond        0 times
    00:03:20.976 /Devices/SATA/Port2/ReadBytes           0 bytes
    00:03:20.976 /Devices/SATA/Port2/WrittenBytes        0 bytes
    00:03:20.976 /Devices/SATA/Port20/DMA                0 times
    00:03:20.976 /Devices/SATA/Port20/IORequestsPerSecond        0 times
    00:03:20.976 /Devices/SATA/Port20/ReadBytes          0 bytes
    00:03:20.976 /Devices/SATA/Port20/WrittenBytes        0 bytes
    00:03:20.976 /Devices/SATA/Port21/DMA                0 times
    00:03:20.976 /Devices/SATA/Port21/IORequestsPerSecond        0 times
    00:03:20.976 /Devices/SATA/Port21/ReadBytes          0 bytes
    00:03:20.976 /Devices/SATA/Port21/WrittenBytes        0 bytes
    00:03:20.976 /Devices/SATA/Port22/DMA                0 times
    00:03:20.976 /Devices/SATA/Port22/IORequestsPerSecond        0 times
    00:03:20.976 /Devices/SATA/Port22/ReadBytes          0 bytes
    00:03:20.976 /Devices/SATA/Port22/WrittenBytes        0 bytes
    00:03:20.976 /Devices/SATA/Port23/DMA                0 times
    00:03:20.976 /Devices/SATA/Port23/IORequestsPerSecond        0 times
    00:03:20.976 /Devices/SATA/Port23/ReadBytes          0 bytes
    00:03:20.976 /Devices/SATA/Port23/WrittenBytes        0 bytes
    00:03:20.976 /Devices/SATA/Port24/DMA                0 times
    00:03:20.976 /Devices/SATA/Port24/IORequestsPerSecond        0 times
    00:03:20.976 /Devices/SATA/Port24/ReadBytes          0 bytes
    00:03:20.976 /Devices/SATA/Port24/WrittenBytes        0 bytes
    00:03:20.976 /Devices/SATA/Port25/DMA                0 times
    00:03:20.976 /Devices/SATA/Port25/IORequestsPerSecond        0 times
    00:03:20.976 /Devices/SATA/Port25/ReadBytes          0 bytes
    00:03:20.976 /Devices/SATA/Port25/WrittenBytes        0 bytes
    00:03:20.976 /Devices/SATA/Port26/DMA                0 times
    00:03:20.976 /Devices/SATA/Port26/IORequestsPerSecond        0 times
    00:03:20.976 /Devices/SATA/Port26/ReadBytes          0 bytes
    00:03:20.976 /Devices/SATA/Port26/WrittenBytes        0 bytes
    00:03:20.976 /Devices/SATA/Port27/DMA                0 times
    00:03:20.976 /Devices/SATA/Port27/IORequestsPerSecond        0 times
    00:03:20.976 /Devices/SATA/Port27/ReadBytes          0 bytes
    00:03:20.976 /Devices/SATA/Port27/WrittenBytes        0 bytes
    00:03:20.976 /Devices/SATA/Port28/DMA                0 times
    00:03:20.976 /Devices/SATA/Port28/IORequestsPerSecond        0 times
    00:03:20.976 /Devices/SATA/Port28/ReadBytes          0 bytes
    00:03:20.976 /Devices/SATA/Port28/WrittenBytes        0 bytes
    00:03:20.976 /Devices/SATA/Port29/DMA                0 times
    00:03:20.976 /Devices/SATA/Port29/IORequestsPerSecond        0 times
    00:03:20.976 /Devices/SATA/Port29/ReadBytes          0 bytes
    00:03:20.976 /Devices/SATA/Port29/WrittenBytes        0 bytes
    00:03:20.976 /Devices/SATA/Port3/DMA                 0 times
    00:03:20.976 /Devices/SATA/Port3/IORequestsPerSecond        0 times
    00:03:20.976 /Devices/SATA/Port3/ReadBytes           0 bytes
    00:03:20.976 /Devices/SATA/Port3/WrittenBytes        0 bytes
    00:03:20.976 /Devices/SATA/Port4/DMA                 0 times
    00:03:20.976 /Devices/SATA/Port4/IORequestsPerSecond        0 times
    00:03:20.976 /Devices/SATA/Port4/ReadBytes           0 bytes
    00:03:20.976 /Devices/SATA/Port4/WrittenBytes        0 bytes
    00:03:20.976 /Devices/SATA/Port5/DMA                 0 times
    00:03:20.976 /Devices/SATA/Port5/IORequestsPerSecond        0 times
    00:03:20.976 /Devices/SATA/Port5/ReadBytes           0 bytes
    00:03:20.976 /Devices/SATA/Port5/WrittenBytes        0 bytes
    00:03:20.976 /Devices/SATA/Port6/DMA                 0 times
    00:03:20.976 /Devices/SATA/Port6/IORequestsPerSecond        0 times
    00:03:20.976 /Devices/SATA/Port6/ReadBytes           0 bytes
    00:03:20.976 /Devices/SATA/Port6/WrittenBytes        0 bytes
    00:03:20.976 /Devices/SATA/Port7/DMA                 0 times
    00:03:20.976 /Devices/SATA/Port7/IORequestsPerSecond        0 times
    00:03:20.976 /Devices/SATA/Port7/ReadBytes           0 bytes
    00:03:20.976 /Devices/SATA/Port7/WrittenBytes        0 bytes
    00:03:20.976 /Devices/SATA/Port8/DMA                 0 times
    00:03:20.976 /Devices/SATA/Port8/IORequestsPerSecond        0 times
    00:03:20.976 /Devices/SATA/Port8/ReadBytes           0 bytes
    00:03:20.976 /Devices/SATA/Port8/WrittenBytes        0 bytes
    00:03:20.976 /Devices/SATA/Port9/DMA                 0 times
    00:03:20.976 /Devices/SATA/Port9/IORequestsPerSecond        0 times
    00:03:20.976 /Devices/SATA/Port9/ReadBytes           0 bytes
    00:03:20.976 /Devices/SATA/Port9/WrittenBytes        0 bytes
    00:03:20.976 /GVMM/EMTs                              2 calls
    00:03:20.976 /GVMM/Sum/HaltBlocking             119193 calls
    00:03:20.976 /GVMM/Sum/HaltCalls                119202 calls
    00:03:20.976 /GVMM/Sum/HaltNotBlocking               9 calls
    00:03:20.976 /GVMM/Sum/HaltTimeouts              63612 calls
    00:03:20.976 /GVMM/Sum/HaltWakeUps                 688 calls
    00:03:20.976 /GVMM/Sum/PokeCalls                  1480 calls
    00:03:20.976 /GVMM/Sum/PokeNotBusy                 212 calls
    00:03:20.976 /GVMM/Sum/PollCalls                    91 calls
    00:03:20.976 /GVMM/Sum/PollHalts                     0 calls
    00:03:20.977 /GVMM/Sum/PollWakeUps                   0 calls
    00:03:20.977 /GVMM/Sum/WakeUpCalls              102546 calls
    00:03:20.977 /GVMM/Sum/WakeUpNotHalted           48466 calls
    00:03:20.977 /GVMM/Sum/WakeUpWakeUps               217 calls
    00:03:20.977 /GVMM/VM/HaltBlocking              119193 calls
    00:03:20.977 /GVMM/VM/HaltCalls                 119202 calls
    00:03:20.977 /GVMM/VM/HaltNotBlocking                9 calls
    00:03:20.977 /GVMM/VM/HaltTimeouts               63612 calls
    00:03:20.977 /GVMM/VM/HaltWakeUps                  688 calls
    00:03:20.977 /GVMM/VM/PokeCalls                   1480 calls
    00:03:20.977 /GVMM/VM/PokeNotBusy                  212 calls
    00:03:20.977 /GVMM/VM/PollCalls                     91 calls
    00:03:20.977 /GVMM/VM/PollHalts                      0 calls
    00:03:20.977 /GVMM/VM/PollWakeUps                    0 calls
    00:03:20.977 /GVMM/VM/WakeUpCalls               102546 calls
    00:03:20.977 /GVMM/VM/WakeUpNotHalted            48466 calls
    00:03:20.977 /GVMM/VM/WakeUpWakeUps                217 calls
    00:03:20.977 /GVMM/VMs                               1 calls
    00:03:20.977 /MM/HyperHeap/cbFree              1809472 bytes
    00:03:20.977 /MM/HyperHeap/cbHeap              2227904 bytes
    00:03:20.977 /PDM/CritSects/AHCI/ContentionR3        0 times
    00:03:20.977 /PDM/CritSects/AHCI/ContentionRZLock        0 times
    00:03:20.977 /PDM/CritSects/AHCI/ContentionRZUnlock        0 times
    00:03:20.977 /PDM/CritSects/ATA0/ContentionR3        0 times
    00:03:20.977 /PDM/CritSects/ATA0/ContentionRZLock        0 times
    00:03:20.977 /PDM/CritSects/ATA0/ContentionRZUnlock        0 times
    00:03:20.977 /PDM/CritSects/ATA1/ContentionR3        0 times
    00:03:20.977 /PDM/CritSects/ATA1/ContentionRZLock        2 times
    00:03:20.977 /PDM/CritSects/ATA1/ContentionRZUnlock        0 times
    00:03:20.977 /PDM/CritSects/EM-REM/ContentionR3        0 times
    00:03:20.977 /PDM/CritSects/EM-REM/ContentionRZLock       29 times
    00:03:20.977 /PDM/CritSects/EM-REM/ContentionRZUnlock        0 times
    00:03:20.977 /PDM/CritSects/EmulatedATA0/ContentionR3        0 times
    00:03:20.977 /PDM/CritSects/EmulatedATA0/ContentionRZLock        1 times
    00:03:20.977 /PDM/CritSects/EmulatedATA0/ContentionRZUnlock        0 times
    00:03:20.977 /PDM/CritSects/EmulatedATA1/ContentionR3        0 times
    00:03:20.977 /PDM/CritSects/EmulatedATA1/ContentionRZLock        0 times
    00:03:20.977 /PDM/CritSects/EmulatedATA1/ContentionRZUnlock        0 times
    00:03:20.977 /PDM/CritSects/IOM EMT Lock/ContentionR3        0 times
    00:03:20.977 /PDM/CritSects/IOM EMT Lock/ContentionRZLock        0 times
    00:03:20.977 /PDM/CritSects/IOM EMT Lock/ContentionRZUnlock        0 times
    00:03:20.977 /PDM/CritSects/MM-HYPER/ContentionR3        0 times
    00:03:20.977 /PDM/CritSects/MM-HYPER/ContentionRZLock        0 times
    00:03:20.977 /PDM/CritSects/MM-HYPER/ContentionRZUnlock        0 times
    00:03:20.977 /PDM/CritSects/PCNet#0/ContentionR3        0 times
    00:03:20.977 /PDM/CritSects/PCNet#0/ContentionRZLock        0 times
    00:03:20.977 /PDM/CritSects/PCNet#0/ContentionRZUnlock        0 times
    00:03:20.977 /PDM/CritSects/PDM/ContentionR3         0 times
    00:03:20.977 /PDM/CritSects/PDM/ContentionRZLock      111 times
    00:03:20.977 /PDM/CritSects/PDM/ContentionRZUnlock        0 times
    00:03:20.977 /PDM/CritSects/PGM/ContentionR3         0 times
    00:03:20.977 /PDM/CritSects/PGM/ContentionRZLock      210 times
    00:03:20.977 /PDM/CritSects/PGM/ContentionRZUnlock        0 times
    00:03:20.977 /PDM/CritSects/REM-Register/ContentionR3        0 times
    00:03:20.977 /PDM/CritSects/REM-Register/ContentionRZLock        0 times
    00:03:20.977 /PDM/CritSects/REM-Register/ContentionRZUnlock        0 times
    00:03:20.977 /PDM/CritSects/TM Timer Lock/ContentionR3      115 times
    00:03:20.977 /PDM/CritSects/TM Timer Lock/ContentionRZLock        0 times
    00:03:20.977 /PDM/CritSects/TM Timer Lock/ContentionRZUnlock        0 times
    00:03:20.977 /PDM/CritSects/TM VirtualSync Lock/ContentionR3        0 times
    00:03:20.977 /PDM/CritSects/TM VirtualSync Lock/ContentionRZLock       10 times
    00:03:20.977 /PDM/CritSects/TM VirtualSync Lock/ContentionRZUnlock        0 times
    00:03:20.977 /PDM/CritSects/VGA/ContentionR3         0 times
    00:03:20.977 /PDM/CritSects/VGA/ContentionRZLock        1 times
    00:03:20.977 /PDM/CritSects/VGA/ContentionRZUnlock        0 times
    00:03:20.977 /PDM/CritSects/VMMDev/ContentionR3        0 times
    00:03:20.977 /PDM/CritSects/VMMDev/ContentionRZLock        0 times
    00:03:20.977 /PDM/CritSects/VMMDev/ContentionRZUnlock        0 times
    00:03:20.977 /PGM/CPU0/cGuestModeChanges          2158 times
    00:03:20.977 /PGM/CPU1/cGuestModeChanges             1 times
    00:03:20.977 /PGM/ChunkR3Map/c                    1025 times
    00:03:20.977 /PGM/ChunkR3Map/cMax             4294967295 times
    00:03:20.977 /PGM/Page/cAllPages                278738 times
    00:03:20.977 /PGM/Page/cHandyPages                  89 times
    00:03:20.977 /PGM/Page/cPrivatePages            278641 times
    00:03:20.977 /PGM/Page/cSharedPages                  0 times
    00:03:20.977 /PGM/Page/cZeroPages                   97 times
    00:03:20.977 /PGM/cRelocations                       0 times
    00:03:20.977 /PROF/CPU0/EM/ForcedActions         83066 times
    00:03:20.977 /PROF/CPU0/EM/Halted                17625 times
    00:03:20.977 /PROF/CPU0/EM/RAWTotal                  0 times
    00:03:20.977 /PROF/CPU0/EM/REMTotal               1075 times
    00:03:20.977 /PROF/CPU0/EM/Total              496245584950 ticks/call (496245584950 ticks,       1 times, max 496245584950, min 496245584950)
    00:03:20.977 /PROF/CPU1/EM/ForcedActions         31748 times
    00:03:20.977 /PROF/CPU1/EM/Halted                31747 times
    00:03:20.977 /PROF/CPU1/EM/RAWTotal                  0 times
    00:03:20.977 /PROF/CPU1/EM/REMTotal                  0 times
    00:03:20.977 /PROF/CPU1/EM/Total              496836960225 ticks/call (496836960225 ticks,       1 times, max 496836960225, min 496836960225)
    00:03:20.977 /PROF/VM/CPU0/Halt/Block         20045852 ticks/call (363872320560 ticks,   18152 times, max 161912563, min    2475)
    00:03:20.977 /PROF/VM/CPU0/Halt/Timers             292 ticks/call (    10446001 ticks,   35773 times, max    179225, min      87)
    00:03:20.977 /PROF/VM/CPU0/Halt/Yield                0 ticks/call (           0 ticks,       0 times, max         0, min      -1)
    00:03:20.977 /PROF/VM/CPU1/Halt/Block          4876914 ticks/call (492802415177 ticks,  101048 times, max  37377537, min    2175)
    00:03:20.977 /PROF/VM/CPU1/Halt/Timers            2552 ticks/call (  1116917274 ticks,  437579 times, max  12594475, min    1225)
    00:03:20.977 /PROF/VM/CPU1/Halt/Yield             5314 ticks/call (      483627 ticks,      91 times, max     67950, min    2013)
    00:03:20.977 /REM/TbFlushCount                    1075 times
    00:03:20.977 /REM/TbPhysInvldCount               12388 times
    00:03:20.977 /REM/TlbFlushCount                 141643 times
    00:03:20.977 /TM/R0/1nsSteps                     41624 times
    00:03:20.977 /TM/R3/1nsSteps                     80624 times
    00:03:20.977 /TM/TSC/offCPU0                    347695 ticks
    00:03:20.977 /TM/TSC/offCPU1                         0 ticks
    00:03:20.977 /TM/VirtualSync/CurrentOffset           0 ns
    00:03:20.977 /VUSB/0/cUrbsInPool                     0 count
    00:03:20.977 /VUSB/1/cUrbsInPool                     0 count
    00:03:20.977 ********************* End of statistics **********************
    00:03:20.985 Changing the VM state from 'DESTROYING' to 'TERMINATED'.
Sony Vaio SZ79SN/C, 2.6.31.12-rt21, VTx. Core2Duo T9300 2 x 2.50GHz, 800MHz FSB, 6MB L2, GM965, NVIDIA 8400M GS / X3100, 4GB DDR2 667, Seagate GForce, 13.3 WXGA TFT White LED, Intel 4965AGN, 1.76kg
Top
vak
Posts: 2
Joined: 18. Aug 2009, 16:03
Primary OS: Ubuntu other
VBox Version: OSE other
Guest OSses: Windows Vista

Re: Virtual Box 3.04 slow and high CPU load on Window XP SP3

Post by vak »

I got an issue all like described above, albeit my guest is not WinXP but Vista:

VirtualBox: 3.0.4
CPU: Intel Core 2 Duo T6*** 2GHz
RAM: 5GB
Host: Ubuntu Karmic Dev 9.10 (AMD64)
Guest: Windows Vista (32 bit)
Last edited by vak on 18. Aug 2009, 19:53, edited 3 times in total.
Top
chuangy
Posts: 1
Joined: 18. Aug 2009, 16:04
Primary OS: Ubuntu other
VBox Version: VirtualBox+Oracle ExtPack
Guest OSses: Windows XP Professional 32 bit

Re: Virtual Box 3.04 slow and high CPU load on Window XP SP3

Post by chuangy »

Hi,
I am quite new to VirtualBox, and now I have pretty much the same problem. The CPU usage as seen in the task manager of Windows XP Professional Guest stay at 100% for pretty much half an hour since start up. The CPU usage as seen in System Monitor of Ubuntu also shows 100% usage in one of the cores for half an hour. Also, the CPU frequency reading in the System Properties in Windows was inconsistent. It differs every time when I start up in virtualbox. No such problem in Native boot.

ThinkPad X61
Intel Core 2 Duo T7250 2GHz
4GB RAM
120GB Harddisk
Host: Ubuntu 9.04
Guest: Windows XP Professional SP3 (32 bit)
Top
vak
Posts: 2
Joined: 18. Aug 2009, 16:03
Primary OS: Ubuntu other
VBox Version: OSE other
Guest OSses: Windows Vista

Re: Virtual Box 3.04 slow and high CPU load on Window XP SP3

Post by vak »

Q1. Is VirtualBox 3.0.4 usable with Windows guests?
(I've tried W7 and Vista and got the same bad issue that people experience with WinXP)

Q2. VirtualBOx developers, did you manage to reproduce this issue?
(Is it something you know about or this all sounds rather odd to you? Let us know, please, if some additional info is needed, thanks!)
Top
fyi
Posts: 25
Joined: 20. Jan 2009, 07:58
Primary OS: Debian Lenny
VBox Version: VirtualBox+Oracle ExtPack
Guest OSses: XP sp3, Debian, others

Re: Virtual Box 3.04 slow and high CPU load on Window XP SP3

Post by fyi »

Well im on a linux host.. I will try 3.0.4 with a non-RT kernel over the next day or so and post back if it fixes the limitation. FWIW I would be curious to know what the ideal baseline host kernel configuration settings are, I'm sure certain kernel configurations work better than others. As far as I'm aware i can disable paravirtualized guest support in the host without performance reduction because vbox is a fully virtualized solution and doesn't benefit from guest kernel modification as such; correct me if I'm wrong.
Sony Vaio SZ79SN/C, 2.6.31.12-rt21, VTx. Core2Duo T9300 2 x 2.50GHz, 800MHz FSB, 6MB L2, GM965, NVIDIA 8400M GS / X3100, 4GB DDR2 667, Seagate GForce, 13.3 WXGA TFT White LED, Intel 4965AGN, 1.76kg
Top
Post Reply

Return to “Windows Guests”