How to diagnose VM crashing issues

Discussions related to using VirtualBox on Windows hosts.
Post Reply
fakka
Posts: 8
Joined: 12. Sep 2012, 13:58

How to diagnose VM crashing issues

Post by fakka »

Hi all
Was crashing before - upgarded - still crashing.
How to read these logs ?

Code: Select all

19:39:19.875  FA: 00 00 00
19:39:19.875  FB: 00 00 00
19:39:19.875  FC: 00 00 00
19:39:19.875  FD: 00 00 00
19:39:19.875  FE: 00 00 00
19:39:19.875  FF: 00 00 00
19:39:19.875 !!
19:39:19.875 !! {vgagr}
19:39:19.875 !!
19:39:19.875 VGA Graphics Controller (3CF): GR index 3CE:05
19:39:19.875  GR00:00 GR01:00 GR02:00 GR03:00 GR04:00 GR05:50 GR06:05 GR07:0F GR08:FF
19:39:19.875 !!
19:39:19.875 !! {vgasr}
19:39:19.875 !!
19:39:19.875 VGA Sequencer (3C5): SR index 3C4:00
19:39:19.875  SR00:03 SR01:20 SR02:0F SR03:04 SR04:0A
19:39:19.875 !!
19:39:19.875 !! {vgatext}
19:39:19.875 !!
19:39:19.875 Not in text mode!
19:39:19.875 !!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!
19:39:19.875 Changing the VM state from 'RUNNING' to 'GURU_MEDITATION'.
23:34:54.896 Console::powerDown(): A request to power off the VM has been issued (mMachineState=Stopping, InUninit=0)
23:34:54.897 Changing the VM state from 'GURU_MEDITATION' to 'POWERING_OFF'.
23:34:54.897 ****************** Guest state at power off ******************
23:34:54.897 Guest CPUM (VCPU 0) state: 
23:34:54.897 eax=00000282 ebx=c1e05160 ecx=00000000 edx=c1e05160 esi=c09e9d00 edi=00000282
23:34:54.897 eip=c04b1491 esp=f2a37e4c ebp=c09db9e4 iopl=0         nv up ei nt zr na pe nc
23:34:54.897 cs={0060 base=0000000000000000 limit=ffffffff flags=0000c09a} dr0=00000000 dr1=00000000
23:34:54.897 ds={007b base=0000000000000000 limit=ffffffff flags=0000c0f3} dr2=00000000 dr3=00000000
23:34:54.897 es={007b base=0000000000000000 limit=ffffffff flags=0000c0f3} dr4=00000000 dr5=00000000
23:34:54.897 fs={00d8 base=0000000001349000 limit=ffffffff flags=00008093} dr6=00000000 dr7=00000000
23:34:54.897 gs={00e0 base=00000000c1e08f40 limit=00000018 flags=00004091} cr0=80050033 cr2=c1e06360
23:34:54.897 ss={0068 base=0000000000000000 limit=ffffffff flags=0000c093} cr3=319af000 cr4=000006f0
23:34:54.897 gdtr=00000000c1e00000:00ff  idtr=00000000c09d9000:07ff  eflags=00000282
23:34:54.897 ldtr={0000 base=00000000 limit=00000000 flags=00000082}
23:34:54.897 tr  ={0080 base=c1e06d80 limit=0000206b flags=0000008b}
23:34:54.897 SysEnter={cs=0000 eip=00000000 esp=00000000}
23:34:54.897 FCW=037f FSW=0000 FTW=0000 FOP=0000 MXCSR=00001f80 MXCSR_MASK=0000ffff
23:34:54.897 FPUIP=00e9db8a CS=0073 Rsrvd1=0000  FPUDP=bfff6cb8 DS=007b Rsvrd2=0000
23:34:54.897 ST(0)=FPR0={0000'00000000'00000000} t0 +0.0000000000000000000000 ^ 0
23:34:54.897 ST(1)=FPR1={0000'00000000'00000000} t0 +0.0000000000000000000000 ^ 0
23:34:54.897 ST(2)=FPR2={0000'00000000'00000000} t0 +0.0000000000000000000000 ^ 0
23:34:54.897 ST(3)=FPR3={0000'00000000'00000000} t0 +0.0000000000000000000000 ^ 0
23:34:54.897 ST(4)=FPR4={0000'00000000'00000000} t0 +0.0000000000000000000000 ^ 0
23:34:54.897 ST(5)=FPR5={0000'00000000'00000000} t0 +0.0000000000000000000000 ^ 0
23:34:54.897 ST(6)=FPR6={0000'00000000'00000000} t0 +0.0000000000000000000000 ^ 0
23:34:54.897 ST(7)=FPR7={0000'00000000'00000000} t0 +0.0000000000000000000000 ^ 0
23:34:54.897 XMM0 =00000000'00000000'00000000'00000000  XMM1 =00000019'00000000'00000000'00000000
23:34:54.897 XMM2 =00000011'00000040'00003600'73754244  XMM3 =00000000'73754244'2e706f74'6b736564
23:34:54.897 XMM4 =00000014'00730107'00000000'00000000  XMM5 =62206567'61737365'6d206568'74202c79
23:34:54.897 XMM6 =696c6f70'20797469'72756365'73207375  XMM7 =72206568'74206465'6b636f6c'62207963
23:34:54.897 XMM8 =00000000'00000000'00000000'00000000  XMM9 =00000000'00000000'00000000'00000000
23:34:54.897 XMM10=00000000'00000000'00000000'00000000  XMM11=00000000'00000000'00000000'00000000
23:34:54.897 XMM12=00000000'00000000'00000000'00000000  XMM13=00000000'00000000'00000000'00000000
23:34:54.897 XMM14=00000000'00000000'00000000'00000000  XMM15=00000000'00000000'00000000'00000000
23:34:54.897 EFER         =0000000000000000
23:34:54.897 PAT          =0007010600070106
23:34:54.897 STAR         =0000000000000000
23:34:54.897 CSTAR        =0000000000000000
23:34:54.897 LSTAR        =0000000000000000
23:34:54.897 SFMASK       =0000000000000000
23:34:54.897 KERNELGSBASE =0000000000000000
23:34:54.897 ***
23:34:54.897 Guest paging mode:  PAE, changed 731186 times, A20 enabled
23:34:54.897 Shadow paging mode: PAE
23:34:54.897 Host paging mode:   AMD64+G+NX
23:34:54.897 ***
23:34:54.897 Active Timers (pVM=0000000002910000)
23:34:54.897 pTimerR3         offNext  offPrev  offSched Clock               Time             Expire HzHint State                     Description
23:34:54.897 0000000005427170 00013990 00000000 00000000 Real          2139240747         2125104717      0 2-ACTIVE                  VGA Refresh Timer
23:34:54.897 000000000543ab00 ffffff80 fffec670 00000000 Real          2139240747         2125104720      0 2-ACTIVE                  EMT Yielder
23:34:54.897 000000000543aa80 00000000 00000080 00000000 Real          2139240747         2125105367      0 2-ACTIVE                  CPU Load Timer
23:34:54.897 00000000054363d0 00000000 00000000 00000000 Virt      70755446945227     70755447045437      0 2-ACTIVE                  Audio timer
23:34:54.897 00000000054121b0 000010d0 00000000 00000000 VrSy      70755445529026     70755446460503   1023 2-ACTIVE                  APIC Timer #0
23:34:54.897 0000000005413280 00026570 ffffef30 00000000 VrSy      70755445529026     70755990000000      0 2-ACTIVE                  MC146818 RTC/CMOS - Second
23:34:54.897 00000000054397f0 00000000 fffd9a90 00000000 VrSy      70755445529026     70791977864459      0 2-ACTIVE                  ACPI PM Timer
23:34:54.897 ***
23:34:54.897 Shadow GDT (GCAddr=fdfd2000):
23:34:54.897 0030 - db00ffff b7dff38d - base=b78ddb00 limit=ffffffff dpl=3 DataRW Accessed Present Page 32-bit 
23:34:54.897 0060 - 0000ffff 00cfbb00 - base=00000000 limit=ffffffff dpl=1 CodeER Accessed Present Page 32-bit 
23:34:54.897 0068 - 0000ffff 00cfb300 - base=00000000 limit=ffffffff dpl=1 DataRW Accessed Present Page 32-bit 
23:34:54.897 0070 - 00008068 00c0fb00 - base=00000000 limit=08068fff dpl=3 CodeER Accessed Present Page 32-bit 
23:34:54.897 0078 - 0000ffff 00cff300 - base=00000000 limit=ffffffff dpl=3 DataRW Accessed Present Page 32-bit 
23:34:54.897 0090 - 0000ffff 0040bb00 - base=00000000 limit=0000ffff dpl=1 CodeER Accessed Present 32-bit 
23:34:54.897 0098 - 0000ffff 0000bb00 - base=00000000 limit=0000ffff dpl=1 CodeER Accessed Present 16-bit 
23:34:54.897 00a0 - 0000ffff 0000b300 - base=00000000 limit=0000ffff dpl=1 DataRW Accessed Present 16-bit 
23:34:54.897 00a8 - 00000000 0000b300 - base=00000000 limit=00000000 dpl=1 DataRW Accessed Present 16-bit 
23:34:54.897 00b0 - 00000000 0000b300 - base=00000000 limit=00000000 dpl=1 DataRW Accessed Present 16-bit 
23:34:54.897 00b8 - 0000ffff 0040bb00 - base=00000000 limit=0000ffff dpl=1 CodeER Accessed Present 32-bit 
23:34:54.897 00c0 - 0000ffff 0000bb00 - base=00000000 limit=0000ffff dpl=1 CodeER Accessed Present 16-bit 
23:34:54.897 00c8 - 0000ffff 0040b300 - base=00000000 limit=0000ffff dpl=1 DataRW Accessed Present 32-bit 
23:34:54.897 00d0 - 0000ffff 00cfb300 - base=00000000 limit=ffffffff dpl=1 DataRW Accessed Present Page 32-bit 
23:34:54.897 00d8 - 9000ffff 018fb334 - base=01349000 limit=ffffffff dpl=1 DataRW Accessed Present Page 16-bit 
23:34:54.897 00e0 - 8f400018 c140b1e0 - base=c1e08f40 limit=00000018 dpl=1 DataRO Accessed Present 32-bit 
23:34:54.897 ffd8 - 81180087 fd008900 - base=fd008118 limit=00000087 dpl=0 TSS32Avail Present 16-bit  HyperTSSTrap08
23:34:54.897 ffe0 - 80900087 fd008b00 - base=fd008090 limit=00000087 dpl=0 TSS32Busy Present 16-bit  HyperTSS
23:34:54.897 ffe8 - 0000ffff 00af9b00 - base=00000000 limit=ffffffff dpl=0 CodeER Accessed Present Page 16-bit  HyperCS64
23:34:54.897 fff0 - 0000ffff 00cf9300 - base=00000000 limit=ffffffff dpl=0 DataRW Accessed Present Page 32-bit  HyperDS
23:34:54.897 fff8 - 0000ffff 00cf9b00 - base=00000000 limit=ffffffff dpl=0 CodeER Accessed Present Page 32-bit  HyperCS
23:34:54.897 ***
23:34:54.897 ************** End of Guest state at power off ***************
23:34:54.918 PDMR3PowerOff: 20 329 710 ns run time
23:34:54.918 Changing the VM state from 'POWERING_OFF' to 'OFF'.
23:34:54.919 Changing the VM state from 'OFF' to 'DESTROYING'.
23:34:54.919 ************************* Statistics *************************
23:34:54.919 /Devices/E1k0/ReceiveBytes       65556827 bytes
23:34:54.919 /Devices/E1k0/TransmitBytes        386989 bytes
23:34:54.919 /Devices/IDE0/ATA0/Unit0/AtapiDMA        0 times
23:34:54.919 /Devices/IDE0/ATA0/Unit0/AtapiPIO        0 times
23:34:54.919 /Devices/IDE0/ATA0/Unit0/DMA            0 times
23:34:54.919 /Devices/IDE0/ATA0/Unit0/PIO            0 times
23:34:54.919 /Devices/IDE0/ATA0/Unit0/ReadBytes        0 bytes
23:34:54.919 /Devices/IDE0/ATA0/Unit0/WrittenBytes        0 bytes
23:34:54.919 /Devices/IDE0/ATA0/Unit1/AtapiDMA        0 times
23:34:54.919 /Devices/IDE0/ATA0/Unit1/AtapiPIO        0 times
23:34:54.919 /Devices/IDE0/ATA0/Unit1/DMA            0 times
23:34:54.919 /Devices/IDE0/ATA0/Unit1/PIO            0 times
23:34:54.919 /Devices/IDE0/ATA0/Unit1/ReadBytes        0 bytes
23:34:54.919 /Devices/IDE0/ATA0/Unit1/WrittenBytes        0 bytes
23:34:54.919 /Devices/IDE0/ATA1/Unit0/AtapiDMA        9 times
23:34:54.919 /Devices/IDE0/ATA1/Unit0/AtapiPIO   423922 times
23:34:54.919 /Devices/IDE0/ATA1/Unit0/DMA            0 times
23:34:54.919 /Devices/IDE0/ATA1/Unit0/PIO            0 times
23:34:54.919 /Devices/IDE0/ATA1/Unit0/ReadBytes        0 bytes
23:34:54.919 /Devices/IDE0/ATA1/Unit0/WrittenBytes        0 bytes
23:34:54.919 /Devices/IDE0/ATA1/Unit1/AtapiDMA        0 times
23:34:54.919 /Devices/IDE0/ATA1/Unit1/AtapiPIO        0 times
23:34:54.919 /Devices/IDE0/ATA1/Unit1/DMA            0 times
23:34:54.919 /Devices/IDE0/ATA1/Unit1/PIO            0 times
23:34:54.919 /Devices/IDE0/ATA1/Unit1/ReadBytes        0 bytes
23:34:54.919 /Devices/IDE0/ATA1/Unit1/WrittenBytes        0 bytes
23:34:54.919 /Devices/SATA0/Port0/DMA           168523 times
23:34:54.919 /Devices/SATA0/Port0/ReadBytes   940112384 bytes
23:34:54.919 /Devices/SATA0/Port0/WrittenBytes 1732050944 bytes
23:34:54.919 /Devices/VMMDev/BalloonChunks           0 count
23:34:54.919 /Drivers/IntNet-0/BadFrames             0 count
23:34:54.919 /Drivers/IntNet-0/Bytes/Received 118369188 bytes
23:34:54.919 /Drivers/IntNet-0/Bytes/Sent       387069 bytes
23:34:54.919 /Drivers/IntNet-0/Overflows/Recv        0 count
23:34:54.919 /Drivers/IntNet-0/Overflows/Sent        0 count
23:34:54.919 /Drivers/IntNet-0/Packets/Lost          0 count
23:34:54.919 /Drivers/IntNet-0/Packets/Received  1044056 count
23:34:54.919 /Drivers/IntNet-0/Packets/Received-Gso        0 count
23:34:54.919 /Drivers/IntNet-0/Packets/Sent       1471 count
23:34:54.919 /Drivers/IntNet-0/Packets/Sent-Gso        0 count
23:34:54.919 /Drivers/IntNet-0/Packets/Sent-R0        0 count
23:34:54.919 /Drivers/IntNet-0/Recv1                 0 ticks/call (           0 ticks,       0 times, max         0, min      -1)
23:34:54.919 /Drivers/IntNet-0/Recv2                 0 ticks/call (           0 ticks,       0 times, max         0, min      -1)
23:34:54.919 /Drivers/IntNet-0/Reserved              0 ticks/call (           0 ticks,       0 times, max         0, min      -1)
23:34:54.919 /Drivers/IntNet-0/Send1             31020 ticks/call (    45631583 ticks,    1471 times, max    233113, min    6888)
23:34:54.919 /Drivers/IntNet-0/Send2             28155 ticks/call (    41416692 ticks,    1471 times, max    230315, min    6172)
23:34:54.919 /Drivers/IntNet-0/XmitProcessRing        0 count
23:34:54.919 /Drivers/IntNet-0/XmitWakeup-R0         0 count
23:34:54.919 /Drivers/IntNet-0/XmitWakeup-R3         0 count
23:34:54.919 /Drivers/IntNet-0/YieldNok              0 count
23:34:54.919 /Drivers/IntNet-0/YieldOk               0 count
23:34:54.919 /FT/Checkpoint/Network                  0 times
23:34:54.919 /FT/Checkpoint/Storage                  0 times
23:34:54.919 /FT/Received/Mem                        0 bytes
23:34:54.919 /FT/Received/State                      0 bytes
23:34:54.919 /FT/Sent/Mem                            0 bytes
23:34:54.919 /FT/Sent/State                          0 bytes
23:34:54.919 /FT/Sync/DeltaMem                       0 times
23:34:54.919 /FT/Sync/DeltaVM                        0 times
23:34:54.919 /FT/Sync/Full                           0 times
23:34:54.919 /GMM/VM/Allocated/cBasePages       574944 pages
23:34:54.919 /GMM/VM/Allocated/cFixedPages           0 pages
23:34:54.919 /GMM/VM/Allocated/cShadowPages          0 pages
23:34:54.919 /GMM/VM/Reserved/cBasePages       1048734 pages
23:34:54.919 /GMM/VM/Reserved/cFixedPages         4100 pages
23:34:54.919 /GMM/VM/Reserved/cShadowPages           1 pages
23:34:54.919 /GMM/VM/cBalloonedPages                 0 pages
23:34:54.919 /GMM/VM/cMaxBalloonedPages              0 pages
23:34:54.919 /GMM/VM/cPrivatePages              574944 pages
23:34:54.919 /GMM/VM/cReqActuallyBalloonedPages        0 pages
23:34:54.919 /GMM/VM/cReqBalloonedPages              0 pages
23:34:54.919 /GMM/VM/cReqDeflatePages                0 pages
23:34:54.919 /GMM/VM/cShareableModules               0 count
23:34:54.919 /GMM/VM/cSharedPages                    0 pages
23:34:54.919 /GMM/VM/enmPolicy                       1 
23:34:54.919 /GMM/VM/enmPriority                     2 
23:34:54.919 /GMM/VM/fBallooningEnabled       false    
23:34:54.919 /GMM/VM/fMayAllocate             false    
23:34:54.919 /GMM/VM/fSharedPagingEnabled     false    
23:34:54.919 /GMM/cAllocatedPages               574944 pages
23:34:54.919 /GMM/cBalloonedPages                    0 pages
23:34:54.919 /GMM/cChunks                         1123 count
23:34:54.919 /GMM/cDuplicatePages                    0 pages
23:34:54.919 /GMM/cFreedChunks                       0 count
23:34:54.919 /GMM/cLeftBehindSharedPages             0 pages
23:34:54.919 /GMM/cMaxPages                   4294967295 pages
23:34:54.919 /GMM/cOverCommittedPages                0 pages
23:34:54.919 /GMM/cReservedPages               1052835 pages
23:34:54.919 /GMM/cShareableModules                  0 count
23:34:54.919 /GMM/cSharedPages                       0 pages
23:34:54.919 /GVMM/EMTs                              1 calls
23:34:54.919 /GVMM/HostCPUs                          8 calls
23:34:54.919 /GVMM/HostCpus/0                        0 
23:34:54.919 /GVMM/HostCpus/0/CurTimerHz             0 Hz
23:34:54.919 /GVMM/HostCpus/0/DesiredHz              0 Hz
23:34:54.919 /GVMM/HostCpus/0/PPTChanges             0 times
23:34:54.919 /GVMM/HostCpus/0/PPTStarts              0 times
23:34:54.919 /GVMM/HostCpus/0/idxCpuSet              0 
23:34:54.919 /GVMM/HostCpus/1                        1 
23:34:54.919 /GVMM/HostCpus/1/CurTimerHz             0 Hz
23:34:54.919 /GVMM/HostCpus/1/DesiredHz              0 Hz
23:34:54.919 /GVMM/HostCpus/1/PPTChanges             0 times
23:34:54.919 /GVMM/HostCpus/1/PPTStarts              0 times
23:34:54.919 /GVMM/HostCpus/1/idxCpuSet              1 
23:34:54.919 /GVMM/HostCpus/2                        2 
23:34:54.919 /GVMM/HostCpus/2/CurTimerHz             0 Hz
23:34:54.919 /GVMM/HostCpus/2/DesiredHz              0 Hz
23:34:54.919 /GVMM/HostCpus/2/PPTChanges             0 times
23:34:54.919 /GVMM/HostCpus/2/PPTStarts              0 times
23:34:54.919 /GVMM/HostCpus/2/idxCpuSet              2 
23:34:54.919 /GVMM/HostCpus/3                        3 
23:34:54.919 /GVMM/HostCpus/3/CurTimerHz             0 Hz
23:34:54.919 /GVMM/HostCpus/3/DesiredHz              0 Hz
23:34:54.919 /GVMM/HostCpus/3/PPTChanges             0 times
23:34:54.919 /GVMM/HostCpus/3/PPTStarts              0 times
23:34:54.919 /GVMM/HostCpus/3/idxCpuSet              3 
23:34:54.919 /GVMM/HostCpus/4                        4 
23:34:54.919 /GVMM/HostCpus/4/CurTimerHz             0 Hz
23:34:54.919 /GVMM/HostCpus/4/DesiredHz              0 Hz
23:34:54.919 /GVMM/HostCpus/4/PPTChanges             0 times
23:34:54.919 /GVMM/HostCpus/4/PPTStarts              0 times
23:34:54.919 /GVMM/HostCpus/4/idxCpuSet              4 
23:34:54.919 /GVMM/HostCpus/5                        5 
23:34:54.919 /GVMM/HostCpus/5/CurTimerHz             0 Hz
23:34:54.919 /GVMM/HostCpus/5/DesiredHz              0 Hz
23:34:54.919 /GVMM/HostCpus/5/PPTChanges             0 times
23:34:54.919 /GVMM/HostCpus/5/PPTStarts              0 times
23:34:54.919 /GVMM/HostCpus/5/idxCpuSet              5 
23:34:54.919 /GVMM/HostCpus/6                        6 
23:34:54.919 /GVMM/HostCpus/6/CurTimerHz             0 Hz
23:34:54.919 /GVMM/HostCpus/6/DesiredHz              0 Hz
23:34:54.919 /GVMM/HostCpus/6/PPTChanges             0 times
23:34:54.919 /GVMM/HostCpus/6/PPTStarts              0 times
23:34:54.919 /GVMM/HostCpus/6/idxCpuSet              6 
23:34:54.919 /GVMM/HostCpus/7                        7 
23:34:54.919 /GVMM/HostCpus/7/CurTimerHz             0 Hz
23:34:54.919 /GVMM/HostCpus/7/DesiredHz              0 Hz
23:34:54.919 /GVMM/HostCpus/7/PPTChanges             0 times
23:34:54.919 /GVMM/HostCpus/7/PPTStarts              0 times
23:34:54.919 /GVMM/HostCpus/7/idxCpuSet              7 
23:34:54.919 /GVMM/Sum/HaltBlocking           20018832 calls
23:34:54.919 /GVMM/Sum/HaltCalls              306957287 calls
23:34:54.919 /GVMM/Sum/HaltNotBlocking        286938455 calls
23:34:54.919 /GVMM/Sum/HaltTimeouts           13488480 calls
23:34:54.919 /GVMM/Sum/HaltWakeUps                   0 calls
23:34:54.919 /GVMM/Sum/PokeCalls                805771 calls
23:34:54.919 /GVMM/Sum/PokeNotBusy               12619 calls
23:34:54.919 /GVMM/Sum/PollCalls                 69462 calls
23:34:54.919 /GVMM/Sum/PollHalts                     0 calls
23:34:54.919 /GVMM/Sum/PollWakeUps                   0 calls
23:34:54.919 /GVMM/Sum/WakeUpCalls             7377534 calls
23:34:54.919 /GVMM/Sum/WakeUpNotHalted         3789776 calls
23:34:54.919 /GVMM/Sum/WakeUpWakeUps                 0 calls
23:34:54.919 /GVMM/VM/HaltBlocking            20018832 calls
23:34:54.919 /GVMM/VM/HaltCalls               306957287 calls
23:34:54.919 /GVMM/VM/HaltNotBlocking         286938455 calls
23:34:54.919 /GVMM/VM/HaltTimeouts            13488480 calls
23:34:54.919 /GVMM/VM/HaltWakeUps                    0 calls
23:34:54.919 /GVMM/VM/PokeCalls                 805771 calls
23:34:54.919 /GVMM/VM/PokeNotBusy                12619 calls
23:34:54.919 /GVMM/VM/PollCalls                  69462 calls
23:34:54.919 /GVMM/VM/PollHalts                      0 calls
23:34:54.919 /GVMM/VM/PollWakeUps                    0 calls
23:34:54.919 /GVMM/VM/WakeUpCalls              7377534 calls
23:34:54.919 /GVMM/VM/WakeUpNotHalted          3789776 calls
23:34:54.919 /GVMM/VM/WakeUpWakeUps                  0 calls
23:34:54.919 /GVMM/VMs                               1 calls
23:34:54.919 /MM/HyperHeap/cbFree                 3680 bytes
23:34:54.919 /MM/HyperHeap/cbHeap              1310400 bytes
23:34:54.919 /PDM/BlkCache/cbCached            5238784 bytes
23:34:54.919 /PDM/BlkCache/cbCachedFru          753664 bytes
23:34:54.919 /PDM/BlkCache/cbCachedMruIn       4485120 bytes
23:34:54.919 /PDM/BlkCache/cbCachedMruOut      2580480 bytes
23:34:54.919 /PDM/BlkCache/cbMax               5242880 bytes
23:34:54.919 /PDM/CritSects/8237A#0 Auto/ContentionR3        0 times
23:34:54.919 /PDM/CritSects/8237A#0 Auto/ContentionRZLock        0 times
23:34:54.919 /PDM/CritSects/8237A#0 Auto/ContentionRZUnlock        0 times
23:34:54.919 /PDM/CritSects/AHCI#0/ContentionR3        0 times
23:34:54.919 /PDM/CritSects/AHCI#0/ContentionRZLock        0 times
23:34:54.919 /PDM/CritSects/AHCI#0/ContentionRZUnlock        0 times
23:34:54.919 /PDM/CritSects/ATA#0/ContentionR3        0 times
23:34:54.919 /PDM/CritSects/ATA#0/ContentionRZLock        0 times
23:34:54.919 /PDM/CritSects/ATA#0/ContentionRZUnlock        0 times
23:34:54.919 /PDM/CritSects/ATA#1/ContentionR3        0 times
23:34:54.919 /PDM/CritSects/ATA#1/ContentionRZLock   315394 times
23:34:54.919 /PDM/CritSects/ATA#1/ContentionRZUnlock        0 times
23:34:54.919 /PDM/CritSects/AudioSniffer#0 Auto/ContentionR3        0 times
23:34:54.919 /PDM/CritSects/AudioSniffer#0 Auto/ContentionRZLock        0 times
23:34:54.919 /PDM/CritSects/AudioSniffer#0 Auto/ContentionRZUnlock        0 times
23:34:54.919 /PDM/CritSects/E1000#0/ContentionR3        0 times
23:34:54.919 /PDM/CritSects/E1000#0/ContentionRZLock       33 times
23:34:54.919 /PDM/CritSects/E1000#0/ContentionRZUnlock       55 times
23:34:54.919 /PDM/CritSects/E1000#0RX/ContentionR3        0 times
23:34:54.919 /PDM/CritSects/E1000#0RX/ContentionRZLock        0 times
23:34:54.919 /PDM/CritSects/E1000#0RX/ContentionRZUnlock        0 times
23:34:54.919 /PDM/CritSects/EM-REM/ContentionR3        0 times
23:34:54.919 /PDM/CritSects/EM-REM/ContentionRZLock        0 times
23:34:54.919 /PDM/CritSects/EM-REM/ContentionRZUnlock        0 times
23:34:54.919 /PDM/CritSects/EmulatedATA0/ContentionR3        0 times
23:34:54.919 /PDM/CritSects/EmulatedATA0/ContentionRZLock        0 times
23:34:54.919 /PDM/CritSects/EmulatedATA0/ContentionRZUnlock        0 times
23:34:54.919 /PDM/CritSects/EmulatedATA1/ContentionR3        0 times
23:34:54.919 /PDM/CritSects/EmulatedATA1/ContentionRZLock        0 times
23:34:54.919 /PDM/CritSects/EmulatedATA1/ContentionRZUnlock        0 times
23:34:54.919 /PDM/CritSects/FTM/ContentionR3         0 times
23:34:54.919 /PDM/CritSects/FTM/ContentionRZLock        0 times
23:34:54.919 /PDM/CritSects/FTM/ContentionRZUnlock        0 times
23:34:54.919 /PDM/CritSects/IOM Lock/ContentionR3        0 times
23:34:54.919 /PDM/CritSects/IOM Lock/ContentionRZLock        0 times
23:34:54.919 /PDM/CritSects/IOM Lock/ContentionRZUnlock        0 times
23:34:54.919 /PDM/CritSects/IntNetXmit_0/ContentionR3        0 times
23:34:54.919 /PDM/CritSects/IntNetXmit_0/ContentionRZLock        0 times
23:34:54.919 /PDM/CritSects/IntNetXmit_0/ContentionRZUnlock        0 times
23:34:54.919 /PDM/CritSects/MM-HYPER/ContentionR3        0 times
23:34:54.919 /PDM/CritSects/MM-HYPER/ContentionRZLock        0 times
23:34:54.919 /PDM/CritSects/MM-HYPER/ContentionRZUnlock        0 times
23:34:54.919 /PDM/CritSects/NOP/ContentionR3         0 times
23:34:54.919 /PDM/CritSects/NOP/ContentionRZLock        0 times
23:34:54.919 /PDM/CritSects/NOP/ContentionRZUnlock        0 times
23:34:54.919 /PDM/CritSects/PDM/ContentionR3         0 times
23:34:54.919 /PDM/CritSects/PDM/ContentionRZLock   304459 times
23:34:54.919 /PDM/CritSects/PDM/ContentionRZUnlock     3071 times
23:34:54.919 /PDM/CritSects/PGM/ContentionR3         0 times
23:34:54.919 /PDM/CritSects/PGM/ContentionRZLock     3046 times
23:34:54.919 /PDM/CritSects/PGM/ContentionRZUnlock    15863 times
23:34:54.919 /PDM/CritSects/PS2KM#0/ContentionR3        0 times
23:34:54.919 /PDM/CritSects/PS2KM#0/ContentionRZLock        0 times
23:34:54.919 /PDM/CritSects/PS2KM#0/ContentionRZUnlock        0 times
23:34:54.919 /PDM/CritSects/REM-Register/ContentionR3        0 times
23:34:54.919 /PDM/CritSects/REM-Register/ContentionRZLock        0 times
23:34:54.919 /PDM/CritSects/REM-Register/ContentionRZUnlock        0 times
23:34:54.919 /PDM/CritSects/TM Timer Lock/ContentionR3        0 times
23:34:54.919 /PDM/CritSects/TM Timer Lock/ContentionRZLock        0 times
23:34:54.920 /PDM/CritSects/TM Timer Lock/ContentionRZUnlock        0 times
23:34:54.920 /PDM/CritSects/TM VirtualSync Lock/ContentionR3        0 times
23:34:54.920 /PDM/CritSects/TM VirtualSync Lock/ContentionRZLock        0 times
23:34:54.920 /PDM/CritSects/TM VirtualSync Lock/ContentionRZUnlock        0 times
23:34:54.920 /PDM/CritSects/VGA#u/ContentionR3        0 times
23:34:54.920 /PDM/CritSects/VGA#u/ContentionRZLock        0 times
23:34:54.920 /PDM/CritSects/VGA#u/ContentionRZUnlock        0 times
23:34:54.920 /PDM/CritSects/VMMDev#0 Auto/ContentionR3        0 times
23:34:54.920 /PDM/CritSects/VMMDev#0 Auto/ContentionRZLock        0 times
23:34:54.920 /PDM/CritSects/VMMDev#0 Auto/ContentionRZUnlock        0 times
23:34:54.920 /PDM/CritSects/VMMDev#u/ContentionR3        0 times
23:34:54.920 /PDM/CritSects/VMMDev#u/ContentionRZLock        0 times
23:34:54.920 /PDM/CritSects/VMMDev#u/ContentionRZUnlock        0 times
23:34:54.920 /PDM/CritSects/acpi0/ContentionR3        0 times
23:34:54.920 /PDM/CritSects/acpi0/ContentionRZLock        0 times
23:34:54.920 /PDM/CritSects/acpi0/ContentionRZUnlock        0 times
23:34:54.920 /PDM/CritSects/ahci#0 Auto/ContentionR3        0 times
23:34:54.920 /PDM/CritSects/ahci#0 Auto/ContentionRZLock        0 times
23:34:54.920 /PDM/CritSects/ahci#0 Auto/ContentionRZUnlock        0 times
23:34:54.920 /PDM/CritSects/e1000#0 Auto/ContentionR3        0 times
23:34:54.920 /PDM/CritSects/e1000#0 Auto/ContentionRZLock        0 times
23:34:54.920 /PDM/CritSects/e1000#0 Auto/ContentionRZUnlock        0 times
23:34:54.920 /PDM/CritSects/i8259#0 Auto/ContentionR3        0 times
23:34:54.920 /PDM/CritSects/i8259#0 Auto/ContentionRZLock        0 times
23:34:54.920 /PDM/CritSects/i8259#0 Auto/ContentionRZUnlock        0 times
23:34:54.920 /PDM/CritSects/ichac97#0 Auto/ContentionR3        0 times
23:34:54.920 /PDM/CritSects/ichac97#0 Auto/ContentionRZLock        0 times
23:34:54.920 /PDM/CritSects/ichac97#0 Auto/ContentionRZUnlock        0 times
23:34:54.920 /PDM/CritSects/mc146818#0 Auto/ContentionR3        0 times
23:34:54.920 /PDM/CritSects/mc146818#0 Auto/ContentionRZLock        0 times
23:34:54.920 /PDM/CritSects/mc146818#0 Auto/ContentionRZUnlock        0 times
23:34:54.920 /PDM/CritSects/pcarch#0 Auto/ContentionR3        0 times
23:34:54.920 /PDM/CritSects/pcarch#0 Auto/ContentionRZLock        0 times
23:34:54.920 /PDM/CritSects/pcarch#0 Auto/ContentionRZUnlock        0 times
23:34:54.920 /PDM/CritSects/pcbios#0 Auto/ContentionR3        0 times
23:34:54.920 /PDM/CritSects/pcbios#0 Auto/ContentionRZLock        0 times
23:34:54.920 /PDM/CritSects/pcbios#0 Auto/ContentionRZUnlock        0 times
23:34:54.920 /PDM/CritSects/pci#0 Auto/ContentionR3        0 times
23:34:54.920 /PDM/CritSects/pci#0 Auto/ContentionRZLock        0 times
23:34:54.920 /PDM/CritSects/pci#0 Auto/ContentionRZUnlock        0 times
23:34:54.920 /PDM/CritSects/pckbd#0 Auto/ContentionR3        0 times
23:34:54.920 /PDM/CritSects/pckbd#0 Auto/ContentionRZLock        0 times
23:34:54.920 /PDM/CritSects/pckbd#0 Auto/ContentionRZUnlock        0 times
23:34:54.920 /PDM/CritSects/piix3ide#0 Auto/ContentionR3        0 times
23:34:54.920 /PDM/CritSects/piix3ide#0 Auto/ContentionRZLock        0 times
23:34:54.920 /PDM/CritSects/piix3ide#0 Auto/ContentionRZUnlock        0 times
23:34:54.920 /PDM/CritSects/pit/ContentionR3         0 times
23:34:54.920 /PDM/CritSects/pit/ContentionRZLock        0 times
23:34:54.920 /PDM/CritSects/pit/ContentionRZUnlock        0 times
23:34:54.920 /PDM/CritSects/usb-ohci#0 Auto/ContentionR3        0 times
23:34:54.920 /PDM/CritSects/usb-ohci#0 Auto/ContentionRZLock        0 times
23:34:54.920 /PDM/CritSects/usb-ohci#0 Auto/ContentionRZUnlock        0 times
23:34:54.920 /PDM/CritSects/vga#0 Auto/ContentionR3        0 times
23:34:54.920 /PDM/CritSects/vga#0 Auto/ContentionRZLock        0 times
23:34:54.920 /PDM/CritSects/vga#0 Auto/ContentionRZUnlock        0 times
23:34:54.920 /PDM/Queue/AHCI-Xmit/AllocFailures        0 times
23:34:54.920 /PDM/Queue/AHCI-Xmit/Flush              0 calls
23:34:54.920 /PDM/Queue/AHCI-Xmit/FlushLeftovers        0 times
23:34:54.920 /PDM/Queue/AHCI-Xmit/Insert        212817 calls
23:34:54.920 /PDM/Queue/AHCI-Xmit/cItems            60 count
23:34:54.920 /PDM/Queue/AHCI-Xmit/cbItem            32 bytes
23:34:54.920 /PDM/Queue/DevHlp/AllocFailures         0 times
23:34:54.920 /PDM/Queue/DevHlp/Flush                 0 calls
23:34:54.920 /PDM/Queue/DevHlp/FlushLeftovers        0 times
23:34:54.920 /PDM/Queue/DevHlp/Insert                0 calls
23:34:54.920 /PDM/Queue/DevHlp/cItems                8 count
23:34:54.920 /PDM/Queue/DevHlp/cbItem               48 bytes
23:34:54.920 /PDM/Queue/E1000-Rcv/AllocFailures       99 times
23:34:54.920 /PDM/Queue/E1000-Rcv/Flush              0 calls
23:34:54.920 /PDM/Queue/E1000-Rcv/FlushLeftovers        0 times
23:34:54.920 /PDM/Queue/E1000-Rcv/Insert        544244 calls
23:34:54.920 /PDM/Queue/E1000-Rcv/cItems             1 count
23:34:54.920 /PDM/Queue/E1000-Rcv/cbItem            24 bytes
23:34:54.920 /PDM/Queue/E1000-Xmit/AllocFailures       47 times
23:34:54.920 /PDM/Queue/E1000-Xmit/Flush             0 calls
23:34:54.920 /PDM/Queue/E1000-Xmit/FlushLeftovers        0 times
23:34:54.920 /PDM/Queue/E1000-Xmit/Insert         1424 calls
23:34:54.920 /PDM/Queue/E1000-Xmit/cItems            1 count
23:34:54.920 /PDM/Queue/E1000-Xmit/cbItem           24 bytes
23:34:54.920 /PDM/Queue/Keyboard/AllocFailures        0 times
23:34:54.920 /PDM/Queue/Keyboard/Flush               0 calls
23:34:54.920 /PDM/Queue/Keyboard/FlushLeftovers        0 times
23:34:54.920 /PDM/Queue/Keyboard/Insert           7300 calls
23:34:54.920 /PDM/Queue/Keyboard/cItems             64 count
23:34:54.920 /PDM/Queue/Keyboard/cbItem             32 bytes
23:34:54.920 /PDM/Queue/Mouse/AllocFailures          0 times
23:34:54.920 /PDM/Queue/Mouse/Flush                  0 calls
23:34:54.920 /PDM/Queue/Mouse/FlushLeftovers         0 times
23:34:54.920 /PDM/Queue/Mouse/Insert              9724 calls
23:34:54.920 /PDM/Queue/Mouse/cItems               128 count
23:34:54.920 /PDM/Queue/Mouse/cbItem                56 bytes
23:34:54.920 /PGM/CPU0/cGuestModeChanges        731186 times
23:34:54.920 /PGM/ChunkR3Map/Mapped               1123 count
23:34:54.920 /PGM/ChunkR3Map/Unmapped                0 count
23:34:54.920 /PGM/ChunkR3Map/c                    1123 count
23:34:54.920 /PGM/ChunkR3Map/cMax             4294967295 count
23:34:54.920 /PGM/LargePage/Recheck                  0 times
23:34:54.920 /PGM/LargePage/Refused                  0 times
23:34:54.920 /PGM/LargePage/Reused                   0 times
23:34:54.920 /PGM/Page/cAllPages               1052742 count
23:34:54.920 /PGM/Page/cBalloonedPages               0 count
23:34:54.920 /PGM/Page/cHandyPages                  82 count
23:34:54.920 /PGM/Page/cLargePages                   0 count
23:34:54.920 /PGM/Page/cLargePagesDisabled           0 count
23:34:54.920 /PGM/Page/cMonitoredPages               0 count
23:34:54.920 /PGM/Page/cPrivatePages            578962 count
23:34:54.920 /PGM/Page/cPureMmioPages               36 count
23:34:54.920 /PGM/Page/cReadLockedPages             10 count
23:34:54.920 /PGM/Page/cReusedSharedPages            0 count
23:34:54.920 /PGM/Page/cSharedPages                  0 count
23:34:54.920 /PGM/Page/cWriteLockedPages             0 count
23:34:54.920 /PGM/Page/cWrittenToPages               0 count
23:34:54.920 /PGM/Page/cZeroPages               473744 count
23:34:54.920 /PGM/ShMod/Check                        0 ticks/call (           0 ticks,       0 times, max         0, min      -1)
23:34:54.920 /PGM/cRelocations                       3 times
23:34:54.920 /PROF/CPU0/EM/Capped                    0 ticks/call (           0 ticks,       0 times, max         0, min      -1)
23:34:54.920 /PROF/CPU0/EM/ForcedActions      12844879 times
23:34:54.920 /PROF/CPU0/EM/Halted              4795121 times
23:34:54.920 /PROF/CPU0/EM/RAWTotal           10452234 times
23:34:54.920 /PROF/CPU0/EM/REMTotal            5511632 times
23:34:54.920 /PROF/CPU0/EM/Total              240023301768727 ticks/call (240023301768727 ticks,       1 times, max 240023301768727, min 240023301768727)
23:34:54.920 /PROF/VM/CPU0/Halt/Block           224046 ns/call (68769500661462 ticks, 306943150 times, max  19493365, min       1)
23:34:54.920 /PROF/VM/CPU0/Halt/BlockInsomnia        0 ns/call (           0 ticks,       0 times, max         0, min      -1)
23:34:54.920 /PROF/VM/CPU0/Halt/BlockOnTime          0 ns/call (           0 ticks,       0 times, max         0, min      -1)
23:34:54.920 /PROF/VM/CPU0/Halt/BlockOverslept        0 ns/call (           0 ticks,       0 times, max         0, min      -1)
23:34:54.920 /PROF/VM/CPU0/Halt/Timers             472 ns/call (301915642413 ticks, 639198991 times, max   1999619, min       2)
23:34:54.920 /PROF/VM/CPU0/Halt/Yield             2600 ns/call (   180605792 ticks,   69462 times, max    100836, min       1)
23:34:54.920 /REM/TbFlushCount                     248 times
23:34:54.920 /REM/TbPhysInvldCount              823876 times
23:34:54.920 /REM/TlbFlushCount                8007059 times
23:34:54.920 /TM/CPU/00/cNsExecuting          1386194994896 ns
23:34:54.920 /TM/CPU/00/cNsHalted             69183053325265 ns
23:34:54.920 /TM/CPU/00/cNsOther              186198626897 ns
23:34:54.920 /TM/CPU/00/cNsTotal              70755446947058 ns
23:34:54.920 /TM/CPU/00/cPeriodsExecuting     27476804 count
23:34:54.920 /TM/CPU/00/cPeriodsHalted         4715096 count
23:34:54.920 /TM/CPU/00/pctExecuting                 1 %
23:34:54.920 /TM/CPU/00/pctHalted                   98 %
23:34:54.920 /TM/CPU/00/pctOther                     0 %
23:34:54.920 /TM/CPU/pctExecuting                    1 %
23:34:54.920 /TM/CPU/pctHalted                      98 %
23:34:54.920 /TM/CPU/pctOther                        0 %
23:34:54.920 /TM/MaxHzHint                           0 Hz
23:34:54.920 /TM/R3/1nsSteps                   2870956 times
23:34:54.920 /TM/RC/1nsSteps                    130229 times
23:34:54.920 /TM/TSC/offCPU0                         0 ticks
23:34:54.920 /TM/VirtualSync/CurrentOffset      720832 ns
23:34:54.920 /VUSB/0/cUrbsInPool                     0 count
23:34:54.920 ********************* End of statistics **********************
23:34:54.971 Changing the VM state from 'DESTROYING' to 'TERMINATED'.
mpack
Site Moderator
Posts: 39134
Joined: 4. Sep 2008, 17:09
Primary OS: MS Windows 10
VBox Version: VirtualBox+Oracle ExtPack
Guest OSses: Mostly XP

