Win7 guest powers off randomly

Discussions about using Windows guests in VirtualBox.
Post Reply
Perryg
Site Moderator
Posts: 34369
Joined: 6. Sep 2008, 22:55
Primary OS: Linux other
VBox Version: OSE self-compiled
Guest OSses: *NIX

Re: Win7 guest powers off randomly

Post by Perryg »

VirtualBox-4.2.22-91556-Linux_amd64.run
OK that's not the tarball. It's precompiled & packed but not a deb package. That explains the location as it is handled the same as OSE.

I build all of my services from source, either tarball or SVN. What I can tell you is I don't have this issue myself, but that doesn't mean too much except that the service is built against the hosts packages and I believe that makes a difference, and have been doing this for far too many years to change now.
Top
marmel
Posts: 75
Joined: 1. Nov 2013, 03:44

Re: Win7 guest powers off randomly

Post by marmel »

I guess I could build from source against wheezy with the native toolchain, and I certainly do agree that's optimal. I backport quite a few packages, so what's a little more building...

Likewise, however, I've been running the generic precompiled binaries for years and don't want to change now. This issue is specific to this one guest, and I would expect some complaints in the host syslog if there were some conflict resulting from the binaries built elsewhere. On the whole, things are extremely stable here with this one exception. Uncaught exception that is. hehe. <-- joke
Top
Perryg
Site Moderator
Posts: 34369
Joined: 6. Sep 2008, 22:55
Primary OS: Linux other
VBox Version: OSE self-compiled
Guest OSses: *NIX

Re: Win7 guest powers off randomly

Post by Perryg »

I hear you. The other option is to install a new copy of the guest and see if it survives better. As strange as it sounds I have seen that actually fix a lot of Windows issues.

I leave you with one final thought. Several years ago (3 maybe 4) we were discussing an issue with guest audio not working using the generic *.run binary, but it would with the deb build. So stranger things do happen especially with intermittent issues.

I will watch this topic for your updates.
Top
marmel
Posts: 75
Joined: 1. Nov 2013, 03:44

Re: Win7 guest powers off randomly

Post by marmel »

Thanks for all your followups. I got in the habit of using the generic binaries due to Arch Linux. As the Oracle folks are nice enough to provide distro-specific packages, I've removed the generic /opt install, and I am now using the Wheezy .deb.

Unfortunately, the exact same behavior is observed. After ~21 minutes (always a different length of time), the guest just gets powered off uncleanly. Mini-dumps are enabled, but none are generated. There is no stop error. From Windows' perspective, it's as if the virtual power plug has been pulled.
Top
Perryg
Site Moderator
Posts: 34369
Joined: 6. Sep 2008, 22:55
Primary OS: Linux other
VBox Version: OSE self-compiled
Guest OSses: *NIX

Re: Win7 guest powers off randomly

Post by Perryg »

Try this and see what it shows:
Start the guest from a terminal window and then minimize the terminal window. Once the guest aborts look and see if anything is recorded in the terminal window.

Code: Select all

VBoxManage startvm <vmname>
Top
marmel
Posts: 75
Joined: 1. Nov 2013, 03:44

Re: Win7 guest powers off randomly

Post by marmel »

Using VBoxManage spawns the process as a child of the vbox service rather than bash, so it will not output anything further to the terminal.

Code: Select all

 virtualbox  --startvm
This has started the vm as desired. Hopefully get some output when it resets:

Code: Select all

marc      3936  0.0  0.1  79944 16560 ?        S    15:58   0:00 xterm -class UXTerm -title uxterm -u8
marc      3940  0.0  0.0  20872  3788 pts/0    Ss   15:58   0:00  \_ bash
marc      4358  8.5 35.4 5094996 4369604 pts/0 Sl+  16:13   0:17      \_ /usr/lib/virtualbox/VirtualBox --startvm /media/Data/Virtual/Win7 Pro Development + VPN/Win7 Pro Development + VPN.vbox

Addtionally, I enabled the option to catch when the vm wants to reset:

Code: Select all

VBoxManage setextradata /media/Data/Virtual/Win7\ Pro\ Development\ +\ VPN/Win7\ Pro\ Development\ +\ VPN.vbox "VBoxInternal/PDM/HaltOnReset" 1
Top
marmel
Posts: 75
Joined: 1. Nov 2013, 03:44

Re: Win7 guest powers off randomly

Post by marmel »

Well, I'm at a complete loss to explain this. The unprompted shutdown happened in the middle of the night last night, after one week of uptime, including nightly vm pauses, and suspend/resumes of the host system.
vbox-win7-poweroff-bug-command-line-7-days.png
vbox-win7-poweroff-bug-command-line-7-days.png (14.78 KiB) Viewed 4015 times
Running from the command line seemed promising, but yeilded no output. The command completed "successfully".

Also the VBoxInternal/PDM/HaltOnReset option did not catch anything, as windows is not throwing a bug check, and is not crashing. As it's always appeared, virtualbox is sending a poweroff signal to the guest.
Attachments
virtualbox-poweroff-bug-command-line.log
(90.61 KiB) Downloaded 15 times
Top
noteirak
Site Moderator
Posts: 5231
Joined: 13. Jan 2012, 11:14
Primary OS: Debian other
VBox Version: OSE Debian
Guest OSses: Debian, Win 2k8, Win 7
Contact:
Contact noteirak

Re: Win7 guest powers off randomly

Post by noteirak »

We keep talking about what's happening in the guest, and we can't get to anything.
What about what is happening on the host? Is this the only VM you are running? does the host have lots of free ram? Have you tried checking RAM & CPU usage before/after a crash?
Hyperbox - Virtual Infrastructure Manager - https://apps.kamax.lu/hyperbox/
Manage your VirtualBox infrastructure the free way!
Top
marmel
Posts: 75
Joined: 1. Nov 2013, 03:44

Re: Win7 guest powers off randomly

Post by marmel »

noteirak wrote:We keep talking about what's happening in the guest, and we can't get to anything.
What about what is happening on the host? Is this the only VM you are running? does the host have lots of free ram? Have you tried checking RAM & CPU usage before/after a crash?
See the OP for answers to every question you asked!

To summarize: Nothing is happening on the host. This is not the only VM, but it IS the only one with an issue. The host has 16GB RAM, about half of which is free at any given time. CPU useage is negligable on the host unless I'm compiling.
Top
marmel
Posts: 75
Joined: 1. Nov 2013, 03:44

Re: Win7 guest powers off randomly

Post by marmel »

Additionally, I've been individually validating each of my stick of RAM with memtest. Currently I only have 12GB installed currently, but RAM could have been ruled out anyhow as their are no other issues with the host or VM stability long-term.

I build the last of the 3.12.x kernels this past weekend (3.12.9), just on the off chance that there was something finicky happening with power management on the 3.10.x long-term kernel I usually run.

As I was typing my previous response, the vm in question disappeared as usual. Not that I really thought these super-stable kernels had any bearing...

I've pasted the log in the code block for those averse to viewing log attachments:

Code: Select all

