VBox 2.1.2 performance decrease

nabamer. · Post by **nabamer.** » 28. Jan 2009, 16:12

00:00:01.047 REM: VBoxREM32
00:00:01.070 TM: GIP - u32Mode=1 (SyncTSC) u32UpdateHz=100
00:00:01.103 TM: cTSCTicksPerSecond=0x810b2fd0 (2164994000) fTSCVirtualized=true  fTSCUseRealTSC=false
00:00:01.103 TM: fMaybeUseOffsettedHostTSC=true  TSCTiedToExecution=false TSCNotTiedToHalt=false
00:00:01.103 CoreCode: R3=19ea6000 R0=34b92000 RC=a040c000 Phys=0000000003ef7000 cb=0x3000
00:00:01.151 SUP: Loaded VBoxDDR0.r0 (/Applications/VirtualBox.app/Contents/MacOS/VBoxDDR0.r0) at 0x2474b060 - ModuleInit at 0000000000000000 and ModuleTerm at 0000000000000000
00:00:01.154 SUP: Loaded VBoxDD2R0.r0 (/Applications/VirtualBox.app/Contents/MacOS/VBoxDD2R0.r0) at 0x246e7060 - ModuleInit at 0000000000000000 and ModuleTerm at 0000000000000000
00:00:01.154 Activating Local APIC
00:00:01.154 CPUMSetGuestCpuIdFeature: Enabled APIC
00:00:01.154 CPUMSetGuestCpuIdFeature: Disabled x2APIC
00:00:01.154 PIT: mode=3 count=0x10000 (65536) - 18.20 Hz (ch=0)
00:00:01.168 Shared Folders service loaded.
00:00:01.258 VDInit finished
00:00:01.259 PIIX3 ATA: LUN#0: disk, PCHS=16383/16/63, total number of sectors 31457280
00:00:01.259 PIIX3 ATA: LUN#1: no unit
00:00:01.530 PIIX3 ATA: LUN#2: CD/DVD, total number of sectors 0, passthrough disabled
00:00:01.530 PIIX3 ATA: LUN#3: no unit
00:00:01.530 PIIX3 ATA: Ctl#0: finished processing RESET
00:00:01.630 PIIX3 ATA: Ctl#1: finished processing RESET
00:00:01.731 Port0: no driver attached
00:00:01.731 AHCI ATA: LUN#0: no unit
00:00:01.731 AHCI ATA: LUN#1: no unit
00:00:01.732 ATA: Ctl: finished processing RESET
00:00:01.832 AHCI ATA: LUN#0: no unit
00:00:01.832 AHCI ATA: LUN#1: no unit
00:00:01.832 ATA: Ctl: finished processing RESET
00:00:01.932 ATA: Ctl: finished processing RESET
00:00:02.032 ATA: Ctl: finished processing RESET
00:00:02.161 NAT: DNS address: 192.168.1.1
00:00:02.162 Audio: Trying driver 'coreaudio'.
00:00:02.162 Audio: set_record_source ars=0 als=0 (not implemented)
00:00:02.601 DevPcBios: ATA LUN#0 LCHS=1024/255/63
00:00:02.623 PGMR3InitFinalize: 4 MB PSE mask 0000000fffffffff
00:00:02.637 HWACCM: Host CR4=00000660
00:00:02.637 HWACCM: MSR_IA32_FEATURE_CONTROL      = 5
00:00:02.637 HWACCM: MSR_IA32_VMX_BASIC_INFO       = 1a040000000007
00:00:02.637 HWACCM: VMCS id                       = 7
00:00:02.637 HWACCM: VMCS size                     = 400
00:00:02.637 HWACCM: VMCS physical address limit   = None
00:00:02.637 HWACCM: VMCS memory type              = 6
00:00:02.637 HWACCM: Dual monitor treatment        = 1
00:00:02.637 HWACCM: MSR_IA32_VMX_PINBASED_CTLS    = 1f00000016
00:00:02.637 HWACCM:    VMX_VMCS_CTRL_PIN_EXEC_CONTROLS_EXT_INT_EXIT
00:00:02.637 HWACCM:    VMX_VMCS_CTRL_PIN_EXEC_CONTROLS_NMI_EXIT
00:00:02.637 HWACCM: MSR_IA32_VMX_PROCBASED_CTLS   = 77b9fffe0401e172
00:00:02.637 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_IRQ_WINDOW_EXIT
00:00:02.637 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_TSC_OFFSET
00:00:02.637 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_HLT_EXIT
00:00:02.637 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_INVLPG_EXIT
00:00:02.637 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_MWAIT_EXIT
00:00:02.637 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_RDPMC_EXIT
00:00:02.637 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_RDTSC_EXIT
00:00:02.637 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_CR3_LOAD_EXIT
00:00:02.637 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_CR3_STORE_EXIT
00:00:02.637 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_CR8_LOAD_EXIT
00:00:02.637 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_CR8_STORE_EXIT
00:00:02.637 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_USE_TPR_SHADOW
00:00:02.637 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_MOV_DR_EXIT
00:00:02.637 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_UNCOND_IO_EXIT
00:00:02.637 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_USE_IO_BITMAPS
00:00:02.637 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_USE_MSR_BITMAPS
00:00:02.637 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_MONITOR_EXIT
00:00:02.637 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_PAUSE_EXIT
00:00:02.637 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_CR3_LOAD_EXIT *must* be set
00:00:02.637 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_CR3_STORE_EXIT *must* be set
00:00:02.637 HWACCM: MSR_IA32_VMX_ENTRY_CTLS       = 1fff000011ff
00:00:02.637 HWACCM:    VMX_VMCS_CTRL_ENTRY_CONTROLS_LOAD_DEBUG
00:00:02.637 HWACCM:    VMX_VMCS_CTRL_ENTRY_CONTROLS_IA64_MODE
00:00:02.637 HWACCM:    VMX_VMCS_CTRL_ENTRY_CONTROLS_ENTRY_SMM
00:00:02.637 HWACCM:    VMX_VMCS_CTRL_ENTRY_CONTROLS_DEACTIVATE_DUALMON
00:00:02.637 HWACCM:    VMX_VMCS_CTRL_ENTRY_CONTROLS_LOAD_DEBUG *must* be set
00:00:02.637 HWACCM: MSR_IA32_VMX_EXIT_CTLS        = 3efff00036dff
00:00:02.637 HWACCM:    VMX_VMCS_CTRL_EXIT_CONTROLS_SAVE_DEBUG
00:00:02.637 HWACCM:    VMX_VMCS_CTRL_EXIT_CONTROLS_HOST_AMD64
00:00:02.637 HWACCM:    VMX_VMCS_CTRL_EXIT_CONTROLS_ACK_EXTERNAL_IRQ
00:00:02.637 HWACCM:    VMX_VMCS_CTRL_EXIT_CONTROLS_SAVE_DEBUG *must* be set
00:00:02.637 HWACCM: MSR_IA32_VMX_MISC             = 403c0
00:00:02.637 HWACCM:    MSR_IA32_VMX_MISC_PREEMPT_TSC_BIT 0
00:00:02.637 HWACCM:    MSR_IA32_VMX_MISC_ACTIVITY_STATES 7
00:00:02.637 HWACCM:    MSR_IA32_VMX_MISC_CR3_TARGET      4
00:00:02.637 HWACCM:    MSR_IA32_VMX_MISC_MAX_MSR         200
00:00:02.637 HWACCM:    MSR_IA32_VMX_MISC_MSEG_ID         0
00:00:02.637 HWACCM: MSR_IA32_VMX_CR0_FIXED0       = 80000021
00:00:02.637 HWACCM: MSR_IA32_VMX_CR0_FIXED1       = ffffffff
00:00:02.637 HWACCM: MSR_IA32_VMX_CR4_FIXED0       = 2000
00:00:02.637 HWACCM: MSR_IA32_VMX_CR4_FIXED1       = 27ff
00:00:02.637 HWACCM: MSR_IA32_VMX_VMCS_ENUM        = 2c
00:00:02.637 HWACCM: TPR shadow physaddr           = 0000000079655000
00:00:02.637 HWACCM: MSR bitmap physaddr           = 000000001b996000
00:00:02.637 HWACCM: VMCS physaddr VCPU0           = 000000006c897000
00:00:02.637 HWACCM: Real Mode TSS guest physaddr  = 00000000f0800000
00:00:02.637 HWACCM: Non-Paging Mode EPT CR3       = 00000000f0803000
00:00:02.649 CPUMSetGuestCpuIdFeature: Enabled sysenter/exit
00:00:02.649 HWACCM: 32-bit guest supported.
00:00:02.649 HWACCM: VMX enabled!
00:00:02.660 VM: Halt method global1 (5)
00:00:02.660 Changing the VM state from 'CREATING' to 'CREATED'.
00:00:02.661 Changing the VM state from 'CREATED' to 'RUNNING'.
00:00:02.672 Guest Log: BIOS: VirtualBox 2.1.2
00:00:02.672 PIT: mode=2 count=0x10000 (65536) - 18.20 Hz (ch=0)
00:00:02.832 PIIX3 ATA: Ctl#0: RESET, DevSel=0 AIOIf=0 CmdIf0=0x00 (-1 usec ago) CmdIf1=0x00 (-1 usec ago)
00:00:02.832 PIIX3 ATA: Ctl#0: finished processing RESET
00:00:02.834 Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63
00:00:02.834 PIIX3 ATA: Ctl#1: RESET, DevSel=0 AIOIf=0 CmdIf0=0x00 (-1 usec ago) CmdIf1=0x00 (-1 usec ago)
00:00:02.834 PIIX3 ATA: Ctl#1: finished processing RESET
00:00:02.835 PIT: mode=2 count=0x48d3 (18643) - 64.00 Hz (ch=0)
00:00:02.841 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=1a9b4000 w=640 h=480 bpp=32 cbLine=0xA00
00:00:05.324 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=1a9b4000 w=640 h=480 bpp=0 cbLine=0x200
00:00:05.339 PIT: mode=2 count=0x10000 (65536) - 18.20 Hz (ch=0)
00:00:05.340 Guest Log: BIOS: Boot from Floppy 0 failed
00:00:05.342 Guest Log: BIOS: CDROM boot failure code : 0003
00:00:05.343 Guest Log: BIOS: Boot from CD-ROM failed
00:00:05.343 Guest Log: BIOS: Booting from Hard Disk...
00:00:05.344 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=00000000 w=720 h=400 bpp=0 cbLine=0x0
00:00:05.734 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=1a9b4000 w=640 h=480 bpp=0 cbLine=0x140
00:00:05.879 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
00:00:05.880 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
00:00:05.880 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
00:00:05.880 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
00:00:05.881 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
00:00:05.881 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
00:00:05.881 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
00:00:11.426 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=00000000 w=720 h=400 bpp=0 cbLine=0x0
00:00:11.544 PIT: mode=2 count=0xffff (65535) - 18.20 Hz (ch=0)
00:00:11.734 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=1a9b4000 w=640 h=480 bpp=0 cbLine=0x140
00:00:18.442 PIIX3 ATA: LUN#0: IDLE IMMEDIATE, CmdIf=0xef (-1 usec ago)
00:00:18.442 PIIX3 ATA: LUN#0: aborting current command
00:00:19.605 Guest Additions information report: additionsVersion = 0x00010004  osType = 0x00033000
00:00:19.702 Guest reported fixed hypervisor window at 0xf6400000 (size = 0x800000, rc = VINF_SUCCESS)
00:00:23.688 Guest requests mouse pointer integration
00:00:24.463 EHCI: Hardware reset
00:00:24.465 EHCI: USB Operational
00:00:25.205 OHCI: Software reset
00:00:25.205 OHCI: USB Reset
00:00:25.206 OHCI: USB Operational
00:00:25.227 Guest adapter information contains unsupported type 5. The block has been skipped.
00:00:26.185 Audio: set_record_source ars=0 als=0 (not implemented)
00:00:26.190 Audio: set_record_source ars=0 als=0 (not implemented)
00:00:26.476 SharedFolders host service: connected, u32ClientID = 1
00:00:28.685 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=1a9b4000 w=1400 h=1050 bpp=32 cbLine=0x15E0
00:00:28.685 VBVA: Enabled.
00:00:28.847 EHCI: USB Suspended
00:00:28.848 OHCI: USB Suspended
00:01:34.572 Guest Log: VBoxService: Started.
00:01:36.145 Guest Log: VBoxTray: Started.
00:01:37.265 Guest Additions capability report: (0x5) seamless: yes, hostWindowMapping: no, graphics: yes
00:01:39.702 Audio: set_record_source ars=0 als=0 (not implemented)
00:01:39.702 Audio: set_record_source ars=0 als=0 (not implemented)
00:02:16.998 PCNet#0: Init: ss32=1 GCRDRA=0x02c3a420[64] GCTDRA=0x02c3a020[64]
00:02:19.741 PCNet#0: Init: ss32=1 GCRDRA=0x02c3a420[64] GCTDRA=0x02c3a020[64]
00:02:20.971 NAT: DHCP offered IP address 10.0.2.15
00:02:20.974 NAT: DHCP offered IP address 10.0.2.15
00:02:21.585 PCNet#0: Init: ss32=1 GCRDRA=0x02c3a420[64] GCTDRA=0x02c3a020[64]
00:02:25.041 PCNet#0: Init: ss32=1 GCRDRA=0x02c3a420[64] GCTDRA=0x02c3a020[64]
00:02:30.844 Guest Log: VBOXNP: DLL loaded.
00:03:21.897 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=1a9b4000 w=1280 h=1024 bpp=32 cbLine=0x1400
00:03:21.897 VBVA: Disabled.
00:03:21.899 VBVA: Enabled.
00:42:22.188 Guest Log: VBOXNP: DLL loaded.
00:42:28.853 PCNet#0: Init: ss32=1 GCRDRA=0x02c3a420[64] GCTDRA=0x02c3a020[64]
00:42:29.361 Guest Log: VBoxService: Ended.
00:42:34.409 PIIX3 ATA: LUN#0: IDLE IMMEDIATE, CmdIf=0xe7 (-1 usec ago)
00:42:34.409 PIIX3 ATA: LUN#0: aborting current command
00:42:36.354 Guest disables mouse pointer integration
00:42:36.377 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=1a9b4000 w=1400 h=26 bpp=0 cbLine=0x5E0
00:42:36.377 VBVA: Disabled.
00:42:36.378 Changing the VM state from 'RUNNING' to 'RESETTING'.
00:42:36.908 CPUMSetGuestCpuIdFeature: Enabled APIC
00:42:36.908 CPUMSetGuestCpuIdFeature: Disabled x2APIC
00:42:36.908 PIT: mode=3 count=0x10000 (65536) - 18.20 Hz (ch=0)
00:42:36.909 SharedFolders host service: disconnected, u32ClientID = 1
00:42:36.922 PIIX3 ATA: Ctl#0: finished processing RESET
00:42:37.022 PIIX3 ATA: Ctl#1: finished processing RESET
00:42:37.122 ATA: Ctl: finished processing RESET
00:42:37.222 ATA: Ctl: finished processing RESET
00:42:37.322 Audio: set_record_source ars=0 als=0 (not implemented)
00:42:37.334 Changing the VM state from 'RESETTING' to 'RUNNING'.
00:42:37.334 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=00000000 w=9 h=1 bpp=0 cbLine=0x0
00:42:37.335 Guest Log: BIOS: VirtualBox 2.1.2
00:42:37.336 PIT: mode=2 count=0x10000 (65536) - 18.20 Hz (ch=0)
00:42:37.436 PIIX3 ATA: Ctl#0: RESET, DevSel=0 AIOIf=0 CmdIf0=0xc8 (-1 usec ago) CmdIf1=0x00 (-1 usec ago)
00:42:37.436 PIIX3 ATA: Ctl#0: finished processing RESET
00:42:37.437 Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63
00:42:37.437 PIIX3 ATA: Ctl#1: RESET, DevSel=0 AIOIf=0 CmdIf0=0xa0 (-1 usec ago) CmdIf1=0x00 (-1 usec ago)
00:42:37.437 PIIX3 ATA: Ctl#1: finished processing RESET
00:42:37.438 PIT: mode=2 count=0x48d3 (18643) - 64.00 Hz (ch=0)
00:42:37.461 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=1a9b4000 w=640 h=480 bpp=32 cbLine=0xA00
00:42:39.223 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=00000000 w=720 h=400 bpp=0 cbLine=0x0
00:42:39.235 PIT: mode=2 count=0x10000 (65536) - 18.20 Hz (ch=0)
00:42:39.237 Guest Log: BIOS: Boot from Floppy 0 failed
00:42:39.239 Guest Log: BIOS: CDROM boot failure code : 0003
00:42:39.240 Guest Log: BIOS: Boot from CD-ROM failed
00:42:39.241 Guest Log: BIOS: Booting from Hard Disk...
00:42:39.783 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
00:42:39.783 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
00:42:39.783 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
00:42:39.783 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
00:42:39.784 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
00:42:39.784 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
00:42:39.784 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
00:42:40.284 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=1a9b4000 w=640 h=480 bpp=0 cbLine=0x140
00:42:46.458 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=00000000 w=720 h=400 bpp=0 cbLine=0x0
00:42:46.592 PIT: mode=2 count=0xffff (65535) - 18.20 Hz (ch=0)
00:42:46.707 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=1a9b4000 w=640 h=480 bpp=0 cbLine=0x140
00:42:53.440 PIIX3 ATA: LUN#0: IDLE IMMEDIATE, CmdIf=0xef (-1 usec ago)
00:42:53.440 PIIX3 ATA: LUN#0: aborting current command
00:42:54.626 Guest Additions information report: additionsVersion = 0x00010004  osType = 0x00033000
00:42:54.742 Guest reported fixed hypervisor window at 0xf6400000 (size = 0x800000, rc = VINF_SUCCESS)
00:42:57.466 Guest requests mouse pointer integration
00:43:01.678 EHCI: Hardware reset
00:43:01.738 EHCI: USB Operational
00:43:02.472 OHCI: Software reset
00:43:02.472 OHCI: USB Reset
00:43:02.473 OHCI: USB Operational
00:43:02.495 Guest adapter information contains unsupported type 5. The block has been skipped.
00:43:02.613 PCNet#0: Init: ss32=1 GCRDRA=0x02fcc420[64] GCTDRA=0x02fcc020[64]
00:43:03.488 Audio: set_record_source ars=0 als=0 (not implemented)
00:43:03.493 Audio: set_record_source ars=0 als=0 (not implemented)
00:43:03.769 SharedFolders host service: connected, u32ClientID = 4
00:43:04.857 PCNet#0: Init: ss32=1 GCRDRA=0x02fcc420[64] GCTDRA=0x02fcc020[64]
00:43:05.688 EHCI: USB Suspended
00:43:05.868 OHCI: USB Suspended
00:43:07.504 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=1a9b4000 w=1280 h=1024 bpp=32 cbLine=0x1400
00:43:07.504 VBVA: Enabled.
00:43:18.459 NAT: DHCP offered IP address 10.0.2.15
00:43:18.480 PCNet#0: Init: ss32=1 GCRDRA=0x02fcc420[64] GCTDRA=0x02fcc020[64]
00:43:52.223 Audio: set_record_source ars=0 als=0 (not implemented)
00:43:52.223 Audio: set_record_source ars=0 als=0 (not implemented)
00:43:58.192 Guest Log: VBoxService: Started.
00:44:33.691 Guest Log: VBoxTray: Started.
00:44:34.326 Guest Additions capability report: (0x5) seamless: yes, hostWindowMapping: no, graphics: yes
00:44:35.946 PCNet#0: Init: ss32=1 GCRDRA=0x02fcc420[64] GCTDRA=0x02fcc020[64]
00:45:26.466 Guest Log: VBOXNP: DLL loaded.
01:05:48.083 Guest Log: VBOXNP: DLL loaded.
01:05:53.424 Guest Log: VBoxService: Ended.
01:05:53.532 PCNet#0: Init: ss32=1 GCRDRA=0x02fcc420[64] GCTDRA=0x02fcc020[64]
01:05:57.480 PIIX3 ATA: LUN#0: IDLE IMMEDIATE, CmdIf=0xe7 (-1 usec ago)
01:05:57.480 PIIX3 ATA: LUN#0: aborting current command
01:05:59.569 Guest disables mouse pointer integration
01:05:59.593 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=1a9b4000 w=1280 h=1024 bpp=0 cbLine=0x400
01:05:59.593 VBVA: Disabled.
01:05:59.594 Changing the VM state from 'RUNNING' to 'RESETTING'.
01:06:00.078 CPUMSetGuestCpuIdFeature: Enabled APIC
01:06:00.078 CPUMSetGuestCpuIdFeature: Disabled x2APIC
01:06:00.079 PIT: mode=3 count=0x10000 (65536) - 18.20 Hz (ch=0)
01:06:00.079 SharedFolders host service: disconnected, u32ClientID = 4
01:06:00.094 PIIX3 ATA: Ctl#0: finished processing RESET
01:06:00.194 PIIX3 ATA: Ctl#1: finished processing RESET
01:06:00.294 ATA: Ctl: finished processing RESET
01:06:00.394 ATA: Ctl: finished processing RESET
01:06:00.495 Audio: set_record_source ars=0 als=0 (not implemented)
01:06:00.506 Changing the VM state from 'RESETTING' to 'RUNNING'.
01:06:00.506 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=00000000 w=9 h=1 bpp=0 cbLine=0x0
01:06:00.508 Guest Log: BIOS: VirtualBox 2.1.2
01:06:00.508 PIT: mode=2 count=0x10000 (65536) - 18.20 Hz (ch=0)
01:06:00.605 PIIX3 ATA: Ctl#0: RESET, DevSel=0 AIOIf=0 CmdIf0=0xe7 (-1 usec ago) CmdIf1=0x00 (-1 usec ago)
01:06:00.605 PIIX3 ATA: Ctl#0: finished processing RESET
01:06:00.606 Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63
01:06:00.606 PIIX3 ATA: Ctl#1: RESET, DevSel=0 AIOIf=0 CmdIf0=0xa0 (-1 usec ago) CmdIf1=0x00 (-1 usec ago)
01:06:00.606 PIIX3 ATA: Ctl#1: finished processing RESET
01:06:00.607 PIT: mode=2 count=0x48d3 (18643) - 64.00 Hz (ch=0)
01:06:00.612 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=1a9b4000 w=640 h=480 bpp=32 cbLine=0xA00
01:06:02.460 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=00000000 w=720 h=400 bpp=0 cbLine=0x0
01:06:02.463 PIT: mode=2 count=0x10000 (65536) - 18.20 Hz (ch=0)
01:06:02.465 Guest Log: BIOS: Boot from Floppy 0 failed
01:06:02.466 Guest Log: BIOS: CDROM boot failure code : 0003
01:06:02.467 Guest Log: BIOS: Boot from CD-ROM failed
01:06:03.856 Guest Log: BIOS: Booting from Hard Disk...
01:06:04.683 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=1a9b4000 w=640 h=480 bpp=0 cbLine=0x140
01:06:04.859 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
01:06:04.859 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
01:06:04.859 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
01:06:04.860 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
01:06:04.860 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
01:06:04.860 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
01:06:04.860 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
01:06:11.104 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=00000000 w=720 h=400 bpp=0 cbLine=0x0
01:06:11.232 PIT: mode=2 count=0xffff (65535) - 18.20 Hz (ch=0)
01:06:11.354 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=1a9b4000 w=640 h=480 bpp=0 cbLine=0x140
01:06:18.064 PIIX3 ATA: LUN#0: IDLE IMMEDIATE, CmdIf=0xef (-1 usec ago)
01:06:18.064 PIIX3 ATA: LUN#0: aborting current command
01:06:19.268 Guest Additions information report: additionsVersion = 0x00010004  osType = 0x00033000
01:06:19.348 Guest reported fixed hypervisor window at 0xf6400000 (size = 0x800000, rc = VINF_SUCCESS)
01:06:22.429 Guest requests mouse pointer integration
01:06:27.588 EHCI: Hardware reset
01:06:27.606 EHCI: USB Operational
01:06:28.509 OHCI: Software reset
01:06:28.510 OHCI: USB Reset
01:06:28.510 OHCI: USB Operational
01:06:28.531 Guest adapter information contains unsupported type 5. The block has been skipped.
01:06:28.635 PCNet#0: Init: ss32=1 GCRDRA=0x02eb7420[64] GCTDRA=0x02eb7020[64]
01:06:29.350 Audio: set_record_source ars=0 als=0 (not implemented)
01:06:29.356 Audio: set_record_source ars=0 als=0 (not implemented)
01:06:29.608 SharedFolders host service: connected, u32ClientID = 7
01:06:30.693 PCNet#0: Init: ss32=1 GCRDRA=0x02eb7420[64] GCTDRA=0x02eb7020[64]
01:06:31.067 EHCI: USB Suspended
01:06:31.077 OHCI: USB Suspended
01:06:32.242 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=1a9b4000 w=1280 h=1024 bpp=32 cbLine=0x1400
01:06:32.242 VBVA: Enabled.
01:06:58.142 NAT: DHCP offered IP address 10.0.2.15
01:06:58.168 PCNet#0: Init: ss32=1 GCRDRA=0x02eb7420[64] GCTDRA=0x02eb7020[64]
01:07:37.598 Audio: set_record_source ars=0 als=0 (not implemented)
01:07:37.599 Audio: set_record_source ars=0 als=0 (not implemented)
01:07:43.659 Guest Log: VBoxTray: Started.
01:07:45.424 Guest Additions capability report: (0x5) seamless: yes, hostWindowMapping: no, graphics: yes
01:07:45.945 Guest Log: VBoxService: Started.
01:07:56.518 PCNet#0: Init: ss32=1 GCRDRA=0x02eb7420[64] GCTDRA=0x02eb7020[64]
01:08:38.027 Guest Log: VBOXNP: DLL loaded.
01:42:41.050 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=1a9b4000 w=640 h=480 bpp=16 cbLine=0x500
01:42:41.064 VBVA: Disabled.
01:42:41.065 VBVA: Enabled.
01:42:41.622 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=1a9b4000 w=640 h=480 bpp=32 cbLine=0xA00
01:42:41.622 VBVA: Disabled.
01:42:41.623 VBVA: Enabled.
01:42:42.114 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=1a9b4000 w=1280 h=1024 bpp=32 cbLine=0x1400
01:42:42.115 VBVA: Disabled.
01:42:42.116 VBVA: Enabled.
01:52:38.621 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=1a9b4000 w=1400 h=1050 bpp=32 cbLine=0x15E0
01:52:38.621 VBVA: Disabled.
01:52:38.623 VBVA: Enabled.
01:53:50.784 Guest Log: VBOXNP: DLL loaded.
01:53:57.433 PCNet#0: Init: ss32=1 GCRDRA=0x02eb7420[64] GCTDRA=0x02eb7020[64]
01:53:57.998 Guest Log: VBoxService: Ended.
01:54:04.415 PIIX3 ATA: LUN#0: IDLE IMMEDIATE, CmdIf=0xe7 (-1 usec ago)
01:54:04.415 PIIX3 ATA: LUN#0: aborting current command
01:54:06.417 Guest requests the VM to be turned off
01:54:06.417 ****************** Guest state at power off ******************
01:54:06.417 Guest CPUM state: se
01:54:06.417 eax=02f5f06c ebx=8266f468 ecx=00000000 edx=0000c040 esi=82b5f06c edi=00000000
01:54:06.417 eip=f77d4eb1 esp=f3d53c94 ebp=f3d53c9c iopl=0         nv up ei pl nz na po nc
01:54:06.417 cs={0008 base=0000000000000000 limit=ffffffff flags=0000c09b} dr0=00000000 dr1=00000000
01:54:06.417 ds={0023 base=0000000000000000 limit=ffffffff flags=0000c0f3} dr2=00000000 dr3=00000000
01:54:06.417 es={0023 base=0000000000000000 limit=ffffffff flags=0000c0f3} dr4=00000000 dr5=00000000
01:54:06.417 fs={0030 base=00000000ffdff000 limit=00001fff flags=0000c093} dr6=ffff0ff0 dr7=00000400
01:54:06.417 gs={0000 base=0000000000000000 limit=ffffffff flags=0001c000} cr0=e001003b cr2=f77fc000
01:54:06.417 ss={0010 base=0000000000000000 limit=ffffffff flags=0000c093} cr3=00039000 cr4=000006d9
01:54:06.417 gdtr=000000008003f000:03ff  idtr=000000008003f400:07ff  eflags=00000246
01:54:06.417 ldtr={0000 base=00000000 limit=00000000 flags=00000082}
01:54:06.417 tr  ={0028 base=80042000 limit=000020ab flags=0000008b}
01:54:06.417 SysEnter={cs=0008 eip=804de6f0 esp=f7968000}
01:54:06.418 FPU:
01:54:06.418 FCW=027f FSW=0000 FTW=00
01:54:06.418 res1=00 FOP=0000 FPUIP=00000000 CS=0000 Rsvrd1=0000
01:54:06.418 FPUDP=0000 DS=0000 Rsvrd2=0000 MXCSR=00001f80 MXCSR_MASK=0000ffff
01:54:06.418 MSR:
01:54:06.418 EFER         =0000000000000000
01:54:06.418 PAT          =0007010600070106
01:54:06.418 STAR         =0000000000000000
01:54:06.418 CSTAR        =0000000000000000
01:54:06.418 LSTAR        =0000000000000000
01:54:06.418 SFMASK       =0000000000000000
01:54:06.418 KERNELGSBASE =0000000000000000
01:54:06.418 ***
01:54:06.418 Guest paging mode:  32-bit, changed 1844 times, A20 enabled
01:54:06.418 Shadow paging mode: PAE
01:54:06.418 Host paging mode:   AMD64+NX
01:54:06.418 ***
01:54:06.418 Active Timers (pVM=161fa000)
01:54:06.418 pTimerR3 offNext  offPrev  offSched Clock Time               Expire             State                     Description
01:54:06.418 19674430 fffec4b0 00000000 00000000 Real  000000000049858052 000000000049858054 ACTIVE                    EMT Yielder
01:54:06.418 196608e0 00000000 00013b50 00000000 Real  000000000049858052 000000000049858063 ACTIVE                    VGA Refresh Timer
01:54:06.418 196705c0 00000000 00000000 00000000 Virt  000006843755498014 000006843757000323 ACTIVE                    Audio timer
01:54:06.418 1965b030 00000330 00000000 00000000 VrSy  000006843755509954 000006843760661652 ACTIVE                    i8254 Programmable Interval Timer
01:54:06.418 1965b360 00000050 fffffcd0 00000000 VrSy  000006843755521841 000006843765625001 ACTIVE                    MC146818 RTC/CMOS - Periodic
01:54:06.418 1965b3b0 00018350 ffffffb0 00000000 VrSy  000006843755533644 000006843990000000 ACTIVE                    MC146818 RTC/CMOS - Second
01:54:06.418 19673700 00000000 fffe7cb0 00000000 VrSy  000006843755545197 000007556573999004 ACTIVE                    ACPI Timer
01:54:06.418 ***
01:54:06.418 Shadow GDT (GCAddr=a0410000):
01:54:06.418 ffd8 - 08f80087 a0008901 - base=a00108f8 limit=00000087 dpl=0 TSS32Avail Present 16-bit  HyperTSSTrap08
01:54:06.418 ffe0 - 08700087 a0008901 - base=a0010870 limit=00000087 dpl=0 TSS32Avail Present 16-bit  HyperTSS
01:54:06.418 ffe8 - 0000ffff 00af9b00 - base=00000000 limit=ffffffff dpl=0 CodeER Accessed Present Page 16-bit  HyperCS64
01:54:06.418 fff0 - 0000ffff 00cf9300 - base=00000000 limit=ffffffff dpl=0 DataRW Accessed Present Page 32-bit  HyperDS
01:54:06.418 fff8 - 0000ffff 00cf9b00 - base=00000000 limit=ffffffff dpl=0 CodeER Accessed Present Page 32-bit  HyperCS
01:54:06.418 ***
01:54:06.418 ************** End of Guest state at power off ***************
01:54:06.418 Changing the VM state from 'RUNNING' to 'OFF'.
01:54:06.419 Console::powerDown(): A request to power off the VM has been issued (mMachineState=8, InUninit=0)
01:54:06.451 SharedFolders host service: disconnected, u32ClientID = 7
01:54:06.456 Changing the VM state from 'OFF' to 'DESTROYING'.
01:54:07.039 ************************* Statistics *************************
01:54:07.039 /Devices/ATA0/Unit0/AtapiDMA            0 times
01:54:07.039 /Devices/ATA0/Unit0/AtapiPIO            0 times
01:54:07.039 /Devices/ATA0/Unit0/DMA            188796 times
01:54:07.039 /Devices/ATA0/Unit0/PIO             18796 times
01:54:07.039 /Devices/ATA0/Unit0/ReadBytes    1624089088 bytes
01:54:07.039 /Devices/ATA0/Unit0/WrittenBytes 3276881408 bytes
01:54:07.039 /Devices/ATA0/Unit1/AtapiDMA            0 times
01:54:07.039 /Devices/ATA0/Unit1/AtapiPIO            0 times
01:54:07.039 /Devices/ATA0/Unit1/DMA                 0 times
01:54:07.039 /Devices/ATA0/Unit1/PIO                 0 times
01:54:07.039 /Devices/ATA0/Unit1/ReadBytes           0 bytes
01:54:07.039 /Devices/ATA0/Unit1/WrittenBytes        0 bytes
01:54:07.039 /Devices/ATA1/Unit0/AtapiDMA            0 times
01:54:07.039 /Devices/ATA1/Unit0/AtapiPIO         6918 times
01:54:07.039 /Devices/ATA1/Unit0/DMA                 0 times
01:54:07.039 /Devices/ATA1/Unit0/PIO                 0 times
01:54:07.039 /Devices/ATA1/Unit0/ReadBytes           0 bytes
01:54:07.039 /Devices/ATA1/Unit0/WrittenBytes        0 bytes
01:54:07.039 /Devices/ATA1/Unit1/AtapiDMA            0 times
01:54:07.039 /Devices/ATA1/Unit1/AtapiPIO            0 times
01:54:07.039 /Devices/ATA1/Unit1/DMA                 0 times
01:54:07.039 /Devices/ATA1/Unit1/PIO                 0 times
01:54:07.039 /Devices/ATA1/Unit1/ReadBytes           0 bytes
01:54:07.039 /Devices/ATA1/Unit1/WrittenBytes        0 bytes
01:54:07.039 /Devices/PCNet0/ReceiveBytes     234899045 bytes
01:54:07.039 /Devices/PCNet0/TransmitBytes     6519237 bytes
01:54:07.039 /Devices/SATA/Port0/DMA                 0 times
01:54:07.039 /Devices/SATA/Port0/IORequestsPerSecond        0 times
01:54:07.039 /Devices/SATA/Port0/ReadBytes           0 bytes
01:54:07.039 /Devices/SATA/Port0/WrittenBytes        0 bytes
01:54:07.039 /Devices/SATA/Port1/DMA                 0 times
01:54:07.039 /Devices/SATA/Port1/IORequestsPerSecond        0 times
01:54:07.039 /Devices/SATA/Port1/ReadBytes           0 bytes
01:54:07.039 /Devices/SATA/Port1/WrittenBytes        0 bytes
01:54:07.039 /Devices/SATA/Port10/DMA                0 times
01:54:07.039 /Devices/SATA/Port10/IORequestsPerSecond        0 times
01:54:07.039 /Devices/SATA/Port10/ReadBytes          0 bytes
01:54:07.039 /Devices/SATA/Port10/WrittenBytes        0 bytes
01:54:07.039 /Devices/SATA/Port11/DMA                0 times
01:54:07.039 /Devices/SATA/Port11/IORequestsPerSecond        0 times
01:54:07.039 /Devices/SATA/Port11/ReadBytes          0 bytes
01:54:07.039 /Devices/SATA/Port11/WrittenBytes        0 bytes
01:54:07.040 /Devices/SATA/Port12/DMA                0 times
01:54:07.040 /Devices/SATA/Port12/IORequestsPerSecond        0 times
01:54:07.040 /Devices/SATA/Port12/ReadBytes          0 bytes
01:54:07.040 /Devices/SATA/Port12/WrittenBytes        0 bytes
01:54:07.040 /Devices/SATA/Port13/DMA                0 times
01:54:07.040 /Devices/SATA/Port13/IORequestsPerSecond        0 times
01:54:07.040 /Devices/SATA/Port13/ReadBytes          0 bytes
01:54:07.040 /Devices/SATA/Port13/WrittenBytes        0 bytes
01:54:07.040 /Devices/SATA/Port14/DMA                0 times
01:54:07.040 /Devices/SATA/Port14/IORequestsPerSecond        0 times
01:54:07.040 /Devices/SATA/Port14/ReadBytes          0 bytes
01:54:07.040 /Devices/SATA/Port14/WrittenBytes        0 bytes
01:54:07.040 /Devices/SATA/Port15/DMA                0 times
01:54:07.040 /Devices/SATA/Port15/IORequestsPerSecond        0 times
01:54:07.040 /Devices/SATA/Port15/ReadBytes          0 bytes
01:54:07.040 /Devices/SATA/Port15/WrittenBytes        0 bytes
01:54:07.040 /Devices/SATA/Port16/DMA                0 times
01:54:07.040 /Devices/SATA/Port16/IORequestsPerSecond        0 times
01:54:07.040 /Devices/SATA/Port16/ReadBytes          0 bytes
01:54:07.040 /Devices/SATA/Port16/WrittenBytes        0 bytes
01:54:07.040 /Devices/SATA/Port17/DMA                0 times
01:54:07.040 /Devices/SATA/Port17/IORequestsPerSecond        0 times
01:54:07.040 /Devices/SATA/Port17/ReadBytes          0 bytes
01:54:07.040 /Devices/SATA/Port17/WrittenBytes        0 bytes
01:54:07.040 /Devices/SATA/Port18/DMA                0 times
01:54:07.040 /Devices/SATA/Port18/IORequestsPerSecond        0 times
01:54:07.040 /Devices/SATA/Port18/ReadBytes          0 bytes
01:54:07.040 /Devices/SATA/Port18/WrittenBytes        0 bytes
01:54:07.040 /Devices/SATA/Port19/DMA                0 times
01:54:07.040 /Devices/SATA/Port19/IORequestsPerSecond        0 times
01:54:07.040 /Devices/SATA/Port19/ReadBytes          0 bytes
01:54:07.040 /Devices/SATA/Port19/WrittenBytes        0 bytes
01:54:07.040 /Devices/SATA/Port2/DMA                 0 times
01:54:07.040 /Devices/SATA/Port2/IORequestsPerSecond        0 times
01:54:07.040 /Devices/SATA/Port2/ReadBytes           0 bytes
01:54:07.040 /Devices/SATA/Port2/WrittenBytes        0 bytes
01:54:07.040 /Devices/SATA/Port20/DMA                0 times
01:54:07.040 /Devices/SATA/Port20/IORequestsPerSecond        0 times
01:54:07.040 /Devices/SATA/Port20/ReadBytes          0 bytes
01:54:07.040 /Devices/SATA/Port20/WrittenBytes        0 bytes
01:54:07.040 /Devices/SATA/Port21/DMA                0 times
01:54:07.040 /Devices/SATA/Port21/IORequestsPerSecond        0 times
01:54:07.040 /Devices/SATA/Port21/ReadBytes          0 bytes
01:54:07.040 /Devices/SATA/Port21/WrittenBytes        0 bytes
01:54:07.040 /Devices/SATA/Port22/DMA                0 times
01:54:07.040 /Devices/SATA/Port22/IORequestsPerSecond        0 times
01:54:07.040 /Devices/SATA/Port22/ReadBytes          0 bytes
01:54:07.040 /Devices/SATA/Port22/WrittenBytes        0 bytes
01:54:07.040 /Devices/SATA/Port23/DMA                0 times
01:54:07.040 /Devices/SATA/Port23/IORequestsPerSecond        0 times
01:54:07.040 /Devices/SATA/Port23/ReadBytes          0 bytes
01:54:07.040 /Devices/SATA/Port23/WrittenBytes        0 bytes
01:54:07.040 /Devices/SATA/Port24/DMA                0 times
01:54:07.040 /Devices/SATA/Port24/IORequestsPerSecond        0 times
01:54:07.040 /Devices/SATA/Port24/ReadBytes          0 bytes
01:54:07.040 /Devices/SATA/Port24/WrittenBytes        0 bytes
01:54:07.040 /Devices/SATA/Port25/DMA                0 times
01:54:07.040 /Devices/SATA/Port25/IORequestsPerSecond        0 times
01:54:07.040 /Devices/SATA/Port25/ReadBytes          0 bytes
01:54:07.040 /Devices/SATA/Port25/WrittenBytes        0 bytes
01:54:07.040 /Devices/SATA/Port26/DMA                0 times
01:54:07.040 /Devices/SATA/Port26/IORequestsPerSecond        0 times
01:54:07.040 /Devices/SATA/Port26/ReadBytes          0 bytes
01:54:07.040 /Devices/SATA/Port26/WrittenBytes        0 bytes
01:54:07.040 /Devices/SATA/Port27/DMA                0 times
01:54:07.040 /Devices/SATA/Port27/IORequestsPerSecond        0 times
01:54:07.040 /Devices/SATA/Port27/ReadBytes          0 bytes
01:54:07.040 /Devices/SATA/Port27/WrittenBytes        0 bytes
01:54:07.040 /Devices/SATA/Port28/DMA                0 times
01:54:07.040 /Devices/SATA/Port28/IORequestsPerSecond        0 times
01:54:07.040 /Devices/SATA/Port28/ReadBytes          0 bytes
01:54:07.040 /Devices/SATA/Port28/WrittenBytes        0 bytes
01:54:07.040 /Devices/SATA/Port29/DMA                0 times
01:54:07.040 /Devices/SATA/Port29/IORequestsPerSecond        0 times
01:54:07.040 /Devices/SATA/Port29/ReadBytes          0 bytes
01:54:07.040 /Devices/SATA/Port29/WrittenBytes        0 bytes
01:54:07.040 /Devices/SATA/Port3/DMA                 0 times
01:54:07.040 /Devices/SATA/Port3/IORequestsPerSecond        0 times
01:54:07.040 /Devices/SATA/Port3/ReadBytes           0 bytes
01:54:07.040 /Devices/SATA/Port3/WrittenBytes        0 bytes
01:54:07.040 /Devices/SATA/Port4/DMA                 0 times
01:54:07.040 /Devices/SATA/Port4/IORequestsPerSecond        0 times
01:54:07.040 /Devices/SATA/Port4/ReadBytes           0 bytes
01:54:07.040 /Devices/SATA/Port4/WrittenBytes        0 bytes
01:54:07.040 /Devices/SATA/Port5/DMA                 0 times
01:54:07.040 /Devices/SATA/Port5/IORequestsPerSecond        0 times
01:54:07.040 /Devices/SATA/Port5/ReadBytes           0 bytes
01:54:07.040 /Devices/SATA/Port5/WrittenBytes        0 bytes
01:54:07.040 /Devices/SATA/Port6/DMA                 0 times
01:54:07.040 /Devices/SATA/Port6/IORequestsPerSecond        0 times
01:54:07.040 /Devices/SATA/Port6/ReadBytes           0 bytes
01:54:07.040 /Devices/SATA/Port6/WrittenBytes        0 bytes
01:54:07.040 /Devices/SATA/Port7/DMA                 0 times
01:54:07.040 /Devices/SATA/Port7/IORequestsPerSecond        0 times
01:54:07.040 /Devices/SATA/Port7/ReadBytes           0 bytes
01:54:07.040 /Devices/SATA/Port7/WrittenBytes        0 bytes
01:54:07.040 /Devices/SATA/Port8/DMA                 0 times
01:54:07.040 /Devices/SATA/Port8/IORequestsPerSecond        0 times
01:54:07.040 /Devices/SATA/Port8/ReadBytes           0 bytes
01:54:07.040 /Devices/SATA/Port8/WrittenBytes        0 bytes
01:54:07.040 /Devices/SATA/Port9/DMA                 0 times
01:54:07.040 /Devices/SATA/Port9/IORequestsPerSecond        0 times
01:54:07.040 /Devices/SATA/Port9/ReadBytes           0 bytes
01:54:07.040 /Devices/SATA/Port9/WrittenBytes        0 bytes
01:54:07.040 /GVMM/Sum/HaltBlocking             753449 calls
01:54:07.040 /GVMM/Sum/HaltCalls              13426858 calls
01:54:07.040 /GVMM/Sum/HaltNotBlocking        12673409 calls
01:54:07.040 /GVMM/Sum/HaltTimeouts             424870 calls
01:54:07.040 /GVMM/Sum/HaltWakeUps                   0 calls
01:54:07.040 /GVMM/Sum/PollCalls                   989 calls
01:54:07.040 /GVMM/Sum/PollHalts                     0 calls
01:54:07.040 /GVMM/Sum/PollWakeUps                   0 calls
01:54:07.040 /GVMM/Sum/WakeUpCalls              390738 calls
01:54:07.040 /GVMM/Sum/WakeUpNotHalted          366175 calls
01:54:07.040 /GVMM/Sum/WakeUpWakeUps                 0 calls
01:54:07.040 /GVMM/VM/HaltBlocking              753449 calls
01:54:07.040 /GVMM/VM/HaltCalls               13426858 calls
01:54:07.040 /GVMM/VM/HaltNotBlocking         12673409 calls
01:54:07.040 /GVMM/VM/HaltTimeouts              424870 calls
01:54:07.040 /GVMM/VM/HaltWakeUps                    0 calls
01:54:07.040 /GVMM/VM/PollCalls                    989 calls
01:54:07.040 /GVMM/VM/PollHalts                      0 calls
01:54:07.040 /GVMM/VM/PollWakeUps                    0 calls
01:54:07.040 /GVMM/VM/WakeUpCalls               390738 calls
01:54:07.040 /GVMM/VM/WakeUpNotHalted           366175 calls
01:54:07.040 /GVMM/VM/WakeUpWakeUps                  0 calls
01:54:07.040 /GVMM/VMs                               1 calls
01:54:07.040 /MM/HyperHeap/cbFree               382448 bytes
01:54:07.040 /MM/HyperHeap/cbHeap               655296 bytes
01:54:07.040 /PDM/VUSB0/cUrbsInPool                  0 count
01:54:07.040 /PDM/VUSB1/cUrbsInPool                  0 count
01:54:07.040 /PGM/cGuestModeChanges               1844 times
01:54:07.040 /PROF/EM/ForcedActions              42194 ticks/call ( 68139804772 ticks, 1614915 times, max 2066764557, min      65)
01:54:07.040 /PROF/EM/Halted                  18847655 ticks/call (4186083101564 ticks,  222101 times, max 128062298, min     182)
01:54:07.040 /PROF/EM/RAWTotal                       0 ticks/call (           0 ticks,       0 times, max         0, min      -1)
01:54:07.040 /PROF/EM/REMTotal                 9779179 ticks/call ( 26931860995 ticks,    2754 times, max 424134958, min  755573)
01:54:07.040 /PROF/EM/Total                   14792407061237 ticks/call (14792407061237 ticks,       1 times, max 14792407061237, min 14792407061237)
01:54:07.040 /PROF/VM/Halt/Block                304415 ticks/call (4087347990544 ticks, 13426857 times, max 127912577, min    6942)
01:54:07.040 /PROF/VM/Halt/Poll                    182 ticks/call (  3473756312 ticks, 18986190 times, max   6747559, min      65)
01:54:07.040 /PROF/VM/Halt/Timers                 3297 ticks/call ( 62612201210 ticks, 18986190 times, max  45829290, min    1495)
01:54:07.040 /PROF/VM/Halt/Yield                 11295 ticks/call (    11171147 ticks,     989 times, max    120458, min    7306)
01:54:07.040 /TM/R0/1nsSteps                    210703 times
01:54:07.040 /TM/R3/1nsSteps                    910250 times
01:54:07.040 /TM/VirtualSync/CurrentOffset           0 ns
01:54:07.040 ********************* End of statistics **********************
01:54:07.293 Changing the VM state from 'DESTROYING' to 'TERMINATED'.

