[SOLVED] Zeit um die VMs herunterzufahren wird nicht eingehalten, oder einfach mal vorher ins Log schauen

J

JensF

Renowned Member
Feb 14, 2020
360
131
83
Wir hatten heute Nacht einen Stromausfall und die USV ist ordnungsgemäß eingesprungen.
Wie geplant fuhr die USV unseren Backup Server zeitnah herunter und lief noch 50 Minuten weiter.
Nach erreichen des Batterielimits sende die USV auch an den PVE den Shutdown Befehl und der PVE began daraufhin die VMs wie gewünscht herunterzufahren.
In den Configs der VMs hab ich Shutdown Zeiten von 180 und 600 Sekunden hinterlegt, welche aber dieses mal einfach ignoriert worden sind.
Wie man im Log sehen kann, hatten die VMs keine Minute Zeit, bevor diese hart ausgeschaltet worden sind.
Code: 
Jun 01 05:27:48 SERVER1 postfix/pickup[4161352]: B47D6603C5: uid=0 from=<USV@root.local>
Jun 01 05:27:48 SERVER1 postfix/cleanup[4186422]: B47D6603C5: message-id=<20250601032748.B47D6603C5@SERVER1.DOMAIN.local>
Jun 01 05:27:48 SERVER1 postfix/qmgr[1921]: B47D6603C5: from=<USV@root.local>, size=843, nrcpt=1 (queue active)
Jun 01 05:27:48 SERVER1 systemd-logind[1513]: The system will power off now!
Jun 01 05:27:48 SERVER1 systemd-logind[1513]: System is powering down.
Jun 01 05:27:48 SERVER1 systemd[1]: 100.scope: Deactivated successfully.
Jun 01 05:27:48 SERVER1 systemd[1]: Stopped 100.scope.
Jun 01 05:27:48 SERVER1 systemd[1]: 100.scope: Consumed 5d 14h 45min 40.953s CPU time.
Jun 01 05:27:48 SERVER1 systemd[1]: 101.scope: Deactivated successfully.
Jun 01 05:27:48 SERVER1 systemd[1]: Stopped 101.scope.
Jun 01 05:27:48 SERVER1 systemd[1]: 101.scope: Consumed 1w 3d 27min 50.083s CPU time.
Jun 01 05:27:48 SERVER1 systemd[1]: 103.scope: Deactivated successfully.
Jun 01 05:27:48 SERVER1 systemd[1]: Stopped 103.scope.
Jun 01 05:27:48 SERVER1 systemd[1]: 103.scope: Consumed 3d 6h 52min 9.835s CPU time.
Jun 01 05:27:48 SERVER1 systemd[1]: 105.scope: Deactivated successfully.
Jun 01 05:27:48 SERVER1 systemd[1]: Stopped 105.scope.
Jun 01 05:27:48 SERVER1 systemd[1]: 105.scope: Consumed 2w 2d 13h 50min 44.352s CPU time.
Jun 01 05:27:48 SERVER1 systemd[1]: Stopping session-1.scope - Session 1 of User root...
Jun 01 05:27:48 SERVER1 systemd[1]: Removed slice qemu.slice - Slice /qemu.
Jun 01 05:27:48 SERVER1 systemd[1]: qemu.slice: Consumed 1month 5d 2h 28min 35.783s CPU time.
Jun 01 05:27:48 SERVER1 systemd[1]: Removed slice system-modprobe.slice - Slice /system/modprobe.
Jun 01 05:27:48 SERVER1 systemd[1]: Stopped target graphical.target - Graphical Interface.
Jun 01 05:27:48 SERVER1 systemd[1]: Stopped target multi-user.target - Multi-User System.
Jun 01 05:27:48 SERVER1 systemd[1]: Stopped target getty.target - Login Prompts.
Jun 01 05:27:48 SERVER1 systemd[1]: Stopped target rpc_pipefs.target.
Jun 01 05:27:48 SERVER1 systemd[1]: Stopped target rpcbind.target - RPC Port Mapper.
Jun 01 05:27:48 SERVER1 systemd[1]: Stopped target timers.target - Timer Units.
Jun 01 05:27:50 SERVER1 pvescheduler[1985]: received signal TERM
Jun 01 05:27:50 SERVER1 pvescheduler[1985]: got shutdown request, signal running jobs to stop
Jun 01 05:27:50 SERVER1 pvescheduler[1985]: server stopped
Jun 01 05:27:51 SERVER1 systemd[1]: pvescheduler.service: Deactivated successfully.
Jun 01 05:27:51 SERVER1 systemd[1]: Stopped pvescheduler.service - Proxmox VE scheduler.
Jun 01 05:27:51 SERVER1 systemd[1]: pvescheduler.service: Consumed 57min 35.318s CPU time.
Jun 01 05:27:51 SERVER1 systemd[1]: Stopping pve-guests.service - PVE guests...
Jun 01 05:27:53 SERVER1 pve-guests[4186507]: <root@pam> starting task UPID:SERVER1:003FE1A6:1B05DDE0:683BC8B9:stopall::root@pam:
Jun 01 05:27:53 SERVER1 pvesh[4186507]: Stopping CT 110 (timeout = 180 seconds)
Jun 01 05:27:53 SERVER1 pve-guests[4186534]: <root@pam> starting task UPID:SERVER1:003FE1A7:1B05DDE4:683BC8B9:vzshutdown:110:root@pam:
Jun 01 05:27:53 SERVER1 pve-guests[4186535]: shutdown CT 110: UPID:SERVER1:003FE1A7:1B05DDE4:683BC8B9:vzshutdown:110:root@pam:
Jun 01 05:27:53 SERVER1 pvesh[4186507]: Stopping VM 103 (timeout = 180 seconds)
Jun 01 05:27:53 SERVER1 pve-guests[4186534]: <root@pam> starting task UPID:SERVER1:003FE1A9:1B05DDE6:683BC8B9:qmshutdown:103:root@pam:
Jun 01 05:27:53 SERVER1 pve-guests[4186537]: shutdown VM 103: UPID:SERVER1:003FE1A9:1B05DDE6:683BC8B9:qmshutdown:103:root@pam:
Jun 01 05:28:02 SERVER1 pve-guests[4186534]: end task UPID:SERVER1:003FE1A9:1B05DDE6:683BC8B9:qmshutdown:103:root@pam:
Jun 01 05:28:03 SERVER1 qmeventd[4186871]: Starting cleanup for 103
Jun 01 05:28:03 SERVER1 qmeventd[4186871]: Finished cleanup for 103
Jun 01 05:28:03 SERVER1 pvesh[4186507]: Stopping CT 108 (timeout = 180 seconds)
Jun 01 05:28:03 SERVER1 pve-guests[4186534]: <root@pam> starting task UPID:SERVER1:003FE309:1B05E1D0:683BC8C3:vzshutdown:108:root@pam:
Jun 01 05:28:03 SERVER1 pve-guests[4186889]: shutdown CT 108: UPID:SERVER1:003FE309:1B05E1D0:683BC8C3:vzshutdown:108:root@pam:
Jun 01 05:28:03 SERVER1 pvesh[4186507]: Stopping VM 101 (timeout = 180 seconds)
Jun 01 05:28:03 SERVER1 pve-guests[4186534]: <root@pam> starting task UPID:SERVER1:003FE30B:1B05E1D1:683BC8C3:qmshutdown:101:root@pam:
Jun 01 05:28:03 SERVER1 pve-guests[4186891]: shutdown VM 101: UPID:SERVER1:003FE30B:1B05E1D1:683BC8C3:qmshutdown:101:root@pam:
Jun 01 05:28:50 SERVER1 pve-guests[4186534]: end task UPID:SERVER1:003FE30B:1B05E1D1:683BC8C3:qmshutdown:101:root@pam:
Jun 01 05:28:51 SERVER1 qmeventd[4187984]: Starting cleanup for 101
Jun 01 05:28:51 SERVER1 qmeventd[4187984]: Finished cleanup for 101
Jun 01 05:28:51 SERVER1 pvesh[4186507]: Stopping VM 100 (timeout = 180 seconds)
Jun 01 05:28:51 SERVER1 pve-guests[4186534]: <root@pam> starting task UPID:SERVER1:003FE752:1B05F495:683BC8F3:qmshutdown:100:root@pam:
Jun 01 05:28:51 SERVER1 pve-guests[4187986]: shutdown VM 100: UPID:SERVER1:003FE752:1B05F495:683BC8F3:qmshutdown:100:root@pam:
Jun 01 05:29:02 SERVER1 pve-guests[4186534]: end task UPID:SERVER1:003FE752:1B05F495:683BC8F3:qmshutdown:100:root@pam:
Jun 01 05:29:03 SERVER1 qmeventd[4188232]: Starting cleanup for 100
Jun 01 05:29:03 SERVER1 qmeventd[4188232]: Finished cleanup for 100
Jun 01 05:29:03 SERVER1 pvesh[4186507]: Stopping VM 105 (timeout = 600 seconds)
Jun 01 05:29:03 SERVER1 pve-guests[4186534]: <root@pam> starting task UPID:SERVER1:003FE849:1B05F947:683BC8FF:qmshutdown:105:root@pam:
Jun 01 05:29:03 SERVER1 pve-guests[4188233]: shutdown VM 105: UPID:SERVER1:003FE849:1B05F947:683BC8FF:qmshutdown:105:root@pam:
Irgendeine Idee, warum es dieses mal nicht so funktioniert hat wie erwartet?
Zur Vollständigkeit halber noch die Versionsstände.
Code: 
proxmox-ve: 8.4.0 (running kernel: 6.11.11-2-pve)
pve-manager: 8.4.1 (running version: 8.4.1/2a5fa54a8503f96d)
proxmox-kernel-helper: 8.1.1
proxmox-kernel-6.11.11-2-pve-signed: 6.11.11-2
proxmox-kernel-6.11: 6.11.11-2
proxmox-kernel-6.11.11-1-pve-signed: 6.11.11-1
proxmox-kernel-6.8.12-10-pve-signed: 6.8.12-10
proxmox-kernel-6.8: 6.8.12-10
ceph-fuse: 17.2.6-pve1+3
corosync: 3.1.9-pve1
criu: 3.17.1-2+deb12u1
glusterfs-client: 10.3-5
ifupdown2: 3.2.0-1+pmx11
intel-microcode: 3.20250211.1~deb12u1
ksm-control-daemon: 1.5-1
libjs-extjs: 7.0.0-5
libknet1: 1.30-pve2
libproxmox-acme-perl: 1.6.0
libproxmox-backup-qemu0: 1.5.1
libproxmox-rs-perl: 0.3.5
libpve-access-control: 8.2.2
libpve-apiclient-perl: 3.3.2
libpve-cluster-api-perl: 8.1.0
libpve-cluster-perl: 8.1.0
libpve-common-perl: 8.3.1
libpve-guest-common-perl: 5.2.2
libpve-http-server-perl: 5.2.2
libpve-network-perl: 0.11.2
libpve-rs-perl: 0.9.4
libpve-storage-perl: 8.3.6
libspice-server1: 0.15.1-1
lvm2: 2.03.16-2
lxc-pve: 6.0.0-1
lxcfs: 6.0.0-pve2
novnc-pve: 1.6.0-2
proxmox-backup-client: 3.4.1-1
proxmox-backup-file-restore: 3.4.1-1
proxmox-firewall: 0.7.1
proxmox-kernel-helper: 8.1.1
proxmox-mail-forward: 0.3.2
proxmox-mini-journalreader: 1.4.0
proxmox-widget-toolkit: 4.3.10
pve-cluster: 8.1.0
pve-container: 5.2.6
pve-docs: 8.4.0
pve-edk2-firmware: 4.2025.02-3
pve-esxi-import-tools: 0.7.3
pve-firewall: 5.1.1
pve-firmware: 3.15-3
pve-ha-manager: 4.0.7
pve-i18n: 3.4.2
pve-qemu-kvm: 9.2.0-5
pve-xtermjs: 5.5.0-2
qemu-server: 8.3.12
smartmontools: 7.3-pve1
spiceterm: 3.3.0
swtpm: 0.8.0+pve1
vncterm: 1.8.0
zfsutils-linux: 2.2.7-pve2
 