Re: How to diagnose VM crashing issues

Post by mpack »

Partial logs are no good to us. Please post the complete log as a zipped attachment - do not just hose us with pasted text.
 Edit:  I see that Perry or someone else with mod powers has edited the message and bracketed the pasted partial log with phpBB code statements. That certainly helps readability, but I'd still like to read the complete log. 
fakka
Posts: 8
Joined: 12. Sep 2012, 13:58

Re: How to diagnose VM crashing issues

Post by fakka »

Sure
Here is complete log
Just figured that was all the log from the time of isue so would have helped :)
Attachments
FAKDB-2012-09-13-11-29-24.zip
logfile from around issue time
(67.71 KiB) Downloaded 6 times
mpack
Site Moderator
Posts: 39134
Joined: 4. Sep 2008, 17:09
Primary OS: MS Windows 10
VBox Version: VirtualBox+Oracle ExtPack
Guest OSses: Mostly XP

Re: How to diagnose VM crashing issues

Post by mpack »

Great, now all you need to do is tell me what the guest OS is. If I'm interpreting the log correctly, you have a 64bit guest, but have not enabled VT-x. VT-x is required to run 64bit guests.
fakka
Posts: 8
Joined: 12. Sep 2012, 13:58

Re: How to diagnose VM crashing issues

Post by fakka »

Thx.
Guest is Oracle Linux 32 bit.
Acceleration PAE/NX is enabled I believe.
Post Reply