Windows 10 Guest does not Shutdown Completely

[cmccarron]

Was having issues with Video Drives in 7.0.14, windows 10 guest screen would go back then come back. Was also having weird mouse issues. So when 7.0.18 came out, I upgraded to it. Now the Windows 10 VM will not completely shutdown. I have to go into task manager and kill the process. I am currently running 7.0.19 which is having the same issue. When you kill it from task manager it leaves the VM in an aborted state.

To reproduce:

1. Start VM
2. Stop VM

[cmccarron]

Logs

[cmccarron]

VM Manager - Aborted

[cmccarron]

Guest - Stopping

[cmccarron]

Task Manager - Guest Stopping

[cmccarron]

Reverted back to v7.0.14, and now virtual machine will shutdown properly.

[cmccarron]

Logs from the virtual machine under a normal shutdown running 7.0.14 and its extensions.

[cmccarron]

Normal Shutdown Log

[cmccarron]

Normal Shutdown Hardening Log

[cmccarron]

Failed shutdown v7.0.18
00:05:17.155790 /TRPM/ForwardRaw/TRAP/1F 52 times
00:05:17.155794 /VMM/EmtHashCollisions/Emt00 0 times
00:05:17.155799 /VMM/EmtHashCollisions/Emt01 0 times
00:05:17.155804 /VMM/EmtHashCollisions/Emt02 0 times
00:05:17.155808 /VMM/LogFlush/00-Flushes 0 times
00:05:17.155813 /VMM/LogFlush/00-NoWakups 0 times
00:05:17.155818 /VMM/LogFlush/CPU0/Rel/cbBuf 4096 bytes
00:05:17.155823 /VMM/LogFlush/CPU1/Rel/cbBuf 4096 bytes
00:05:17.155828 /VMM/LogFlush/CPU2/Rel/cbBuf 4096 bytes
00:05:17.155832 /VUSB/0/cUrbsInPool 0 count
00:05:17.155837 /VUSB/1/cUrbsInPool 0 count
00:05:17.155841 /VUSB/2/cUrbsInPool 0 count
00:05:17.155846 /VUSB/3/cUrbsInPool 0 count
00:05:17.155851 End of statistics
00:05:17.155989 VUSB: Detached 'HidKeyboard' from port 1 on RootHub#1

Good Shutdown v7.0.14
00:18:43.957733 /TRPM/ForwardRaw/TRAP/1F 1327 times
00:18:43.957738 /VMM/EmtHashCollisions/Emt00 0 times
00:18:43.957743 /VMM/EmtHashCollisions/Emt01 0 times
00:18:43.957749 /VMM/EmtHashCollisions/Emt02 0 times
00:18:43.957753 /VMM/LogFlush/00-Flushes 0 times
00:18:43.957757 /VMM/LogFlush/00-NoWakups 0 times
00:18:43.957762 /VMM/LogFlush/CPU0/Rel/cbBuf 4096 bytes
00:18:43.957767 /VMM/LogFlush/CPU1/Rel/cbBuf 4096 bytes
00:18:43.957772 /VMM/LogFlush/CPU2/Rel/cbBuf 4096 bytes
00:18:43.957777 /VUSB/0/cUrbsInPool 0 count
00:18:43.957783 /VUSB/1/cUrbsInPool 0 count
00:18:43.957787 /VUSB/2/cUrbsInPool 0 count
00:18:43.957792 /VUSB/3/cUrbsInPool 0 count
00:18:43.957797 End of statistics
00:18:43.957845 VUSB: Detached 'HidKeyboard' from port 1 on RootHub#1
00:18:43.957976 VUSB: Detached 'HidMouse' from port 2 on RootHub#1
00:18:43.958104 VUSB: Detached 'HidMouse' from port 3 on RootHub#1
00:18:43.974109 NAT: Zone(nm:mbuf_cluster, used:0)
00:18:43.974622 NAT: Zone(nm:mbuf_packet, used:0)
00:18:43.974643 NAT: Zone(nm:mbuf, used:1)
00:18:43.974676 NAT: Zone(nm:mbuf_jumbo_pagesize, used:0)
00:18:43.975175 NAT: Zone(nm:mbuf_jumbo_9k, used:0)
00:18:43.975667 NAT: Zone(nm:mbuf_jumbo_16k, used:0)
00:18:43.975816 NAT: Zone(nm:mbuf_ext_refcnt, used:0)
00:18:43.987943 vmmR3LogFlusher: Terminating (VERR_OBJECT_DESTROYED)
00:18:43.988050 Changing the VM state from 'DESTROYING' to 'TERMINATED'
00:18:43.992177 Console: Machine state changed to 'PoweredOff'
00:18:43.992468 GUI: Request to close Runtime UI because VM is powered off already.
00:18:43.992490 GUI: Passing request to close Runtime UI from UI session to UI machine.
00:18:43.997535 GUI: UICommon: Handling aboutToQuit request..
00:18:45.495844 End of log file - Log started 2024-06-07T10:36:05.478035200Z