VirtualBox VM 4.2.22 r91556 linux.amd64 (Jan 11 2014 00:33:43) release log
00:00:00.251029 Log opened 2014-02-02T18:21:52.455967000Z
00:00:00.251031 OS Product: Linux
00:00:00.251032 OS Release: 3.12.9+mfm
00:00:00.251032 OS Version: #1 SMP Sun Feb 2 13:07:55 EST 2014
00:00:00.251043 DMI Product Name:         
00:00:00.251046 DMI Product Version:         
00:00:00.251086 Host RAM: 12022MB total, 11536MB available
00:00:00.251087 Executable: /usr/lib/virtualbox/VirtualBox
00:00:00.251088 Process ID: 4018
00:00:00.251088 Package type: LINUX_64BITS_DEBIAN_7_0
00:00:00.326692 Installed Extension Packs:
00:00:00.326699   None installed!
00:00:00.328492 Using XKB for keycode to scan code conversion
00:00:00.334871 SUP: Loaded VMMR0.r0 (/usr/lib/virtualbox/VMMR0.r0) at 0xffffffffa0f31020 - ModuleInit at ffffffffa0f474b0 and ModuleTerm at ffffffffa0f47740
00:00:00.334888 SUP: VMMR0EntryEx located at ffffffffa0f48900, VMMR0EntryFast at ffffffffa0f48630 and VMMR0EntryInt at ffffffffa0f48620
00:00:00.336153 OS type: 'Windows7_64'
00:00:00.340029 File system of '/media/Data/Virtual/Win7 Pro Development + VPN/Snapshots' (snapshots) is unknown
00:00:00.340042 File system of '/media/virtual/Virtual-Disks/Win7-Pro-Dev-VPN.vdi' is ext4
00:00:00.350006 Shared clipboard mode: Bidirectional
00:00:00.350717 Drag'n'drop mode: Off
00:00:00.456196 HWACCM: Host CR4=000407F0
00:00:00.456197 HWACCM: MSR_IA32_FEATURE_CONTROL      = 5
00:00:00.456198 HWACCM: MSR_IA32_VMX_BASIC_INFO       = da040000000010
00:00:00.456198 HWACCM: VMCS id                       = 10
00:00:00.456199 HWACCM: VMCS size                     = 400
00:00:00.456199 HWACCM: VMCS physical address limit   = None
00:00:00.456200 HWACCM: VMCS memory type              = 6
00:00:00.456200 HWACCM: Dual monitor treatment        = 1
00:00:00.456200 HWACCM: MSR_IA32_VMX_PINBASED_CTLS    = 7f00000016
00:00:00.456201 HWACCM:    VMX_VMCS_CTRL_PIN_EXEC_CONTROLS_EXT_INT_EXIT
00:00:00.456202 HWACCM:    VMX_VMCS_CTRL_PIN_EXEC_CONTROLS_NMI_EXIT
00:00:00.456202 HWACCM:    VMX_VMCS_CTRL_PIN_EXEC_CONTROLS_VIRTUAL_NMI
00:00:00.456202 HWACCM:    VMX_VMCS_CTRL_PIN_EXEC_CONTROLS_PREEMPT_TIMER
00:00:00.456203 HWACCM: MSR_IA32_VMX_PROCBASED_CTLS   = fff9fffe0401e172
00:00:00.456203 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_IRQ_WINDOW_EXIT
00:00:00.456204 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_TSC_OFFSET
00:00:00.456204 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_HLT_EXIT
00:00:00.456204 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_INVLPG_EXIT
00:00:00.456205 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_MWAIT_EXIT
00:00:00.456205 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_RDPMC_EXIT
00:00:00.456205 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_RDTSC_EXIT
00:00:00.456205 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_CR3_LOAD_EXIT
00:00:00.456206 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_CR3_STORE_EXIT
00:00:00.456206 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_CR8_LOAD_EXIT
00:00:00.456206 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_CR8_STORE_EXIT
00:00:00.456207 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_USE_TPR_SHADOW
00:00:00.456207 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_NMI_WINDOW_EXIT
00:00:00.456207 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_MOV_DR_EXIT
00:00:00.456207 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_UNCOND_IO_EXIT
00:00:00.456208 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_USE_IO_BITMAPS
00:00:00.456209 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_MONITOR_TRAP_FLAG
00:00:00.456209 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_USE_MSR_BITMAPS
00:00:00.456209 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_MONITOR_EXIT
00:00:00.456209 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_PAUSE_EXIT
00:00:00.456210 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_USE_SECONDARY_EXEC_CTRL
00:00:00.456210 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_CR3_LOAD_EXIT *must* be set
00:00:00.456211 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC_CONTROLS_CR3_STORE_EXIT *must* be set
00:00:00.456211 HWACCM: MSR_IA32_VMX_PROCBASED_CTLS2  = ff00000000
00:00:00.456212 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC2_VIRT_APIC
00:00:00.456212 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC2_EPT
00:00:00.456212 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC2_DESCRIPTOR_INSTR_EXIT
00:00:00.456213 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC2_RDTSCP
00:00:00.456213 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC2_X2APIC
00:00:00.456213 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC2_VPID
00:00:00.456213 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC2_WBINVD_EXIT
00:00:00.456214 HWACCM:    VMX_VMCS_CTRL_PROC_EXEC2_REAL_MODE
00:00:00.456214 HWACCM: MSR_IA32_VMX_ENTRY_CTLS       = ffff000011ff
00:00:00.456215 HWACCM:    VMX_VMCS_CTRL_ENTRY_CONTROLS_LOAD_DEBUG
00:00:00.456215 HWACCM:    VMX_VMCS_CTRL_ENTRY_CONTROLS_IA64_MODE
00:00:00.456215 HWACCM:    VMX_VMCS_CTRL_ENTRY_CONTROLS_ENTRY_SMM
00:00:00.456215 HWACCM:    VMX_VMCS_CTRL_ENTRY_CONTROLS_DEACTIVATE_DUALMON
00:00:00.456216 HWACCM:    VMX_VMCS_CTRL_ENTRY_CONTROLS_LOAD_GUEST_PERF_MSR
00:00:00.456216 HWACCM:    VMX_VMCS_CTRL_ENTRY_CONTROLS_LOAD_GUEST_PAT_MSR
00:00:00.456216 HWACCM:    VMX_VMCS_CTRL_ENTRY_CONTROLS_LOAD_GUEST_EFER_MSR
00:00:00.456217 HWACCM:    VMX_VMCS_CTRL_ENTRY_CONTROLS_LOAD_DEBUG *must* be set
00:00:00.456217 HWACCM: MSR_IA32_VMX_EXIT_CTLS        = 7fffff00036dff
00:00:00.456218 HWACCM:    VMX_VMCS_CTRL_EXIT_CONTROLS_SAVE_DEBUG
00:00:00.456218 HWACCM:    VMX_VMCS_CTRL_EXIT_CONTROLS_HOST_AMD64
00:00:00.456219 HWACCM:    VMX_VMCS_CTRL_EXIT_CONTROLS_ACK_EXTERNAL_IRQ
00:00:00.456219 HWACCM:    VMX_VMCS_CTRL_EXIT_CONTROLS_SAVE_GUEST_PAT_MSR
00:00:00.456219 HWACCM:    VMX_VMCS_CTRL_EXIT_CONTROLS_LOAD_HOST_PAT_MSR
00:00:00.456220 HWACCM:    VMX_VMCS_CTRL_EXIT_CONTROLS_SAVE_GUEST_EFER_MSR
00:00:00.456220 HWACCM:    VMX_VMCS_CTRL_EXIT_CONTROLS_LOAD_HOST_EFER_MSR
00:00:00.456220 HWACCM:    VMX_VMCS_CTRL_EXIT_CONTROLS_SAVE_VMX_PREEMPT_TIMER
00:00:00.456221 HWACCM:    VMX_VMCS_CTRL_EXIT_CONTROLS_SAVE_DEBUG *must* be set
00:00:00.456221 HWACCM: MSR_IA32_VMX_EPT_VPID_CAPS    = f0106114141
00:00:00.456221 HWACCM:    MSR_IA32_VMX_EPT_CAPS_RWX_X_ONLY
00:00:00.456222 HWACCM:    MSR_IA32_VMX_EPT_CAPS_GAW_48_BITS
00:00:00.456222 HWACCM:    MSR_IA32_VMX_EPT_CAPS_EMT_UC
00:00:00.456222 HWACCM:    MSR_IA32_VMX_EPT_CAPS_EMT_WB
00:00:00.456223 HWACCM:    MSR_IA32_VMX_EPT_CAPS_SP_21_BITS
00:00:00.456223 HWACCM:    MSR_IA32_VMX_EPT_CAPS_INVEPT
00:00:00.456223 HWACCM:    MSR_IA32_VMX_EPT_CAPS_INVEPT_CAPS_SINGLE_CONTEXT
00:00:00.456223 HWACCM:    MSR_IA32_VMX_EPT_CAPS_INVEPT_CAPS_ALL_CONTEXTS
00:00:00.456224 HWACCM:    MSR_IA32_VMX_EPT_CAPS_INVVPID
00:00:00.456224 HWACCM:    MSR_IA32_VMX_EPT_CAPS_INVVPID_CAPS_INDIV_ADDR
00:00:00.456224 HWACCM:    MSR_IA32_VMX_EPT_CAPS_INVVPID_CAPS_SINGLE_CONTEXT
00:00:00.456225 HWACCM:    MSR_IA32_VMX_EPT_CAPS_INVVPID_CAPS_ALL_CONTEXTS
00:00:00.456225 HWACCM:    MSR_IA32_VMX_EPT_CAPS_INVVPID_CAPS_SINGLE_CONTEXT_RETAIN_GLOBALS
00:00:00.456225 HWACCM: MSR_IA32_VMX_MISC             = 100401e5
00:00:00.456226 HWACCM:    MSR_IA32_VMX_MISC_PREEMPT_TSC_BIT 5
00:00:00.456226 HWACCM:    MSR_IA32_VMX_MISC_ACTIVITY_STATES 7
00:00:00.456226 HWACCM:    MSR_IA32_VMX_MISC_CR3_TARGET      4
00:00:00.456227 HWACCM:    MSR_IA32_VMX_MISC_MAX_MSR         200
00:00:00.456227 HWACCM:    MSR_IA32_VMX_MISC_MSEG_ID         0
00:00:00.456227 HWACCM: MSR_IA32_VMX_CR0_FIXED0       = 80000021
00:00:00.456228 HWACCM: MSR_IA32_VMX_CR0_FIXED1       = ffffffff
00:00:00.456228 HWACCM: MSR_IA32_VMX_CR4_FIXED0       = 2000
00:00:00.456229 HWACCM: MSR_IA32_VMX_CR4_FIXED1       = 627ff
00:00:00.456229 HWACCM: MSR_IA32_VMX_VMCS_ENUM        = 2a
00:00:00.456229 HWACCM: TPR shadow physaddr           = 00000000cd546000
00:00:00.456230 HWACCM: VCPU0: MSR bitmap physaddr    = 00000000cd543000
00:00:00.456231 HWACCM: VCPU0: VMCS physaddr          = 00000000cd545000
00:00:00.456231 HWACCM: VCPU1: MSR bitmap physaddr    = 00000000cd53e000
00:00:00.456232 HWACCM: VCPU1: VMCS physaddr          = 00000000cd540000
00:00:00.456241 CPUMSetGuestCpuIdFeature: Enabled sysenter/exit
00:00:00.456242 CPUMSetGuestCpuIdFeature: Enabled PAE
00:00:00.456242 CPUMSetGuestCpuIdFeature: Enabled LONG MODE
00:00:00.456242 CPUMSetGuestCpuIdFeature: Enabled syscall/ret
00:00:00.456243 CPUMSetGuestCpuIdFeature: Enabled LAHF/SAHF
00:00:00.456243 CPUMSetGuestCpuIdFeature: Enabled NX
00:00:00.456243 HWACCM: 32-bit and 64-bit guests supported.
00:00:00.456244 HWACCM: VMX enabled!
00:00:00.456244 HWACCM: Enabled nested paging
00:00:00.456244 HWACCM: EPT root page                 = 00000000d3e6e000
00:00:00.456245 HWACCM: enmFlushEPT                   = VMX_FLUSH_EPT_SINGLE_CONTEXT
00:00:00.456245 HWACCM: Unrestricted guest execution enabled!
00:00:00.456246 HWACCM: Enabled VPID
00:00:00.456246 HWACCM: enmFlushVPID                  = VMX_FLUSH_VPID_SINGLE_CONTEXT
00:00:00.456246 HWACCM: TPR Patching disabled.
00:00:00.456247 HWACCM: Using the VMX-preemption timer (cPreemptTimerShift=5)
00:00:00.456248 HWACCM:    VT-x/AMD-V init method: GLOBAL
00:00:00.461389 VM: Halt method global1 (5)
00:00:00.461422 HaltedGlobal1 config: cNsSpinBlockThresholdCfg=2000
00:00:00.461448 Changing the VM state from 'CREATING' to 'CREATED'.
00:00:00.485740 SharedFolders host service: adding host mapping
00:00:00.485747     Host path '/home/marc/development', map name 'development-host', writable, automount=true, create_symlinks=false, missing=false
00:00:00.486226 SharedFolders host service: adding host mapping
00:00:00.486235     Host path '/home/marc/downloads', map name 'downloads-host', writable, automount=true, create_symlinks=false, missing=false
00:00:00.486294 Changing the VM state from 'CREATED' to 'POWERING_ON'.
00:00:00.486319 AIOMgr: Endpoints without assigned bandwidth groups:
00:00:00.486323 AIOMgr:     /media/virtual/Virtual-Disks/Win7-Pro-Dev-VPN.vdi
00:00:00.486356 Changing the VM state from 'POWERING_ON' to 'RUNNING'.
00:00:00.489663 Guest Log: BIOS: VirtualBox 4.2.22
00:00:00.489737 PIT: mode=2 count=0x10000 (65536) - 18.20 Hz (ch=0)
00:00:00.494232 ERROR [COM]: aRC=VBOX_E_IPRT_ERROR (0x80bb0005) aIID={b83ee395-8679-40ca-8d60-1a0cbe724930} aComponent={Display} aText={Could not take a screenshot (VERR_NOT_SUPPORTED)}, preserve=false
00:00:00.499150 ERROR [COM]: aRC=VBOX_E_IPRT_ERROR (0x80bb0005) aIID={b83ee395-8679-40ca-8d60-1a0cbe724930} aComponent={Display} aText={Could not take a screenshot (VERR_NOT_SUPPORTED)}, preserve=false
00:00:00.500468 PIIX3 ATA: Ctl#0: RESET, DevSel=0 AIOIf=0 CmdIf0=0x00 (-1 usec ago) CmdIf1=0x00 (-1 usec ago)
00:00:00.500491 PIIX3 ATA: Ctl#0: finished processing RESET
00:00:00.504795 AHCI#0: Reset the HBA
00:00:00.504841 AHCI#0: Port 0 reset
00:00:00.505065 Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 67108864 sectors
00:00:00.505094 PIT: mode=2 count=0x48d3 (18643) - 64.00 Hz (ch=0)
00:00:00.515185 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=00007f9458f8a000 w=640 h=480 bpp=32 cbLine=0xA00, flags=0x1
00:00:00.678544 2D video acceleration is disabled.
00:00:02.978209 PIT: mode=2 count=0x10000 (65536) - 18.20 Hz (ch=0)
00:00:02.978333 Guest Log: BIOS: Boot : bseqnr=1, bootseq=0002
00:00:02.978738 Guest Log: BIOS: Booting from Hard Disk...
00:00:02.985213 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=0000000000000000 w=720 h=400 bpp=0 cbLine=0x0, flags=0x1
00:00:03.155183 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=00007f9458f8a000 w=1024 h=768 bpp=24 cbLine=0xC00, flags=0x1
00:00:06.356656 AHCI#0: Reset the HBA
00:00:06.367503 Guest Additions information report: Version 4.2.22 r91556 '4.2.22'
00:00:06.367540 Guest Additions information report: Interface = 0x00010004 osType = 0x00037100
00:00:06.367581 Guest Additions capability report: (0x0) seamless: no, hostWindowMapping: no, graphics: no
00:00:06.367687 Guest reported fixed hypervisor window at 0x0000000008000000 (size = 0x2800000, rc = VINF_SUCCESS)
00:00:06.697975 AIOMgr: Flush failed with VERR_INVALID_PARAMETER, disabling async flushes
00:00:07.270368 Guest Log: VBoxMP::VBoxDrvFindAdapter: using HGSMI
00:00:07.808743 Guest Log: VBoxDisp[0]: VBVA enabled
00:00:07.808762 VBVA_INFO_SCREEN: [0] @0,0 2560x1440, line 0x2800, BPP 32, flags 0x1
00:00:07.808767 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=00007f9458f8a000 w=2560 h=1440 bpp=32 cbLine=0x2800, flags=0x1
00:00:07.817578 Display::handleDisplayResize(): uScreenId = 0, pvVRAM=00007f9458f8a000 w=2560 h=1440 bpp=32 cbLine=0x2800, flags=0x1
00:00:10.192992 Starting host clipboard service
00:00:10.193011 Initializing X11 clipboard backend
00:00:10.193829 Shared clipboard: starting shared clipboard thread
00:00:10.760653 Guest Additions capability report: (0x1) seamless: yes, hostWindowMapping: no, graphics: no
00:00:10.760696 Guest Additions capability report: (0x5) seamless: yes, hostWindowMapping: no, graphics: yes
00:20:21.179335 Changing the VM state from 'RUNNING' to 'SUSPENDING'.
00:20:21.181702 AIOMgr: Endpoint for file '/media/virtual/Virtual-Disks/Win7-Pro-Dev-VPN.vdi' (flags 000c0781) created successfully
00:20:21.278847 PDMR3Suspend: 99 486 236 ns run time
00:20:21.278859 Changing the VM state from 'SUSPENDING' to 'SUSPENDED'.
00:37:42.908331 Changing the VM state from 'SUSPENDED' to 'RESUMING'.
00:37:42.908397 AIOMgr: Endpoint for file '/media/virtual/Virtual-Disks/Win7-Pro-Dev-VPN.vdi' (flags 000c0723) created successfully
00:37:42.909733 Changing the VM state from 'RESUMING' to 'RUNNING'.
00:37:49.994373 AIOMgr: Flush failed with VERR_INVALID_PARAMETER, disabling async flushes
06:51:24.614143 Changing the VM state from 'RUNNING' to 'SUSPENDING'.
06:51:24.616627 AIOMgr: Endpoint for file '/media/virtual/Virtual-Disks/Win7-Pro-Dev-VPN.vdi' (flags 000c0781) created successfully
06:51:24.674726 PDMR3Suspend: 60 548 768 ns run time
06:51:24.674735 Changing the VM state from 'SUSPENDING' to 'SUSPENDED'.
07:10:12.797830 Changing the VM state from 'SUSPENDED' to 'RESUMING'.
07:10:12.798054 AIOMgr: Endpoint for file '/media/virtual/Virtual-Disks/Win7-Pro-Dev-VPN.vdi' (flags 000c0723) created successfully
07:10:12.800059 Changing the VM state from 'RESUMING' to 'RUNNING'.
07:10:16.476020 AIOMgr: Flush failed with VERR_INVALID_PARAMETER, disabling async flushes

