VMs Crash Intermittantly
Posted: 6. Jun 2009, 06:50
I run 2 VirtualBox VMs from my Ubuntu home server 24x7. Unfortunately they crash intermittently, and I've never been able to figure out why. Can anyone tell what might be happening from this log output? The host OS is 64-bit Ubuntu 9, and the VM OS's are 32-bit Solaris and Windows XP. I've been running all of the latest versions of VirtualBox and the OS's. Unfortunately with every patch I hope the problem will stop, but it never does.
Code: Select all
06:50:37.794 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=00007f08f7257000 w=1152 h=883 bpp=0 cbLine=0x200
06:50:37.818 VBVA: Disabled.
06:50:37.872 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=00007f08f7257000 w=640 h=480 bpp=0 cbLine=0x140
06:50:38.045 Changing the VM state from 'RUNNING' to 'RESETTING'.
06:50:38.128 CPUMSetGuestCpuIdFeature: Enabled APIC
06:50:38.128 CPUMSetGuestCpuIdFeature: Disabled x2APIC
06:50:38.128 PIT: mode=3 count=0x10000 (65536) - 18.20 Hz (ch=0)
06:50:38.128 SharedFolders host service: disconnected, u32ClientID = 1
06:50:38.129 VBoxX11ClipboardStopX11: stopping the shared clipboard X11 backend
06:50:38.129 Shared clipboard: host clipboard thread terminated successfully
06:50:38.167 PIIX3 ATA: Ctl#0: finished processing RESET
06:50:38.267 PIIX3 ATA: Ctl#1: finished processing RESET
06:50:38.368 Changing the VM state from 'RESETTING' to 'RUNNING'.
06:50:38.374 Guest Log: BIOS: VirtualBox 2.2.4
06:50:38.375 PIT: mode=2 count=0x10000 (65536) - 18.20 Hz (ch=0)
06:50:38.406 PIIX3 ATA: Ctl#0: RESET, DevSel=0 AIOIf=0 CmdIf0=0xe0 (-1 usec ago) CmdIf1=0x00 (-1 usec ago)
06:50:38.406 PIIX3 ATA: Ctl#0: finished processing RESET
06:50:38.417 Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63
06:50:38.418 PIIX3 ATA: Ctl#1: RESET, DevSel=0 AIOIf=0 CmdIf0=0xa0 (-1 usec ago) CmdIf1=0x00 (-1 usec ago)
06:50:38.418 PIIX3 ATA: Ctl#1: finished processing RESET
06:50:38.419 PIT: mode=2 count=0x48d3 (18643) - 64.00 Hz (ch=0)
06:50:38.438 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=00007f08f7257000 w=640 h=480 bpp=32 cbLine=0xA00
06:50:40.552 PIT: mode=2 count=0x10000 (65536) - 18.20 Hz (ch=0)
06:50:40.554 Guest Log: BIOS: Boot from Floppy 0 failed
06:50:40.556 Guest Log: BIOS: CDROM boot failure code : 0004
06:50:40.556 Guest Log: BIOS: Boot from CD-ROM failed
06:50:40.557 Guest Log: BIOS: Booting from Hard Disk...
06:50:40.572 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=0000000000000000 w=720 h=400 bpp=0 cbLine=0x0
06:50:40.828 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
06:50:40.829 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
06:50:40.829 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
06:50:40.830 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
06:50:40.830 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
06:50:40.831 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
06:50:40.831 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
06:50:42.431 PIT: mode=2 count=0x2ead (11949) - 99.85 Hz (ch=0)
CPUMSetGuestCpuIdFeature: Disabled x2APIC
06:50:42.740 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=00007f08f7257000 w=640 h=480 bpp=0 cbLine=0x140
06:50:44.138 PIIX3 ATA: LUN#0: IDLE IMMEDIATE, CmdIf=0xef (-1 usec ago)
06:50:44.138 PIIX3 ATA: LUN#0: aborting current command
06:50:45.209 Guest Additions information report: additionsVersion = 0x00010004 osType = 0x00033000
06:50:45.247 Guest reported fixed hypervisor window at 0x00000000f6400000 (size = 0xc00000, rc = VINF_SUCCESS)
06:50:49.817 Guest requests mouse pointer integration
06:51:06.035 PCNet#0: Init: ss32=1 GCRDRA=0x065a1420[64] GCTDRA=0x065a1020[64]
06:51:06.331 SharedFolders host service: connected, u32ClientID = 4
06:51:06.425 PCNet#0: Init: ss32=1 GCRDRA=0x065a1420[64] GCTDRA=0x065a1020[64]
06:51:15.920 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=00007f08f7257000 w=1152 h=883 bpp=32 cbLine=0x1200
06:51:15.921 VBVA: Enabled.
06:51:17.030 Guest Log: VBoxService: Started.
06:51:17.042 Guest Log: vboxTimeSyncThread: Started.
06:51:19.709 PCNet#0: Init: ss32=1 GCRDRA=0x065a1420[64] GCTDRA=0x065a1020[64]
06:51:24.142 Guest Log: VBoxTray: Started.
06:51:24.653 AssertLogRel /home/vbox/vbox-2.2.4/src/VBox/HostServices/SharedClipboard/x11-clipboard.cpp(212) int vboxClipboardConnect(VBOXCLIPBOARDCLIENTDATA*): g_ctxHost.pClient == 0
06:51:24.704 Guest Additions capability report: (0x5) seamless: yes, hostWindowMapping: no, graphics: yes
06:51:25.098 PATM: Disable block at 86616244 - write 8661634a-8661634e
06:51:40.172 Guest Log: VBOXNP: DLL loaded.
06:51:41.019 Guest Log: VBOXNP: DLL unloaded.
06:51:41.044 Guest Log: VBOXNP: DLL loaded.
06:51:41.172 Guest Log: VBOXNP: DLL loaded.
06:51:41.218 Guest Log: VBOXNP: DLL unloaded.
06:51:44.053 PCNet#0: Init: ss32=1 GCRDRA=0x065a1420[64] GCTDRA=0x065a1020[64]
06:51:51.396 Guest Log: VBOXNP: DLL loaded.
07:25:19.852 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=00007f08f7257000 w=1152 h=883 bpp=0 cbLine=0x200
07:25:19.852 VBVA: Disabled.
07:25:20.069 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=00007f08f7257000 w=640 h=480 bpp=0 cbLine=0x140
07:25:20.187 Changing the VM state from 'RUNNING' to 'RESETTING'.
07:25:20.289 CPUMSetGuestCpuIdFeature: Enabled APIC
07:25:20.289 CPUMSetGuestCpuIdFeature: Disabled x2APIC
07:25:20.289 PIT: mode=3 count=0x10000 (65536) - 18.20 Hz (ch=0)
07:25:20.299 SharedFolders host service: disconnected, u32ClientID = 4
07:25:20.353 PIIX3 ATA: Ctl#0: finished processing RESET
07:25:20.454 PIIX3 ATA: Ctl#1: finished processing RESET
07:25:20.554 TM: Aborting catch-up attempt on reset with a 497788073 ns lag on reset; new total: 497788073 ns
07:25:20.556 Changing the VM state from 'RESETTING' to 'RUNNING'.
07:25:20.561 Guest Log: BIOS: VirtualBox 2.2.4
07:25:20.561 PIT: mode=2 count=0x10000 (65536) - 18.20 Hz (ch=0)
07:25:20.589 PIIX3 ATA: Ctl#0: RESET, DevSel=0 AIOIf=0 CmdIf0=0xe0 (-1 usec ago) CmdIf1=0x00 (-1 usec ago)
07:25:20.589 PIIX3 ATA: Ctl#0: finished processing RESET
07:25:20.591 Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63
07:25:20.592 PIIX3 ATA: Ctl#1: RESET, DevSel=0 AIOIf=0 CmdIf0=0xa0 (-1 usec ago) CmdIf1=0x00 (-1 usec ago)
07:25:20.592 PIIX3 ATA: Ctl#1: finished processing RESET
07:25:20.594 PIT: mode=2 count=0x48d3 (18643) - 64.00 Hz (ch=0)
07:25:20.606 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=00007f08f7257000 w=640 h=480 bpp=32 cbLine=0xA00
07:25:23.068 PIT: mode=2 count=0x10000 (65536) - 18.20 Hz (ch=0)
07:25:23.071 Guest Log: BIOS: Boot from Floppy 0 failed
07:25:23.072 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=0000000000000000 w=720 h=400 bpp=0 cbLine=0x0
07:25:23.099 Guest Log: BIOS: CDROM boot failure code : 0004
07:25:23.099 Guest Log: BIOS: Boot from CD-ROM failed
07:25:23.147 Guest Log: BIOS: Booting from Hard Disk...
07:25:23.491 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
07:25:23.491 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
07:25:23.491 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
07:25:23.492 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
07:25:23.492 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
07:25:23.492 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
07:25:23.493 Guest Log: BIOS: int13_harddisk: function 02, parameters out of range 0000/0000/0001!
07:25:25.403 PIT: mode=2 count=0x2ead (11949) - 99.85 Hz (ch=0)
CPUMSetGuestCpuIdFeature: Disabled x2APIC
07:25:25.685 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=00007f08f7257000 w=640 h=480 bpp=0 cbLine=0x140
07:25:27.177 PIIX3 ATA: LUN#0: IDLE IMMEDIATE, CmdIf=0xef (-1 usec ago)
07:25:27.177 PIIX3 ATA: LUN#0: aborting current command
07:25:28.252 Guest Additions information report: additionsVersion = 0x00010004 osType = 0x00033000
07:25:28.294 Guest reported fixed hypervisor window at 0x00000000f6400000 (size = 0xc00000, rc = VINF_SUCCESS)
07:25:32.985 Guest requests mouse pointer integration
07:25:38.853 PCNet#0: Init: ss32=1 GCRDRA=0x06524420[64] GCTDRA=0x06524020[64]
07:25:41.499 SharedFolders host service: connected, u32ClientID = 7
07:25:41.545 PATM: Disable block at 86527acc - write 86527b49-86527b4d
07:25:42.636 PCNet#0: Init: ss32=1 GCRDRA=0x06524420[64] GCTDRA=0x06524020[64]
07:25:57.492 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=00007f08f7257000 w=1152 h=883 bpp=32 cbLine=0x1200
07:25:57.507 VBVA: Enabled.
07:25:58.645 Guest Log: VBoxService: Started.
07:25:58.659 Guest Log: vboxTimeSyncThread: Started.
07:26:00.937 PCNet#0: Init: ss32=1 GCRDRA=0x06524420[64] GCTDRA=0x06524020[64]
07:26:05.163 Guest Log: VBoxTray: Started.
07:26:05.661 AssertLogRel /home/vbox/vbox-2.2.4/src/VBox/HostServices/SharedClipboard/x11-clipboard.cpp(212) int vboxClipboardConnect(VBOXCLIPBOARDCLIENTDATA*): g_ctxHost.pClient == 0
07:26:05.781 Guest Additions capability report: (0x5) seamless: yes, hostWindowMapping: no, graphics: yes
07:26:06.431 PATM: Disable block at 866a58a4 - write 866a598c-866a5990
07:26:22.053 Guest Log: VBOXNP: DLL loaded.
07:26:22.424 Guest Log: VBOXNP: DLL unloaded.
07:26:22.483 Guest Log: VBOXNP: DLL loaded.
07:26:22.575 Guest Log: VBOXNP: DLL loaded.
07:26:22.611 Guest Log: VBOXNP: DLL unloaded.
07:26:25.111 PCNet#0: Init: ss32=1 GCRDRA=0x06524420[64] GCTDRA=0x06524020[64]
07:26:31.693 Guest Log: VBOXNP: DLL loaded.
07:37:53.408 !!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!
07:37:53.408 !!
07:37:53.408 !! Guru Meditation -2403 (VERR_TRPM_DONT_PANIC)
07:37:53.408 !!
07:37:53.408 !! TRAP=0e ERRCD=0000000000000002 CR2=00000000a0d66e50 EIP=f6c14716 Type=0
07:37:53.408 !! EIP in VMMGC.gc (f6bd8000) at rva 3c716 near symbols:
07:37:53.408 !! f6c14690 rva 0003c690 off 00000086 RTTimeNanoTSLFenceSync
07:37:53.408 !! f6c14940 rva 0003c940 off -0000022a RTTimeNanoTSLFenceAsync
07:37:53.408 !! fff8:f6c14716 f0 0f c7 0e lock cmpxchg8b qword [esi]
07:37:53.409 !!
07:37:53.409 !!
07:37:53.409 !!
07:37:53.409 Hypervisor CPUM state: se
07:37:53.419 .eax=00000000 .ebx=00000000 .ecx=00000000 .edx=0004194d .esi=a0d66e50 .edi=0001b528
07:37:53.420 .eip=f6c14716 .esp=f6567c8c .ebp=f6567d44 .iopl=0 rf nv up di nt zr ac pe nc
07:37:53.420 .cs={fff8 base=0000000000000000 limit=00000000 flags=00000000} .dr0=00000000 .dr1=00000000
07:37:53.420 .ds={fff0 base=0000000000000000 limit=00000000 flags=00000000} .dr2=00000000 .dr3=00000000
07:37:53.420 .es={fff0 base=0000000000000000 limit=00000000 flags=00000000} .dr4=00000000 .dr5=00000000
07:37:53.420 .fs={0030 base=0000000000000000 limit=00000000 flags=00000000} .dr6=00000000 .dr7=00000000
07:37:53.420 .gs={0000 base=0000000000000000 limit=00000000 flags=00000000} .cr0=00000000 .cr2=00000000
07:37:53.420 .ss={fff0 base=0000000000000000 limit=00000000 flags=00000000} .cr3=bbc58000 .cr4=00000000
07:37:53.420 .gdtr=00000000f69ae000:ffff .idtr=00000000f640f3d0:07ff .eflags=00010092
07:37:53.420 .ldtr={0000 base=00000000 limit=00000000 flags=00000000}
07:37:53.420 .tr ={ffe0 base=00000000 limit=00000000 flags=00000000}
07:37:53.420 .SysEnter={cs=0000 eip=00000000 esp=00000000}
07:37:53.420 FPU:
07:37:53.420 .FCW=0000 .FSW=0000 .FTW=00
07:37:53.420 .res1=00 .FOP=0000 .FPUIP=00000000 .CS=0000 .Rsvrd1=0000
07:37:53.420 .FPUDP=0000 .DS=0000 .Rsvrd2=0000 .MXCSR=00000000 .MXCSR_MASK=00000000
07:37:53.420 MSR:
07:37:53.420 .EFER =0000000000000000
07:37:53.420 .PAT =0000000000000000
07:37:53.420 .STAR =0000000000000000
07:37:53.420 .CSTAR =0000000000000000
07:37:53.420 .LSTAR =0000000000000000
07:37:53.420 .SFMASK =0000000000000000
07:37:53.420 .KERNELGSBASE =0000000000000000
07:37:53.420 CR4OrMask=0x204 CR4AndMask=0x403
07:37:53.420 !!
07:37:53.420 !! Call Stack:
07:37:53.420 !!
07:37:53.420 EBP Ret EBP Ret CS:EIP Arg0 Arg1 Arg2 Arg3 CS:EIP Symbol [line]
07:37:53.420 f6567d44 f6567d94 fff8:f6c08372 f6413a90 80000fff 0040f300 00000000 fff8:00000000f6c14716 RTTimeNanoTSLFenceSync+86
07:37:53.420 f6567d94 f6567da4 fff8:f6c084c9 f6401000 00000001 f6567e24 f6c06ceb fff8:00000000f6c08372 TMVirtualGetEx+62
07:37:53.420 f6567da4 f6567e24 fff8:f6c06ceb f6401000 00000003 00000000 f6567ed4 fff8:00000000f6c084c9 TMVirtualGet+19
07:37:53.420 f6567e24 f6567e94 fff8:f6bda088 f6401000 00000000 00000000 00000000 fff8:00000000f6c06ceb TMTimerPoll+4b
07:37:53.420 f6567e94 f6567ec4 fff8:f6bda22c f6401000 00000000 f6567ed4 f6567ed4 fff8:00000000f6bda088 _ZL14trpmGCExitTrapP2VMiP11CPUMCTXCORE+128
07:37:53.420 f6567ec4 80042000 fff8:f6bdb1b6 f640f380 f6567ed4 8648fda0 864854e8 fff8:00000000f6bda22c TRPMGCTrap0eHandler+5c
07:37:53.420 80042000 0c458b24 fff8:b2d46de0 8b080010 758b0855 eac14008 ffe68110 fff8:00000000f6bdb1b6 gt_HaveHandler+8
07:37:53.421 0c458b24 00000000 fff8:00000000 00000000 00000000 00000000 00000000 fff8:00000000b2d46de0
07:37:53.421 !!
07:37:53.421 !! Raw stack (mind the direction). pbEMTStackRC=f6566000 pbEMTStackBottomRC=f6568000
07:37:53.421 !!
07:37:53.421 0000000005496000 0000: 00 00 00 00 00 00 00 00-00 00 00 00 00 00 00 00 ................
07:37:53.465 Hypervisor Virtual handlers:
07:37:53.465 From - To (excl) HandlerHC HandlerGC Type Description
07:37:53.465 00000000f640f3d0 - 00000000f640fbcf 0000000000000000 f6bd9a80 WriteHyp Shadow IDT write access handler
07:37:53.465 00000000f64108b0 - 00000000f6410937 0000000000000000 f6bd9390 WriteHyp Shadow TSS write access handler
07:37:53.465 00000000f69ae000 - 00000000f69bdfff 0000000000000000 f6bd94b0 WriteHyp Shadow GDT write access handler
07:37:53.465 00000000f69bf000 - 00000000f69cffff 0000000000000000 f6bd9420 WriteHyp Shadow LDT write access handler
07:37:53.465 !!
07:37:53.465 !! {cfgm, <NULL>}
07:37:53.465 !!
07:37:53.465 pRoot=0000000001f63b60:{/}
07:37:53.465 [/] (level 0)
07:37:53.465 Name <string> = "tabasco02" (cch=10)
07:37:53.465 UUID <bytes> = "4b b8 81 a5 bc ae d0 47 bf 0a 31 2c 21 c4 43 ed" (cb=16)
07:37:53.466 RamSize <integer> = 0x0000000040000000 (1073741824)
07:37:53.466 RamHoleSize <integer> = 0x0000000020000000 (536870912)
07:37:53.466 NumCPUs <integer> = 0x0000000000000001 (1)
07:37:53.466 TimerMillies <integer> = 0x000000000000000a (10)
07:37:53.466 RawR3Enabled <integer> = 0x0000000000000001 (1)
07:37:53.466 RawR0Enabled <integer> = 0x0000000000000001 (1)
07:37:53.466 PATMEnabled <integer> = 0x0000000000000001 (1)
07:37:53.466 CSAMEnabled <integer> = 0x0000000000000001 (1)
07:37:53.466 HwVirtExtForced <integer> = 0x0000000000000000 (0)
07:37:53.466 EnableNestedPaging <integer> = 0x0000000000000000 (0)
07:37:53.466 EnableVPID <integer> = 0x0000000000000000 (0)
07:37:53.466 EnablePAE <integer> = 0x0000000000000000 (0)
07:37:53.466
07:37:53.466 [/HWVirtExt/] (level 1)
07:37:53.466
07:37:53.466 [/PDM/] (level 1)
07:37:53.466
07:37:53.466 [/PDM/Drivers/] (level 2)
07:37:53.466
07:37:53.466 [/PDM/Drivers/VBoxC/] (level 3)
07:37:53.466 Path <string> = "/usr/lib/virtualbox/components/VBoxC" (cch=37)
07:37:53.466
07:37:53.466 [/Devices/] (level 1)
07:37:53.466
07:37:53.466 [/Devices/pcarch/] (level 2)
07:37:53.466
07:37:53.466 [/Devices/pcarch/0/] (level 3)
07:37:53.466 Trusted <integer> = 0x0000000000000001 (1)
07:37:53.466
07:37:53.466 [/Devices/pcarch/0/Config/] (level 4) (restricted root)
07:37:53.466
07:37:53.466 [/Devices/pcbios/] (level 2)
07:37:53.466
07:37:53.466 [/Devices/pcbios/0/] (level 3)
07:37:53.466 Trusted <integer> = 0x0000000000000001 (1)
07:37:53.466
07:37:53.466 [/Devices/pcbios/0/Config/] (level 4) (restricted root)
07:37:53.466 RamSize <integer> = 0x0000000040000000 (1073741824)
07:37:53.466 RamHoleSize <integer> = 0x0000000020000000 (536870912)
07:37:53.466 NumCPUs <integer> = 0x0000000000000001 (1)
07:37:53.466 HardDiskDevice <string> = "piix3ide" (cch=9)
07:37:53.466 FloppyDevice <string> = "i82078" (cch=7)
07:37:53.466 IOAPIC <integer> = 0x0000000000000000 (0)
07:37:53.466 PXEDebug <integer> = 0x0000000000000000 (0)
07:37:53.466 UUID <bytes> = "4b b8 81 a5 bc ae d0 47 bf 0a 31 2c 21 c4 43 ed" (cb=16)
07:37:53.466 BootDevice0 <string> = "FLOPPY" (cch=7)
07:37:53.466 BootDevice1 <string> = "DVD" (cch=4)
07:37:53.466 BootDevice2 <string> = "IDE" (cch=4)
07:37:53.466 BootDevice3 <string> = "NONE" (cch=5)
07:37:53.466
07:37:53.466 [/Devices/8237A/] (level 2)
07:37:53.466
07:37:53.466 [/Devices/8237A/0/] (level 3)
07:37:53.466 Trusted <integer> = 0x0000000000000001 (1)
07:37:53.466
07:37:53.466 [/Devices/8237A/0/Config/] (level 4) (restricted root)
07:37:53.467
07:37:53.467 [/Devices/pci/] (level 2)
07:37:53.467
07:37:53.467 [/Devices/pci/0/] (level 3)
07:37:53.467 Trusted <integer> = 0x0000000000000001 (1)
07:37:53.467
07:37:53.467 [/Devices/pci/0/Config/] (level 4) (restricted root)
07:37:53.467 IOAPIC <integer> = 0x0000000000000000 (0)
07:37:53.467
07:37:53.467 [/Devices/pckbd/] (level 2)
07:37:53.467
07:37:53.467 [/Devices/pckbd/0/] (level 3)
07:37:53.467 Trusted <integer> = 0x0000000000000001 (1)
07:37:53.467
07:37:53.467 [/Devices/pckbd/0/Config/] (level 4) (restricted root)
07:37:53.467
07:37:53.467 [/Devices/pckbd/0/LUN#0/] (level 4)
07:37:53.467 Driver <string> = "KeyboardQueue" (cch=14)
07:37:53.467
07:37:53.467 [/Devices/pckbd/0/LUN#0/Config/] (level 5) (restricted root)
07:37:53.467 QueueSize <integer> = 0x0000000000000040 (64)
07:37:53.467
07:37:53.467 [/Devices/pckbd/0/LUN#0/AttachedDriver/] (level 5)
07:37:53.467 Driver <string> = "MainKeyboard" (cch=13)
07:37:53.467
07:37:53.467 [/Devices/pckbd/0/LUN#0/AttachedDriver/Config/] (level 6) (restricted root)
07:37:53.467 Object <integer> = 0x00000000022a28b0 (36317360)
07:37:53.467
07:37:53.467 [/Devices/pckbd/0/LUN#1/] (level 4)
07:37:53.467 Driver <string> = "MouseQueue" (cch=11)
07:37:53.467
07:37:53.467 [/Devices/pckbd/0/LUN#1/Config/] (level 5) (restricted root)
07:37:53.467 QueueSize <integer> = 0x0000000000000080 (128)
07:37:53.467
07:37:53.467 [/Devices/pckbd/0/LUN#1/AttachedDriver/] (level 5)
07:37:53.467 Driver <string> = "MainMouse" (cch=10)
07:37:53.467
07:37:53.467 [/Devices/pckbd/0/LUN#1/AttachedDriver/Config/] (level 6) (restricted root)
07:37:53.467 Object <integer> = 0x00000000022a2a00 (36317696)
07:37:53.467
07:37:53.467 [/Devices/i82078/] (level 2)
07:37:53.467
07:37:53.467 [/Devices/i82078/0/] (level 3)
07:37:53.467 Trusted <integer> = 0x0000000000000001 (1)
07:37:53.467
07:37:53.467 [/Devices/i82078/0/Config/] (level 4) (restricted root)
07:37:53.467 IRQ <integer> = 0x0000000000000006 (6)
07:37:53.467 DMA <integer> = 0x0000000000000002 (2)
07:37:53.468 MemMapped <integer> = 0x0000000000000000 (0)
07:37:53.468 IOBase <integer> = 0x00000000000003f0 (1008)
07:37:53.468
07:37:53.468 [/Devices/i82078/0/LUN#999/] (level 4)
07:37:53.468 Driver <string> = "MainStatus" (cch=11)
07:37:53.468
07:37:53.468 [/Devices/i82078/0/LUN#999/Config/] (level 5) (restricted root)
07:37:53.468 papLeds <integer> = 0x00000000022a1dd0 (36314576)
07:37:53.468 First <integer> = 0x0000000000000000 (0)
07:37:53.468 Last <integer> = 0x0000000000000000 (0)
07:37:53.468
07:37:53.468 [/Devices/i82078/0/LUN#0/] (level 4)
07:37:53.468 Driver <string> = "Block" (cch=6)
07:37:53.468
07:37:53.468 [/Devices/i82078/0/LUN#0/Config/] (level 5) (restricted root)
07:37:53.468 Type <string> = "Floppy 1.44" (cch=12)
07:37:53.468 Mountable <integer> = 0x0000000000000001 (1)
07:37:53.468
07:37:53.468 [/Devices/acpi/] (level 2)
07:37:53.468
07:37:53.468 [/Devices/acpi/0/] (level 3)
07:37:53.468 Trusted <integer> = 0x0000000000000001 (1)
07:37:53.468 PCIDeviceNo <integer> = 0x0000000000000007 (7)
07:37:53.468 PCIFunctionNo <integer> = 0x0000000000000000 (0)
07:37:53.468
07:37:53.468 [/Devices/acpi/0/Config/] (level 4) (restricted root)
07:37:53.468 RamSize <integer> = 0x0000000040000000 (1073741824)
07:37:53.468 RamHoleSize <integer> = 0x0000000020000000 (536870912)
07:37:53.468 NumCPUs <integer> = 0x0000000000000001 (1)
07:37:53.468 IOAPIC <integer> = 0x0000000000000000 (0)
07:37:53.468 FdcEnabled <integer> = 0x0000000000000001 (1)
07:37:53.468
07:37:53.468 [/Devices/acpi/0/LUN#0/] (level 4)
07:37:53.468 Driver <string> = "ACPIHost" (cch=9)
07:37:53.468
07:37:53.468 [/Devices/acpi/0/LUN#0/Config/] (level 5) (restricted root)
07:37:53.468
07:37:53.468 [/Devices/i8254/] (level 2)
07:37:53.468
07:37:53.468 [/Devices/i8254/0/] (level 3)
07:37:53.468
07:37:53.468 [/Devices/i8254/0/Config/] (level 4) (restricted root)
07:37:53.468
07:37:53.468 [/Devices/i8259/] (level 2)
07:37:53.468
07:37:53.468 [/Devices/i8259/0/] (level 3)
07:37:53.468 Trusted <integer> = 0x0000000000000001 (1)
07:37:53.468
07:37:53.468 [/Devices/i8259/0/Config/] (level 4) (restricted root)
07:37:53.468
07:37:53.468 [/Devices/apic/] (level 2)
07:37:53.468
07:37:53.468 [/Devices/apic/0/] (level 3)
07:37:53.469 Trusted <integer> = 0x0000000000000001 (1)
07:37:53.469
07:37:53.469 [/Devices/apic/0/Config/] (level 4) (restricted root)
07:37:53.469 IOAPIC <integer> = 0x0000000000000000 (0)
07:37:53.469 NumCPUs <integer> = 0x0000000000000001 (1)
07:37:53.469
07:37:53.469 [/Devices/mc146818/] (level 2)
07:37:53.469
07:37:53.469 [/Devices/mc146818/0/] (level 3)
07:37:53.469
07:37:53.469 [/Devices/mc146818/0/Config/] (level 4) (restricted root)
07:37:53.469
07:37:53.469 [/Devices/vga/] (level 2)
07:37:53.469
07:37:53.469 [/Devices/vga/0/] (level 3)
07:37:53.469 Trusted <integer> = 0x0000000000000001 (1)
07:37:53.469 PCIDeviceNo <integer> = 0x0000000000000002 (2)
07:37:53.469 PCIFunctionNo <integer> = 0x0000000000000000 (0)
07:37:53.469
07:37:53.469 [/Devices/vga/0/Config/] (level 4) (restricted root)
07:37:53.469 VRamSize <integer> = 0x0000000008000000 (134217728)
07:37:53.469 FadeIn <integer> = 0x0000000000000001 (1)
07:37:53.469 FadeOut <integer> = 0x0000000000000001 (1)
07:37:53.469 LogoTime <integer> = 0x0000000000000000 (0)
07:37:53.469 LogoFile <string> = "" (cch=1)
07:37:53.469 ShowBootMenu <integer> = 0x0000000000000002 (2)
07:37:53.469 CustomVideoModes <integer> = 0x0000000000000000 (0)
07:37:53.469 HeightReduction <integer> = 0x0000000000000000 (0)
07:37:53.469
07:37:53.469 [/Devices/vga/0/LUN#0/] (level 4)
07:37:53.469 Driver <string> = "MainDisplay" (cch=12)
07:37:53.469
07:37:53.469 [/Devices/vga/0/LUN#0/Config/] (level 5) (restricted root)
07:37:53.469 Object <integer> = 0x00000000022a2b50 (36318032)
07:37:53.469
07:37:53.469 [/Devices/piix3ide/] (level 2)
07:37:53.469
07:37:53.469 [/Devices/piix3ide/0/] (level 3)
07:37:53.469 Trusted <integer> = 0x0000000000000001 (1)
07:37:53.469 PCIDeviceNo <integer> = 0x0000000000000001 (1)
07:37:53.469 PCIFunctionNo <integer> = 0x0000000000000001 (1)
07:37:53.469
07:37:53.469 [/Devices/piix3ide/0/Config/] (level 4) (restricted root)
07:37:53.469 Type <string> = "PIIX4" (cch=6)
07:37:53.469
07:37:53.469 [/Devices/piix3ide/0/LUN#999/] (level 4)
07:37:53.469 Driver <string> = "MainStatus" (cch=11)
07:37:53.469
07:37:53.469 [/Devices/piix3ide/0/LUN#999/Config/] (level 5) (restricted root)
07:37:53.469 papLeds <integer> = 0x00000000022a1de0 (36314592)
07:37:53.469 First <integer> = 0x0000000000000000 (0)
07:37:53.469 Last <integer> = 0x0000000000000003 (3)
07:37:53.469
07:37:53.469 [/Devices/piix3ide/0/LUN#2/] (level 4)
07:37:53.469 Driver <string> = "Block" (cch=6)
07:37:53.470
07:37:53.470 [/Devices/piix3ide/0/LUN#2/Config/] (level 5) (restricted root)
07:37:53.470 Type <string> = "DVD" (cch=4)
07:37:53.470 Mountable <integer> = 0x0000000000000001 (1)
07:37:53.470
07:37:53.470 [/Devices/piix3ide/0/LUN#2/AttachedDriver/] (level 5)
07:37:53.470 Driver <string> = "MediaISO" (cch=9)
07:37:53.470
07:37:53.470 [/Devices/piix3ide/0/LUN#2/AttachedDriver/Config/] (level 6) (restricted root)
07:37:53.470 Path <string> = "/usr/share/virtualbox/VBoxGuestAdditions.iso" (cch=45)
07:37:53.470
07:37:53.470 [/Devices/piix3ide/0/LUN#0/] (level 4)
07:37:53.470 Driver <string> = "Block" (cch=6)
07:37:53.470
07:37:53.470 [/Devices/piix3ide/0/LUN#0/Config/] (level 5) (restricted root)
07:37:53.470 Type <string> = "HardDisk" (cch=9)
07:37:53.470 Mountable <integer> = 0x0000000000000000 (0)
07:37:53.470
07:37:53.470 [/Devices/piix3ide/0/LUN#0/AttachedDriver/] (level 5)
07:37:53.470 Driver <string> = "VD" (cch=3)
07:37:53.470
07:37:53.470 [/Devices/piix3ide/0/LUN#0/AttachedDriver/Config/] (level 6) (restricted root)
07:37:53.470 Path <string> = "/home/chris/.VirtualBox/HardDisks/tabasco02.vdi" (cch=48)
07:37:53.470 Format <string> = "VDI" (cch=4)
07:37:53.470
07:37:53.470 [/Devices/pcnet/] (level 2)
07:37:53.470
07:37:53.470 [/Devices/pcnet/0/] (level 3)
07:37:53.470 Trusted <integer> = 0x0000000000000001 (1)
07:37:53.470 PCIDeviceNo <integer> = 0x0000000000000003 (3)
07:37:53.470 PCIFunctionNo <integer> = 0x0000000000000000 (0)
07:37:53.470
07:37:53.470 [/Devices/pcnet/0/Config/] (level 4) (restricted root)
07:37:53.470 Am79C973 <integer> = 0x0000000000000001 (1)
07:37:53.470 MAC <bytes> = "08 00 27 b9 32 40" (cb=6)
07:37:53.470 CableConnected <integer> = 0x0000000000000001 (1)
07:37:53.470 LineSpeed <integer> = 0x0000000000000000 (0)
07:37:53.470
07:37:53.470 [/Devices/pcnet/0/LUN#999/] (level 4)
07:37:53.470 Driver <string> = "MainStatus" (cch=11)
07:37:53.470
07:37:53.470 [/Devices/pcnet/0/LUN#999/Config/] (level 5) (restricted root)
07:37:53.470 papLeds <integer> = 0x00000000022a1f70 (36314992)
07:37:53.470
07:37:53.470 [/Devices/pcnet/0/LUN#0/] (level 4)
07:37:53.470 Driver <string> = "IntNet" (cch=7)
07:37:53.470
07:37:53.470 [/Devices/pcnet/0/LUN#0/Config/] (level 5) (restricted root)
07:37:53.470 Trunk <string> = "eth0" (cch=5)
07:37:53.470 TrunkType <integer> = 0x0000000000000003 (3)
07:37:53.471 Network <string> = "HostInterfaceNetworking-eth0" (cch=29)
07:37:53.471
07:37:53.471 [/Devices/e1000/] (level 2)
07:37:53.471
07:37:53.471 [/Devices/serial/] (level 2)
07:37:53.471
07:37:53.471 [/Devices/parallel/] (level 2)
07:37:53.471
07:37:53.471 [/Devices/VMMDev/] (level 2)
07:37:53.471
07:37:53.471 [/Devices/VMMDev/0/] (level 3)
07:37:53.471 Trusted <integer> = 0x0000000000000001 (1)
07:37:53.471 PCIDeviceNo <integer> = 0x0000000000000004 (4)
07:37:53.471 PCIFunctionNo <integer> = 0x0000000000000000 (0)
07:37:53.471
07:37:53.471 [/Devices/VMMDev/0/Config/] (level 4) (restricted root)
07:37:53.471
07:37:53.471 [/Devices/VMMDev/0/LUN#0/] (level 4)
07:37:53.471 Driver <string> = "MainVMMDev" (cch=11)
07:37:53.471
07:37:53.471 [/Devices/VMMDev/0/LUN#0/Config/] (level 5) (restricted root)
07:37:53.471 Object <integer> = 0x00000000022a2250 (36315728)
07:37:53.471
07:37:53.471 [/Devices/VMMDev/0/LUN#999/] (level 4)
07:37:53.471 Driver <string> = "MainStatus" (cch=11)
07:37:53.471
07:37:53.471 [/Devices/VMMDev/0/LUN#999/Config/] (level 5) (restricted root)
07:37:53.471 papLeds <integer> = 0x00000000022a1fb0 (36315056)
07:37:53.471 First <integer> = 0x0000000000000000 (0)
07:37:53.471 Last <integer> = 0x0000000000000000 (0)
07:37:53.471
07:37:53.471 [/Devices/AudioSniffer/] (level 2)
07:37:53.471
07:37:53.471 [/Devices/AudioSniffer/0/] (level 3)
07:37:53.471
07:37:53.471 [/Devices/AudioSniffer/0/Config/] (level 4) (restricted root)
07:37:53.471
07:37:53.471 [/Devices/AudioSniffer/0/LUN#0/] (level 4)
07:37:53.471 Driver <string> = "MainAudioSniffer" (cch=17)
07:37:53.471
07:37:53.471 [/Devices/AudioSniffer/0/LUN#0/Config/] (level 5) (restricted root)
07:37:53.471 Object <integer> = 0x00000000022a3270 (36319856)
07:37:53.471
07:37:53.471 [/TM/] (level 1)
07:37:53.471 UTCOffset <integer> = 0x0000000000000000 (0)
07:37:53.471
07:37:53.471 [/MM/] (level 1)
07:37:53.471
07:37:53.471 !!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!
07:37:54.471 Changing the VM state from 'RUNNING' to 'GURU_MEDITATION'.
09:09:49.667 Console::powerDown(): A request to power off the VM has been issued (mMachineState=6, InUninit=0)
09:09:49.851 SharedFolders host service: disconnected, u32ClientID = 7
09:09:49.879 vboxClipboardDestroy: shutting down host clipboard
09:09:49.899 Changing the VM state from 'GURU_MEDITATION' to 'OFF'.
09:09:49.960 Changing the VM state from 'OFF' to 'DESTROYING'.
09:09:49.960 ************************* Statistics *************************
09:09:49.960 /Devices/ATA0/Unit0/AtapiDMA 0 times
09:09:49.960 /Devices/ATA0/Unit0/AtapiPIO 0 times
09:09:49.960 /Devices/ATA0/Unit0/DMA 0 times
09:09:49.960 /Devices/ATA0/Unit0/PIO 78148 times
09:09:49.960 /Devices/ATA0/Unit0/ReadBytes 1553538048 bytes
09:09:49.960 /Devices/ATA0/Unit0/WrittenBytes 884425216 bytes
09:09:49.960 /Devices/ATA0/Unit1/AtapiDMA 0 times
09:09:49.960 /Devices/ATA0/Unit1/AtapiPIO 0 times
09:09:49.960 /Devices/ATA0/Unit1/DMA 0 times
09:09:49.960 /Devices/ATA0/Unit1/PIO 0 times
09:09:49.960 /Devices/ATA0/Unit1/ReadBytes 0 bytes
09:09:49.960 /Devices/ATA0/Unit1/WrittenBytes 0 bytes
09:09:49.960 /Devices/ATA1/Unit0/AtapiDMA 81 times
09:09:49.961 /Devices/ATA1/Unit0/AtapiPIO 27479 times
09:09:49.961 /Devices/ATA1/Unit0/DMA 0 times
09:09:49.961 /Devices/ATA1/Unit0/PIO 0 times
09:09:49.961 /Devices/ATA1/Unit0/ReadBytes 104448 bytes
09:09:49.961 /Devices/ATA1/Unit0/WrittenBytes 0 bytes
09:09:49.961 /Devices/ATA1/Unit1/AtapiDMA 0 times
09:09:49.961 /Devices/ATA1/Unit1/AtapiPIO 0 times
09:09:49.961 /Devices/ATA1/Unit1/DMA 0 times
09:09:49.961 /Devices/ATA1/Unit1/PIO 0 times
09:09:49.961 /Devices/ATA1/Unit1/ReadBytes 0 bytes
09:09:49.961 /Devices/ATA1/Unit1/WrittenBytes 0 bytes
09:09:49.961 /Devices/PCNet0/ReceiveBytes 20371683 bytes
09:09:49.961 /Devices/PCNet0/TransmitBytes 400609 bytes
09:09:49.961 /GVMM/Sum/HaltBlocking 78120143 calls
09:09:49.961 /GVMM/Sum/HaltCalls 78227177 calls
09:09:49.961 /GVMM/Sum/HaltNotBlocking 107034 calls
09:09:49.961 /GVMM/Sum/HaltTimeouts 38301343 calls
09:09:49.961 /GVMM/Sum/HaltWakeUps 1332577 calls
09:09:49.961 /GVMM/Sum/PollCalls 196834 calls
09:09:49.961 /GVMM/Sum/PollHalts 0 calls
09:09:49.961 /GVMM/Sum/PollWakeUps 3017 calls
09:09:49.961 /GVMM/Sum/WakeUpCalls 70544209 calls
09:09:49.961 /GVMM/Sum/WakeUpNotHalted 58585166 calls
09:09:49.961 /GVMM/Sum/WakeUpWakeUps 3033064 calls
09:09:49.961 /GVMM/VM/HaltBlocking 7514500 calls
09:09:49.961 /GVMM/VM/HaltCalls 7515680 calls
09:09:49.961 /GVMM/VM/HaltNotBlocking 1180 calls
09:09:49.961 /GVMM/VM/HaltTimeouts 3409040 calls
09:09:49.961 /GVMM/VM/HaltWakeUps 727620 calls
09:09:49.961 /GVMM/VM/PollCalls 18 calls
09:09:49.961 /GVMM/VM/PollHalts 0 calls
09:09:49.961 /GVMM/VM/PollWakeUps 1 calls
09:09:49.961 /GVMM/VM/WakeUpCalls 6614808 calls
09:09:49.961 /GVMM/VM/WakeUpNotHalted 5384236 calls
09:09:49.961 /GVMM/VM/WakeUpWakeUps 1407195 calls
09:09:49.961 /GVMM/VMs 2 calls
09:09:49.961 /MM/HyperHeap/cbFree 773040 bytes
09:09:49.961 /MM/HyperHeap/cbHeap 1310592 bytes
09:09:49.961 /Net/IntNet0/Bytes/Received 21677585 bytes
09:09:49.961 /Net/IntNet0/Bytes/Sent 400609 bytes
09:09:49.961 /Net/IntNet0/Packets/Lost 0 bytes
09:09:49.961 /Net/IntNet0/Packets/Received 115247 bytes
09:09:49.961 /Net/IntNet0/Packets/Sent 3784 bytes
09:09:49.961 /Net/IntNet0/YieldNok 0 bytes
09:09:49.961 /PDM/CritSects/ATA0/ContentionR3 0 times
09:09:49.961 /PDM/CritSects/ATA0/ContentionRZLock 775 times
09:09:49.961 /PDM/CritSects/ATA0/ContentionRZUnlock 89 times
09:09:49.961 /PDM/CritSects/ATA1/ContentionR3 0 times
09:09:49.961 /PDM/CritSects/ATA1/ContentionRZLock 6576 times
09:09:49.961 /PDM/CritSects/ATA1/ContentionRZUnlock 932 times
09:09:49.961 /PDM/CritSects/PCNet#0/ContentionR3 0 times
09:09:49.961 /PDM/CritSects/PCNet#0/ContentionRZLock 72 times
09:09:49.961 /PDM/CritSects/PCNet#0/ContentionRZUnlock 4 times
09:09:49.961 /PDM/CritSects/PDM/ContentionR3 0 times
09:09:49.961 /PDM/CritSects/PDM/ContentionRZLock 4528 times
09:09:49.961 /PDM/CritSects/PDM/ContentionRZUnlock 1118 times
09:09:49.961 /PDM/CritSects/PGM/ContentionR3 0 times
09:09:49.961 /PDM/CritSects/PGM/ContentionRZLock 139 times
09:09:49.961 /PDM/CritSects/PGM/ContentionRZUnlock 283 times
09:09:49.961 /PGM/ChunkR3Map/c 1984 times
09:09:49.961 /PGM/ChunkR3Map/cMax 4294967295 times
09:09:49.961 /PGM/Page/cAllPages 296141 times
09:09:49.961 /PGM/Page/cHandyPages 115 times
09:09:49.961 /PGM/Page/cPrivatePages 296049 times
09:09:49.961 /PGM/Page/cSharedPages 0 times
09:09:49.961 /PGM/Page/cZeroPages 92 times
09:09:49.961 /PGM/cGuestModeChanges 2030 times
09:09:49.961 /PGM/cRelocations 5 times
09:09:49.961 /PROF/EM/ForcedActions 16765 ticks/call ( 50636200536 ticks, 3020265 times, max 895499190, min 180)
09:09:49.961 /PROF/EM/Halted 22531705 ticks/call (62840791222020 ticks, 2788994 times, max 67103298, min 225)
09:09:49.961 /PROF/EM/RAWTotal 936973 ticks/call (2925820282716 ticks, 3122629 times, max 2396981628, min 29340)
09:09:49.961 /PROF/EM/REMTotal 872773 ticks/call (111333656106 ticks, 127563 times, max 5845668993, min 2871)
09:09:49.961 /PROF/EM/Total 65936828660148 ticks/call (65936828660148 ticks, 1 times, max 65936828660148, min 65936828660148)
09:09:49.961 /PROF/VM/Halt/Block 8351653 ticks/call (62722251277191 ticks, 7510160 times, max 60499179, min 2718)
09:09:49.961 /PROF/VM/Halt/Timers 8664 ticks/call ( 90564173415 ticks, 10452923 times, max 17103555, min 765)
09:09:49.961 /PROF/VM/Halt/Yield 8002 ticks/call ( 144045 ticks, 18 times, max 17874, min 6183)
09:09:49.961 /REM/TbFlushCount 107761 times
09:09:49.961 /REM/TbPhysInvldCount 211448 times
09:09:49.961 /REM/TlbFlushCount 566495 times
09:09:49.961 /TM/GC/1nsSteps 374968 times
09:09:49.961 /TM/R3/1nsSteps 1239955 times
09:09:49.961 /TM/VirtualSync/CurrentOffset 500496055 ns
09:09:49.961 /TM/VirtualSync/GivenUp 497788073 ns
09:09:49.961 ********************* End of statistics **********************
09:09:50.032 Changing the VM state from 'DESTROYING' to 'TERMINATED'.