Guest seems to be unresponsive

[Michele31415]

Running VB Version 7.0.14 r161095 (Qt5.15.2) on a Windows 11 host with all updates applied. I start a Ubuntu guest and all is well until at some random point, usually a few hours, the guest suddenly just stops responding. I can move the mouse around but button clicks and keyboard actions have no effect. The tail of the log:

Code: Select all

00:00:45.098078 VMMDev: Guest Log: 16:07:45.138449 main     VBoxService 7.0.14 r161095 (verbosity: 0) linux.amd64 (Jan 15 2024 15:01:58) release log
00:00:45.098090 VMMDev: Guest Log: 16:07:45.138450 main     Log opened 2024-01-31T16:07:45.138445000Z
00:00:45.098133 VMMDev: Guest Log: 16:07:45.138517 main     OS Product: Linux
00:00:45.098154 VMMDev: Guest Log: 16:07:45.138539 main     OS Release: 6.5.0-15-generic
00:00:45.098175 VMMDev: Guest Log: 16:07:45.138560 main     OS Version: #15~22.04.1-Ubuntu SMP PREEMPT_DYNAMIC Fri Jan 12 18:54:30 UTC 2
00:00:45.098195 VMMDev: Guest Log: 16:07:45.138581 main     Executable: /opt/VBoxGuestAdditions-7.0.14/sbin/VBoxService
00:00:45.098198 VMMDev: Guest Log: 16:07:45.138581 main     Process ID: 1123
00:00:45.098200 VMMDev: Guest Log: 16:07:45.138581 main     Package type: LINUX_64BITS_GENERIC
00:00:45.117699 VMMDev: Guest Log: 16:07:45.158070 main     7.0.14 r161095 started. Verbose level = 0
00:00:45.118735 GUI: UISession::sltAdditionsChange: GA state change event came, notifying listeners
00:00:45.118745 GUI: UIMachineLogicNormal::sltCheckForRequestedVisualStateType: Requested-state=1, Machine-state=6
00:00:45.118930 VMMDev: Guest Log: 16:07:45.159258 main     vbglR3GuestCtrlDetectPeekGetCancelSupport: Supported (#1)
00:00:45.119003 Guest Control: GUEST_MSG_REPORT_FEATURES: 0xf, 0x8000000000000000
00:00:45.126025 GUI: UISession::sltAdditionsChange: GA state really changed, notifying listeners
00:00:45.126044 GUI: UIMachineViewNormal::adjustGuestScreenSize: Adjust guest-screen size if necessary
00:00:45.126055 GUI: UIMachineView::sltPerformGuestResize: Omitting to send size-hint 1280x800 to guest-screen 0 because frame-buffer is already of the same size.
00:00:45.126058 GUI: UIMachineViewNormal::adjustGuestScreenSize: Adjust guest-screen size if necessary
00:00:45.126061 GUI: UISession::sltAdditionsChange: GA state change event came, notifying listeners
00:00:45.126064 GUI: UIMachineLogicNormal::sltCheckForRequestedVisualStateType: Requested-state=1, Machine-state=6
00:00:45.195552 VMMDev: Guest Additions capability report: (0x0 -> 0x4) seamless: no, hostWindowMapping: no, graphics: yes
00:00:45.198705 GUI: UISession::sltAdditionsChange: GA state really changed, notifying listeners
00:00:45.198721 GUI: UIMachineViewNormal::adjustGuestScreenSize: Adjust guest-screen size if necessary
00:00:45.198733 GUI: UIMachineView::sltPerformGuestResize: Omitting to send size-hint 1280x800 to guest-screen 0 because frame-buffer is already of the same size.
00:00:45.198739 GUI: UIMachineViewNormal::adjustGuestScreenSize: Adjust guest-screen size if necessary
00:00:45.198744 GUI: UISession::sltAdditionsChange: GA state change event came, notifying listeners
00:00:45.198747 GUI: UIMachineLogicNormal::sltCheckForRequestedVisualStateType: Requested-state=1, Machine-state=6
00:00:45.213078 Display::i_handleDisplayResize: uScreenId=0 pvVRAM=0000000000000000 w=1280 h=800 bpp=0 cbLine=0x0 flags=0x2 origin=0,0
00:00:45.273336 VMMDev: Guest Log: vboxsf: g_fHostFeatures=0x8000000f g_fSfFeatures=0x1 g_uSfLastFunction=29
00:00:45.273424 VMMDev: Guest Log: vboxsf: Successfully loaded version 7.0.14 r161095 on 6.5.0-15-generic (LINUX_VERSION_CODE=0x60503)
00:00:45.275461 VMMDev: Guest Log: 16:07:45.315831 automount vbsvcAutomounterMountIt: Successfully mounted 'Downloads' on '/home/michele/downloadshare'
00:00:45.285580 Display::i_handleDisplayResize: uScreenId=0 pvVRAM=000001e3e8bc0000 w=1919 h=1071 bpp=32 cbLine=0x1DFC flags=0x1 origin=0,0
00:00:45.293075 GUI: UIMachineLogic: Guest-screen count changed
00:00:45.594698 GUI: UIMachineView::sltPerformGuestResize: Omitting to send size-hint 1919x1071 to guest-screen 0 because frame-buffer is already of the same size.
00:00:45.621563 GUI: UIMediumEnumerator: Medium-enumeration finished!
00:00:49.972814 Audio: Warning: Scheduling hint of stream '[WasAPI] Output:0' is bigger (5ms) than used period size (0ms)
00:00:49.976652 Audio: Warning: Scheduling hint of stream '[WasAPI] Line-In:0' is bigger (10ms) than used period size (0ms)
00:01:09.585263 Display::i_handleDisplayResize: uScreenId=0 pvVRAM=0000000000000000 w=1919 h=1071 bpp=0 cbLine=0x0 flags=0x2 origin=0,0
00:01:11.455310 Display::i_handleDisplayResize: uScreenId=0 pvVRAM=000001e3e83e8000 w=1920 h=1080 bpp=32 cbLine=0x1E00 flags=0x1 origin=0,0
00:01:11.464768 GUI: UIMachineLogic: Guest-screen count changed
00:01:11.763694 GUI: UIMachineView::sltPerformGuestResize: Omitting to send size-hint 1920x1080 to guest-screen 0 because frame-buffer is already of the same size.
00:01:11.775284 GUI: UIMediumEnumerator: Medium-enumeration finished!
00:01:15.311406 VMMDev: Guest Additions capability report: (0x4 -> 0x5) seamless: yes, hostWindowMapping: no, graphics: yes
00:01:15.315038 GUI: UISession::sltAdditionsChange: GA state really changed, notifying listeners
00:01:15.315096 GUI: UIMachineViewNormal::adjustGuestScreenSize: Adjust guest-screen size if necessary
00:01:15.315105 GUI: UIMachineViewNormal::adjustGuestScreenSize: Adjust guest-screen size if necessary
00:01:15.315109 GUI: UISession::sltAdditionsChange: GA state change event came, notifying listeners
00:01:15.315111 GUI: UIMachineLogicNormal::sltCheckForRequestedVisualStateType: Requested-state=1, Machine-state=6
00:01:15.336945 DnD: Feature is disabled, ignoring request from guest
00:01:15.351075 Shared Clipboard: New Clipboard API enabled
00:04:26.572785 GUI: UIMediumEnumerator: Medium-enumeration started...
00:04:27.576306 GUI: UIMediumEnumerator: Medium-enumeration finished!
00:04:58.728034 GUI: UIMediumEnumerator: Medium-enumeration finished!
00:05:06.687091 GUI: UIMediumEnumerator: Medium-enumeration finished!
01:25:29.381758 VMMDev: vmmDevHeartbeatFlatlinedTimer: Guest seems to be unresponsive. Last heartbeat received 4 seconds ago
01:25:29.789772 VMMDev: GuestHeartBeat: Guest is alive (gone 4 408 513 333 ns)
08:56:22.015296 GUI: UIMediumEnumerator: Medium-enumeration finished!
42:05:02.923043 VMMDev: vmmDevHeartbeatFlatlinedTimer: Guest seems to be unresponsive. Last heartbeat received 4 seconds ago
42:05:03.544239 VMMDev: GuestHeartBeat: Guest is alive (gone 4 621 287 595 ns)
43:26:16.751124 TM: Giving up catch-up attempt at a 60 000 666 393 ns lag; new total: 60 000 666 393 ns
43:53:53.337270 TM: Giving up catch-up attempt at a 60 001 565 200 ns lag; new total: 120 002 231 593 ns
44:13:40.654892 TM: Giving up catch-up attempt at a 60 001 287 866 ns lag; new total: 180 003 519 459 ns
44:31:57.206327 TM: Giving up catch-up attempt at a 60 004 131 859 ns lag; new total: 240 007 651 318 ns
44:48:38.775222 TM: Giving up catch-up attempt at a 60 004 461 228 ns lag; new total: 300 012 112 546 ns
45:06:55.290866 TM: Giving up catch-up attempt at a 60 000 081 790 ns lag; new total: 360 012 194 336 ns
45:19:30.069332 VMMDev: vmmDevHeartbeatFlatlinedTimer: Guest seems to be unresponsive. Last heartbeat received 4 seconds ago

So far I have disabled HyperV on the host and disabled nested paging in the guest's settings. Didn't help. I searched the forum for this topic and found nothing. What's going on here?

[scottgus1]

Please start the VM from full normal shutdown, not save-state. Run until you see the problem happen, then shut down the VM from within the VM's OS if possible. If not possible, close the Virtualbox window for the VM with the Power Off option set.

Right-click the VM in the main Virtualbox window's VM list, choose Show in Explorer/Finder/File Manager. In the "Logs" subfolder, zip the VM's "vbox.log", and post the zip file, using the forum's Attachments tab. (Configure your host OS to show all extensions so you can find the "vbox.log", not "vbox.log.1", etc.)

[Michele31415]

shut down the VM from within the VM's OS if possible. If not possible, close the Virtualbox window for the VM with the Power Off option set.

No, it's not possible. The VM is totally unresponsive so there's no way to shut it down normally. How do I "close the Virtualbox window for the VM with the Power Off option set"? Do you mean Machine -> ACPI Shutdown? I don't see a Power Off option.

[scottgus1]

How do I "close the Virtualbox window for the VM with the Power Off option set"?

When you click the X box on the VM window through the usual method for closing windows in Windows, you get a pop-up box asking for a few options. One of the options will be "Power Off".

[Michele31415]

Ah, now I see, thanks. OK, here's the file.

[Michele31415]

FWIW, I'm getting the same problem with my Win XP guest, so it's not just Ubuntu:

Code: Select all

166:05:33.825572 VMMDev: Guest Log: VBoxDisp[1]: VBVA enabled
166:05:33.825606 Display::i_handleDisplayResize: uScreenId=1 pvVRAM=0000028ea8df6000 w=1400 h=1050 bpp=32 cbLine=0x15E0 flags=0x1 origin=0,0
166:05:33.826462 Display::i_handleDisplayResize: uScreenId=1 pvVRAM=0000028ea8df6000 w=1400 h=1050 bpp=32 cbLine=0x15E0 flags=0x1 origin=1907,0
166:05:33.993839 GUI: UIMediumEnumerator: Medium-enumeration finished!
166:05:35.184918 VMMDev: Guest Log: 14:49:52.916416 main     VBoxService 6.1.32 r149290 (verbosity: 0) win.x86 (Jan 13 2022 19:11:45) release log
166:05:35.184933 VMMDev: Guest Log: 14:49:52.916416 main     Log opened 2024-02-11T14:49:52.916416000Z
166:05:35.184963 VMMDev: Guest Log: 14:49:52.916416 main     OS Product: Windows XP Home
166:05:35.184982 VMMDev: Guest Log: 14:49:52.916416 main     OS Release: 5.1.2600
166:05:35.185001 VMMDev: Guest Log: 14:49:52.916416 main     OS Service Pack: 3
166:05:35.185020 VMMDev: Guest Log: 14:49:52.916416 main     Executable: C:\WINDOWS\System32\VBoxService.exe
166:05:35.185022 VMMDev: Guest Log: 14:49:52.916416 main     Process ID: 1980
166:05:35.185024 VMMDev: Guest Log: 14:49:52.916416 main     Package type: WINDOWS_32BITS_GENERIC
166:05:35.186836 VMMDev: Guest Log: 14:49:52.916416 main     6.1.32 r149290 started. Verbose level = 0
166:05:35.188025 GUI: UISession::sltAdditionsChange: GA state change event came, notifying listeners
166:05:35.188033 GUI: UIMachineLogicNormal::sltCheckForRequestedVisualStateType: Requested-state=1, Machine-state=6
166:05:35.188200 VMMDev: Guest Log: 14:49:52.916416          vbglR3GuestCtrlDetectPeekGetCancelSupport: Supported (#1)
166:05:35.188263 Guest Control: GUEST_MSG_REPORT_FEATURES: 0x7, 0x8000000000000000
166:05:35.188482 VMMDev: Guest Log: 14:49:52.916416          vgsvcTimeSyncInit: Initially 100144 (100ns) units per 100144 (100 ns) units interval, disabled=1
166:05:35.194278 GUI: UISession::sltAdditionsChange: GA state really changed, notifying listeners
166:05:35.194278 GUI: UIMachineViewNormal::adjustGuestScreenSize: Adjust guest-screen size if necessary
166:05:35.194278 GUI: UIMachineViewNormal::adjustGuestScreenSize: Adjust guest-screen size if necessary
166:05:35.194278 GUI: UISession::sltAdditionsChange: GA state change event came, notifying listeners
166:05:35.194278 GUI: UIMachineLogicNormal::sltCheckForRequestedVisualStateType: Requested-state=1, Machine-state=6
166:05:35.201856 VMMDev: Guest Log: VBOXNP: DLL loaded.
166:05:35.207315 VMMDev: Guest Log: 14:49:52.938447 automount vbsvcAutomounterMountIt: Successfully mounted 'My_Documents' on 'Z:'
166:05:35.214802 VMMDev: Guest Log: 14:49:52.949463 automount vbsvcAutomounterMountIt: Successfully mounted 'My_Download_Files' on 'Y:'
166:05:35.218819 VMMDev: Guest Log: 14:49:52.949463 automount vbsvcAutomounterMountIt: Successfully mounted 'Program_Files' on 'X:'
166:05:44.341841 AC97: Record select to left=mic, right=mic
166:05:44.341888 AC97: Record select to left=mic, right=mic
166:05:48.008274 VMMDev: Guest Log: VBOXNP: DLL loaded.
166:05:48.247615 VMMDev: Guest Log: Windows version 5.1 build 2600 (uNtVersion=0x50010000000a28)
166:05:48.247753 GUI: UISession::sltAdditionsChange: GA state change event came, notifying listeners
166:05:48.247761 GUI: UIMachineLogicNormal::sltCheckForRequestedVisualStateType: Requested-state=1, Machine-state=6
166:05:48.307176 VMMDev: Guest Log: Starting services ...
166:05:48.307207 VMMDev: Guest Log: Starting service 'display' ...
166:05:48.309015 VMMDev: Guest Log: Service 'display' started
166:05:48.309049 VMMDev: Guest Log: Starting service 'clipboard' ...
166:05:48.320206 VMMDev: Guest Log: Shared Clipboard: New Clipboard API not available (VERR_SYMBOL_NOT_FOUND)
166:05:48.332615 Shared Clipboard: New Clipboard API enabled
166:05:48.350399 VMMDev: Guest Log: Service 'clipboard' started
166:05:48.350436 VMMDev: Guest Log: Starting service 'seamless' ...
166:05:48.350477 VMMDev: Guest Additions capability report: (0x0 -> 0x1) seamless: yes, hostWindowMapping: no, graphics: no
166:05:48.352661 GUI: UISession::sltAdditionsChange: GA state really changed, notifying listeners
166:05:48.352661 GUI: UIMachineViewNormal::adjustGuestScreenSize: Adjust guest-screen size if necessary
166:05:48.352661 GUI: UIMachineViewNormal::adjustGuestScreenSize: Adjust guest-screen size if necessary
166:05:48.352661 GUI: UISession::sltAdditionsChange: GA state change event came, notifying listeners
166:05:48.352661 GUI: UIMachineLogicNormal::sltCheckForRequestedVisualStateType: Requested-state=1, Machine-state=6
166:05:48.355910 VMMDev: Guest Log: Service 'seamless' started
166:05:48.355936 VMMDev: Guest Log: Starting service 'VRDP' ...
166:05:48.358272 VMMDev: Guest Log: Service 'VRDP' started
166:05:48.358305 VMMDev: Guest Log: Starting service 'IPC' ...
166:05:48.358618 VMMDev: Guest Log: VBoxIPCInit: Local IPC server now running at "\\.\pipe\VBoxTrayIPC-Michele"
166:05:48.361861 VMMDev: Guest Log: Service 'IPC' started
166:05:48.361894 VMMDev: Guest Log: Starting service 'LA' ...
166:05:48.361938 VMMDev: Guest Log: LA: RegQueryValueExW: failed [SOFTWARE\Oracle\VirtualBox Guest Additions/VBoxTrayLog]
166:05:48.361961 VMMDev: Guest Log: LA: RegQueryValueExW: failed [SOFTWARE\Oracle\VirtualBox Guest Additions/VBoxTrayLA]
166:05:48.361980 VMMDev: Guest Log: LA: DetachOnDisconnect=true 
166:05:48.365158 VMMDev: Guest Log: Service 'LA' started
166:05:48.365183 VMMDev: Guest Log: Starting service 'draganddrop' ...
166:05:48.384765 VMMDev: Guest Log: DnD: Drag and drop service successfully started
166:05:48.386326 VMMDev: Guest Log: Service 'draganddrop' started
166:05:48.386351 VMMDev: Guest Log: All services started
166:05:48.401049 VMMDev: Guest Additions capability report: (0x1 -> 0x5) seamless: yes, hostWindowMapping: no, graphics: yes
166:05:48.401800 GUI: UISession::sltAdditionsChange: GA state really changed, notifying listeners
166:05:48.401822 GUI: UIMachineViewNormal::adjustGuestScreenSize: Adjust guest-screen size if necessary
166:05:48.401830 GUI: UIMachineViewNormal::adjustGuestScreenSize: Adjust guest-screen size if necessary
166:05:48.401834 GUI: UISession::sltAdditionsChange: GA state change event came, notifying listeners
166:05:48.401836 GUI: UIMachineLogicNormal::sltCheckForRequestedVisualStateType: Requested-state=1, Machine-state=6
166:05:48.401849 GUI: UISession::sltAdditionsChange: GA state change event came, notifying listeners
166:05:48.401851 GUI: UIMachineLogicNormal::sltCheckForRequestedVisualStateType: Requested-state=1, Machine-state=6
176:11:20.359641 PIIX3 ATA: Ctl#0: RESET, DevSel=0 AIOIf=0 CmdIf0=0x39 (9984104 usec ago) CmdIf1=0x00 (-1 usec ago)
176:12:01.706174 VMMDev: vmmDevHeartbeatFlatlinedTimer: Guest seems to be unresponsive. Last heartbeat received 4 seconds ago
176:12:01.900745 PIIX3 ATA: Ctl#0: finished processing RESET
176:12:01.900764 PIIX3 ATA: execution time for ATA command 0x39 was 51 seconds
176:12:01.900843 ATA: LUN#0: INITIALIZE DEVICE PARAMETERS: 63 sectors per track, 16 heads
176:12:02.776270 VMMDev: GuestHeartBeat: Guest is alive (gone 43 528 664 039 ns)
176:12:31.143935 PIIX3 ATA: Ctl#0: RESET, DevSel=0 AIOIf=0 CmdIf0=0x39 (9118076 usec ago) CmdIf1=0x00 (-1 usec ago)
176:12:55.348892 VMMDev: vmmDevHeartbeatFlatlinedTimer: Guest seems to be unresponsive. Last heartbeat received 4 seconds ago
176:12:55.349527 PIIX3 ATA: Ctl#0: finished processing RESET
176:12:55.349533 PIIX3 ATA: execution time for ATA command 0x39 was 33 seconds
176:12:55.349640 ATA: LUN#0: INITIALIZE DEVICE PARAMETERS: 63 sectors per track, 16 heads
176:12:57.188640 VMMDev: GuestHeartBeat: Guest is alive (gone 26 197 761 946 ns)
177:16:06.281826 VMMDev: vmmDevHeartbeatFlatlinedTimer: Guest seems to be unresponsive. Last heartbeat received 4 seconds ago

Like the Ubuntu guest, I can move the mouse around, but mouse clicks and keyboard input do nothing. It's pretty mysterious.

[scottgus1]

FWIW Hyper-V on the host is still enabled:

00:00:02.759860 HM: HMR3Init: Attempting fall back to NEM: VT-x is not available

See HMR3Init: Attempting fall back to NEM (Hyper-V is active).

Active host Hyper-V is gradually becoming not as much of a problem, but it does still interfere at times.

One other thing, you "Core(TM) i7-12700K" processor has those performance & efficiency cores, and if Virtualbox gets put on the efficiency cores, performance could drop. See viewtopic.php?f=6&t=108745 for possibilities.

[Michele31415]

Thank you for some good suggestions. I didn't even know about p-cores and e-cores. I thought I'd disabled Hyper-V by unchecking it in the Windows features box. Meanwhile I tried the power-throtlling commands.

Code: Select all

C:\Program Files\qemu>powercfg /powerthrottling disable /path "C:\Program Files\Oracle\VirtualBox\VBoxHeadless.exe"

C:\Program Files\qemu>powercfg /powerthrottling disable /path "C:\Program Files\Oracle\VirtualBox\VirtualBoxVM.exe"

I have both my Ubuntu an XP VM's running now. We'll see what happens.

[Michele31415]

Update

I don't know if this can be considered a solution or a workaround but I got my VM's to stay running just by disabling acceleration entirely (Settings -> System -> Acceleration -> Paravirtualization Interface: None) and unchecking Enable 3d Acceleration under Display. My Ubuntu guest has now been up for nine days, whereas it always locked up somewhere between a few hours to less than two days before. My XP VM has been up for over four days with no problems too.

And honestly, I don't notice any difference in speed or responsiveness of the UI. Admittedly I'm not mining bitcoins or cracking wifi passwords but it's good enough for me.