07:40:04.134019 Guest requests the VM to be turned off  <-- LIES, ALL LIES!!! :)

07:40:04.134044 Changing the VM state from 'RUNNING' to 'POWERING_OFF'.
07:40:04.134057 ****************** Guest state at power off ******************
07:40:04.134064 Guest CPUM (VCPU 0) state: 
07:40:04.134066 rax=00000000dbda6094 rbx=fffff8800369c094 rcx=ffffffff00000000 rdx=000000000000d020
07:40:04.134070 rsi=fffffa8004c93010 rdi=0000000000000004 r8 =fffffa8004c93128 r9 =fffffa8004c93010
07:40:04.134072 r10=0000000000000000 r11=0000000000000000 r12=0000000000000000 r13=fffff800027f7a00
07:40:04.134073 r14=0000000000000000 r15=fffff880009ebec0
07:40:04.134074 rip=fffff88001221194 rsp=fffff880009dfc30 rbp=0000000000000000 iopl=0         nv up ei pl nz na po nc
07:40:04.134075 cs={0010 base=0000000000000000 limit=00000000 flags=0000209b}
07:40:04.134076 ds={002b base=0000000000000000 limit=ffffffff flags=0000c0f3}
07:40:04.134077 es={002b base=0000000000000000 limit=ffffffff flags=0000c0f3}
07:40:04.134077 fs={0053 base=00000000fffa4000 limit=00003c00 flags=000040f3}
07:40:04.134078 gs={002b base=fffff80002856d00 limit=ffffffff flags=0000c0f3}
07:40:04.134079 ss={0018 base=0000000000000000 limit=ffffffff flags=0000c093}
07:40:04.134080 cr0=0000000080050031 cr2=fffff88000c1e158 cr3=0000000000187000 cr4=00000000000006f8
07:40:04.134081 dr0=0000000000000000 dr1=0000000000000000 dr2=0000000000000000 dr3=0000000000000000
07:40:04.134082 dr4=0000000000000000 dr5=0000000000000000 dr6=00000000ffff0ff0 dr7=0000000000000400
07:40:04.134083 gdtr=fffff80003c46000:007f  idtr=fffff80003c46080:0fff  eflags=00000246
07:40:04.134084 ldtr={0000 base=00000000 limit=00000000 flags=00000082}
07:40:04.134085 tr  ={0040 base=fffff80003c47080 limit=00000067 flags=0000008b}
07:40:04.134086 SysEnter={cs=0000 eip=0000000000000000 esp=0000000000000000}
07:40:04.134087 FCW=027f FSW=3800 FTW=0080 FOP=0000 MXCSR=00001f80 MXCSR_MASK=0000ffff
07:40:04.134088 FPUIP=026dedc3 CS=0010 Rsrvd1=0000  FPUDP=045c1e00 DS=002b Rsvrd2=0000
07:40:04.134089 ST(0)=FPR7={4008'9fc00000'00000000} t1 +1.0002287828610704211968 ^ 16392
07:40:04.134091 ST(1)=FPR0={4008'9fc00000'00000000} t0 +1.0002287828610704211968 ^ 16392
07:40:04.134093 ST(2)=FPR1={4008'9fc00000'00000000} t0 +1.0002287828610704211968 ^ 16392
07:40:04.134094 ST(3)=FPR2={4008'9fc00000'00000000} t0 +1.0002287828610704211968 ^ 16392
07:40:04.134096 ST(4)=FPR3={4008'9fc00000'00000000} t0 +1.0002287828610704211968 ^ 16392
07:40:04.134097 ST(5)=FPR4={4008'9fc00000'00000000} t0 +1.0002287828610704211968 ^ 16392
07:40:04.134099 ST(6)=FPR5={4008'9fc00000'00000000} t0 +1.0002287828610704211968 ^ 16392
07:40:04.134100 ST(7)=FPR6={4008'9fc00000'00000000} t0 +1.0002287828610704211968 ^ 16392
07:40:04.134101 XMM0 =e40b9ea6'd4eee4cc'a87cba14'354b52f8  XMM1 =00000000'00000000'00000000'00000000
07:40:04.134103 XMM2 =00000000'00000000'00000000'00000000  XMM3 =00000000'00000000'00000000'00000000
07:40:04.134105 XMM4 =00000000'00000000'00000000'00000000  XMM5 =00000000'00000000'00000000'00000000
07:40:04.134106 XMM6 =00000000'00000000'00000000'00000000  XMM7 =00000000'00000000'00000000'00000000
07:40:04.134107 XMM8 =00000000'00000000'00000000'00000000  XMM9 =00000000'00000000'00000000'00000000
07:40:04.134108 XMM10=00000000'00000000'00000000'00000000  XMM11=00000000'00000000'00000000'00000000
07:40:04.134110 XMM12=00000000'00000000'00000000'00000000  XMM13=00000000'00000000'00000000'00000000
07:40:04.134111 XMM14=00000000'00000000'00000000'00000000  XMM15=00000000'00000000'00000000'00000000
07:40:04.134112 EFER         =0000000000000d01
07:40:04.134113 PAT          =0007010600070106
07:40:04.134114 STAR         =0023001000000000
07:40:04.134114 CSTAR        =fffff800026da900
07:40:04.134115 LSTAR        =fffff800026dabc0
07:40:04.134115 SFMASK       =0000000000004700
07:40:04.134116 KERNELGSBASE =000007fffffa2000
07:40:04.134116 ***
07:40:04.134119 Guest paging mode:  AMD64+NX (changed 65054 times), A20 enabled (changed 2 times)
07:40:04.134120 Shadow paging mode: EPT
07:40:04.134120 Host paging mode:   AMD64+G+NX
07:40:04.134121 ***
07:40:04.134122 Active Timers (pVM=00007f946559e000)
07:40:04.134122 pTimerR3         offNext  offPrev  offSched Clock               Time             Expire HzHint State                     Description
07:40:04.134124 00007f9464936a60 000115a0 00000000 00000000 Real            27672177           27672178      0 2-ACTIVE                  VGA Refresh Timer
07:40:04.134127 00007f9464948000 00000000 fffeea60 00000000 Real            27672177           27673169      0 2-ACTIVE                  CPU Load Timer
07:40:04.134129 00007f946492d840 00000700 00000000 00000000 VrSy      25433891202008     25433903190722    312 2-ACTIVE                  HPET Timer
07:40:04.134131 00007f946492df40 00000670 fffff900 00000000 VrSy      25433891204403     25433940889411     18 2-ACTIVE                  i8254 Programmable Interval Timer
07:40:04.134133 00007f946492e5b0 fffff310 fffff990 00000000 VrSy      25433891206563     25433990000000      0 2-ACTIVE                  MC146818 RTC/CMOS - Second
07:40:04.134136 00007f946492d8c0 00019500 00000cf0 00000000 VrSy      25433891208763     25500905375628      0 2-ACTIVE                  HPET Timer
07:40:04.134138 00007f9464946dc0 00000000 fffe6b00 00000000 VrSy      25433891210732     26397008695222      0 2-ACTIVE                  ACPI PM Timer
07:40:04.134140 ***
07:40:04.134141 Shadow GDT (GCAddr=fe4a1000):
07:40:04.134152 ffd8 - 81980087 fd008940 - base=fd408198 limit=00000087 dpl=0 TSS32Avail Present 16-bit  HyperTSSTrap08
07:40:04.134153 ffe0 - 81100087 fd008940 - base=fd408110 limit=00000087 dpl=0 TSS32Avail Present 16-bit  HyperTSS
07:40:04.134155 ffe8 - 0000ffff 00af9b00 - base=00000000 limit=ffffffff dpl=0 CodeER Accessed Present Page 16-bit  HyperCS64
07:40:04.134156 fff0 - 0000ffff 00cf9300 - base=00000000 limit=ffffffff dpl=0 DataRW Accessed Present Page 32-bit  HyperDS
07:40:04.134157 fff8 - 0000ffff 00cf9b00 - base=00000000 limit=ffffffff dpl=0 CodeER Accessed Present Page 32-bit  HyperCS
07:40:04.134157 ***
07:40:04.134158 ************** End of Guest state at power off ***************
07:40:04.158868 PDMR3PowerOff: 24 696 656 ns run time
07:40:04.158879 Changing the VM state from 'POWERING_OFF' to 'OFF'.
07:40:04.159258 Console::powerDown(): A request to power off the VM has been issued (mMachineState=Stopping, InUninit=0)
07:40:04.159449 Stopping the host clipboard service
07:40:04.159455 ClipStopX11: stopping the shared clipboard X11 backend
07:40:04.159469 Shared clipboard: shared clipboard thread terminated successfully
07:40:04.159967 Changing the VM state from 'OFF' to 'DESTROYING'.
07:40:04.159986 ************************* Statistics *************************
07:40:04.160042 /Devices/E1k0/ReceiveBytes        7433349 bytes
07:40:04.160046 /Devices/E1k0/TransmitBytes       2042749 bytes
07:40:04.160048 /Devices/IDE0/ATA0/Unit0/AtapiDMA        0 times
07:40:04.160049 /Devices/IDE0/ATA0/Unit0/AtapiPIO    25570 times
07:40:04.160051 /Devices/IDE0/ATA0/Unit0/DMA            0 times
07:40:04.160053 /Devices/IDE0/ATA0/Unit0/PIO            0 times
07:40:04.160054 /Devices/IDE0/ATA0/Unit0/ReadBytes        0 bytes
07:40:04.160056 /Devices/IDE0/ATA0/Unit0/WrittenBytes        0 bytes
07:40:04.160057 /Devices/IDE0/ATA0/Unit1/AtapiDMA        0 times
07:40:04.160059 /Devices/IDE0/ATA0/Unit1/AtapiPIO        0 times
07:40:04.160060 /Devices/IDE0/ATA0/Unit1/DMA            0 times
07:40:04.160062 /Devices/IDE0/ATA0/Unit1/PIO            0 times
07:40:04.160063 /Devices/IDE0/ATA0/Unit1/ReadBytes        0 bytes
07:40:04.160071 /Devices/IDE0/ATA0/Unit1/WrittenBytes        0 bytes
07:40:04.160073 /Devices/IDE0/ATA1/Unit0/AtapiDMA        0 times
07:40:04.160074 /Devices/IDE0/ATA1/Unit0/AtapiPIO        0 times
07:40:04.160076 /Devices/IDE0/ATA1/Unit0/DMA            0 times
07:40:04.160078 /Devices/IDE0/ATA1/Unit0/PIO            0 times
07:40:04.160079 /Devices/IDE0/ATA1/Unit0/ReadBytes        0 bytes
07:40:04.160081 /Devices/IDE0/ATA1/Unit0/WrittenBytes        0 bytes
07:40:04.160082 /Devices/IDE0/ATA1/Unit1/AtapiDMA        0 times
07:40:04.160083 /Devices/IDE0/ATA1/Unit1/AtapiPIO        0 times
07:40:04.160085 /Devices/IDE0/ATA1/Unit1/DMA            0 times
07:40:04.160086 /Devices/IDE0/ATA1/Unit1/PIO            0 times
07:40:04.160088 /Devices/IDE0/ATA1/Unit1/ReadBytes        0 bytes
07:40:04.160089 /Devices/IDE0/ATA1/Unit1/WrittenBytes        0 bytes
07:40:04.160091 /Devices/SATA0/Port0/DMA            60560 times
07:40:04.160092 /Devices/SATA0/Port0/ReadBytes   959118848 bytes
07:40:04.160094 /Devices/SATA0/Port0/WrittenBytes 62175744 bytes
07:40:04.160097 /Devices/VMMDev/BalloonChunks           0 count
07:40:04.160099 /Drivers/IntNet-0/BadFrames             0 count
07:40:04.160101 /Drivers/IntNet-0/Bytes/Received  7318018 bytes
07:40:04.160102 /Drivers/IntNet-0/Bytes/Sent      2042749 bytes
07:40:04.160104 /Drivers/IntNet-0/Overflows/Recv        0 count
07:40:04.160105 /Drivers/IntNet-0/Overflows/Sent        0 count
07:40:04.160106 /Drivers/IntNet-0/Packets/Lost          0 count
07:40:04.160108 /Drivers/IntNet-0/Packets/Received    29944 count
07:40:04.160109 /Drivers/IntNet-0/Packets/Received-Gso        0 count
07:40:04.160111 /Drivers/IntNet-0/Packets/Sent      15555 count
07:40:04.160112 /Drivers/IntNet-0/Packets/Sent-Gso        0 count
07:40:04.160114 /Drivers/IntNet-0/Packets/Sent-R0    15515 count
07:40:04.160115 /Drivers/IntNet-0/Recv1                 0 ticks/call (           0 ticks,       0 times, max         0, min      -1)
07:40:04.160117 /Drivers/IntNet-0/Recv2                 0 ticks/call (           0 ticks,       0 times, max         0, min      -1)
07:40:04.160119 /Drivers/IntNet-0/Reserved              0 ticks/call (           0 ticks,       0 times, max         0, min      -1)
07:40:04.160121 /Drivers/IntNet-0/Send1             19772 ticks/call (   307565369 ticks,   15555 times, max    220723, min    4550)
07:40:04.160123 /Drivers/IntNet-0/Send2             17816 ticks/call (   277140761 ticks,   15555 times, max    208855, min    4011)
07:40:04.160125 /Drivers/IntNet-0/XmitProcessRing        0 count
07:40:04.160127 /Drivers/IntNet-0/XmitWakeup-R0         0 count
07:40:04.160128 /Drivers/IntNet-0/XmitWakeup-R3         0 count
07:40:04.160130 /Drivers/IntNet-0/YieldNok              0 count
07:40:04.160131 /Drivers/IntNet-0/YieldOk               0 count
07:40:04.160133 /FT/Checkpoint/Network                  0 times
07:40:04.160134 /FT/Checkpoint/Storage                  0 times
07:40:04.160143 /FT/Received/Mem                        0 bytes
07:40:04.160145 /FT/Received/State                      0 bytes
07:40:04.160147 /FT/Sent/Mem                            0 bytes
07:40:04.160148 /FT/Sent/State                          0 bytes
07:40:04.160150 /FT/Sync/DeltaMem                       0 times
07:40:04.160152 /FT/Sync/DeltaVM                        0 times
07:40:04.160153 /FT/Sync/Full                           0 times
07:40:04.160155 /GMM/VM/Allocated/cBasePages      1024045 pages
07:40:04.160156 /GMM/VM/Allocated/cFixedPages           0 pages
07:40:04.160158 /GMM/VM/Allocated/cShadowPages          0 pages
07:40:04.160159 /GMM/VM/Reserved/cBasePages       1048734 pages
07:40:04.160161 /GMM/VM/Reserved/cFixedPages        33796 pages
07:40:04.160162 /GMM/VM/Reserved/cShadowPages           1 pages
07:40:04.160164 /GMM/VM/cBalloonedPages                 0 pages
07:40:04.160165 /GMM/VM/cMaxBalloonedPages              0 pages
07:40:04.160167 /GMM/VM/cPrivatePages             1024045 pages
07:40:04.160168 /GMM/VM/cReqActuallyBalloonedPages        0 pages
07:40:04.160170 /GMM/VM/cReqBalloonedPages              0 pages
07:40:04.160173 /GMM/VM/cReqDeflatePages                0 pages
07:40:04.160175 /GMM/VM/cShareableModules               0 count
07:40:04.160177 /GMM/VM/cSharedPages                    0 pages
07:40:04.160178 /GMM/VM/enmPolicy                       1 
07:40:04.160180 /GMM/VM/enmPriority                     2 
07:40:04.160181 /GMM/VM/fBallooningEnabled       false    
07:40:04.160183 /GMM/VM/fMayAllocate             false    
07:40:04.160184 /GMM/VM/fSharedPagingEnabled     false    
07:40:04.160186 /GMM/cAllocatedPages              1024045 pages
07:40:04.160187 /GMM/cBalloonedPages                    0 pages
07:40:04.160189 /GMM/cChunks                         2001 count
07:40:04.160191 /GMM/cDuplicatePages                    0 pages
07:40:04.160192 /GMM/cFreedChunks                       0 count
07:40:04.160194 /GMM/cLeftBehindSharedPages             0 pages
07:40:04.160195 /GMM/cMaxPages                   4294967295 pages
07:40:04.160197 /GMM/cOverCommittedPages                0 pages
07:40:04.160198 /GMM/cReservedPages               1082531 pages
07:40:04.160200 /GMM/cShareableModules                  0 count
07:40:04.160201 /GMM/cSharedPages                       0 pages
07:40:04.160203 /GVMM/EMTs                              2 calls
07:40:04.160204 /GVMM/HostCPUs                          4 calls
07:40:04.160206 /GVMM/HostCpus/0                        0 
07:40:04.160207 /GVMM/HostCpus/0/CurTimerHz           343 Hz
07:40:04.160209 /GVMM/HostCpus/0/DesiredHz              0 Hz
07:40:04.160210 /GVMM/HostCpus/0/PPTChanges         20989 times
07:40:04.160212 /GVMM/HostCpus/0/PPTStarts          15778 times
07:40:04.160213 /GVMM/HostCpus/0/idxCpuSet              0 
07:40:04.160215 /GVMM/HostCpus/1                        1 
07:40:04.160216 /GVMM/HostCpus/1/CurTimerHz           346 Hz
07:40:04.160218 /GVMM/HostCpus/1/DesiredHz            343 Hz
07:40:04.160219 /GVMM/HostCpus/1/PPTChanges         30449 times
07:40:04.160221 /GVMM/HostCpus/1/PPTStarts          13822 times
07:40:04.160222 /GVMM/HostCpus/1/idxCpuSet              1 
07:40:04.160224 /GVMM/HostCpus/2                        2 
07:40:04.160225 /GVMM/HostCpus/2/CurTimerHz           346 Hz
07:40:04.160227 /GVMM/HostCpus/2/DesiredHz              0 Hz
07:40:04.160228 /GVMM/HostCpus/2/PPTChanges         28838 times
07:40:04.160230 /GVMM/HostCpus/2/PPTStarts          13429 times
07:40:04.160231 /GVMM/HostCpus/2/idxCpuSet              2 
07:40:04.160232 /GVMM/HostCpus/3                        3 
07:40:04.160234 /GVMM/HostCpus/3/CurTimerHz           346 Hz
07:40:04.160235 /GVMM/HostCpus/3/DesiredHz              0 Hz
07:40:04.160237 /GVMM/HostCpus/3/PPTChanges         29985 times
07:40:04.160238 /GVMM/HostCpus/3/PPTStarts          13320 times
07:40:04.160240 /GVMM/HostCpus/3/idxCpuSet              3 
07:40:04.160241 /GVMM/Sum/HaltBlocking            7466523 calls
07:40:04.160243 /GVMM/Sum/HaltCalls               7470997 calls
07:40:04.160244 /GVMM/Sum/HaltNotBlocking            4711 calls
07:40:04.160246 /GVMM/Sum/HaltTimeouts            2206922 calls
07:40:04.160247 /GVMM/Sum/HaltWakeUps                   0 calls
07:40:04.160249 /GVMM/Sum/PokeCalls                 53595 calls
07:40:04.160250 /GVMM/Sum/PokeNotBusy                2919 calls
07:40:04.160252 /GVMM/Sum/PollCalls                     3 calls
07:40:04.160253 /GVMM/Sum/PollHalts                     0 calls
07:40:04.160255 /GVMM/Sum/PollWakeUps                   0 calls
07:40:04.160256 /GVMM/Sum/WakeUpCalls             5274629 calls
07:40:04.160258 /GVMM/Sum/WakeUpNotHalted           46690 calls
07:40:04.160260 /GVMM/Sum/WakeUpWakeUps                 0 calls
07:40:04.160261 /GVMM/VM/HaltBlocking             7466523 calls
07:40:04.160263 /GVMM/VM/HaltCalls                7470997 calls
07:40:04.160264 /GVMM/VM/HaltNotBlocking             4711 calls
07:40:04.160266 /GVMM/VM/HaltTimeouts             2206922 calls
07:40:04.160267 /GVMM/VM/HaltWakeUps                    0 calls
07:40:04.160269 /GVMM/VM/PokeCalls                  53595 calls
07:40:04.160270 /GVMM/VM/PokeNotBusy                 2919 calls
07:40:04.160274 /GVMM/VM/PollCalls                      3 calls
07:40:04.160275 /GVMM/VM/PollHalts                      0 calls
07:40:04.160277 /GVMM/VM/PollWakeUps                    0 calls
07:40:04.160279 /GVMM/VM/WakeUpCalls              5274629 calls
07:40:04.160280 /GVMM/VM/WakeUpNotHalted            46690 calls
07:40:04.160282 /GVMM/VM/WakeUpWakeUps                  0 calls
07:40:04.160283 /GVMM/VMs                               1 calls
07:40:04.160285 /IEM/CPU0/cInstructions                 0 count
07:40:04.160286 /IEM/CPU0/cPotentialExits               0 count
07:40:04.160288 /IEM/CPU0/cRetAspectNotImplemented        0 count
07:40:04.160289 /IEM/CPU0/cRetErrStatuses               0 count
07:40:04.160291 /IEM/CPU0/cRetInfStatuses               0 count
07:40:04.160292 /IEM/CPU0/cRetInstrNotImplemented        0 count
07:40:04.160294 /IEM/CPU0/cbWritten                     0 bytes
07:40:04.160295 /IEM/CPU1/cInstructions                 0 count
07:40:04.160297 /IEM/CPU1/cPotentialExits               0 count
07:40:04.160298 /IEM/CPU1/cRetAspectNotImplemented        0 count
07:40:04.160300 /IEM/CPU1/cRetErrStatuses               0 count
07:40:04.160301 /IEM/CPU1/cRetInfStatuses               0 count
07:40:04.160303 /IEM/CPU1/cRetInstrNotImplemented        0 count
07:40:04.160304 /IEM/CPU1/cbWritten                     0 bytes
07:40:04.160306 /MM/HyperHeap/cbFree              1764832 bytes
07:40:04.160307 /MM/HyperHeap/cbHeap              2358976 bytes
07:40:04.160309 /PDM/BlkCache/cbCached            5181440 bytes
07:40:04.160311 /PDM/BlkCache/cbCachedFru         2039296 bytes
07:40:04.160312 /PDM/BlkCache/cbCachedMruIn       3142144 bytes
07:40:04.160314 /PDM/BlkCache/cbCachedMruOut      2521088 bytes
07:40:04.160315 /PDM/BlkCache/cbMax               5242880 bytes
07:40:04.160317 /PDM/CritSects/8237A#0 Auto/ContentionR3        0 times
07:40:04.160318 /PDM/CritSects/8237A#0 Auto/ContentionRZLock        0 times
07:40:04.160320 /PDM/CritSects/8237A#0 Auto/ContentionRZUnlock        0 times
07:40:04.160321 /PDM/CritSects/AHCI#0/ContentionR3        0 times
07:40:04.160323 /PDM/CritSects/AHCI#0/ContentionRZLock        2 times
07:40:04.160324 /PDM/CritSects/AHCI#0/ContentionRZUnlock        0 times
07:40:04.160326 /PDM/CritSects/ATA#0/ContentionR3        0 times
07:40:04.160327 /PDM/CritSects/ATA#0/ContentionRZLock        9 times
07:40:04.160328 /PDM/CritSects/ATA#0/ContentionRZUnlock        0 times
07:40:04.160330 /PDM/CritSects/ATA#1/ContentionR3        0 times
07:40:04.160331 /PDM/CritSects/ATA#1/ContentionRZLock        0 times
07:40:04.160333 /PDM/CritSects/ATA#1/ContentionRZUnlock        0 times
07:40:04.160334 /PDM/CritSects/AudioSniffer#0 Auto/ContentionR3        0 times
07:40:04.160336 /PDM/CritSects/AudioSniffer#0 Auto/ContentionRZLock        0 times
07:40:04.160337 /PDM/CritSects/AudioSniffer#0 Auto/ContentionRZUnlock        0 times
07:40:04.160339 /PDM/CritSects/E1000#0/ContentionR3        0 times
07:40:04.160340 /PDM/CritSects/E1000#0/ContentionRZLock        1 times
07:40:04.160342 /PDM/CritSects/E1000#0/ContentionRZUnlock        0 times
07:40:04.160343 /PDM/CritSects/E1000#0RX/ContentionR3        0 times
07:40:04.160344 /PDM/CritSects/E1000#0RX/ContentionRZLock        0 times
07:40:04.160346 /PDM/CritSects/E1000#0RX/ContentionRZUnlock        0 times
07:40:04.160347 /PDM/CritSects/E1000#0TX/ContentionR3        0 times
07:40:04.160349 /PDM/CritSects/E1000#0TX/ContentionRZLock        0 times
07:40:04.160350 /PDM/CritSects/E1000#0TX/ContentionRZUnlock        0 times
07:40:04.160352 /PDM/CritSects/EM-REM/ContentionR3        0 times
07:40:04.160353 /PDM/CritSects/EM-REM/ContentionRZLock        0 times
07:40:04.160355 /PDM/CritSects/EM-REM/ContentionRZUnlock        0 times
07:40:04.160356 /PDM/CritSects/FTM/ContentionR3         0 times
07:40:04.160358 /PDM/CritSects/FTM/ContentionRZLock        0 times
07:40:04.160359 /PDM/CritSects/FTM/ContentionRZUnlock        0 times
07:40:04.160360 /PDM/CritSects/HPET#0/ContentionR3        0 times
07:40:04.160362 /PDM/CritSects/HPET#0/ContentionRZLock        0 times
07:40:04.160365 /PDM/CritSects/HPET#0/ContentionRZUnlock        0 times
07:40:04.160367 /PDM/CritSects/IOM Lock/ContentionR3        0 times
07:40:04.160369 /PDM/CritSects/IOM Lock/ContentionRZLock     2294 times
07:40:04.160370 /PDM/CritSects/IOM Lock/ContentionRZUnlock        0 times
07:40:04.160372 /PDM/CritSects/IntNetXmit_0/ContentionR3        0 times
07:40:04.160373 /PDM/CritSects/IntNetXmit_0/ContentionRZLock        0 times
07:40:04.160375 /PDM/CritSects/IntNetXmit_0/ContentionRZUnlock        0 times
07:40:04.160376 /PDM/CritSects/MM-HYPER/ContentionR3        0 times
07:40:04.160377 /PDM/CritSects/MM-HYPER/ContentionRZLock        0 times
07:40:04.160379 /PDM/CritSects/MM-HYPER/ContentionRZUnlock        0 times
07:40:04.160380 /PDM/CritSects/NOP/ContentionR3         0 times
07:40:04.160382 /PDM/CritSects/NOP/ContentionRZLock        0 times
07:40:04.160383 /PDM/CritSects/NOP/ContentionRZUnlock        0 times
07:40:04.160385 /PDM/CritSects/PDM/ContentionR3         0 times
07:40:04.160386 /PDM/CritSects/PDM/ContentionRZLock     1495 times
07:40:04.160388 /PDM/CritSects/PDM/ContentionRZUnlock       12 times
07:40:04.160389 /PDM/CritSects/PGM/ContentionR3         0 times
07:40:04.160391 /PDM/CritSects/PGM/ContentionRZLock     1837 times
07:40:04.160392 /PDM/CritSects/PGM/ContentionRZUnlock        0 times
07:40:04.160394 /PDM/CritSects/PS2K#0/ContentionR3        0 times
07:40:04.160395 /PDM/CritSects/PS2K#0/ContentionRZLock        0 times
07:40:04.160396 /PDM/CritSects/PS2K#0/ContentionRZUnlock        0 times
07:40:04.160398 /PDM/CritSects/PS2KM#0/ContentionR3        0 times
07:40:04.160399 /PDM/CritSects/PS2KM#0/ContentionRZLock        0 times
07:40:04.160401 /PDM/CritSects/PS2KM#0/ContentionRZUnlock        0 times
07:40:04.160402 /PDM/CritSects/REM-Register/ContentionR3        0 times
07:40:04.160404 /PDM/CritSects/REM-Register/ContentionRZLock        0 times
07:40:04.160405 /PDM/CritSects/REM-Register/ContentionRZUnlock        0 times
07:40:04.160406 /PDM/CritSects/TM Timer Lock/ContentionR3    46029 times
07:40:04.160408 /PDM/CritSects/TM Timer Lock/ContentionRZLock        0 times
07:40:04.160409 /PDM/CritSects/TM Timer Lock/ContentionRZUnlock        0 times
07:40:04.160411 /PDM/CritSects/TM VirtualSync Lock/ContentionR3        0 times
07:40:04.160412 /PDM/CritSects/TM VirtualSync Lock/ContentionRZLock   376086 times
07:40:04.160414 /PDM/CritSects/TM VirtualSync Lock/ContentionRZUnlock        3 times
07:40:04.160415 /PDM/CritSects/VGA#u/ContentionR3        0 times
07:40:04.160417 /PDM/CritSects/VGA#u/ContentionRZLock        2 times
07:40:04.160418 /PDM/CritSects/VGA#u/ContentionRZUnlock        0 times
07:40:04.160420 /PDM/CritSects/VMMDev#0 Auto/ContentionR3        0 times
07:40:04.160421 /PDM/CritSects/VMMDev#0 Auto/ContentionRZLock        0 times
07:40:04.160423 /PDM/CritSects/VMMDev#0 Auto/ContentionRZUnlock        0 times
07:40:04.160424 /PDM/CritSects/VMMDev#u/ContentionR3        0 times
07:40:04.160425 /PDM/CritSects/VMMDev#u/ContentionRZLock        0 times
07:40:04.160427 /PDM/CritSects/VMMDev#u/ContentionRZUnlock        0 times
07:40:04.160428 /PDM/CritSects/acpi0/ContentionR3        0 times
07:40:04.160430 /PDM/CritSects/acpi0/ContentionRZLock        0 times
07:40:04.160431 /PDM/CritSects/acpi0/ContentionRZUnlock        0 times
07:40:04.160433 /PDM/CritSects/ahci#0 Auto/ContentionR3        0 times
07:40:04.160434 /PDM/CritSects/ahci#0 Auto/ContentionRZLock        0 times
07:40:04.160435 /PDM/CritSects/ahci#0 Auto/ContentionRZUnlock        0 times
07:40:04.160437 /PDM/CritSects/e1000#0 Auto/ContentionR3        0 times
07:40:04.160438 /PDM/CritSects/e1000#0 Auto/ContentionRZLock      104 times
07:40:04.160440 /PDM/CritSects/e1000#0 Auto/ContentionRZUnlock        0 times
07:40:04.160441 /PDM/CritSects/ich9pci#0 Auto/ContentionR3        0 times
07:40:04.160443 /PDM/CritSects/ich9pci#0 Auto/ContentionRZLock        0 times
07:40:04.160444 /PDM/CritSects/ich9pci#0 Auto/ContentionRZUnlock        0 times
07:40:04.160446 /PDM/CritSects/lpc#0 Auto/ContentionR3        0 times
07:40:04.160447 /PDM/CritSects/lpc#0 Auto/ContentionRZLock        0 times
07:40:04.160450 /PDM/CritSects/lpc#0 Auto/ContentionRZUnlock        0 times
07:40:04.160452 /PDM/CritSects/mc146818#0 Auto/ContentionR3        0 times
07:40:04.160453 /PDM/CritSects/mc146818#0 Auto/ContentionRZLock        0 times
07:40:04.160455 /PDM/CritSects/mc146818#0 Auto/ContentionRZUnlock        0 times
07:40:04.160456 /PDM/CritSects/pcarch#0 Auto/ContentionR3        0 times
07:40:04.160458 /PDM/CritSects/pcarch#0 Auto/ContentionRZLock        0 times
07:40:04.160459 /PDM/CritSects/pcarch#0 Auto/ContentionRZUnlock        0 times
07:40:04.160461 /PDM/CritSects/pcbios#0 Auto/ContentionR3        0 times
07:40:04.160462 /PDM/CritSects/pcbios#0 Auto/ContentionRZLock        0 times
07:40:04.160464 /PDM/CritSects/pcbios#0 Auto/ContentionRZUnlock        0 times
07:40:04.160465 /PDM/CritSects/pckbd#0 Auto/ContentionR3        0 times
07:40:04.160467 /PDM/CritSects/pckbd#0 Auto/ContentionRZLock        1 times
07:40:04.160468 /PDM/CritSects/pckbd#0 Auto/ContentionRZUnlock        0 times
07:40:04.160470 /PDM/CritSects/piix3ide#0 Auto/ContentionR3        0 times
07:40:04.160471 /PDM/CritSects/piix3ide#0 Auto/ContentionRZLock        0 times
07:40:04.160473 /PDM/CritSects/piix3ide#0 Auto/ContentionRZUnlock        0 times
07:40:04.160474 /PDM/CritSects/pit/ContentionR3         0 times
07:40:04.160475 /PDM/CritSects/pit/ContentionRZLock        0 times
07:40:04.160477 /PDM/CritSects/pit/ContentionRZUnlock        0 times
07:40:04.160478 /PDM/CritSects/vga#0 Auto/ContentionR3        0 times
07:40:04.160480 /PDM/CritSects/vga#0 Auto/ContentionRZLock        0 times
07:40:04.160481 /PDM/CritSects/vga#0 Auto/ContentionRZUnlock        0 times
07:40:04.160483 /PDM/Queue/AHCI-Xmit/AllocFailures        0 times
07:40:04.160484 /PDM/Queue/AHCI-Xmit/Flush              0 calls
07:40:04.160485 /PDM/Queue/AHCI-Xmit/FlushLeftovers        0 times
07:40:04.160487 /PDM/Queue/AHCI-Xmit/Insert         61386 calls
07:40:04.160488 /PDM/Queue/AHCI-Xmit/cItems            60 count
07:40:04.160490 /PDM/Queue/AHCI-Xmit/cbItem            32 bytes
07:40:04.160491 /PDM/Queue/DevHlp/AllocFailures         0 times
07:40:04.160493 /PDM/Queue/DevHlp/Flush                 0 calls
07:40:04.160494 /PDM/Queue/DevHlp/FlushLeftovers        0 times
07:40:04.160496 /PDM/Queue/DevHlp/Insert                0 calls
07:40:04.160497 /PDM/Queue/DevHlp/cItems                8 count
07:40:04.160499 /PDM/Queue/DevHlp/cbItem               56 bytes
07:40:04.160500 /PDM/Queue/E1000-Rcv/AllocFailures        0 times
07:40:04.160502 /PDM/Queue/E1000-Rcv/Flush              0 calls
07:40:04.160503 /PDM/Queue/E1000-Rcv/FlushLeftovers        0 times
07:40:04.160505 /PDM/Queue/E1000-Rcv/Insert          2101 calls
07:40:04.160506 /PDM/Queue/E1000-Rcv/cItems             1 count
07:40:04.160507 /PDM/Queue/E1000-Rcv/cbItem            24 bytes
07:40:04.160509 /PDM/Queue/E1000-Xmit/AllocFailures        0 times
07:40:04.160510 /PDM/Queue/E1000-Xmit/Flush             0 calls
07:40:04.160512 /PDM/Queue/E1000-Xmit/FlushLeftovers        0 times
07:40:04.160513 /PDM/Queue/E1000-Xmit/Insert            0 calls
07:40:04.160515 /PDM/Queue/E1000-Xmit/cItems            1 count
07:40:04.160516 /PDM/Queue/E1000-Xmit/cbItem           24 bytes
07:40:04.160518 /PDM/Queue/Keyboard/AllocFailures        0 times
07:40:04.160519 /PDM/Queue/Keyboard/Flush               0 calls
07:40:04.160521 /PDM/Queue/Keyboard/FlushLeftovers        0 times
07:40:04.160522 /PDM/Queue/Keyboard/Insert           1169 calls
07:40:04.160524 /PDM/Queue/Keyboard/cItems             64 count
07:40:04.160525 /PDM/Queue/Keyboard/cbItem             32 bytes
07:40:04.160527 /PDM/Queue/Mouse/AllocFailures          0 times
07:40:04.160528 /PDM/Queue/Mouse/Flush                  0 calls
07:40:04.160530 /PDM/Queue/Mouse/FlushLeftovers         0 times
07:40:04.160531 /PDM/Queue/Mouse/Insert               898 calls
07:40:04.160533 /PDM/Queue/Mouse/cItems               128 count
07:40:04.160534 /PDM/Queue/Mouse/cbItem                56 bytes
07:40:04.160536 /PGM/CPU0/cA20Changes                   2 times
07:40:04.160537 /PGM/CPU0/cGuestModeChanges         65054 times
07:40:04.160541 /PGM/CPU1/cA20Changes                   0 times
07:40:04.160542 /PGM/CPU1/cGuestModeChanges             3 times
07:40:04.160544 /PGM/ChunkR3Map/Mapped               2001 count
07:40:04.160546 /PGM/ChunkR3Map/Unmapped                0 count
07:40:04.160547 /PGM/ChunkR3Map/c                    2001 count
07:40:04.160549 /PGM/ChunkR3Map/cMax             4294967295 count
07:40:04.160550 /PGM/LargePage/Recheck                  0 times
07:40:04.160552 /PGM/LargePage/Refused                  0 times
07:40:04.160554 /PGM/LargePage/Reused                   0 times
07:40:04.160556 /PGM/Page/cAllPages               1098827 count
07:40:04.160557 /PGM/Page/cBalloonedPages               0 count
07:40:04.160559 /PGM/Page/cHandyPages                  56 count
07:40:04.160560 /PGM/Page/cLargePages                   0 count
07:40:04.160562 /PGM/Page/cLargePagesDisabled           0 count
07:40:04.160563 /PGM/Page/cMonitoredPages               0 count
07:40:04.160565 /PGM/Page/cPrivatePages           1057785 count
07:40:04.160566 /PGM/Page/cPureMmioPages            16425 count
07:40:04.160568 /PGM/Page/cReadLockedPages              0 count
07:40:04.160569 /PGM/Page/cReusedSharedPages            0 count
07:40:04.160571 /PGM/Page/cSharedPages                  0 count
07:40:04.160572 /PGM/Page/cWriteLockedPages             0 count
07:40:04.160574 /PGM/Page/cWrittenToPages               0 count
07:40:04.160575 /PGM/Page/cZeroPages                24617 count
07:40:04.160577 /PGM/ShMod/Check                        0 ticks/call (           0 ticks,       0 times, max         0, min      -1)
07:40:04.160579 /PGM/cRelocations                       0 times
07:40:04.160580 /PROF/CPU0/EM/Capped                    0 ticks/call (           0 ticks,       0 times, max         0, min      -1)
07:40:04.160582 /PROF/CPU0/EM/ForcedActions       2452071 times
07:40:04.160584 /PROF/CPU0/EM/Halted              2395103 times
07:40:04.160586 /PROF/CPU0/EM/RAWTotal                  0 times
07:40:04.160587 /PROF/CPU0/EM/REMTotal                  0 times
07:40:04.160589 /PROF/CPU0/EM/Total              27914150494386 ticks/call (83742451483159 ticks,       3 times, max 73825135477197, min 4019220571864)
07:40:04.160591 /PROF/CPU1/EM/Capped                    0 ticks/call (           0 ticks,       0 times, max         0, min      -1)
07:40:04.160593 /PROF/CPU1/EM/ForcedActions       2169969 times
07:40:04.160595 /PROF/CPU1/EM/Halted              2123828 times
07:40:04.160596 /PROF/CPU1/EM/RAWTotal                  0 times
07:40:04.160598 /PROF/CPU1/EM/REMTotal                  0 times
07:40:04.160599 /PROF/CPU1/EM/Total              27914178251955 ticks/call (83742534755865 ticks,       3 times, max 73825135534196, min 4019220847999)
07:40:04.160602 /PROF/VM/CPU0/Halt/Block         10554825 ns/call (25299874353899 ticks, 2396996 times, max  22655102, min       1)
07:40:04.160604 /PROF/VM/CPU0/Halt/BlockInsomnia        0 ns/call (           0 ticks,       0 times, max         0, min      -1)
07:40:04.160606 /PROF/VM/CPU0/Halt/BlockOnTime          0 ns/call (           0 ticks,       0 times, max         0, min      -1)
07:40:04.160608 /PROF/VM/CPU0/Halt/BlockOverslept        0 ns/call (           0 ticks,       0 times, max         0, min      -1)
07:40:04.160610 /PROF/VM/CPU0/Halt/Timers             229 ns/call (  1098706732 ticks, 4791622 times, max    165877, min       1)
07:40:04.160612 /PROF/VM/CPU0/Halt/Yield                0 ns/call (           0 ticks,       0 times, max         0, min      -1)
07:40:04.160614 /PROF/VM/CPU1/Halt/Block          4992621 ns/call (25312198311151 ticks, 5069921 times, max  32996064, min       1)
07:40:04.160616 /PROF/VM/CPU1/Halt/BlockInsomnia        0 ns/call (           0 ticks,       0 times, max         0, min      -1)
07:40:04.160618 /PROF/VM/CPU1/Halt/BlockOnTime          0 ns/call (           0 ticks,       0 times, max         0, min      -1)
07:40:04.160619 /PROF/VM/CPU1/Halt/BlockOverslept        0 ns/call (           0 ticks,       0 times, max         0, min      -1)
07:40:04.160623 /PROF/VM/CPU1/Halt/Timers            4489 ns/call ( 32356157715 ticks, 7206623 times, max   3372500, min       2)
07:40:04.160625 /PROF/VM/CPU1/Halt/Yield              871 ns/call (        2615 ticks,       3 times, max      1317, min     647)
07:40:04.160628 /REM/TbFlushCount                       0 times
07:40:04.160629 /REM/TbPhysInvldCount                5343 times
07:40:04.160631 /REM/TlbFlushCount                   7044 times
07:40:04.160632 /SELM/HyperSels/Changed                 0 times
07:40:04.160634 /SELM/HyperSels/Scan                    0 times
07:40:04.160635 /SELM/LoadHidSel/GstReadErrors          0 times
07:40:04.160637 /SELM/LoadHidSel/NoGoodGuest            0 times
07:40:04.160638 /SELM/UpdateFromCPUM/AlreadyStaleCS        0 times
07:40:04.160640 /SELM/UpdateFromCPUM/AlreadyStaleDS        0 times
07:40:04.160641 /SELM/UpdateFromCPUM/AlreadyStaleES        0 times
07:40:04.160643 /SELM/UpdateFromCPUM/AlreadyStaleFS        0 times
07:40:04.160644 /SELM/UpdateFromCPUM/AlreadyStaleGS        0 times
07:40:04.160646 /SELM/UpdateFromCPUM/AlreadyStaleSS        0 times
07:40:04.160647 /SELM/UpdateFromCPUM/DetectedStaleCS        0 times
07:40:04.160648 /SELM/UpdateFromCPUM/DetectedStaleDS        0 times
07:40:04.160650 /SELM/UpdateFromCPUM/DetectedStaleES        0 times
07:40:04.160651 /SELM/UpdateFromCPUM/DetectedStaleFS        0 times
07:40:04.160653 /SELM/UpdateFromCPUM/DetectedStaleGS        0 times
07:40:04.160654 /SELM/UpdateFromCPUM/DetectedStaleSS        0 times
07:40:04.160655 /SELM/UpdateFromCPUM/StaleToUnstale        0 times
07:40:04.160657 /TM/CPU/00/cNsExecuting          78952641497 ns
07:40:04.160659 /TM/CPU/00/cNsHalted             25302254038123 ns
07:40:04.160660 /TM/CPU/00/cNsOther              52684719601 ns
07:40:04.160662 /TM/CPU/00/cNsTotal              25433891399221 ns
07:40:04.160664 /TM/CPU/00/cPeriodsExecuting     34921641 count
07:40:04.160665 /TM/CPU/00/cPeriodsHalted         2394626 count
07:40:04.160667 /TM/CPU/00/pctExecuting                11 %
07:40:04.160669 /TM/CPU/00/pctHalted                   85 %
07:40:04.160670 /TM/CPU/00/pctOther                     3 %
07:40:04.160672 /TM/CPU/01/cNsExecuting          55301155362 ns
07:40:04.160673 /TM/CPU/01/cNsHalted             25347833386970 ns
07:40:04.160675 /TM/CPU/01/cNsOther              30782086997 ns
07:40:04.160677 /TM/CPU/01/cNsTotal              25433916629329 ns
07:40:04.160678 /TM/CPU/01/cPeriodsExecuting     21206349 count
07:40:04.160680 /TM/CPU/01/cPeriodsHalted         2123598 count
07:40:04.160681 /TM/CPU/01/pctExecuting                12 %
07:40:04.160683 /TM/CPU/01/pctHalted                   85 %
07:40:04.160685 /TM/CPU/01/pctOther                     2 %
07:40:04.160686 /TM/CPU/pctExecuting                   11 %
07:40:04.160688 /TM/CPU/pctHalted                      85 %
07:40:04.160689 /TM/CPU/pctOther                        2 %
07:40:04.160691 /TM/MaxHzHint                         312 Hz
07:40:04.160692 /TM/R0/1nsSteps                    268666 times
07:40:04.160694 /TM/R3/1nsSteps                    268074 times
07:40:04.160696 /TM/TSC/offCPU0                  12028653418 ticks
07:40:04.160697 /TM/TSC/offCPU1                  12028653440 ticks
07:40:04.160699 /TM/VirtualSync/CurrentOffset      327469 ns
07:40:04.160701 ********************* End of statistics **********************
07:40:04.167655 Changing the VM state from 'DESTROYING' to 'TERMINATED'.
07:40:04.322949 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={db7ab4ca-2a3f-4183-9243-c1208da92392} aComponent={Console} aText={The virtual machine is being powered down}, preserve=false
Top
Perryg
Site Moderator
Posts: 34369
Joined: 6. Sep 2008, 22:55
Primary OS: Linux other
VBox Version: OSE self-compiled
Guest OSses: *NIX

