Guest freeze after restoring from saved state

[gkcgareth]

Saved guest state, left it alone over night. Resumed from saved state the next morning, guest ran for a few minutes then froze. Required power off to recover.

Version 6.1.22

00:00:41.067179 TM: cTSCTicksPerSecond=0x7de2930c (2 112 000 780) enmTSCMode=1 (VirtTscEmulated) (state load) 00:00:41.067225 GIM: KVM: VCPU 0: Enabled system-time struct. at 0x0000000070e01000 - u32TscScale=0xf26c9548 i8TscShift=-1 uVersion=2 fFlags=0x1 uTsc=0x3e6641e77 uVirtNanoTS=0x1d8b90e9e TscKHz=2112000 00:00:41.067391 TM: Switching TSC mode from 'VirtTscEmulated' to 'RealTscOffset' 00:00:41.067504 GIM: KVM: VCPU 1: Enabled system-time struct. at 0x0000000070e01040 - u32TscScale=0xf26c9548 i8TscShift=-1 uVersion=2 fFlags=0x1 uTsc=0x44d7575f1 uVirtNanoTS=0x209861cac TscKHz=2112000 00:00:41.067524 GIM: KVM: VCPU 2: Enabled system-time struct. at 0x0000000070e01080 - u32TscScale=0xf26c9548 i8TscShift=-1 uVersion=2 fFlags=0x1 uTsc=0x44ddad072 uVirtNanoTS=0x209b619f2 TscKHz=2112000 00:00:41.067536 GIM: KVM: VCPU 3: Enabled system-time struct. at 0x0000000070e010c0 - u32TscScale=0xf26c9548 i8TscShift=-1 uVersion=2 fFlags=0x1 uTsc=0x44e3dffac uVirtNanoTS=0x209e5104d TscKHz=2112000 00:00:41.067695 PIT: mode=2 count=0x10000 (65536) - 18.20 Hz (ch=0) (restore) 00:00:41.067710 RTC: Stopped the periodic timer (restore) 00:00:41.067746 HGCM: Restoring [VBoxGuestPropSvc] 00:00:41.068417 HGCM: Restoring [VBoxGuestControlSvc] 00:00:41.068625 HGCM: Restoring [VBoxSharedFolders] 00:00:41.069102 HGCM: Restoring [VBoxDragAndDropSvc] 00:00:41.069378 HGCM: Restoring [VBoxSharedClipboard] 00:00:41.069740 Shared Clipboard: New Clipboard API enabled 00:00:41.073974 VMMDev: Guest Additions information report: Interface = 0x00010004 osType = 0x00053100 (Linux >= 2.6, 64-bit) 00:00:41.074187 GUI: UISession::sltAdditionsChange: GA state really changed, notifying listeners 00:00:41.074218 GUI: UIMachineViewNormal::adjustGuestScreenSize: Adjust guest-screen size if necessary 00:00:41.074239 GUI: UISession::sltAdditionsChange: GA state change event came, notifying listeners 00:00:41.074248 GUI: UIMachineLogicNormal::sltCheckForRequestedVisualStateType: Requested-state=0, Machine-state=13 00:00:41.074260 GUI: UISession::sltAdditionsChange: GA state really changed, notifying listeners 00:00:41.074269 GUI: UIMachineViewNormal::adjustGuestScreenSize: Adjust guest-screen size if necessary 00:00:41.074279 GUI: UISession::sltAdditionsChange: GA state change event came, notifying listeners 00:00:41.074287 GUI: UIMachineLogicNormal::sltCheckForRequestedVisualStateType: Requested-state=0, Machine-state=13 00:00:41.074319 GUI: UISession::sltAdditionsChange: GA state change event came, notifying listeners 00:00:41.074328 GUI: UIMachineLogicNormal::sltCheckForRequestedVisualStateType: Requested-state=0, Machine-state=13 00:00:41.074339 GUI: UISession::sltAdditionsChange: GA state change event came, notifying listeners 00:00:41.074347 GUI: UIMachineLogicNormal::sltCheckForRequestedVisualStateType: Requested-state=0, Machine-state=13 00:00:41.075353 E1000#0: Interrupt attempts: 0 00:00:41.075369 E1000#0: Interrupts raised : 0 00:00:41.075377 E1000#0: Interrupts lowered: 0 00:00:41.075384 E1000#0: ICR outside ISR : 0 00:00:41.075391 E1000#0: IMS raised ints : 0 00:00:41.075398 E1000#0: Interrupts skipped: 0 00:00:41.075405 E1000#0: Masked interrupts : 0 00:00:41.075412 E1000#0: Early interrupts : 0 00:00:41.075419 E1000#0: Late interrupts : 0 00:00:41.075426 E1000#0: Lost interrupts : 0 00:00:41.075432 E1000#0: Interrupts by RX : 0 00:00:41.075439 E1000#0: Interrupts by TX : 0 00:00:41.075446 E1000#0: Interrupts by ICS : 0 00:00:41.075453 E1000#0: Interrupts by RDTR: 0 00:00:41.075460 E1000#0: Interrupts by RDMT: 0 00:00:41.075467 E1000#0: Interrupts by TXQE: 0 00:00:41.075474 E1000#0: TX int delay asked: 0 00:00:41.075481 E1000#0: TX delayed: 0 00:00:41.075488 E1000#0: TX delay expired: 0 00:00:41.075495 E1000#0: TX no report asked: 0 00:00:41.075502 E1000#0: TX abs timer expd : 0 00:00:41.075509 E1000#0: TX int timer expd : 0 00:00:41.075516 E1000#0: RX abs timer expd : 0 00:00:41.075523 E1000#0: RX int timer expd : 0 00:00:41.075530 E1000#0: TX CTX descriptors: 0 00:00:41.075536 E1000#0: TX DAT descriptors: 0 00:00:41.075543 E1000#0: TX LEG descriptors: 0 00:00:41.075550 E1000#0: Received frames : 0 00:00:41.075557 E1000#0: Transmitted frames: 0 00:00:41.075564 E1000#0: TX frames up to 1514: 0 00:00:41.075577 E1000#0: TX frames up to 2962: 0 00:00:41.075586 E1000#0: TX frames up to 4410: 0 00:00:41.075593 E1000#0: TX frames up to 5858: 0 00:00:41.075600 E1000#0: TX frames up to 7306: 0 00:00:41.075607 E1000#0: TX frames up to 8754: 0 00:00:41.075614 E1000#0: TX frames up to 16384: 0 00:00:41.075621 E1000#0: TX frames up to 32768: 0 00:00:41.075628 E1000#0: Larger TX frames : 0 00:00:41.075635 E1000#0: Max TX Delay : 0 00:00:41.075646 E1000#1: Interrupt attempts: 0 00:00:41.075653 E1000#1: Interrupts raised : 0 00:00:41.075660 E1000#1: Interrupts lowered: 0 00:00:41.075667 E1000#1: ICR outside ISR : 0 00:00:41.075674 E1000#1: IMS raised ints : 0 00:00:41.075681 E1000#1: Interrupts skipped: 0 00:00:41.075688 E1000#1: Masked interrupts : 0 00:00:41.075694 E1000#1: Early interrupts : 0 00:00:41.075701 E1000#1: Late interrupts : 0 00:00:41.075708 E1000#1: Lost interrupts : 0 00:00:41.075715 E1000#1: Interrupts by RX : 0 00:00:41.075722 E1000#1: Interrupts by TX : 0 00:00:41.075729 E1000#1: Interrupts by ICS : 0 00:00:41.075736 E1000#1: Interrupts by RDTR: 0 00:00:41.075743 E1000#1: Interrupts by RDMT: 0 00:00:41.075749 E1000#1: Interrupts by TXQE: 0 00:00:41.075756 E1000#1: TX int delay asked: 0 00:00:41.075763 E1000#1: TX delayed: 0 00:00:41.075770 E1000#1: TX delay expired: 0 00:00:41.075777 E1000#1: TX no report asked: 0 00:00:41.075784 E1000#1: TX abs timer expd : 0 00:00:41.075790 E1000#1: TX int timer expd : 0 00:00:41.075797 E1000#1: RX abs timer expd : 0 00:00:41.075804 E1000#1: RX int timer expd : 0 00:00:41.075811 E1000#1: TX CTX descriptors: 0 00:00:41.075818 E1000#1: TX DAT descriptors: 0 00:00:41.075825 E1000#1: TX LEG descriptors: 0 00:00:41.075831 E1000#1: Received frames : 0 00:00:41.075838 E1000#1: Transmitted frames: 0 00:00:41.075845 E1000#1: TX frames up to 1514: 0 00:00:41.075852 E1000#1: TX frames up to 2962: 0 00:00:41.075859 E1000#1: TX frames up to 4410: 0 00:00:41.075866 E1000#1: TX frames up to 5858: 0 00:00:41.075873 E1000#1: TX frames up to 7306: 0 00:00:41.075879 E1000#1: TX frames up to 8754: 0 00:00:41.075886 E1000#1: TX frames up to 16384: 0 00:00:41.075893 E1000#1: TX frames up to 32768: 0 00:00:41.075900 E1000#1: Larger TX frames : 0 00:00:41.075907 E1000#1: Max TX Delay : 0 00:00:41.075917 E1000#2: Interrupt attempts: 0 00:00:41.075924 E1000#2: Interrupts raised : 0 00:00:41.075931 E1000#2: Interrupts lowered: 0 00:00:41.075938 E1000#2: ICR outside ISR : 0 00:00:41.075945 E1000#2: IMS raised ints : 0 00:00:41.075952 E1000#2: Interrupts skipped: 0 00:00:41.075959 E1000#2: Masked interrupts : 0 00:00:41.075966 E1000#2: Early interrupts : 0 00:00:41.075972 E1000#2: Late interrupts : 0 00:00:41.075979 E1000#2: Lost interrupts : 0 00:00:41.075986 E1000#2: Interrupts by RX : 0 00:00:41.075993 E1000#2: Interrupts by TX : 0 00:00:41.076000 E1000#2: Interrupts by ICS : 0 00:00:41.076007 E1000#2: Interrupts by RDTR: 0 00:00:41.076013 E1000#2: Interrupts by RDMT: 0 00:00:41.076020 E1000#2: Interrupts by TXQE: 0 00:00:41.076027 E1000#2: TX int delay asked: 0 00:00:41.076034 E1000#2: TX delayed: 0 00:00:41.076041 E1000#2: TX delay expired: 0 00:00:41.076047 E1000#2: TX no report asked: 0 00:00:41.076054 E1000#2: TX abs timer expd : 0 00:00:41.076061 E1000#2: TX int timer expd : 0 00:00:41.076068 E1000#2: RX abs timer expd : 0 00:00:41.076075 E1000#2: RX int timer expd : 0 00:00:41.076081 E1000#2: TX CTX descriptors: 0 00:00:41.076088 E1000#2: TX DAT descriptors: 0 00:00:41.076095 E1000#2: TX LEG descriptors: 0 00:00:41.076102 E1000#2: Received frames : 0 00:00:41.076109 E1000#2: Transmitted frames: 0 00:00:41.076116 E1000#2: TX frames up to 1514: 0 00:00:41.076122 E1000#2: TX frames up to 2962: 0 00:00:41.076129 E1000#2: TX frames up to 4410: 0 00:00:41.076136 E1000#2: TX frames up to 5858: 0 00:00:41.076143 E1000#2: TX frames up to 7306: 0 00:00:41.076150 E1000#2: TX frames up to 8754: 0 00:00:41.076157 E1000#2: TX frames up to 16384: 0 00:00:41.076165 E1000#2: TX frames up to 32768: 0 00:00:41.076172 E1000#2: Larger TX frames : 0 00:00:41.076179 E1000#2: Max TX Delay : 0 00:00:41.076189 E1000#3: Interrupt attempts: 0 00:00:41.076197 E1000#3: Interrupts raised : 0 00:00:41.076204 E1000#3: Interrupts lowered: 0 00:00:41.076211 E1000#3: ICR outside ISR : 0 00:00:41.076218 E1000#3: IMS raised ints : 0 00:00:41.076225 E1000#3: Interrupts skipped: 0 00:00:41.076231 E1000#3: Masked interrupts : 0 00:00:41.076238 E1000#3: Early interrupts : 0 00:00:41.076245 E1000#3: Late interrupts : 0 00:00:41.076252 E1000#3: Lost interrupts : 0 00:00:41.076259 E1000#3: Interrupts by RX : 0 00:00:41.076283 E1000#3: Interrupts by TX : 0 00:00:41.076290 E1000#3: Interrupts by ICS : 0 00:00:41.076297 E1000#3: Interrupts by RDTR: 0 00:00:41.076304 E1000#3: Interrupts by RDMT: 0 00:00:41.076311 E1000#3: Interrupts by TXQE: 0 00:00:41.076318 E1000#3: TX int delay asked: 0 00:00:41.076325 E1000#3: TX delayed: 0 00:00:41.076332 E1000#3: TX delay expired: 0 00:00:41.076339 E1000#3: TX no report asked: 0 00:00:41.076346 E1000#3: TX abs timer expd : 0 00:00:41.076352 E1000#3: TX int timer expd : 0 00:00:41.076359 E1000#3: RX abs timer expd : 0 00:00:41.076366 E1000#3: RX int timer expd : 0 00:00:41.076373 E1000#3: TX CTX descriptors: 0 00:00:41.076380 E1000#3: TX DAT descriptors: 0 00:00:41.076387 E1000#3: TX LEG descriptors: 0 00:00:41.076394 E1000#3: Received frames : 0 00:00:41.076401 E1000#3: Transmitted frames: 0 00:00:41.076408 E1000#3: TX frames up to 1514: 0 00:00:41.076414 E1000#3: TX frames up to 2962: 0 00:00:41.076421 E1000#3: TX frames up to 4410: 0 00:00:41.076428 E1000#3: TX frames up to 5858: 0 00:00:41.076435 E1000#3: TX frames up to 7306: 0 00:00:41.076442 E1000#3: TX frames up to 8754: 0 00:00:41.076449 E1000#3: TX frames up to 16384: 0 00:00:41.076456 E1000#3: TX frames up to 32768: 0 00:00:41.076462 E1000#3: Larger TX frames : 0 00:00:41.076469 E1000#3: Max TX Delay : 0 00:00:41.077169 Changing the VM state from 'LOADING' to 'SUSPENDED' 00:00:41.077264 Changing the VM state from 'SUSPENDED' to 'RESUMING' 00:00:41.077634 NAT: Link down 00:00:41.077688 Changing the VM state from 'RESUMING' to 'RUNNING' 00:00:41.077711 Console: Machine state changed to 'Running' 00:00:41.413451 ERROR [COM]: aRC=VBOX_E_VM_ERROR (0x80bb0003) aIID={4680b2de-8690-11e9-b83d-5719e53cf1de} aComponent={DisplayWrap} aText={Could not take a screenshot (VERR_NOT_SUPPORTED)}, preserve=false aResultDetail=-37 00:00:41.468266 VMMDev: Guest Log: 05:23:50.740142 control Session 0 is about to close ... 00:00:41.496511 VMMDev: Guest Log: 05:23:50.765559 control Stopping all guest processes ... 00:00:41.520448 VMMDev: Guest Log: 05:23:50.785749 control Closing all guest files ... 00:00:41.538332 VMMDev: Guest Log: 05:23:50.809370 control vbglR3GuestCtrlDetectPeekGetCancelSupport: Supported (#1) 00:00:41.553963 Guest Control: GUEST_MSG_REPORT_FEATURES: 0x7, 0x8000000000000000 00:00:41.562333 VMMDev: Guest Log: 19:58:06.188434 timesync vgsvcTimeSyncWorker: Radical host time change: 52 465 401 000 000ns (HostNow=1 626 897 486 173 000 000 ns HostLast=1 626 845 020 772 000 000 ns) 00:00:41.603543 VD#0: DEK is missing 00:00:41.605905 VM: Raising runtime error 'DrvVD_DEKMISSING' (fFlags=0x6) 00:00:41.606263 Changing the VM state from 'RUNNING' to 'SUSPENDING' 00:00:41.682916 PDMR3Suspend: 76 471 817 ns run time 00:00:41.682942 Changing the VM state from 'SUSPENDING' to 'SUSPENDED' 00:00:41.682963 Console: Machine state changed to 'Paused' 00:00:41.683147 Console: VM runtime error: fatal=false, errorID=DrvVD_DEKMISSING message="VD: The DEK for this disk is missing" 00:00:41.901987 GUI: UIMachineViewNormal::adjustGuestScreenSize: Adjust guest-screen size if necessary 00:00:41.906646 ERROR [COM]: aRC=VBOX_E_VM_ERROR (0x80bb0003) aIID={4680b2de-8690-11e9-b83d-5719e53cf1de} aComponent={DisplayWrap} aText={Could not take a screenshot (VERR_NOT_SUPPORTED)}, preserve=false aResultDetail=-37 00:00:41.914961 ERROR [COM]: aRC=VBOX_E_VM_ERROR (0x80bb0003) aIID={4680b2de-8690-11e9-b83d-5719e53cf1de} aComponent={DisplayWrap} aText={Could not take a screenshot (VERR_NOT_SUPPORTED)}, preserve=false aResultDetail=-37 00:00:41.924632 GUI: UIMediumEnumerator: Medium-enumeration finished! 00:00:41.932803 ERROR [COM]: aRC=VBOX_E_VM_ERROR (0x80bb0003) aIID={4680b2de-8690-11e9-b83d-5719e53cf1de} aComponent={DisplayWrap} aText={Could not take a screenshot (VERR_NOT_SUPPORTED)}, preserve=false aResultDetail=-37 00:00:55.433857 Changing the VM state from 'SUSPENDED' to 'RESUMING' 00:00:55.437234 Changing the VM state from 'RESUMING' to 'RUNNING' 00:00:55.437266 Console: Machine state changed to 'Running' 00:00:55.437702 VUSB: Attached 'HidMouse' to port 1 on RootHub#1 (FullSpeed) 00:00:55.442756 GUI: 2D video acceleration is disabled 00:00:55.442897 GUI: HID LEDs sync is enabled 00:00:55.448377 GUI: UIMachineLogicNormal::sltCheckForRequestedVisualStateType: Requested-state=0, Machine-state=5 00:00:55.454667 ERROR [COM]: aRC=VBOX_E_VM_ERROR (0x80bb0003) aIID={4680b2de-8690-11e9-b83d-5719e53cf1de} aComponent={DisplayWrap} aText={Could not take a screenshot (VERR_NOT_SUPPORTED)}, preserve=false aResultDetail=-37 00:00:55.461637 ERROR [COM]: aRC=VBOX_E_VM_ERROR (0x80bb0003) aIID={4680b2de-8690-11e9-b83d-5719e53cf1de} aComponent={DisplayWrap} aText={Could not take a screenshot (VERR_NOT_SUPPORTED)}, preserve=false aResultDetail=-37 00:00:56.250340 Display::i_handleDisplayResize: uScreenId=0 pvVRAM=000000000f820000 w=1920 h=1027 bpp=32 cbLine=0x1E00 flags=0x1 origin=0,0 00:00:58.156594 NAT: Link up 00:00:58.165917 NAT: DNS#0: 192.168.1.1 00:01:00.002777 NAT: IPv6 not supported 00:01:00.012749 NAT: DHCP offered IP address 10.0.4.15 00:01:05.442153 VMMDev: Guest Log: 19:58:16.316090 timesync vgsvcTimeSyncWorker: Radical guest time change: 52 465 497 062 000ns (GuestNow=1 626 897 496 316 076 000 ns GuestLast=1 626 845 030 819 014 000 ns fSetTimeLastLoop=true )

[gkcgareth]

See https://www.virtualbox.org/ticket/20537