[cmccarron]

Still an issue in v7.0.20

Tail end of Vbox.log 00:02:59.976923 /TM/MaxHzHint 0 Hz 00:02:59.976932 /TM/MaxHzHint/real 0 Hz 00:02:59.976940 /TM/MaxHzHint/tsc 0 Hz 00:02:59.976948 /TM/MaxHzHint/virtual 0 Hz 00:02:59.976956 /TM/MaxHzHint/virtual_sync 0 Hz 00:02:59.976963 /TM/PIT/Handler 0 ticks/call ( 0 ticks, 0 calls, max 0, min -1) 00:02:59.976973 /TM/PIT/Irq 0 times 00:02:59.976982 /TM/R3/1nsSteps 2263 times 00:02:59.976990 /TM/TSC/offCPU0 0 ticks 00:02:59.976999 /TM/TSC/offCPU1 1549 ticks 00:02:59.977007 /TM/TSC/offCPU2 357 ticks 00:02:59.977014 /TM/VirtualSync/CurrentOffset 451248 ns 00:02:59.977023 /TRPM/ForwardRaw/IRQ/2F 368481 times 00:02:59.977033 /TRPM/ForwardRaw/IRQ/50 18730 times 00:02:59.977042 /TRPM/ForwardRaw/IRQ/60 154148 times 00:02:59.977050 /TRPM/ForwardRaw/IRQ/61 1756 times 00:02:59.977059 /TRPM/ForwardRaw/IRQ/68 10 times 00:02:59.977069 /TRPM/ForwardRaw/IRQ/81 1033 times 00:02:59.977077 /TRPM/ForwardRaw/IRQ/90 48 times 00:02:59.977084 /TRPM/ForwardRaw/IRQ/91 1187 times 00:02:59.977094 /TRPM/ForwardRaw/IRQ/A0 11 times 00:02:59.977519 /TRPM/ForwardRaw/IRQ/B1 697 times 00:02:59.977531 /TRPM/ForwardRaw/IRQ/D1 24771 times 00:02:59.977539 /TRPM/ForwardRaw/IRQ/D2 109186 times 00:02:59.977547 /TRPM/ForwardRaw/IRQ/E1 105101 times 00:02:59.977559 /TRPM/ForwardRaw/TRAP/1F 70 times 00:02:59.977567 /VMM/EmtHashCollisions/Emt00 0 times 00:02:59.977574 /VMM/EmtHashCollisions/Emt01 0 times 00:02:59.977582 /VMM/EmtHashCollisions/Emt02 0 times 00:02:59.977590 /VMM/LogFlush/00-Flushes 0 times 00:02:59.977598 /VMM/LogFlush/00-NoWakups 0 times 00:02:59.977606 /VMM/LogFlush/CPU0/Rel/cbBuf 4096 bytes 00:02:59.977615 /VMM/LogFlush/CPU1/Rel/cbBuf 4096 bytes 00:02:59.977623 /VMM/LogFlush/CPU2/Rel/cbBuf 4096 bytes 00:02:59.977630 /VUSB/0/cUrbsInPool 0 count 00:02:59.977639 /VUSB/1/cUrbsInPool 0 count 00:02:59.977646 /VUSB/2/cUrbsInPool 0 count 00:02:59.977654 /VUSB/3/cUrbsInPool 0 count 00:02:59.977663 * End of statistics 00:02:59.977745 VUSB: Detached 'HidKeyboard' from port 1 on RootHub#1 00:03:41.257036 GUI: UIMediumEnumerator: Medium-enumeration finished! 00:03:41.583552 GUI: UIMediumEnumerator: Medium-enumeration finished!