Re: Win7 guest powers off randomly

Post by Perryg »

I still have a real issue with the reason and think that you are hung on the wrong impression that VirtualBox is issuing this command. I ran some test and the results below do not produce the same response as yours does ( 25:16:20.002585 Guest requests the VM to be turned off )
VBoxManage controlvm <name|uuid>

Code: Select all

acpipowerbutton = 
00:02:42.755886 Entering S5 power state (power down)

Code: Select all

poweroff = 
00:00:36.773508 Console::powerDown(): A request to power off the VM has been issued (mMachineState=Stopping, InUninit=0)

Code: Select all

Issue shutdown using the guest shutdown button =
00:01:02.287588 Entering S5 power state (power down)
Could there be something in the guest that is doing this, some app? All indications are this is being instigated within this one guest.

Note: I see a lot of code but to tell you the truth i have never seen the one "guest requestes" that I can remember. I am going to search the code to see if it shows anything.
Top
Perryg
Site Moderator
Posts: 34369
Joined: 6. Sep 2008, 22:55
Primary OS: Linux other
VBox Version: OSE self-compiled
Guest OSses: *NIX

Re: Win7 guest powers off randomly

Post by Perryg »

This is all I can find on the exact phrase.

/trunk/src/VBox/Devices/VMMDev/VMMDev.cpp

