Scheduled backup causes host shutdown

May 15, 2024
4
0
1
It appears that the start of a scheduled daily backup will trigger the host to shutdown. After it does shutdown, I find the console to show the usual login prompt, but all the VMs are shutdown, and the webUI is shutdown. To get things going again, I have to physically power cycle the host. This used to happen very infrequently but is now happening almost daily. Is there anything in the system log that points to the problem, or what to look for next?

Code:
Jan 18 00:00:00 verona pmxcfs[1832]: [status] notice: received log
Jan 18 00:00:05 verona pvescheduler[2853871]: <root@pam> starting task UPID:verona:002B8BF0:01FEE0A2:696C84F5:vzdump::root@pam:
Jan 18 00:00:05 verona systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service...
Jan 18 00:00:05 verona systemd[1]: Starting logrotate.service - Rotate log files...
Jan 18 00:00:05 verona systemd[1]: dpkg-db-backup.service: Deactivated successfully.
Jan 18 00:00:05 verona systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service.
Jan 18 00:00:05 verona systemd[1]: Reloading pveproxy.service - PVE API Proxy Server...
Jan 18 00:00:05 verona pvescheduler[2853872]: INFO: starting new backup job: vzdump 109 112 200 114 204 210 208 106 209 115 116 110 --mode snapshot --storage proxmox-backup --quiet 1 --prune-backups 'keep-last=1' --notes-template '{{guestname}}' --fleecing 0 --compress zstd
Jan 18 00:00:05 verona pvescheduler[2853872]: INFO: Starting Backup of VM 200 (qemu)
Jan 18 00:00:06 verona pveproxy[2853891]: send HUP to 2007
Jan 18 00:00:06 verona pveproxy[2007]: received signal HUP
Jan 18 00:00:06 verona pveproxy[2007]: server closing
Jan 18 00:00:06 verona pveproxy[2007]: server shutdown (restart)
Jan 18 00:00:06 verona systemd[1]: Reloaded pveproxy.service - PVE API Proxy Server.
Jan 18 00:00:06 verona systemd[1]: Reloading spiceproxy.service - PVE SPICE Proxy Server...
Jan 18 00:00:07 verona spiceproxy[2853936]: send HUP to 2013
Jan 18 00:00:07 verona spiceproxy[2013]: received signal HUP
Jan 18 00:00:07 verona spiceproxy[2013]: server closing
Jan 18 00:00:07 verona spiceproxy[2013]: server shutdown (restart)
Jan 18 00:00:07 verona systemd[1]: Reloaded spiceproxy.service - PVE SPICE Proxy Server.
Jan 18 00:00:07 verona pvefw-logger[2118515]: received terminate request (signal)
Jan 18 00:00:07 verona pvefw-logger[2118515]: stopping pvefw logger
Jan 18 00:00:07 verona systemd[1]: Stopping pvefw-logger.service - Proxmox VE firewall logger...
Jan 18 00:00:07 verona systemd[1]: pvefw-logger.service: Deactivated successfully.
Jan 18 00:00:07 verona systemd[1]: Stopped pvefw-logger.service - Proxmox VE firewall logger.
Jan 18 00:00:07 verona systemd[1]: pvefw-logger.service: Consumed 6.197s CPU time.
Jan 18 00:00:07 verona systemd[1]: Starting pvefw-logger.service - Proxmox VE firewall logger...
Jan 18 00:00:07 verona pvefw-logger[2853947]: starting pvefw logger
Jan 18 00:00:07 verona systemd[1]: Started pvefw-logger.service - Proxmox VE firewall logger.
Jan 18 00:00:07 verona systemd[1]: logrotate.service: Deactivated successfully.
Jan 18 00:00:07 verona systemd[1]: Finished logrotate.service - Rotate log files.
Jan 18 00:00:07 verona spiceproxy[2013]: restarting server
Jan 18 00:00:07 verona spiceproxy[2013]: starting 1 worker(s)
Jan 18 00:00:07 verona spiceproxy[2013]: worker 2853953 started
Jan 18 00:00:07 verona pveproxy[2007]: restarting server
Jan 18 00:00:07 verona pveproxy[2007]: starting 3 worker(s)
Jan 18 00:00:07 verona pveproxy[2007]: worker 2853954 started
Jan 18 00:00:07 verona pveproxy[2007]: worker 2853955 started
Jan 18 00:00:07 verona pveproxy[2007]: worker 2853956 started
Jan 18 00:00:07 verona pmxcfs[1832]: [dcdb] notice: data verification successful
Jan 18 00:00:11 verona sshd[2854005]: Connection closed by 10.0.0.35 port 16141 [preauth]
Jan 18 00:00:12 verona spiceproxy[2118520]: worker exit
Jan 18 00:00:12 verona spiceproxy[2013]: worker 2118520 finished
Jan 18 00:00:12 verona pveproxy[2118522]: worker exit
Jan 18 00:00:12 verona pveproxy[2118521]: worker exit
Jan 18 00:00:12 verona pveproxy[2118523]: worker exit
Jan 18 00:00:12 verona pveproxy[2007]: worker 2118522 finished
Jan 18 00:00:12 verona pveproxy[2007]: worker 2118523 finished
Jan 18 00:00:12 verona pveproxy[2007]: worker 2118521 finished
Jan 18 00:00:19 verona pvescheduler[2853872]: INFO: Finished Backup of VM 200 (00:00:14)
Jan 18 00:00:19 verona pvescheduler[2853872]: INFO: Starting Backup of VM 204 (qemu)
Jan 18 00:00:20 verona systemd[1]: Started 204.scope.
Jan 18 00:00:20 verona kernel: tap204i0: entered promiscuous mode
Jan 18 00:00:21 verona kernel: vmbr0: port 9(fwpr204p0) entered blocking state
Jan 18 00:00:21 verona kernel: vmbr0: port 9(fwpr204p0) entered disabled state
Jan 18 00:00:21 verona kernel: fwpr204p0: entered allmulticast mode
Jan 18 00:00:21 verona kernel: fwpr204p0: entered promiscuous mode
Jan 18 00:00:21 verona kernel: vmbr0: port 9(fwpr204p0) entered blocking state
Jan 18 00:00:21 verona kernel: vmbr0: port 9(fwpr204p0) entered forwarding state
Jan 18 00:00:21 verona kernel: fwbr204i0: port 1(fwln204i0) entered blocking state
Jan 18 00:00:21 verona kernel: fwbr204i0: port 1(fwln204i0) entered disabled state
Jan 18 00:00:21 verona kernel: fwln204i0: entered allmulticast mode
Jan 18 00:00:21 verona kernel: fwln204i0: entered promiscuous mode
Jan 18 00:00:21 verona kernel: fwbr204i0: port 1(fwln204i0) entered blocking state
Jan 18 00:00:21 verona kernel: fwbr204i0: port 1(fwln204i0) entered forwarding state
Jan 18 00:00:21 verona kernel: fwbr204i0: port 2(tap204i0) entered blocking state
Jan 18 00:00:21 verona kernel: fwbr204i0: port 2(tap204i0) entered disabled state
Jan 18 00:00:21 verona kernel: tap204i0: entered allmulticast mode
Jan 18 00:00:21 verona kernel: fwbr204i0: port 2(tap204i0) entered blocking state
Jan 18 00:00:21 verona kernel: fwbr204i0: port 2(tap204i0) entered forwarding state
Jan 18 00:00:21 verona pvescheduler[2853872]: VM 204 started with PID 2854227.
Jan 18 00:00:43 verona sshd[2854610]: Connection closed by 10.0.0.35 port 31386 [preauth]
Jan 18 00:01:15 verona sshd[2854883]: Connection closed by 10.0.0.35 port 12903 [preauth]
-- Reboot --