Intermittant vm shutdown during successful backup #42
Description
I'm running Hyper-V-BU on a series of hyper-v hosts, and only one is having issues, although it's the largest/busiest one.
Intermittantly (and I can't spot a pattern) when I come in, in the morning, the VMs are off. I'm pretty confident it's the hyper-v backup script, as I disabled it for a month and had no issues, then it popped up again within a week after enabling it again.
I don't have a whole lot of actionable info here - a sample from the largest VM on the problem host
»»»» Get-EventLog -ComputerName fs01a System -Newest 10000 | `
>> Where EventId -in 41,1074,1076,6005,6006,6008,6009,6013 | `
>> Format-Table TimeGenerated,EventId,UserName,Message -AutoSize -wrap
TimeGenerated EventID UserName Message
------------- ------- -------- -------
2/13/2025 7:48:56 AM 41 NT AUTHORITY\SYSTEM The description for Event ID '41' in Source 'Microsoft-Windows-Kernel-Power'
cannot be found. The local computer may not have the necessary registry
information or message DLL files to display the message, or you may not have
permission to access them. The following information is part of the event:'0',
'0x0', '0x0', '0x0', '0x0', '0', '0', '0', '0', 'true', '0', '1', 'false', '0',
'1', 'false'
2/13/2025 7:49:00 AM 6013 The system uptime is 5 seconds.
2/13/2025 7:49:00 AM 6005 The Event log service was started.
2/13/2025 7:49:00 AM 6009 Microsoft (R) Windows (R) 10.00. 20348 Multiprocessor Free.
2/13/2025 7:49:00 AM 6008 The previous system shutdown at 1:13:54 AM on 2/13/2025 was unexpected.
And the hyper-v-bu log. Note the shutdown time is during the time it's being backed up.
--- Running with the following config ---
Utility Version: 24.08.29
Hostname: VS01A.
Windows Version: 10.0.20348.
No. of VMs: 6.
VMs to backup:
cs01A
dc01B
fs01A
it01A
mailfilter
management01A
Backup directory: \\floppy01A\VM_Backups.
Working directory: E:\Hyper-V_BU\Temp-WorkDir.
Backups to keep: 2 days
Logs directory: E:\Hyper-V_BU\Logs.
E-mail log to: [email protected].
E-mail log from: [email protected].
SMTP server: Configured
SMTP auth: Configured
---
2025-02-13 00:11:06 [INFO] Process started
2025-02-13 00:11:06 [INFO] (VM:cs01A) has [2] CPU cores, [1GB] RAM, Storage: [Current Size = 8GB - Max Size = 127GB]
2025-02-13 00:11:07 [INFO] (VM:dc01B) has [2] CPU cores, [4GB] RAM, Storage: [Current Size = 41GB - Max Size = 127GB]
2025-02-13 00:11:07 [INFO] (VM:fs01A) has [2] CPU cores, [8GB] RAM, Storage: [Current Size = 309 128GB - Max Size = 384 127GB]
2025-02-13 00:11:07 [INFO] (VM:it01A) has [1] CPU cores, [1GB] RAM, Storage: [Current Size = 13GB - Max Size = 40GB]
2025-02-13 00:11:08 [INFO] (VM:mailfilter) has [4] CPU cores, [4GB] RAM, Storage: [Current Size = 69GB - Max Size = 256GB]
2025-02-13 00:11:08 [INFO] (VM:management01A) has [2] CPU cores, [1GB] RAM, Storage: [Current Size = 42GB - Max Size = 127GB]
2025-02-13 00:11:08 [INFO] (VM:cs01A) Attempting to export VM
2025-02-13 00:11:32 [INFO] (VM:cs01A) Removing backup folders older than: 2 days
2025-02-13 00:13:55 [SUCCESS] (VM:cs01A) Export Successful
2025-02-13 00:13:55 [INFO] (VM:cs01A) Processed in 00:02:46
2025-02-13 00:13:55 [INFO] (VM:dc01B) Attempting to export VM
2025-02-13 00:15:23 [INFO] (VM:dc01B) Removing backup folders older than: 2 days
dc01B-2025-02-10_00-14-44 2/10/2025 12:14:44 AM
dc01B-2025-02-11_00-14-45 2/11/2025 12:14:48 AM
2025-02-13 00:26:02 [SUCCESS] (VM:dc01B) Export Successful
2025-02-13 00:26:02 [INFO] (VM:dc01B) Processed in 00:12:07
2025-02-13 00:26:02 [INFO] (VM:fs01A) Attempting to export VM
2025-02-13 00:40:23 [INFO] (VM:fs01A) Removing backup folders older than: 2 days
fs01A-2025-02-10_00-36-57 2/10/2025 12:36:57 AM
2025-02-13 01:49:36 [SUCCESS] (VM:fs01A) Export Successful
2025-02-13 01:49:36 [INFO] (VM:fs01A) Processed in 01:23:33
2025-02-13 01:49:36 [INFO] (VM:it01A) Attempting to export VM
2025-02-13 01:50:08 [INFO] (VM:it01A) Removing backup folders older than: 2 days
2025-02-13 01:52:11 [SUCCESS] (VM:it01A) Export Successful
2025-02-13 01:52:11 [INFO] (VM:it01A) Processed in 00:02:35
2025-02-13 01:52:11 [INFO] (VM:mailfilter) Attempting to export VM
2025-02-13 01:54:35 [INFO] (VM:mailfilter) Removing backup folders older than: 2 days
2025-02-13 02:06:42 [SUCCESS] (VM:mailfilter) Export Successful
2025-02-13 02:06:42 [INFO] (VM:mailfilter) Processed in 00:14:31
2025-02-13 02:06:43 [INFO] (VM:management01A) Attempting to export VM
2025-02-13 02:08:54 [INFO] (VM:management01A) Removing backup folders older than: 2 days
2025-02-13 02:15:40 [SUCCESS] (VM:management01A) Export Successful
2025-02-13 02:15:40 [INFO] (VM:management01A) Processed in 00:08:57
2025-02-13 02:15:40 [INFO] Process finished.
2025-02-13 02:15:40 [INFO] Number of VMs to Backup:6
2025-02-13 02:15:40 [INFO] Backups Successful:6
2025-02-13 02:15:40 [INFO] Backups Failed:0
I think I'll start backing up all except the large VM and see how that goes. Open to suggestions on how to track this down further welcome...