Tail end of VBoxHardening.log 5784.54a0: supR3HardNtViCallWinVerifyTrustCatFile: hCatAdmin=000001dbbfd317d0 5784.54a0: supR3HardNtViCallWinVerifyTrustCatFile: cbHash=20 wszDigest=740E2B1196D9F01AD376694D1055FFD45F803926 5784.54a0: supR3HardenedMonitor_LdrLoadDll: returns rcNt=0x0 hMod=00007ff9d91f0000 'C:\WINDOWS\system32\rsaenh.dll' 5784.54a0: supR3HardenedMonitor_LdrLoadDll: returns rcNt=0x0 hMod=00007ff9da360000 'C:\WINDOWS\System32\crypt32.dll' 5784.54a0: supR3HardNtViCallWinVerifyTrustCatFile: WinVerifyTrust => 0x0; cat='C:\WINDOWS\system32\CatRoot\{F750E6C3-38EE-11D1-85E5-00C04FC295EE}\Microsoft-Windows-Client-Desktop-Required-Package0516~31bf3856ad364e35~amd6410.0.19041.4529.cat'; file='\Device\HarddiskVolume6\Windows\System32\dhcpcsvc.dll' 5784.54a0: supR3HardNtViCallWinVerifyTrustCatFile -> 0 (org 22900) 5784.54a0: supR3HardenedWinVerifyCacheProcessWvtTodos: 0 (was 22900) fWinVerifyTrust=1 for '\Device\HarddiskVolume6\Windows\System32\dhcpcsvc.dll' 5784.54a0: supR3HardNtViCallWinVerifyTrustCatFile: hFile=0000000000001548 pwszName=\Device\HarddiskVolume6\Windows\System32\dhcpcsvc6.dll 5784.54a0: supR3HardNtViCallWinVerifyTrustCatFile: Cached context 000001dbbfd317d0 5784.54a0: supR3HardNtViCallWinVerifyTrustCatFile: hCatAdmin=000001dbbfd317d0 5784.54a0: supR3HardNtViCallWinVerifyTrustCatFile: cbHash=20 wszDigest=A56431C69FDD081884ADAA1F10C80307CE8E2412 5784.54a0: supR3HardenedMonitor_LdrLoadDll: returns rcNt=0x0 hMod=00007ff9d91f0000 'C:\WINDOWS\system32\rsaenh.dll' 5784.54a0: supR3HardenedMonitor_LdrLoadDll: returns rcNt=0x0 hMod=00007ff9da360000 'C:\WINDOWS\System32\crypt32.dll' 5784.54a0: supR3HardNtViCallWinVerifyTrustCatFile: WinVerifyTrust => 0x0; cat='C:\WINDOWS\system32\CatRoot\{F750E6C3-38EE-11D1-85E5-00C04FC295EE}\Microsoft-Windows-Client-Desktop-Required-Package0516~31bf3856ad364e35~amd6410.0.19041.4529.cat'; file='\Device\HarddiskVolume6\Windows\System32\dhcpcsvc6.dll' 5784.54a0: supR3HardNtViCallWinVerifyTrustCatFile -> 0 (org 22900) 5784.54a0: supR3HardenedWinVerifyCacheProcessWvtTodos: 0 (was 22900) fWinVerifyTrust=1 for '\Device\HarddiskVolume6\Windows\System32\dhcpcsvc6.dll' 5784.54a0: supR3HardenedMonitor_LdrLoadDll: returns rcNt=0x0 hMod=00007ff9d91f0000 'C:\WINDOWS\system32\rsaenh.dll' 5784.54a0: supR3HardenedMonitor_LdrLoadDll: returns rcNt=0x0 hMod=00007ff9da360000 'C:\WINDOWS\System32\crypt32.dll' 5784.54a0: supR3HardenedWinVerifyCacheScheduleImports: Import todo: #12 'ws2_32.dll'. 5784.54a0: supR3HardenedWinVerifyCacheScheduleImports: Import todo: #21 'rpcrt4.dll'. 5784.54a0: supHardenedWinVerifyImageByHandle: -> 0 (\Device\HarddiskVolume6\Windows\System32\mswsock.dll) WinVerifyTrust 5784.54a0: supR3HardenedWinVerifyCacheInsert: \Device\HarddiskVolume6\Windows\System32\mswsock.dll 5784.54a0: supR3HardenedWinVerifyCacheProcessImportTodos: Processing 'rpcrt4.dll'... 5784.54a0: supR3HardenedWinVerifyCacheProcessImportTodos: 'rpcrt4.dll' -> '\Device\HarddiskVolume6\Windows\System32\rpcrt4.dll' [rcNtRedir=0xc0150008] 5784.54a0: supR3HardenedWinVerifyCacheProcessImportTodos: Processing 'ws2_32.dll'... 5784.54a0: supR3HardenedWinVerifyCacheProcessImportTodos: 'ws2_32.dll' -> '\Device\HarddiskVolume6\Windows\System32\ws2_32.dll' [rcNtRedir=0xc0150008] 5784.54a0: supR3HardenedScreenImage/Imports: cache hit (VINF_SUCCESS) on \Device\HarddiskVolume6\Windows\System32\ws2_32.dll 5784.54a0: supR3HardenedMonitor_LdrLoadDll: pName=C:\WINDOWS\system32\mswsock.dll (rcNtResolve=0xc0150008) *pfFlags=0x0 pwszSearchPath=0000000000000001:<flags> [calling] 5784.54a0: supR3HardenedScreenImage/NtCreateSection: cache hit (VINF_SUCCESS) on \Device\HarddiskVolume6\Windows\System32\mswsock.dll 5784.54a0: supR3HardenedDllNotificationCallback: load 00007ff9d9940000 LB 0x0006a000 C:\WINDOWS\system32\mswsock.dll [fFlags=0x0] 5784.54a0: supR3HardenedScreenImage/LdrLoadDll: cache hit (VINF_SUCCESS) on \Device\HarddiskVolume6\Windows\System32\mswsock.dll 5784.54a0: supR3HardenedMonitor_LdrLoadDll: returns rcNt=0x0 hMod=00007ff9d9940000 'C:\WINDOWS\system32\mswsock.dll' 5784.5444: supR3HardenedMonitor_LdrLoadDll: returns rcNt=0x0 hMod=00007ff9d91f0000 'C:\WINDOWS\system32\rsaenh.dll' 5784.5470: supR3HardenedIsApiSetDll: ApiSetQueryApiSetPresence(api-ms-win-core-sysinfo-l1-2-1) -> 0x0, fPresent=1 5784.5470: supR3HardenedMonitor_LdrLoadDll: pName=api-ms-win-core-sysinfo-l1-2-1 (rcNtResolve=0x0) *pfFlags=0x0 pwszSearchPath=0000000000000801:<flags> [calling] 5784.5470: supR3HardenedMonitor_LdrLoadDll: returns rcNt=0x0 hMod=00007ff9da5b0000 'api-ms-win-core-sysinfo-l1-2-1' 5784.3f34: supR3HardenedMonitor_LdrLoadDll: returns rcNt=0x0 hMod=00007ff9db530000 'C:\WINDOWS\system32\User32.dll' 5784.274c: supR3HardenedScreenImage/LdrLoadDll: cache hit (VINF_SUCCESS) on \Device\HarddiskVolume6\Windows\System32\msctf.dll 5784.274c: supR3HardenedMonitor_LdrLoadDll: pName=C:\WINDOWS\System32\MSCTF.dll (rcNtResolve=0xc0150008) *pfFlags=0x0 pwszSearchPath=0000000000000009:<flags> [calling] 5784.274c: supR3HardenedMonitor_LdrLoadDll: returns rcNt=0x0 hMod=00007ff9dad30000 'C:\WINDOWS\System32\MSCTF.dll' 5784.5ba4: supR3HardenedDllNotificationCallback: Unload 00007ff9b3b20000 LB 0x0000d000 C:\Program Files\Oracle\VirtualBox\VBoxGuestPropSvc.DLL [flags=0x0] 5784.5850: supR3HardenedDllNotificationCallback: Unload 00007ff9b3b50000 LB 0x0000c000 C:\Program Files\Oracle\VirtualBox\VBoxGuestControlSvc.DLL [flags=0x0] 5784.3d78: supR3HardenedDllNotificationCallback: Unload 00007ff9ac200000 LB 0x00014000 C:\Program Files\Oracle\VirtualBox\VBoxSharedFolders.DLL [flags=0x0] 5784.3f4c: supR3HardenedDllNotificationCallback: Unload 00007ff9b44b0000 LB 0x0000d000 C:\Program Files\Oracle\VirtualBox\VBoxDragAndDropSvc.DLL [flags=0x0] 5784.3f34: supR3HardenedDllNotificationCallback: Unload 00007ff9b5000000 LB 0x00010000 C:\Program Files\Oracle\VirtualBox\VBoxSharedClipboard.DLL [flags=0x0]