Post by **sandervl** » 28. Jan 2009, 16:14

It is not useful to post logs here. Was my previous request unclear in any way?

nabamer. · Post by **nabamer.** » 28. Jan 2009, 16:22

I am sorry, I just saw the log before and postet mine.

But I have a guess what it could be.
There is no driver installed for VEN_8086 DEV_2829, which equals the Intel AHCI Controller. That could be an explanation.

Post by **sandervl** » 28. Jan 2009, 16:24

nabamer. wrote:I am sorry, I just saw the log before and postet mine.

But I have a guess what it could be.
There is no driver installed for VEN_8086 DEV_2829, which equals the Intel AHCI Controller. That could be an explanation.

You can find out by disabling the SATA controller for your VM and see if that helps.

marco · Post by **marco** » 28. Jan 2009, 18:30

sandervl wrote:It is not useful to post logs here. Was my previous request unclear in any way?

Your post was clear, even in telling that the usual "forum" password was not enough and an additional registration was required.

Ok I'm lazy (and I try to have as less accounts and passwords as possibile), it's my fault, but I thought you could easily copy/paste the logs and deliver them to the proper destination, as you have actually already done by creating the defect page itself...

rm53 · Post by **rm53** » 28. Jan 2009, 18:49

my personal benchmark (reading in a text file 0.6MB and then doing some computations) runs equally fast as in earlier versions (my last VB was 2.0.2) and almost as fast as natively in Leopard.

