100% CPU Usage on Ubuntu 8.10

Discussions related to using VirtualBox on Linux hosts.
delgurth
Posts: 1
Joined: 13. Nov 2008, 14:14

Post by delgurth »

I'm having the same problems when I boot the native XP on my laptop, and was checking the load when I was in the GRUB menu. VirtualBox is using 100% cpu in GRUB, but after checking with people on IRC I understand that that's normal.

My VirtualBox gives < 10% CPU usage when I boot the opensolaris live cd. I'll see if the HAL updater gives me better performance when I boot windows XP.
relpats_eht
Posts: 4
Joined: 9. Nov 2008, 01:46

Post by relpats_eht »

The HAL updater does rather curious things. My CPU is still listed as 100% but VirtualBox is now listed as using <3% CPU (while idle). Some resource managers show the values switched (50% CPU for virtual box (100% of one core) but <3% overall CPU usage).
worc
Posts: 3
Joined: 11. May 2008, 16:32

Post by worc »

Same problem here.

Intel Core 2 Duo laptop,
VirtualBox 2.0.4,
running Ubuntu 8.10 (64bit) as the host, the guest is Windows XP (32bit).

It's a fresh install of Ubuntu 8.10, but the guest is from an old vdi file. The vdi file was setup on the same laptop with Ubuntu 7.10 (32bit) as the host.

One month ago, I was running Ubuntu 8.04 (32bit) on the same laptop, with VirtualBox 1.6, the same vdi file. Everything was OK.

A couple of days ago, I fresh installed Ubuntu 8.10 (64bit) on the same laptop. I installed VirtualBox 2.0.4 (not OSE), and picked up the same vdi file in hd settings. When I tried to boot the guset, the cpu usage of VirtualBox starts to climb too. The CPU usage of VirtualBox can quickly raise to 100% (eating up one of the two cores), and it is very difficult for me to log into the guest windows, because of the high CPU usage.

I guess this might be a problem between VirtualBox 2.0.4 and Ubuntu 8.10 64bit?
I do want to try the HAL Updater, but how can I run it without first loging into the guest Windows? Whatever, I do not think it will help, cause my hardware stays the same, only OS and VB version were changed.
SixedUp
Posts: 1
Joined: 2. Dec 2008, 07:51

Post by SixedUp »

I'm also seeing this on a Thinkpad T60p, with a CoreDuo cpu (ie, 32bit dual core with VT extensions) running freshly installed Ubuntu 8.10 host and Windows XP Pro SP3 guest. I'm using the latest (non-OSE) VB 2.0.6. Guest networking is default NATed.

The Windows guest is actually a real physical install that I'm running within VB using raw partition support. VB guest additions have been added to it. I have 2GB real memory, and VB has 768MB of memory defined to the guest. Host system is not paging. ACPI, IO ACPI and VT-x are all enabled, and VB has been given access to both cores.

As soon as I start the partition I see 100% CPU (ie, both cores fully utilised). "top" shows VB using all the CPU. Guest does run but performance is extremely poor, with frequent freezes. Host OS is however, always still responsive. Saving the state of the guest causes immediate reduction in CPU usage back to idle.