[klipsh]

We are seeing the same problems with a lot users at our site - with both Windows 7 and Windows 10 guests. Reverting back to 7.0.14 and the problems are all gone.

I've tested with stable releases 7.0.16 / 7.0.18 / 7.0.20 - but 7.0.14 seems to be last stable release without the issue.

[cmccarron]

7.1.0 VBox.log

[cmccarron]

7.1.0 VBoxHardening.log

[cmccarron]

This problem still exists in 7.1.0. I restored a version of my VM with the 7.0.14 extensions, changed pointing device to PS/2, booted to safe mode, uninstalled them, removed all hidden drivers then restarted in normal boot mode. With no extensions installed the VM will shutdown and restart like it is suppose to. Once I installed the 7.1.0 extensions, the VM hung on reboot with Not Responding and had to kill the process using task manager.

00:03:00.783150 VMMDev: Guest Log: VBoxGuest: Windows version 10.0, build 19045 00:03:00.793348 VMMDev: Guest Additions information report: Version 7.1.0 r164728 '7.1.0' 00:03:00.793408 VMMDev: Guest Additions information report: Interface = 0x00010004 osType = 0x0003B100 (Windows 10, 64-bit) 00:03:00.793539 VMMDev: Guest Additions capability report: (0x0 -> 0x0) seamless: no, hostWindowMapping: no, graphics: no 00:03:00.793582 GUI: UISession::sltAdditionsChange: GA state doesn't really changed, still notifying listeners. 00:03:00.793600 GUI: UIMachineLogicNormal::sltCheckForRequestedVisualStateType: Requested-state=1, Machine-state=6 00:03:00.793647 GUI: UISession::sltAdditionsChange: GA state doesn't really changed, still notifying listeners. 00:03:00.793661 GUI: UIMachineLogicNormal::sltCheckForRequestedVisualStateType: Requested-state=1, Machine-state=6 00:03:00.793676 VMMDev: vmmDevReqHandler_HeartbeatConfigure: No change (fHeartbeatActive=false) 00:03:00.793712 VMMDev: Heartbeat flatline timer set to trigger after 4 000 000 000 ns 00:03:00.794040 GUI: UISession::sltAdditionsChange: GA state really changed, notifying listeners. 00:03:00.794066 GUI: UIMachineLogicNormal::sltCheckForRequestedVisualStateType: Requested-state=1, Machine-state=6 00:03:00.794073 GUI: UIMachineLogicNormal::sltCheckForRequestedVisualStateType: Requested-state=1, Machine-state=6 00:03:00.796543 VMMDev: Guest Additions capability report: (0x0 -> 0x0) seamless: no, hostWindowMapping: no, graphics: no 00:03:00.796636 GUI: UISession::sltAdditionsChange: GA state doesn't really changed, still notifying listeners. 00:03:00.796648 GUI: UIMachineLogicNormal::sltCheckForRequestedVisualStateType: Requested-state=1, Machine-state=6 00:03:06.749227 VMMDev: Guest Log: VBoxMP::DriverEntry: VBox WDDM Driver for Windows 8+ version 7.1.0r164728 rel, 64 bit; Built Sep 6 2024 18:08:08 00:03:06.749858 VMMDev: Guest Log: VBoxMP::DriverEntry: OsVersion(10, 0, 19045) 00:03:06.752674 VMMDev: Guest Log: VBoxMP::DriverEntry: WDDM: VGA configuration version 1 00:03:06.753409 VMMDev: Guest Log: VBoxMP::DriverEntry: WDDM: VGA configuration: 3D 0, hardware type 1, VGPU10 1 00:03:06.753699 VMMDev: Guest Log: VBoxMP::DriverEntry: WDDM: 3D is not supported, hardware type 1 00:03:06.753922 VMMDev: Guest Log: VBoxMP::DriverEntry: 3D is NOT supported by the host, falling back to display-only mode.. 00:03:06.949144 VMMDev: Guest Log: VBoxMP::vboxWddmPickResources: found the VBE card 00:03:06.955528 VMMDev: Guest Log: VBoxMP::DxgkDdiStartDevice: Handling complex topologies enabled 00:03:06.960299 VMMDev: Guest Log: VBoxMP::DxgkDdiStartDevice: using HGSMI 00:03:06.960839 GUI: UIFrameBufferPrivate::IsVideoModeSupported: Mode: BPP=32, Size=1600x1200 is NOT supported 00:03:06.961309 VMMDev: Guest Log: VBoxMP::vboxWddmVModesAdd: WARNING! :resolution 1600x1200 not accepted by the frontend 00:03:06.961364 GUI: UIFrameBufferPrivate::IsVideoModeSupported: Mode: BPP=32, Size=1920x1440 is NOT supported 00:03:06.962507 VMMDev: Guest Log: VBoxMP::vboxWddmVModesAdd: WARNING! :resolution 1920x1440 not accepted by the frontend 00:03:06.963794 VMMDev: Guest Log: VBoxMP::DxgkDdiStartDevice: WDDM: VRAM 0xe0000000/0x8000000, FIFO 0xe8400000/0x200000, IO 0x60b0/0x10 00:03:06.965161 Enabling different vbva mode 00:03:06.966168 Enabling different vbva mode 00:03:07.118649 Display::i_handleDisplayResize: uScreenId=0 pvVRAM=000002dd63290000 w=1203 h=792 bpp=32 cbLine=0x12CC flags=0x1 origin=0,0 00:03:07.448991 GUI: UIMediumEnumerator: Medium-enumeration finished! 00:03:23.509124 GUI: UIMediumEnumerator: Medium-enumeration finished! 00:03:24.498891 VMMDev: Guest Log: VBOXNP: DLL loaded. 00:03:24.499052 VMMDev: Guest Log: VBOXNP: vbsfIOCTL: Error opening device, last error = 2 00:03:33.302483 VMMDev: Guest Log: VBOXNP: DLL loaded. 00:03:33.302619 VMMDev: Guest Log: VBOXNP: vbsfIOCTL: Error opening device, last error = 2 00:03:43.816377 EHCI: USB Suspended 00:03:43.971968 xHCI: USB Suspended 00:03:48.890879 VMMDev: vmmDevHeartbeatFlatlinedTimer: Guest seems to be unresponsive. Last heartbeat received 4 seconds ago