My benchmark does not include any graphic work.

nabamer. · Post by **nabamer.** » 28. Jan 2009, 18:58

Disabling SATA-Controller did not speed anything up. What did was setting System->"Perfomance Options"->"Visual Effects"->"Adjust for best performance"
This disables all the nitty effects which difference XP from Win2000. Afterwards, the system does respond a lot faster, almost natural. Guess it is some kind of graphics problem then.

marco · Post by **marco** » 29. Jan 2009, 10:05

I also noticed that in my XP32 host the VB interface itself seems to respond slower than usual to clicks. For example, the time between the click on a VM name in the VM list and the VM name highlighting (+ display of the VM's properties on the right side of the window) seems to be longer than before.

I did not measure it in VB210, though.
Maybe it's just a sensation of mine.

Trundicho · Post by **Trundicho** » 29. Jan 2009, 12:43

same problem here!

VBOX 2.1.2 => CPU (macbook 2.4ghz) usage 100% on every little action

checked: no pio mode
checked: guestadditions 2.1.2 installed

E · Post by E » 30. Jan 2009, 02:27

Same problems here. Upgraded to v2.1.2 and began experiencing much longer boot times for XP guest. XP is much more unstable. Programs crash.

I tried to install 2.1.0 over 2.1.2 and had the same problem with the xml file, etc.

If anyone figures out how to roll back, please post instructions here. Thanks.

Post by **tkwm** » 30. Jan 2009, 03:11

To roll back -
first deinstall the guest-additions from within the guest-OS,
shut down the guest-OS,
then remove the virtual disk from the vm,
delete the vm or manually delete $HOME/Library/VirtualBox/Machines/(machine),
run the uninstall-script provided with the virtualbox-installation-package,
install the previous or the wanted vitualbox-version,
add a new vm and select the previous removed virtual-disc,
install the guest-additions again.

E · Post by E » 30. Jan 2009, 05:00

Fortunately, I run Time Machine regularly. I decided to completely delete $HOME/Library/VirtualBox and restore a backup copy from before my upgrade to 2.1.2. After restoring the entire VirtualBox folder, I installed 2.1.0. Everything is much more stable so far.

To me, the biggest difference is that in 2.1.2, it shows the battery status on the XP guest. I noticed that when I booted the XP guest in 2.1.2, the portion of the screen in the XP guest where the battery icon appears (lower right corner in tray) didn't "paint" very well. It was often blacked out.

Anyway, I'm going to stay on 2.1.0 until I see another release come out.

marco · Post by **marco** » 30. Jan 2009, 08:57

I hope the developers will quickly set up, if not a new release, a patch to install over 212 to solve the problem (automatic revert to 210 preserving 212 snapshots and saved states?) or at least a workaround to achieve reasonable graphic perfomance while waiting for a new release.

The idea of waiting for three/four weeks, until next scheduled upgrade, without being able to actually work with my VMs is quite sad and hardly acceptable.

nabamer. · Post by **nabamer.** » 30. Jan 2009, 12:27

I am somehow happy that it is a release problem and not a generic VB problem. I'm still using Parallels, but I want to switch to VB. Guess I have to use it some weeks more.

E · Post by E » 30. Jan 2009, 22:51

nabamer. wrote:I am somehow happy that it is a release problem and not a generic VB problem. I'm still using Parallels, but I want to switch to VB. Guess I have to use it some weeks more.

You can make the switch now if you want. Just install 2.1.0 and not 2.1.2. Overall my experience with VBox has been outstanding. It is a wonderful piece of software. I'm hooked.

virtualbox.org

VBox 2.1.2 performance decrease

not slower for me