Okay, selbst noch ein wenig nachgeforscht.
Der Befehl den die USV beim Shutdown ausführt ist systemctl poweroff
Der hat doch den gleichen Effekt wie shutdown now und fährt alle VMs runter, oder?
Edit: Nope! Gerade nochmal nachgeschaut. shutdown -h now ist der Command, welcher ausgeführt wird. Und das sollte ja passen.
 
Last edited:
vIelleicht war der Akku leer und es kam der hardoff. Ich würde keine 50 minuten warten, wenn die gut ist vielleicht 10, dann hast reserve.
Poweroff fährt linux runter und alle dienste inkl. vm.
 
Der Shutdown Befehl der USV kommt, wenn die Batterien noch 35% Kapazität haben.
Der PVE fuhr ja dann auch sauber herunter, nachdem er die VMs abgeschossen hat.
Also ein Hard Off war es nicht.
Edit: Und ja, die Guest Agents waren und sind aktiviert und auch funktionstüchtig.
 
Last edited:
Okay, ich revidiere alles! Im Journal sieht es tatsächlich so aus, als ob die USV 8 Minuten nach dem Shutdown Befehl die Stromzufuhr abgestellt hat!
Hab es jetzt so eingestellt, dass die USV nach 20 Minuten Stromausfall alles herunter fährt.
Besser für die Batterien der USV und für meine Nerven. :rolleyes:
 
  • Like
Reactions: Johannes S and ThoSo
You must log in or register to reply here.
Top Bottom