Code: Select all

/**
 * Handles VMMDevReq_SetPowerStatus.
 *
 * @returns VBox status code that the guest should see.
 * @param   pThis           The VMMDev instance data.
 * @param   pReqHdr         The header of the request to handle.
 */
static int vmmdevReqHandler_SetPowerStatus(PVMMDEV pThis, VMMDevRequestHeader *pReqHdr)
{
    VMMDevPowerStateRequest *pReq = (VMMDevPowerStateRequest *)pReqHdr;
    AssertMsgReturn(pReq->header.size == sizeof(*pReq), ("%u\n", pReq->header.size), VERR_INVALID_PARAMETER);

    switch (pReq->powerState)
    {
        case VMMDevPowerState_Pause:
        {
            LogRel(("Guest requests the VM to be suspended (paused)\n"));
            return PDMDevHlpVMSuspend(pThis->pDevIns);
        }

        case VMMDevPowerState_PowerOff:
        {
            LogRel(("Guest requests the VM to be turned off\n"));
            return PDMDevHlpVMPowerOff(pThis->pDevIns);
        }

        case VMMDevPowerState_SaveState:
        {
            if (true /*pThis->fAllowGuestToSaveState*/)
            {
                LogRel(("Guest requests the VM to be saved and powered off\n"));
                return PDMDevHlpVMSuspendSaveAndPowerOff(pThis->pDevIns);
            }
            LogRel(("Guest requests the VM to be saved and powered off, declined\n"));
            return VERR_ACCESS_DENIED;
        }

        default:
            AssertMsgFailed(("VMMDev invalid power state request: %d\n", pReq->powerState));
            return VERR_INVALID_PARAMETER;
    }
}
Top
marmel
Posts: 75
Joined: 1. Nov 2013, 03:44