Thank you

[cmccarron]

Additional VBox.log file after installing 7.1.0 extensions.

[cmccarron]

Additional 7.1.0 VBox.log file

[cmccarron]

If I uninstall the "Standard OpenHCD USB Host Controller" USB driver from the Guest before shutting it down, the guest will shutdown without issue.

Universal Serial Bus Controllers

Intel(R) 82801FB/FBM USB2 Enhanced Host Controller - 265C
Intel(R) USB3.0 eXtensible Host Controller - 1.0 (Microsoft)
Standard OpenHCD USB Host Controller
USB Root Hub
USB Root Hub
USB Root Hub (USB 3.0)

[cmccarron]

It appears that VirtualBox was hanging on to old USB configurations. To fix I had to disable USB Controller support in the USB Setup screen and click the ok button to save the changes. I then enabled USB Controller support, then selected USB 3.0 (xHCI) Controller and clicked the OK button to save the changes. When I started the VM, I now only have the following in Device Manager. The VM now shutsdown without issue.

Universal Serial Bus Controllers

Intel(R) USB3.0 eXtensible Host Controller - 1.0 (Microsoft)
USB Root Hub (USB 3.0)

[cmccarron]

Link to forum topic on this issue.

​https://forums.virtualbox.org/viewtopic.php?p=548166#p548166