Previous experiences with Ubuntu 7.10 and Windows XP Pro SP2 were much more successful than this. Something is definitely not right :(
RavenTheSkydiver
Posts: 2
Joined: 21. Dec 2008, 16:15

Post by RavenTheSkydiver »

I too have this problem.

T60 with dual core, one core uses 100% as soon as I start it. ACPI is disabled but I never found settings for USB.

Ubuntu 8.10 recently installed.

Should I downgrade the kernel? VB?

Currently in a totally unuseful state :(
ramaboo
Posts: 6
Joined: 1. Nov 2008, 01:30

Post by ramaboo »

Can anyone confirm if this is fixed in VB 2.1.0? I am dying to upgrade from Ubuntu 8.04 to 8.10 but can't until this is resolved.
RavenTheSkydiver
Posts: 2
Joined: 21. Dec 2008, 16:15

Post by RavenTheSkydiver »

It works on my computer now. The weird thing is I didn't do anything that should fix this :)

I've removed the harddrive and used it in a similar computer for couple of minutes. I got problems with the display so I moved it back.

After that I "polished" the cd with windows as it was kinda dirty.

Then I reinstalled windows. During the install one of my cores was always close to 100% but this time they took turns working, before only one of them would work.

After installing windows uses 20% or so percent on one core.

I know, it's weird. But I'm happy :)
timuckun
Posts: 4
Joined: 21. Aug 2008, 02:22

Post by timuckun »

ramaboo wrote:Can anyone confirm if this is fixed in VB 2.1.0? I am dying to upgrade from Ubuntu 8.04 to 8.10 but can't until this is resolved.
I am having this problem with ubuntu 8.10 on one of my VMs (xp)
ahoesch
Posts: 1
Joined: 30. Dec 2008, 19:15

Post by ahoesch »

timuckun wrote:
ramaboo wrote:Can anyone confirm if this is fixed in VB 2.1.0? I am dying to upgrade from Ubuntu 8.04 to 8.10 but can't until this is resolved.
I am having this problem with ubuntu 8.10 on one of my VMs (xp)
I am having the same problem

02:55:06.669 PIIX3 ATA: execution time for ATA command 0xca was 15 seconds
02:55:09.218 TM: Giving up catch-up attempt at a 60044597961 ns lag; new total: 3212553098982 ns
02:56:18.205 TM: Giving up catch-up attempt at a 60178468869 ns lag; new total: 3272731567851 ns
02:57:33.519 TM: Giving up catch-up attempt at a 60048473280 ns lag; new total: 3332780041131 ns

on a Ultra 20 with Solaris 10 u6 as the host and Solaris 10 and Windows as guests. I am running VirtualBox 2.1.0. It runs smoothly for a couple of ours, then the trouble starts and the machine freezes after a while! :-(

Hints are greatly appreciated!
bin1010
Posts: 1
Joined: 9. Mar 2009, 20:44

Re: 100% CPU Usage on Ubuntu 8.10

Post by bin1010 »

I am having the same problem with a freebsd VM client. When I set it to compile, it runs 100% on ONE CPU and becomes unresponsive. I am running ubuntu 64bit 8.10 with virtualbox 2.1.4

Code: Select all

:::::::::::::::::::::::::::::::::::::::::::::HERE IS THE LOG - incase there is a Developer in the house - :::::::::::::::::::::::::::::::::
00:00:00.478 VirtualBox 2.1.4 r42893 linux.amd64 (Feb 16 2009 21:58:39) release log
00:00:00.478 Log opened 2009-03-09T16:24:10.637357000Z
00:00:00.478 OS Product: Linux
00:00:00.478 OS Release: 2.6.27-11-generic
00:00:00.478 OS Version: #1 SMP Thu Jan 29 19:28:32 UTC 2009
00:00:00.478 Package type: LINUX_64BITS_UBUNTU_8_10
00:00:00.513 SUP: Loaded VMMR0.r0 (/usr/lib/virtualbox/VMMR0.r0) at 0xffffffffa05a60c0 - ModuleInit at ffffffffa05b5ca0 and ModuleTerm at ffffffffa05b5c70
00:00:00.513 SUP: VMMR0EntryEx located at ffffffffa05b5ba0, VMMR0EntryFast at ffffffffa05b4ee0 and VMMR0EntryInt at ffffffffa05b4cc0
00:00:00.532 Initializing host clipboard service
00:00:00.555 VBoxSharedClipboard mode: Bidirectional
00:00:00.555 Shared clipboard: starting host clipboard thread
00:00:00.597 ************************* CFGM dump *************************
00:00:00.597 pRoot=00000000022513d0:{/}
00:00:00.597 [/] (level 0)
00:00:00.597   Name               <string>  = "freebsd7.1-pfsense-dev" (cch=23)
00:00:00.597   RamSize            <integer> = 0x0000000040000000 (1073741824)
00:00:00.597   NumCPUs            <integer> = 0x0000000000000001 (1)
00:00:00.597   TimerMillies       <integer> = 0x000000000000000a (10)
00:00:00.597   RawR3Enabled       <integer> = 0x0000000000000001 (1)
00:00:00.597   RawR0Enabled       <integer> = 0x0000000000000001 (1)
00:00:00.597   PATMEnabled        <integer> = 0x0000000000000001 (1)
00:00:00.597   CSAMEnabled        <integer> = 0x0000000000000001 (1)
00:00:00.597   HwVirtExtForced    <integer> = 0x0000000000000000 (0)
00:00:00.597   EnableNestedPaging <integer> = 0x0000000000000000 (0)
00:00:00.597   EnableVPID         <integer> = 0x0000000000000000 (0)
00:00:00.597   EnablePAE          <integer> = 0x0000000000000000 (0)
00:00:00.597 
00:00:00.597 [/HWVirtExt/] (level 1)
00:00:00.597   Enabled      <integer> = 0x0000000000000001 (1)
00:00:00.597   64bitEnabled <integer> = 0x0000000000000001 (1)
00:00:00.597 
00:00:00.597 [/PDM/] (level 1)
00:00:00.597 
00:00:00.597 [/PDM/Drivers/] (level 2)
00:00:00.597 
00:00:00.597 [/PDM/Drivers/VBoxC/] (level 3)
00:00:00.597   Path <string>  = "/usr/lib/virtualbox/components/VBoxC" (cch=37)
00:00:00.597 
00:00:00.597 [/Devices/] (level 1)
00:00:00.597 
00:00:00.597 [/Devices/pcarch/] (level 2)
00:00:00.597 
00:00:00.597 [/Devices/pcarch/0/] (level 3)
00:00:00.597   Trusted <integer> = 0x0000000000000001 (1)
00:00:00.597 
00:00:00.597 [/Devices/pcarch/0/Config/] (level 4)
00:00:00.597 
00:00:00.597 [/Devices/pcbios/] (level 2)
00:00:00.597 
00:00:00.597 [/Devices/pcbios/0/] (level 3)
00:00:00.597   Trusted <integer> = 0x0000000000000001 (1)
00:00:00.597 
00:00:00.597 [/Devices/pcbios/0/Config/] (level 4)
00:00:00.597   RamSize        <integer> = 0x0000000040000000 (1073741824)
00:00:00.597   NumCPUs        <integer> = 0x0000000000000001 (1)
00:00:00.597   HardDiskDevice <string>  = "piix3ide" (cch=9)
00:00:00.597   FloppyDevice   <string>  = "i82078" (cch=7)
00:00:00.597   IOAPIC         <integer> = 0x0000000000000001 (1)
00:00:00.597   PXEDebug       <integer> = 0x0000000000000000 (0)
00:00:00.597   BootDevice0    <string>  = "FLOPPY" (cch=7)
00:00:00.597   BootDevice1    <string>  = "DVD" (cch=4)
00:00:00.597   BootDevice2    <string>  = "IDE" (cch=4)
00:00:00.597   BootDevice3    <string>  = "NONE" (cch=5)
00:00:00.597 
00:00:00.597 [/Devices/8237A/] (level 2)
00:00:00.597 
00:00:00.597 [/Devices/8237A/0/] (level 3)
00:00:00.597   Trusted <integer> = 0x0000000000000001 (1)
00:00:00.597 
00:00:00.597 [/Devices/pci/] (level 2)
00:00:00.597 
00:00:00.597 [/Devices/pci/0/] (level 3)
00:00:00.597   Trusted <integer> = 0x0000000000000001 (1)
00:00:00.597 
00:00:00.597 [/Devices/pci/0/Config/] (level 4)
00:00:00.597   IOAPIC <integer> = 0x0000000000000001 (1)
00:00:00.597 
00:00:00.597 [/Devices/pckbd/] (level 2)
00:00:00.597 
00:00:00.597 [/Devices/pckbd/0/] (level 3)
00:00:00.597   Trusted <integer> = 0x0000000000000001 (1)
00:00:00.597 
00:00:00.597 [/Devices/pckbd/0/Config/] (level 4)
00:00:00.597 
00:00:00.597 [/Devices/pckbd/0/LUN#0/] (level 4)
00:00:00.597   Driver <string>  = "KeyboardQueue" (cch=14)
00:00:00.597 
00:00:00.597 [/Devices/pckbd/0/LUN#0/Config/] (level 5)
00:00:00.597   QueueSize <integer> = 0x0000000000000040 (64)
00:00:00.597 
00:00:00.597 [/Devices/pckbd/0/LUN#0/AttachedDriver/] (level 5)
00:00:00.597   Driver <string>  = "MainKeyboard" (cch=13)
00:00:00.597 
00:00:00.597 [/Devices/pckbd/0/LUN#0/AttachedDriver/Config/] (level 6)
00:00:00.597   Object <integer> = 0x00000000022276f0 (35813104)
00:00:00.597 
00:00:00.597 [/Devices/pckbd/0/LUN#1/] (level 4)
00:00:00.598   Driver <string>  = "MouseQueue" (cch=11)
00:00:00.598 
00:00:00.598 [/Devices/pckbd/0/LUN#1/Config/] (level 5)
00:00:00.598   QueueSize <integer> = 0x0000000000000080 (128)
00:00:00.598 
00:00:00.598 [/Devices/pckbd/0/LUN#1/AttachedDriver/] (level 5)
00:00:00.598   Driver <string>  = "MainMouse" (cch=10)
00:00:00.598 
00:00:00.598 [/Devices/pckbd/0/LUN#1/AttachedDriver/Config/] (level 6)
00:00:00.598   Object <integer> = 0x0000000002227840 (35813440)
00:00:00.598 
00:00:00.598 [/Devices/i82078/] (level 2)
00:00:00.598 
00:00:00.598 [/Devices/i82078/0/] (level 3)
00:00:00.598   Trusted <integer> = 0x0000000000000001 (1)
00:00:00.598 
00:00:00.598 [/Devices/i82078/0/Config/] (level 4)
00:00:00.598   IRQ       <integer> = 0x0000000000000006 (6)
00:00:00.598   DMA       <integer> = 0x0000000000000002 (2)
00:00:00.598   MemMapped <integer> = 0x0000000000000000 (0)
00:00:00.598   IOBase    <integer> = 0x00000000000003f0 (1008)
00:00:00.598 
00:00:00.598 [/Devices/i82078/0/LUN#999/] (level 4)
00:00:00.598   Driver <string>  = "MainStatus" (cch=11)
00:00:00.598 
00:00:00.598 [/Devices/i82078/0/LUN#999/Config/] (level 5)
00:00:00.598   papLeds <integer> = 0x0000000002226d10 (35810576)
00:00:00.598   First   <integer> = 0x0000000000000000 (0)
00:00:00.598   Last    <integer> = 0x0000000000000000 (0)
00:00:00.598 
00:00:00.598 [/Devices/i82078/0/LUN#0/] (level 4)
00:00:00.598   Driver <string>  = "Block" (cch=6)
00:00:00.598 
00:00:00.598 [/Devices/i82078/0/LUN#0/Config/] (level 5)
00:00:00.598   Type      <string>  = "Floppy 1.44" (cch=12)
00:00:00.598   Mountable <integer> = 0x0000000000000001 (1)
00:00:00.598 
00:00:00.598 [/Devices/acpi/] (level 2)
00:00:00.598 
00:00:00.598 [/Devices/acpi/0/] (level 3)
00:00:00.598   Trusted       <integer> = 0x0000000000000001 (1)
00:00:00.598   PCIDeviceNo   <integer> = 0x0000000000000007 (7)
00:00:00.598   PCIFunctionNo <integer> = 0x0000000000000000 (0)
00:00:00.598 
00:00:00.598 [/Devices/acpi/0/Config/] (level 4)
00:00:00.598   RamSize    <integer> = 0x0000000040000000 (1073741824)
00:00:00.598   NumCPUs    <integer> = 0x0000000000000001 (1)
00:00:00.598   IOAPIC     <integer> = 0x0000000000000001 (1)
00:00:00.598   FdcEnabled <integer> = 0x0000000000000001 (1)
00:00:00.598 
00:00:00.598 [/Devices/acpi/0/LUN#0/] (level 4)
00:00:00.598   Driver <string>  = "ACPIHost" (cch=9)
00:00:00.598 
00:00:00.598 [/Devices/acpi/0/LUN#0/Config/] (level 5)
00:00:00.598 
00:00:00.598 [/Devices/i8254/] (level 2)
00:00:00.598 
00:00:00.598 [/Devices/i8254/0/] (level 3)
00:00:00.598 
00:00:00.598 [/Devices/i8254/0/Config/] (level 4)
00:00:00.598 
00:00:00.598 [/Devices/i8259/] (level 2)
00:00:00.598 
00:00:00.598 [/Devices/i8259/0/] (level 3)
00:00:00.598   Trusted <integer> = 0x0000000000000001 (1)
00:00:00.598 
00:00:00.598 [/Devices/i8259/0/Config/] (level 4)
00:00:00.598 
00:00:00.598 [/Devices/apic/] (level 2)
00:00:00.598 
00:00:00.598 [/Devices/apic/0/] (level 3)
00:00:00.598   Trusted <integer> = 0x0000000000000001 (1)
00:00:00.598 
00:00:00.598 [/Devices/apic/0/Config/] (level 4)
00:00:00.598   IOAPIC  <integer> = 0x0000000000000001 (1)
00:00:00.598   NumCPUs <integer> = 0x0000000000000001 (1)
00:00:00.598 
00:00:00.598 [/Devices/ioapic/] (level 2)
00:00:00.598 
00:00:00.598 [/Devices/ioapic/0/] (level 3)
00:00:00.598   Trusted <integer> = 0x0000000000000001 (1)
00:00:00.598 
00:00:00.598 [/Devices/ioapic/0/Config/] (level 4)
00:00:00.598 
00:00:00.598 [/Devices/mc146818/] (level 2)
00:00:00.598 
00:00:00.598 [/Devices/mc146818/0/] (level 3)
00:00:00.598 
00:00:00.598 [/Devices/mc146818/0/Config/] (level 4)
00:00:00.598 
00:00:00.598 [/Devices/vga/] (level 2)
00:00:00.598 
00:00:00.598 [/Devices/vga/0/] (level 3)
00:00:00.598   Trusted       <integer> = 0x0000000000000001 (1)
00:00:00.598   PCIDeviceNo   <integer> = 0x0000000000000002 (2)
00:00:00.598   PCIFunctionNo <integer> = 0x0000000000000000 (0)
00:00:00.598 
00:00:00.598 [/Devices/vga/0/Config/] (level 4)
00:00:00.598   VRamSize         <integer> = 0x0000000001400000 (20971520)
00:00:00.598   FadeIn           <integer> = 0x0000000000000001 (1)
00:00:00.598   FadeOut          <integer> = 0x0000000000000001 (1)
00:00:00.598   LogoTime         <integer> = 0x0000000000000000 (0)
00:00:00.598   LogoFile         <string>  = "" (cch=1)
00:00:00.598   ShowBootMenu     <integer> = 0x0000000000000002 (2)
00:00:00.598   CustomVideoModes <integer> = 0x0000000000000000 (0)
00:00:00.598   HeightReduction  <integer> = 0x0000000000000000 (0)
00:00:00.598 
00:00:00.598 [/Devices/vga/0/LUN#0/] (level 4)
00:00:00.598   Driver <string>  = "MainDisplay" (cch=12)
00:00:00.598 
00:00:00.598 [/Devices/vga/0/LUN#0/Config/] (level 5)
00:00:00.598   Object <integer> = 0x0000000002227990 (35813776)
00:00:00.598 
00:00:00.598 [/Devices/piix3ide/] (level 2)
00:00:00.598 
00:00:00.598 [/Devices/piix3ide/0/] (level 3)
00:00:00.598   Trusted       <integer> = 0x0000000000000001 (1)
00:00:00.598   PCIDeviceNo   <integer> = 0x0000000000000001 (1)
00:00:00.598   PCIFunctionNo <integer> = 0x0000000000000001 (1)
00:00:00.598 
00:00:00.598 [/Devices/piix3ide/0/Config/] (level 4)
00:00:00.598   PIIX4 <integer> = 0x0000000000000001 (1)
00:00:00.598 
00:00:00.598 [/Devices/piix3ide/0/LUN#999/] (level 4)
00:00:00.599   Driver <string>  = "MainStatus" (cch=11)
00:00:00.599 
00:00:00.599 [/Devices/piix3ide/0/LUN#999/Config/] (level 5)
00:00:00.599   papLeds <integer> = 0x0000000002226d20 (35810592)
00:00:00.599   First   <integer> = 0x0000000000000000 (0)
00:00:00.599   Last    <integer> = 0x0000000000000003 (3)
00:00:00.599 
00:00:00.599 [/Devices/piix3ide/0/LUN#0/] (level 4)
00:00:00.599   Driver <string>  = "Block" (cch=6)
00:00:00.599 
00:00:00.599 [/Devices/piix3ide/0/LUN#0/Config/] (level 5)
00:00:00.599   Type      <string>  = "HardDisk" (cch=9)
00:00:00.599   Mountable <integer> = 0x0000000000000000 (0)
00:00:00.599 
00:00:00.599 [/Devices/piix3ide/0/LUN#0/AttachedDriver/] (level 5)
00:00:00.599   Driver <string>  = "VD" (cch=3)
00:00:00.599 
00:00:00.599 [/Devices/piix3ide/0/LUN#0/AttachedDriver/Config/] (level 6)
00:00:00.599   Path   <string>  = "/home/mia/.VirtualBox/HardDisks/freebsd7.1-pfsense-dev.vdi" (cch=59)
00:00:00.599   Format <string>  = "VDI" (cch=4)
00:00:00.599 
00:00:00.599 [/Devices/piix3ide/0/LUN#2/] (level 4)
00:00:00.599   Driver <string>  = "Block" (cch=6)
00:00:00.599 
00:00:00.599 [/Devices/piix3ide/0/LUN#2/Config/] (level 5)
00:00:00.599   Type      <string>  = "DVD" (cch=4)
00:00:00.599   Mountable <integer> = 0x0000000000000001 (1)
00:00:00.599 
00:00:00.599 [/Devices/piix3ide/0/LUN#2/AttachedDriver/] (level 5)
00:00:00.599   Driver <string>  = "MediaISO" (cch=9)
00:00:00.599 
00:00:00.599 [/Devices/piix3ide/0/LUN#2/AttachedDriver/Config/] (level 6)
00:00:00.599   Path <string>  = "/home/mia/sharedFolder/ISOs/7.1-RELEASE-amd64-bootonly.iso" (cch=59)
00:00:00.599 
00:00:00.599 [/Devices/pcnet/] (level 2)
00:00:00.599 
00:00:00.599 [/Devices/e1000/] (level 2)
00:00:00.599 
00:00:00.599 [/Devices/e1000/0/] (level 3)
00:00:00.599   Trusted       <integer> = 0x0000000000000001 (1)
00:00:00.599   PCIDeviceNo   <integer> = 0x0000000000000003 (3)
00:00:00.599   PCIFunctionNo <integer> = 0x0000000000000000 (0)
00:00:00.599 
00:00:00.599 [/Devices/e1000/0/Config/] (level 4)
00:00:00.599   AdapterType    <integer> = 0x0000000000000000 (0)
00:00:00.599   MAC            <bytes>   = "08 00 27 a6 0d 27" (cb=6)
00:00:00.599   CableConnected <integer> = 0x0000000000000001 (1)
00:00:00.599   LineSpeed      <integer> = 0x0000000000000000 (0)
00:00:00.599 
00:00:00.599 [/Devices/e1000/0/LUN#999/] (level 4)
00:00:00.599   Driver <string>  = "MainStatus" (cch=11)
00:00:00.599 
00:00:00.599 [/Devices/e1000/0/LUN#999/Config/] (level 5)
00:00:00.599   papLeds <integer> = 0x0000000002226e30 (35810864)
00:00:00.599 
00:00:00.599 [/Devices/e1000/0/LUN#0/] (level 4)
00:00:00.599   Driver <string>  = "NAT" (cch=4)
00:00:00.599 
00:00:00.599 [/Devices/e1000/0/LUN#0/Config/] (level 5)
00:00:00.599   TFTPPrefix <string>  = "/home/mia/.VirtualBox/TFTP" (cch=27)
00:00:00.599   BootFile   <string>  = "freebsd7.1-pfsense-dev.pxe" (cch=27)
00:00:00.599 
00:00:00.599 [/Devices/serial/] (level 2)
00:00:00.599 
00:00:00.599 [/Devices/parallel/] (level 2)
00:00:00.599 
00:00:00.599 [/Devices/VMMDev/] (level 2)
00:00:00.599 
00:00:00.599 [/Devices/VMMDev/0/] (level 3)
00:00:00.599   Trusted       <integer> = 0x0000000000000001 (1)
00:00:00.599   PCIDeviceNo   <integer> = 0x0000000000000004 (4)
00:00:00.599   PCIFunctionNo <integer> = 0x0000000000000000 (0)
00:00:00.599 
00:00:00.599 [/Devices/VMMDev/0/Config/] (level 4)
00:00:00.599 
00:00:00.599 [/Devices/VMMDev/0/LUN#0/] (level 4)
00:00:00.599   Driver <string>  = "MainVMMDev" (cch=11)
00:00:00.599 
00:00:00.599 [/Devices/VMMDev/0/LUN#0/Config/] (level 5)
00:00:00.599   Object <integer> = 0x0000000002227100 (35811584)
00:00:00.599 
00:00:00.599 [/Devices/VMMDev/0/LUN#999/] (level 4)
00:00:00.599   Driver <string>  = "MainStatus" (cch=11)
00:00:00.599 
00:00:00.599 [/Devices/VMMDev/0/LUN#999/Config/] (level 5)
00:00:00.599   papLeds <integer> = 0x0000000002226e70 (35810928)
00:00:00.599   First   <integer> = 0x0000000000000000 (0)
00:00:00.599   Last    <integer> = 0x0000000000000000 (0)
00:00:00.599 
00:00:00.599 [/Devices/AudioSniffer/] (level 2)
00:00:00.599 
00:00:00.599 [/Devices/AudioSniffer/0/] (level 3)
00:00:00.599 
00:00:00.599 [/Devices/AudioSniffer/0/Config/] (level 4)
00:00:00.599 
00:00:00.599 [/Devices/AudioSniffer/0/LUN#0/] (level 4)
00:00:00.599   Driver <string>  = "MainAudioSniffer" (cch=17)
00:00:00.599 
00:00:00.599 [/Devices/AudioSniffer/0/LUN#0/Config/] (level 5)
00:00:00.599   Object <integer> = 0x0000000002228410 (35816464)
00:00:00.599 
00:00:00.599 [/Devices/ichac97/] (level 2)
00:00:00.599 
00:00:00.599 [/Devices/ichac97/0/] (level 3)
00:00:00.599   Trusted       <integer> = 0x0000000000000001 (1)
00:00:00.599   PCIDeviceNo   <integer> = 0x0000000000000005 (5)
00:00:00.599   PCIFunctionNo <integer> = 0x0000000000000000 (0)
00:00:00.599 
00:00:00.599 [/Devices/ichac97/0/Config/] (level 4)
00:00:00.599 
00:00:00.599 [/Devices/ichac97/0/LUN#0/] (level 4)
00:00:00.599   Driver <string>  = "AUDIO" (cch=6)
00:00:00.599 
00:00:00.599 [/Devices/ichac97/0/LUN#0/Config/] (level 5)
00:00:00.599   AudioDriver <string>  = "alsa" (cch=5)
00:00:00.599   StreamName  <string>  = "freebsd7.1-pfsense-dev" (cch=23)
00:00:00.599 
00:00:00.599 [/Devices/usb-ohci/] (level 2)
00:00:00.599 
00:00:00.599 [/Devices/usb-ohci/0/] (level 3)
00:00:00.600   Trusted       <integer> = 0x0000000000000001 (1)
00:00:00.600   PCIDeviceNo   <integer> = 0x0000000000000006 (6)
00:00:00.600   PCIFunctionNo <integer> = 0x0000000000000000 (0)
00:00:00.600 
00:00:00.600 [/Devices/usb-ohci/0/Config/] (level 4)
00:00:00.600 
00:00:00.600 [/Devices/usb-ohci/0/LUN#0/] (level 4)
00:00:00.600   Driver <string>  = "VUSBRootHub" (cch=12)
00:00:00.600 
00:00:00.600 [/Devices/usb-ohci/0/LUN#0/Config/] (level 5)
00:00:00.600 
00:00:00.600 [/Devices/usb-ohci/0/LUN#999/] (level 4)
00:00:00.600   Driver <string>  = "MainStatus" (cch=11)
00:00:00.600 
00:00:00.600 [/Devices/usb-ohci/0/LUN#999/Config/] (level 5)
00:00:00.600   papLeds <integer> = 0x0000000002226e78 (35810936)
00:00:00.600   First   <integer> = 0x0000000000000000 (0)
00:00:00.600   Last    <integer> = 0x0000000000000000 (0)
00:00:00.600 
00:00:00.600 [/Devices/usb-ehci/] (level 2)
00:00:00.600 
00:00:00.600 [/Devices/usb-ehci/0/] (level 3)
00:00:00.600   Trusted       <integer> = 0x0000000000000001 (1)
00:00:00.600   PCIDeviceNo   <integer> = 0x000000000000000b (11)
00:00:00.600   PCIFunctionNo <integer> = 0x0000000000000000 (0)
00:00:00.600 
00:00:00.600 [/Devices/usb-ehci/0/Config/] (level 4)
00:00:00.600 
00:00:00.600 [/Devices/usb-ehci/0/LUN#0/] (level 4)
00:00:00.600   Driver <string>  = "VUSBRootHub" (cch=12)
00:00:00.600 
00:00:00.600 [/Devices/usb-ehci/0/LUN#0/Config/] (level 5)
00:00:00.600 
00:00:00.600 [/Devices/usb-ehci/0/LUN#999/] (level 4)
00:00:00.600   Driver <string>  = "MainStatus" (cch=11)
00:00:00.600 
00:00:00.600 [/Devices/usb-ehci/0/LUN#999/Config/] (level 5)
00:00:00.600   papLeds <integer> = 0x0000000002226e80 (35810944)
00:00:00.600   First   <integer> = 0x0000000000000000 (0)
00:00:00.600   Last    <integer> = 0x0000000000000000 (0)
00:00:00.600 
00:00:00.600 [/TM/] (level 1)
00:00:00.600   UTCOffset <integer> = 0x0000000000000000 (0)
00:00:00.600 
00:00:00.600 ********************* End of CFGM dump **********************
00:00:00.601 Logical host processors: 2, processor active mask: 0000000000000003
00:00:00.601 ************************* CPUID dump ************************
00:00:00.601          RAW Standard CPUIDs
00:00:00.601      Function  eax      ebx      ecx      edx
00:00:00.601 Gst: 00000000  00000002 756e6547 6c65746e 49656e69
00:00:00.601 Hst:           0000000a 756e6547 6c65746e 49656e69
00:00:00.601 Gst: 00000001  00010676 00000800 00000009 078bf1bf
00:00:00.601 Hst:           00010676 00020800 0008e3fd bfebfbff
00:00:00.601 Gst: 00000002  05b0b101 005657f0 00000000 2cb4304e
00:00:00.601 Hst:           05b0b101 005657f0 00000000 2cb4304e
00:00:00.601 Gst: 00000003  07280202 00000000 00000000 00000503*
00:00:00.601 Hst:           00000000 00000000 00000000 00000000
00:00:00.601 Gst: 00000004  00000000 00000000 00000000 00000503*
00:00:00.601 Hst:           04000121 01c0003f 0000003f 00000001
00:00:00.601 Gst: 00000005  07280202 00000000 00000000 00000503*
00:00:00.601 Hst:           00000040 00000040 00000003 03122220
00:00:00.601 Name:                            GenuineIntel
00:00:00.601 Supports:                        0-2
00:00:00.601 Family:                          6  	Extended: 0 	Effective: 6
00:00:00.601 Model:                           7  	Extended: 1 	Effective: 23
00:00:00.601 Stepping:                        6
00:00:00.601 APIC ID:                         0x00
00:00:00.601 Logical CPUs:                    0
00:00:00.601 CLFLUSH Size:                    8
00:00:00.601 Brand ID:                        0x00
00:00:00.601 Mnemonic - Description                 = guest (host)
00:00:00.601 FPU - x87 FPU on Chip                  = 1 (1)
00:00:00.601 VME - Virtual 8086 Mode Enhancements   = 1 (1)
00:00:00.601 DE - Debugging extensions              = 1 (1)
00:00:00.601 PSE - Page Size Extension              = 1 (1)
00:00:00.601 TSC - Time Stamp Counter               = 1 (1)
00:00:00.601 MSR - Model Specific Registers         = 1 (1)
00:00:00.601 PAE - Physical Address Extension       = 0 (1)
00:00:00.601 MCE - Machine Check Exception          = 1 (1)
00:00:00.601 CX8 - CMPXCHG8B instruction            = 1 (1)
00:00:00.601 APIC - APIC On-Chip                    = 0 (1)
00:00:00.601 Reserved                               = 0 (0)
00:00:00.601 SEP - SYSENTER and SYSEXIT             = 0 (1)
00:00:00.601 MTRR - Memory Type Range Registers     = 1 (1)
00:00:00.601 PGE - PTE Global Bit                   = 1 (1)
00:00:00.601 MCA - Machine Check Architecture       = 1 (1)
00:00:00.601 CMOV - Conditional Move Instructions   = 1 (1)
00:00:00.601 PAT - Page Attribute Table             = 1 (1)
00:00:00.601 PSE-36 - 36-bit Page Size Extention    = 1 (1)
00:00:00.601 PSN - Processor Serial Number          = 0 (0)
00:00:00.601 CLFSH - CLFLUSH Instruction.           = 1 (1)
00:00:00.601 Reserved                               = 0 (0)
00:00:00.601 DS - Debug Store                       = 0 (1)
00:00:00.601 ACPI - Thermal Mon. & Soft. Clock Ctrl.= 0 (1)
00:00:00.601 MMX - Intel MMX Technology             = 1 (1)
00:00:00.601 FXSR - FXSAVE and FXRSTOR Instructions = 1 (1)
00:00:00.601 SSE - SSE Support                      = 1 (1)
00:00:00.601 SSE2 - SSE2 Support                    = 1 (1)
00:00:00.601 SS - Self Snoop                        = 0 (1)
00:00:00.601 HTT - Hyper-Threading Technolog        = 0 (1)
00:00:00.601 TM - Thermal Monitor                   = 0 (1)
00:00:00.601 30 - Reserved                          = 0 (0)
00:00:00.601 PBE - Pending Break Enable             = 0 (1)
00:00:00.601 Supports SSE3 or not                   = 1 (1)
00:00:00.601 Reserved                               = 0 (2)
00:00:00.601 Supports MONITOR/MWAIT                 = 1 (1)
00:00:00.601 CPL-DS - CPL Qualified Debug Store     = 0 (1)
00:00:00.601 VMX - Virtual Machine Technology       = 0 (1)
00:00:00.601 Reserved                               = 0 (1)
00:00:00.601 Enhanced SpeedStep Technology          = 0 (1)
00:00:00.601 Terminal Monitor 2                     = 0 (1)
00:00:00.601 Supports Supplemental SSE3 or not      = 0 (1)
00:00:00.601 L1 Context ID                          = 0 (0)
00:00:00.601 Reserved                               = 0x0 (0x0)
00:00:00.601 CMPXCHG16B                             = 0 (1)
00:00:00.601 xTPR Update Control                    = 0 (1)
00:00:00.601 Reserved                               = 0x0 (0x11)
00:00:00.601 
00:00:00.601          RAW Extended CPUIDs
00:00:00.601      Function  eax      ebx      ecx      edx
00:00:00.601 Gst: 80000000  80000008 00000000 00000000 00000000
00:00:00.601 Hst:           80000008 00000000 00000000 00000000
00:00:00.601 Gst: 80000001  00000000 00000000 00000000 00000000
00:00:00.601 Hst:           00000000 00000000 00000001 20100800
00:00:00.601 Gst: 80000002  65746e49 2952286c 726f4320 4d542865
00:00:00.601 Hst:           65746e49 2952286c 726f4320 4d542865
00:00:00.601 Gst: 80000003  44203229 43206f75 20205550 50202020
00:00:00.601 Hst:           44203229 43206f75 20205550 50202020
00:00:00.601 Gst: 80000004  30303539 20402020 33352e32 007a4847
00:00:00.601 Hst:           30303539 20402020 33352e32 007a4847
00:00:00.601 Gst: 80000005  00000000 00000000 00000000 00000000
00:00:00.601 Hst:           00000000 00000000 00000000 00000000
00:00:00.601 Gst: 80000006  00000000 00000000 18008040 00000000
00:00:00.601 Hst:           00000000 00000000 18008040 00000000
00:00:00.601 Gst: 80000007  00000000 00000000 00000000 00000000
00:00:00.601 Hst:           00000000 00000000 00000000 00000000
00:00:00.601 Gst: 80000008  00003024 00000000 00000000 00000000
00:00:00.601 Hst:           00003024 00000000 00000000 00000000
00:00:00.601 Gst: 80000009  07280202 00000000 00000000 00000503*
00:00:00.601 Hst:           07280202 00000000 00000000 00000503
00:00:00.601 Ext Name:                        
00:00:00.601 Ext Supports:                    0x80000000-0x80000008
00:00:00.601 Family:                          0  	Extended: 0 	Effective: 0
00:00:00.601 Model:                           0  	Extended: 0 	Effective: 0
00:00:00.601 Stepping:                        0
00:00:00.601 Brand ID:                        0x000
00:00:00.601 Mnemonic - Description                 = guest (host)
00:00:00.601 FPU - x87 FPU on Chip                  = 0 (0)
00:00:00.601 VME - Virtual 8086 Mode Enhancements   = 0 (0)
00:00:00.601 DE - Debugging extensions              = 0 (0)
00:00:00.601 PSE - Page Size Extension              = 0 (0)
00:00:00.601 TSC - Time Stamp Counter               = 0 (0)
00:00:00.601 MSR - K86 Model Specific Registers     = 0 (0)
00:00:00.601 PAE - Physical Address Extension       = 0 (0)
00:00:00.601 MCE - Machine Check Exception          = 0 (0)
00:00:00.601 CX8 - CMPXCHG8B instruction            = 0 (0)
00:00:00.601 APIC - APIC On-Chip                    = 0 (0)
00:00:00.601 10 - Reserved                          = 0 (0)
00:00:00.601 SEP - SYSCALL and SYSRET               = 0 (1)
00:00:00.601 MTRR - Memory Type Range Registers     = 0 (0)
00:00:00.601 PGE - PTE Global Bit                   = 0 (0)
00:00:00.601 MCA - Machine Check Architecture       = 0 (0)
00:00:00.601 CMOV - Conditional Move Instructions   = 0 (0)
00:00:00.601 PAT - Page Attribute Table             = 0 (0)
00:00:00.601 PSE-36 - 36-bit Page Size Extention    = 0 (0)
00:00:00.601 18 - Reserved                          = 0 (0)
00:00:00.601 19 - Reserved                          = 0 (0)
00:00:00.601 NX - No-Execute Page Protection        = 0 (1)
00:00:00.601 DS - Debug Store                       = 0 (0)
00:00:00.601 AXMMX - AMD Extensions to MMX Instr.   = 0 (0)
00:00:00.601 MMX - Intel MMX Technology             = 0 (0)
00:00:00.601 FXSR - FXSAVE and FXRSTOR Instructions = 0 (0)
00:00:00.601 25 - AMD fast FXSAVE and FXRSTOR Instr.= 0 (0)
00:00:00.601 26 - 1 GB large page support           = 0 (0)
00:00:00.601 27 - RDTSCP instruction                = 0 (0)
00:00:00.601 28 - Reserved                          = 0 (0)
00:00:00.601 29 - AMD Long Mode                     = 0 (1)
00:00:00.601 30 - AMD Extensions to 3DNow           = 0 (0)
00:00:00.601 31 - AMD 3DNow                         = 0 (0)
00:00:00.601 LahfSahf - LAHF/SAHF in 64-bit mode    = 0 (1)
00:00:00.601 CmpLegacy - Core MP legacy mode (depr) = 0 (0)
00:00:00.601 SVM - AMD VM Extensions                = 0 (0)
00:00:00.601 APIC registers starting at 0x400       = 0 (0)
00:00:00.601 AltMovCR8 - LOCK MOV CR0 means MOV CR8 = 0 (0)
00:00:00.601 Advanced bit manipulation              = 0 (0)
00:00:00.601 SSE4A instruction support              = 0 (0)
00:00:00.601 Misaligned SSE mode                    = 0 (0)
00:00:00.601 PREFETCH and PREFETCHW instruction     = 0 (0)
00:00:00.601 OS visible workaround                  = 0 (0)
00:00:00.601 Instruction based sampling             = 0 (0)
00:00:00.601 SSE5 support                           = 0 (0)
00:00:00.601 SKINIT, STGI, and DEV support          = 0 (0)
00:00:00.601 Watchdog timer support.                = 0 (0)
00:00:00.601 31:14 - Reserved                       = 0x0 (0x0)
00:00:00.601 Full Name:                       Intel(R) Core(TM)2 Duo CPU     P9500  @ 2.53GHz
00:00:00.601 TLB 2/4M Instr/Uni:              res0     0 entries
00:00:00.601 TLB 2/4M Data:                   res0     0 entries
00:00:00.601 TLB 4K Instr/Uni:                res0     0 entries
00:00:00.601 TLB 4K Data:                     res0     0 entries
00:00:00.601 L1 Instr Cache Line Size:        0 bytes
00:00:00.601 L1 Instr Cache Lines Per Tag:    0
00:00:00.601 L1 Instr Cache Associativity:    res0  
00:00:00.601 L1 Instr Cache Size:             0 KB
00:00:00.601 L1 Data Cache Line Size:         0 bytes
00:00:00.601 L1 Data Cache Lines Per Tag:     0
00:00:00.601 L1 Data Cache Associativity:     res0  
00:00:00.601 L1 Data Cache Size:              0 KB
00:00:00.601 L2 TLB 2/4M Instr/Uni:           off       0 entries
00:00:00.601 L2 TLB 2/4M Data:                off       0 entries
00:00:00.601 L2 TLB 4K Instr/Uni:             off       0 entries
00:00:00.601 L2 TLB 4K Data:                  off       0 entries
00:00:00.601 L2 Cache Line Size:              0 bytes
00:00:00.601 L2 Cache Lines Per Tag:          0
00:00:00.601 L2 Cache Associativity:          off   
00:00:00.601 L2 Cache Size:                   0 KB
00:00:00.601 APM Features:                   
00:00:00.601 Physical Address Width:          36 bits
00:00:00.601 Virtual Address Width:           48 bits
00:00:00.601 Physical Core Count:             0
00:00:00.601 
00:00:00.601          RAW Centaur CPUIDs
00:00:00.601      Function  eax      ebx      ecx      edx
00:00:00.601 Gst: c0000000  07280202 00000000 00000000 00000503
00:00:00.601 Hst:           07280202 00000000 00000000 00000503
00:00:00.601 Gst: c0000001  07280202 00000000 00000000 00000503
00:00:00.601 Hst:           07280202 00000000 00000000 00000503
00:00:00.601 Gst: c0000002  07280202 00000000 00000000 00000503
00:00:00.601 Hst:           07280202 00000000 00000000 00000503
00:00:00.601 Gst: c0000003  07280202 00000000 00000000 00000503
00:00:00.601 Hst:           07280202 00000000 00000000 00000503
00:00:00.601 Centaur Supports:                0xc0000000-0x07280202
00:00:00.601 Mnemonic - Description                 = guest (host)
00:00:00.601 AIS - Alternate Instruction Set        = 0 (1)
00:00:00.601 AIS-E - AIS enabled                    = 0 (1)
00:00:00.601 RNG - Random Number Generator          = 0 (0)
00:00:00.601 RNG-E - RNG enabled                    = 0 (0)
00:00:00.601 LH - LongHaul MSR 0000_110Ah           = 0 (0)
00:00:00.601 FEMMS - FEMMS                          = 0 (0)
00:00:00.601 ACE - Advanced Cryptography Engine     = 0 (0)
00:00:00.601 ACE-E - ACE enabled                    = 0 (0)
00:00:00.601 ACE2 - Advanced Cryptography Engine 2  = 0 (1)
00:00:00.601 ACE2-E - ACE enabled                   = 0 (0)
00:00:00.601 PHE - Hash Engine                      = 0 (1)
00:00:00.601 PHE-E - PHE enabled                    = 0 (0)
00:00:00.601 PMM - Montgomery Multiplier            = 0 (0)
00:00:00.601 PMM-E - PMM enabled                    = 0 (0)
00:00:00.601 
00:00:00.601 
00:00:00.601 ******************** End of CPUID dump **********************
00:00:00.602 Debug: HCPhysShw32BitPD=00000000d38f7000 aHCPhysPaePDs={00000000ded38000,00000000ded1f000,00000000dec3d000,00000000dec06000} HCPhysShwPaePdpt=00000000da5b2000 HCPhysShwPaePml4=0000000000000000
00:00:00.602 Debug: HCPhysInterPD=00000000d895a000 HCPhysInterPaePDPT=00000000379f2000 HCPhysInterPaePML4=00000000d3921000
00:00:00.602 Debug: apInterPTs={000000003782f000,00000000d881a000} apInterPaePTs={00000000dec6a000,00000000ded46000} apInterPaePDs={00000000decba000,00000000dec35000,00000000dec73000,00000000dec23000} pInterPaePDPT64=00000000d3927000
00:00:00.614 TM: GIP - u32Mode=1 (SyncTSC) u32UpdateHz=83
00:00:00.646 TM: cTSCTicksPerSecond=0x963613db (2520126427) fTSCVirtualized=true  fTSCUseRealTSC=false
00:00:00.646 TM: fMaybeUseOffsettedHostTSC=true  TSCTiedToExecution=false TSCNotTiedToHalt=false
00:00:00.646 CoreCode: R3=00007f9c80471000 R0=ffffc2000067d000 RC=a05ac000 Phys=00000000618d2000 cb=0x1000
00:00:00.678 SUP: Loaded VBoxDDR0.r0 (/usr/lib/virtualbox/VBoxDDR0.r0) at 0xffffffffa060c3a0 - ModuleInit at 0000000000000000 and ModuleTerm at 0000000000000000
00:00:00.698 SUP: Loaded VBoxDD2R0.r0 (/usr/lib/virtualbox/VBoxDD2R0.r0) at 0xffffffffa061e280 - ModuleInit at 0000000000000000 and ModuleTerm at 0000000000000000
00:00:00.698 Activating Local APIC
00:00:00.698 CPUMSetGuestCpuIdFeature: Enabled APIC
00:00:00.698 CPUMSetGuestCpuIdFeature: Disabled x2APIC
00:00:00.698 PIT: mode=3 count=0x10000 (65536) - 18.20 Hz (ch=0)
00:00:00.700 Shared Folders service loaded.
00:00:00.712 VDInit finished
00:00:00.712 PIIX3 ATA: LUN#0: disk, PCHS=16383/16/63, total number of sectors 16777216
00:00:00.712 PIIX3 ATA: LUN#1: no unit
00:00:00.727 PIIX3 ATA: LUN#2: CD/DVD, total number of sectors 19307, passthrough disabled
00:00:00.727 PIIX3 ATA: LUN#3: no unit
00:00:00.727 PIIX3 ATA: Ctl#0: finished processing RESET
00:00:00.727 PIIX3 ATA: Ctl#1: finished processing RESET
00:00:00.825 NAT: passing domain name meansnothing.org
00:00:00.825 NAT: DNS address: 192.168.7.1
00:00:00.826 Audio: Trying driver 'alsa'.
00:00:00.827 Audio: set_record_source ars=0 als=0 (not implemented)
00:00:00.871 ALSA: ADC frequency 44100Hz, period size 1024, buffer size 4096
00:00:00.919 ALSA: DAC frequency 44100Hz, period size 256, buffer size 1024
00:00:00.929 DevPcBios: ATA LUN#0 LCHS=1024/255/63
00:00:00.929 PGMR3InitFinalize: 4 MB PSE mask 0000000fffffffff
00:00:00.966 HWACCM: Host CR4=000006E0
00:00:00.966 HWACCM: MSR_IA32_FEATURE_CONTROL      = d
00:00:00.966 HWACCM: MSR_IA32_VMX_BASIC_INFO       = 5a08000000000d
00:00:00.966 HWACCM: VMCS id                       = d
00:00:00.966 HWACCM: VMCS size                     = 800
00:00:00.966 HWACCM: VMCS physical address limit   = None
00:00:00.966 HWACCM: VMCS memory type              = 6
00:00:00.966 HWACCM: Dual monitor treatment        = 1
00:00:00.966 HWACCM: MSR_IA32_VMX_PINBASED_CTLS    = 3f00000016
00:00:00.966 HWACCM:    VMX_VMCS_CTRL_PIN_EXEC_CONTROLS_EXT_INT_EXIT
00:00:00.966 HWACCM:    VMX_VMCS_CTRL_PIN_EXEC_CONTROLS_NMI_EXIT
00:00:00.966 HWACCM:    VMX_VMCS_CTRL_PIN_EXEC_CONTROLS_VIRTUAL_NMI
00:00:00.966 HWACCM: MSR_IA32_VMX_PROCBASED_CTLS   = f7f9fffe0401e172
00:00:00.966 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_IRQ_WINDOW_EXIT
00:00:00.966 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_TSC_OFFSET
00:00:00.966 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_HLT_EXIT
00:00:00.966 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_INVLPG_EXIT
00:00:00.966 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_MWAIT_EXIT
00:00:00.966 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_RDPMC_EXIT
00:00:00.966 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_RDTSC_EXIT
00:00:00.966 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_CR3_LOAD_EXIT
00:00:00.966 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_CR3_STORE_EXIT
00:00:00.966 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_CR8_LOAD_EXIT
00:00:00.966 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_CR8_STORE_EXIT
00:00:00.966 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_USE_TPR_SHADOW
00:00:00.966 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_NMI_WINDOW_EXIT
00:00:00.966 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_MOV_DR_EXIT
00:00:00.966 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_UNCOND_IO_EXIT
00:00:00.966 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_USE_IO_BITMAPS
00:00:00.966 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_USE_MSR_BITMAPS
00:00:00.966 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_MONITOR_EXIT
00:00:00.966 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_PAUSE_EXIT
00:00:00.966 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_USE_SECONDARY_EXEC_CTRL
00:00:00.966 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_CR3_LOAD_EXIT *must* be set
00:00:00.966 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_CR3_STORE_EXIT *must* be set
00:00:00.966 HWACCM: MSR_IA32_VMX_PROCBASED_CTLS2  = 4100000000
00:00:00.966 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC2_VIRT_APIC
00:00:00.966 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC2_WBINVD_EXIT
00:00:00.966 HWACCM: MSR_IA32_VMX_ENTRY_CTLS       = 3fff000011ff
00:00:00.966 HWACCM:    VMX_VMCS_CTRL_ENTRY_CONTROLS_LOAD_DEBUG
00:00:00.966 HWACCM:    VMX_VMCS_CTRL_ENTRY_CONTROLS_IA64_MODE
00:00:00.966 HWACCM:    VMX_VMCS_CTRL_ENTRY_CONTROLS_ENTRY_SMM
00:00:00.966 HWACCM:    VMX_VMCS_CTRL_ENTRY_CONTROLS_DEACTIVATE_DUALMON
00:00:00.966 HWACCM:    VMX_VMCS_CTRL_ENTRY_CONTROLS_LOAD_GUEST_PERF_MSR
00:00:00.966 HWACCM:    VMX_VMCS_CTRL_ENTRY_CONTROLS_LOAD_DEBUG *must* be set
00:00:00.966 HWACCM: MSR_IA32_VMX_EXIT_CTLS        = 3ffff00036dff
00:00:00.966 HWACCM:    VMX_VMCS_CTRL_EXIT_CONTROLS_SAVE_DEBUG
00:00:00.966 HWACCM:    VMX_VMCS_CTRL_EXIT_CONTROLS_HOST_AMD64
00:00:00.966 HWACCM:    VMX_VMCS_CTRL_EXIT_CONTROLS_ACK_EXTERNAL_IRQ
00:00:00.966 HWACCM:    VMX_VMCS_CTRL_EXIT_CONTROLS_SAVE_DEBUG *must* be set
00:00:00.966 HWACCM: MSR_IA32_VMX_MISC             = 403c0
00:00:00.966 HWACCM:    MSR_IA32_VMX_MISC_PREEMPT_TSC_BIT 0
00:00:00.966 HWACCM:    MSR_IA32_VMX_MISC_ACTIVITY_STATES 7
00:00:00.966 HWACCM:    MSR_IA32_VMX_MISC_CR3_TARGET      4
00:00:00.966 HWACCM:    MSR_IA32_VMX_MISC_MAX_MSR         200
00:00:00.966 HWACCM:    MSR_IA32_VMX_MISC_MSEG_ID         0
00:00:00.966 HWACCM: MSR_IA32_VMX_CR0_FIXED0       = 80000021
00:00:00.966 HWACCM: MSR_IA32_VMX_CR0_FIXED1       = ffffffff
00:00:00.966 HWACCM: MSR_IA32_VMX_CR4_FIXED0       = 2000
00:00:00.966 HWACCM: MSR_IA32_VMX_CR4_FIXED1       = 67ff
00:00:00.966 HWACCM: MSR_IA32_VMX_VMCS_ENUM        = 2c
00:00:00.967 HWACCM: TPR shadow physaddr           = 00000000dac0c000
00:00:00.967 HWACCM: MSR bitmap physaddr           = 00000000dac0d000
00:00:00.967 HWACCM: VMCS physaddr VCPU0           = 00000000dac0e000
00:00:00.967 HWACCM: Real Mode TSS guest physaddr  = 00000000f0800000
00:00:00.967 HWACCM: Non-Paging Mode EPT CR3       = 00000000f0803000
00:00:01.008 CPUMSetGuestCpuIdFeature: Enabled sysenter/exit
00:00:01.008 CPUMSetGuestCpuIdFeature: Enabled PAE
00:00:01.008 CPUMSetGuestCpuIdFeature: Enabled LONG MODE
00:00:01.008 CPUMSetGuestCpuIdFeature: Enabled syscall/ret
00:00:01.008 CPUMSetGuestCpuIdFeature: Enabled LAHF/SAHF
00:00:01.008 CPUMSetGuestCpuIdFeature: Enabled NXE
00:00:01.008 HWACCM: 32-bit and 64-bit guest supported.
00:00:01.008 HWACCM: VMX enabled!
00:00:01.030 VM: Halt method global1 (5)
00:00:01.030 Changing the VM state from 'CREATING' to 'CREATED'.
00:00:01.030 SharedFolders host service: adding host mapping.
00:00:01.030     Host path /home/mia/sharedFolder, map name sharedFolder, writable 1
00:00:01.031 SharedFolders host service: add mapping result VINF_SUCCESS
00:00:01.031 Changing the VM state from 'CREATED' to 'RUNNING'.
00:00:01.055 Guest Log: BIOS: VirtualBox 2.1.4
00:00:01.055 PIT: mode=2 count=0x10000 (65536) - 18.20 Hz (ch=0)
00:00:01.102 PIIX3 ATA: Ctl#0: RESET, DevSel=0 AIOIf=0 CmdIf0=0x00 (-1 usec ago) CmdIf1=0x00 (-1 usec ago)
00:00:01.102 PIIX3 ATA: Ctl#0: finished processing RESET
00:00:01.102 Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63
00:00:01.102 PIIX3 ATA: Ctl#1: RESET, DevSel=0 AIOIf=0 CmdIf0=0x00 (-1 usec ago) CmdIf1=0x00 (-1 usec ago)
00:00:01.102 PIIX3 ATA: Ctl#1: finished processing RESET
00:00:01.102 PIT: mode=2 count=0x48d3 (18643) - 64.00 Hz (ch=0)
00:00:01.116 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=00007f9c6e7fc000 w=640 h=480 bpp=32 cbLine=0xA00
00:00:03.576 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=0000000000000000 w=720 h=400 bpp=0 cbLine=0x0
00:00:03.580 PIT: mode=2 count=0x10000 (65536) - 18.20 Hz (ch=0)
00:00:03.580 Guest Log: BIOS: Boot from Floppy 0 failed
00:00:03.606 Guest Log: BIOS: Booting from CD-ROM...
00:00:03.639 Guest Log: BIOS: int13_diskette: unsupported AH=41
00:00:10.197 PIT: mode=2 count=0x4a9 (1193) - 1000.15 Hz (ch=0)
00:00:17.742 PIIX3 ATA: Ctl#0: RESET, DevSel=0 AIOIf=0 CmdIf0=0xec (-1 usec ago) CmdIf1=0x00 (-1 usec ago)
00:00:17.746 PIIX3 ATA: Ctl#0: finished processing RESET
00:00:17.792 PIIX3 ATA: Ctl#1: RESET, DevSel=0 AIOIf=0 CmdIf0=0xa0 (-1 usec ago) CmdIf1=0x00 (-1 usec ago)
00:00:17.796 PIIX3 ATA: Ctl#1: finished processing RESET
00:00:18.215 OHCI: Software reset
00:00:18.216 OHCI: USB Operational
00:00:18.385 EHCI: Hardware reset
00:00:18.386 EHCI: USB Operational
00:00:25.434 PIT: mode=2 count=0x10000 (65536) - 18.20 Hz (ch=0)
00:00:25.522 PIIX3 ATA: LUN#2: performing device RESET
00:03:16.640 NAT: DHCP offered IP address 10.0.2.15
00:03:16.753 NAT: IPv6 not supported
00:03:18.644 NAT: DHCP offered IP address 10.0.2.15
01:21:21.409 EHCI: USB Suspended
01:21:21.426 EHCI: Hardware reset
01:21:22.368 Changing the VM state from 'RUNNING' to 'RESETTING'.
01:21:22.779 CPUMSetGuestCpuIdFeature: Enabled APIC
01:21:22.779 CPUMSetGuestCpuIdFeature: Disabled x2APIC
01:21:22.779 PIT: mode=3 count=0x10000 (65536) - 18.20 Hz (ch=0)
01:21:22.784 PIIX3 ATA: Ctl#0: finished processing RESET
01:21:22.785 PIIX3 ATA: Ctl#1: finished processing RESET
01:21:22.885 Audio: set_record_source ars=0 als=0 (not implemented)
01:21:22.928 Changing the VM state from 'RESETTING' to 'RUNNING'.
01:21:22.929 Guest Log: BIOS: VirtualBox 2.1.4
01:21:22.929 PIT: mode=2 count=0x10000 (65536) - 18.20 Hz (ch=0)
01:21:23.033 PIIX3 ATA: Ctl#0: RESET, DevSel=0 AIOIf=0 CmdIf0=0xe7 (-1 usec ago) CmdIf1=0x00 (-1 usec ago)
01:21:23.033 PIIX3 ATA: Ctl#0: finished processing RESET
01:21:23.034 Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63
01:21:23.034 PIIX3 ATA: Ctl#1: RESET, DevSel=0 AIOIf=0 CmdIf0=0xa0 (-1 usec ago) CmdIf1=0x00 (-1 usec ago)
01:21:23.034 PIIX3 ATA: Ctl#1: finished processing RESET
01:21:23.034 PIT: mode=2 count=0x48d3 (18643) - 64.00 Hz (ch=0)
01:21:23.050 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=00007f9c6e7fc000 w=640 h=480 bpp=32 cbLine=0xA00
01:21:24.638 Changing the VM state from 'RUNNING' to 'SUSPENDED'.
01:21:24.644 Changing the VM state from 'SUSPENDED' to 'RUNNING'.
01:21:25.178 PIT: mode=2 count=0x10000 (65536) - 18.20 Hz (ch=0)
01:21:25.178 Guest Log: BIOS: Boot from Floppy 0 failed
01:21:25.179 Guest Log: BIOS: CDROM boot failure code : 0003
01:21:25.179 Guest Log: BIOS: Boot from CD-ROM failed
01:21:25.179 Guest Log: BIOS: Booting from Hard Disk...
01:21:25.186 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=0000000000000000 w=720 h=400 bpp=0 cbLine=0x0
01:21:38.221 Guest Log: BIOS: int13_diskette: unsupported AH=41
01:21:44.959 PIT: mode=2 count=0x4a9 (1193) - 1000.15 Hz (ch=0)
01:21:52.269 PIIX3 ATA: Ctl#0: RESET, DevSel=0 AIOIf=0 CmdIf0=0x20 (-1 usec ago) CmdIf1=0x00 (-1 usec ago)
01:21:52.272 PIIX3 ATA: Ctl#0: finished processing RESET
01:21:52.309 PIIX3 ATA: Ctl#1: RESET, DevSel=0 AIOIf=0 CmdIf0=0xa0 (-1 usec ago) CmdIf1=0x00 (-1 usec ago)
01:21:52.314 PIIX3 ATA: Ctl#1: finished processing RESET
01:21:52.696 OHCI: USB Reset
01:21:52.793 OHCI: Software reset
01:21:52.794 OHCI: USB Operational
01:21:53.225 EHCI: Hardware reset
01:21:53.228 EHCI: USB Operational
01:22:00.370 PIT: mode=2 count=0x10000 (65536) - 18.20 Hz (ch=0)
01:22:00.460 PIIX3 ATA: LUN#2: performing device RESET
01:22:02.919 NAT: DHCP offered IP address 10.0.2.15
01:22:04.688 NAT: DHCP offered IP address 10.0.2.15
01:25:38.356 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=00007f9c6e7fc000 w=1280 h=1024 bpp=16 cbLine=0xA00
01:41:02.985 TM: Giving up catch-up attempt at a 60000431984 ns lag; new total: 60000431984 ns
01:43:18.264 TM: Giving up catch-up attempt at a 60001348803 ns lag; new total: 120001780787 ns
01:45:12.929 TM: Giving up catch-up attempt at a 60000247918 ns lag; new total: 180002028705 ns
01:46:46.508 TM: Giving up catch-up attempt at a 60000430481 ns lag; new total: 240002459186 ns
01:48:43.100 TM: Giving up catch-up attempt at a 60000890411 ns lag; new total: 300003349597 ns
01:50:26.908 TM: Giving up catch-up attempt at a 60001557447 ns lag; new total: 360004907044 ns
01:52:57.270 TM: Giving up catch-up attempt at a 60000296998 ns lag; new total: 420005204042 ns
01:54:46.046 TM: Giving up catch-up attempt at a 60001280419 ns lag; new total: 480006484461 ns
01:56:26.874 TM: Giving up catch-up attempt at a 60000444607 ns lag; new total: 540006929068 ns
01:58:10.902 TM: Giving up catch-up attempt at a 60000532488 ns lag; new total: 600007461556 ns
01:59:56.140 TM: Giving up catch-up attempt at a 60000593245 ns lag; new total: 660008054801 ns
02:01:36.009 TM: Giving up catch-up attempt at a 60000333122 ns lag; new total: 720008387923 ns
02:03:22.507 TM: Giving up catch-up attempt at a 60000146602 ns lag; new total: 780008534525 ns
02:05:06.216 TM: Giving up catch-up attempt at a 60001033807 ns lag; new total: 840009568332 ns
02:06:49.965 TM: Giving up catch-up attempt at a 60001122347 ns lag; new total: 900010690679 ns
02:08:27.384 TM: Giving up catch-up attempt at a 60000172325 ns lag; new total: 960010863004 ns
02:10:28.300 TM: Giving up catch-up attempt at a 60001333110 ns lag; new total: 1020012196114 ns
02:12:10.256 TM: Giving up catch-up attempt at a 60002061005 ns lag; new total: 1080014257119 ns
02:13:52.408 TM: Giving up catch-up attempt at a 60000714650 ns lag; new total: 1140014971769 ns
02:15:41.365 TM: Giving up catch-up attempt at a 60000355286 ns lag; new total: 1200015327055 ns
02:18:19.760 TM: Giving up catch-up attempt at a 60000547221 ns lag; new total: 1260015874276 ns
02:20:20.420 TM: Giving up catch-up attempt at a 60001278303 ns lag; new total: 1320017152579 ns
02:22:17.424 TM: Giving up catch-up attempt at a 60001641532 ns lag; new total: 1380018794111 ns
02:24:11.293 TM: Giving up catch-up attempt at a 60000021792 ns lag; new total: 1440018815903 ns
02:26:03.516 TM: Giving up catch-up attempt at a 60001510844 ns lag; new total: 1500020326747 ns
02:27:48.464 TM: Giving up catch-up attempt at a 60000868410 ns lag; new total: 1560021195157 ns
02:29:25.245 TM: Giving up catch-up attempt at a 60000257081 ns lag; new total: 1620021452238 ns
02:31:21.316 TM: Giving up catch-up attempt at a 60001885326 ns lag; new total: 1680023337564 ns
02:33:24.496 TM: Giving up catch-up attempt at a 60001055587 ns lag; new total: 1740024393151 ns
02:35:27.724 TM: Giving up catch-up attempt at a 60002301737 ns lag; new total: 1800026694888 ns
02:37:21.012 TM: Giving up catch-up attempt at a 60000737951 ns lag; new total: 1860027432839 ns
02:39:02.364 TM: Giving up catch-up attempt at a 60001345464 ns lag; new total: 1920028778303 ns
02:40:57.006 TM: Giving up catch-up attempt at a 60000038414 ns lag; new total: 1980028816717 ns
02:42:52.813 TM: Giving up catch-up attempt at a 60000110361 ns lag; new total: 2040028927078 ns
thewzd
Posts: 6
Joined: 10. Mar 2009, 17:25

Re: 100% CPU Usage on Ubuntu 8.10

Post by thewzd »

Hi all, I'm still having the same problems as described by many of you. My setup is :-

HOST: sony laptop, 3.2ghz intel 32-bit single processor, 1gb ram, ubuntu 8.04 (fully updated), virtualbox 2.1.4 installed with 1 guest o/s.
GUEST: windows xp sp3 guest using 448mb of hosts ram (showing only 220mb ram used)

Extra info:
Guest has no anti-virus running. Uninstalling the AV previously installed software did make a slight difference.

The processor runs at 85% or higher, often 100%, rendering the guest and host very slow to use.

Applying the affinity does not seem to be able to fix the issue for me, as I've only got one processor.
Is there a way I can reduce the idle activity of the VM? or a way that I can reduve the amount of CPU that it's allowed to use.

It's crippling me, but I need it at the moment, and have no choice to continue in this condition. Any recommendations much appreciated.

About me : Windows and Linux friendly. But no expert. Just a software & web developer. Not an O/S expert.

Thanks
Sasquatch
Volunteer
Posts: 17798
Joined: 17. Mar 2008, 13:41
Primary OS: Debian other
VBox Version: PUEL
Guest OSses: Windows XP, Windows 7, Linux
Location: /dev/random

Re: 100% CPU Usage on Ubuntu 8.10

Post by Sasquatch »

You can try to run an empty dummy VM and pause it. That somehow triggers something that causes the CPU to stay normal. Also see if you have I/O APIC enabled for the Guest. You might want to disable that and reinstall the Guest (Windows doesn't like it when it's suddenly disabled).
Read the Forum Posting Guide before opening a topic.
VirtualBox FAQ: Check this before asking questions.
Online User Manual: A must read if you want to know what we're talking about.
Howto: Install Linux Guest Additions
Howto: Use Shared Folders on Linux Guest
See the Tutorials and FAQ section at the top of the Forum for more guides.
Try searching the forums first with Google and add the site filter for this forum.
E.g. install guest additions site:forums.virtualbox.org

Retired from this Forum since OSSO introduction.
thewzd
Posts: 6
Joined: 10. Mar 2009, 17:25

Re: 100% CPU Usage on Ubuntu 8.10

Post by thewzd »

Sasquatch wrote:You can try to run an empty dummy VM and pause it. That somehow triggers something that causes the CPU to stay normal. Also see if you have I/O APIC enabled for the Guest. You might want to disable that and reinstall the Guest (Windows doesn't like it when it's suddenly disabled).
Thanks for the response... some more information here :-

The I/O APIC box, is not checked
The ACPI box, is checked
The PAE/NX box, is checked

If I 'pause the virtual machine' whilst the host CPU then all host CPU activity returns to normal, and my cpu fans stop freaking out.

The guest has 384mb ram allocated, but is using around 150mb (Limit being 1,042,680 bytes, Peak being 159,120), so it seems it's not memory causing the problem.

Guest Additions are installed on the guest : v2.1.4.r42893

MORE INTERESTINGLY....

In the guest, Windows Task Manager reports the CPU at 5-8% whilst the host is showing 95+%.

Any more suggestions for me to try? Or more information that you need to help make a suggestion?
fixedwheel
Volunteer
Posts: 1699
Joined: 13. Sep 2008, 02:18

Re: 100% CPU Usage on Ubuntu 8.10

Post by fixedwheel »

thewzd wrote:Any more suggestions
USB enabled for the guest? active USB Filters?
sometimes i observed high CPU load on my debian lenny host with guest USB enabled. IIRC not that high ... somewhat about 40-50% on a 2GHz P4 with idle guest.
thewzd
Posts: 6
Joined: 10. Mar 2009, 17:25

Re: 100% CPU Usage on Ubuntu 8.10

Post by thewzd »

Thanks for the pointer to the USB. I've disabled the USB functions for when I don't need them, and it's made about 10-15% difference in the CPU usage. We're hovering between 65% and 90% now, which is more usable. I will continue to investigate, whilst still getting on with my work....

But I'm hoping that when I buy my new laptop, that resources shouldn't be a problem as it will an AMD-64, 4gb ram, and 895mb video ram. Fingers crossed....

But thanks for the help this far, I'm working, and that's what counts.
Post Reply