Re: Win7 guest powers off randomly

Post by marmel »

Thanks for looking into this further. It's got to be a string literal somewhere in the codebase. Keep in mind I'm on 4.2.x, not trunk. I'll poke around and try to track down the condition which logs that string.

I get the same exact string when my XP guest is powered off, so I don't think there's anything suspicious about that message.

I guess I have never tried running the XP VM for long periods of time to see if the issue exists there as well. It serves exactly one application (Quicken), and so I just run it on-demand.

I'll fire it up today and see how long it goes.

The Debian VM is my build/backport machine, so it's up as long and often as the host usually. No issues ever with that guest. It powers off with

Code: Select all

Entering S5 power state (power down)
Last edited by marmel on 4. Feb 2014, 03:49, edited 1 time in total.
Top
marmel
Posts: 75
Joined: 1. Nov 2013, 03:44

Re: Win7 guest powers off randomly

Post by marmel »

Perryg wrote:Could there be something in the guest that is doing this, some app? All indications are this is being instigated within this one guest.
I just can't imagine:

a) what application would have the ability to power off the machine without prompt from the OS.
b) what application would decide, at any (always different) time ranging from ~20minutes to ~14 days, that "now" is a good time to power off!
Top
Perryg
Site Moderator
Posts: 34369
Joined: 6. Sep 2008, 22:55
Primary OS: Linux other
VBox Version: OSE self-compiled
Guest OSses: *NIX

Re: Win7 guest powers off randomly

Post by Perryg »

I don't know what else to tell you and we have probably gone as far as you and I can go. I would ask the DEVs what they think at bugtracker
Top
Post Reply

Return to “Windows Guests”