GNU bug report logs - #76368
Cannot reboot when virtlogd and containerd is running?

Previous Next

To add a comment to this bug, you must first unarchive it, by sending
a message to control AT debbugs.gnu.org, with unarchive 76368 in the body.
You can then email your comments to 76368 AT debbugs.gnu.org in the normal way.

Toggle the display of automated, internal messages from the tracker.

Message #5 received at submit <at> debbugs.gnu.org (full text, mbox):

From: Simen Endsjø <contact <at> simendsjo.me>
To: bug-guix <at> gnu.org
Subject: Cannot reboot when virtlogd and containerd is running?
Date: Mon, 17 Feb 2025 19:44:13 +0100

[Message part 1 (text/plain, inline)]

Rebooting just hangs forever. The user freakingpenguin on IRC has the
same issue.

Here is the tail of messages:

2025-02-17 19:32:03 localhost shepherd[1]: Respawning virtlogd.
2025-02-17 19:32:03 localhost shepherd[1]: Starting service virtlogd...
2025-02-17 19:32:03 localhost shepherd[1]: Service virtlogd started.
2025-02-17 19:32:03 localhost shepherd[1]: Service virtlogd running with value #<<process> id: 21448 command: ("/gnu/store/vazbiad5qrkf35g0kpv647s2s9ixdj3x-libvirt-8.6.0/sbin/virtlogd" "-f" "/gnu/store/643zy7mphh9pa8ln0g4abriwb1chzks7-virtlogd.conf")>.
2025-02-17 19:32:03 localhost shepherd[1]: Service virtlogd has been started.
2025-02-17 19:32:03 localhost shepherd[1]: Respawning containerd.
2025-02-17 19:32:03 localhost shepherd[1]: Starting service containerd...
2025-02-17 19:32:04 localhost shepherd[1]: Service containerd has been started.
2025-02-17 19:32:04 localhost shepherd[1]: Service containerd started.
2025-02-17 19:32:04 localhost shepherd[1]: Service containerd running with value #<<process> id: 21463 command: ("/gnu/store/2yfg543dfxni97fpg46fw4ram0f7dw44-containerd-1.6.22/bin/containerd")>.
2025-02-17 19:32:07 localhost shepherd[1]: waiting for process termination (processes left: (1 2325 21018 21448 21453 21463 26205))
2025-02-17 19:32:07 localhost shepherd[1]: Service virtlogd (PID 21448) terminated with signal 9.
2025-02-17 19:32:07 localhost shepherd[1]: Service containerd (PID 21463) terminated with signal 9.
2025-02-17 19:32:07 localhost shepherd[1]: Respawning virtlogd.
2025-02-17 19:32:07 localhost shepherd[1]: Respawning containerd.
2025-02-17 19:32:07 localhost shepherd[1]: Starting service virtlogd...
2025-02-17 19:32:07 localhost shepherd[1]: Starting service containerd...
2025-02-17 19:32:07 localhost shepherd[1]: Service virtlogd started.
2025-02-17 19:32:07 localhost shepherd[1]: Service virtlogd running with value #<<process> id: 21524 command: ("/gnu/store/vazbiad5qrkf35g0kpv647s2s9ixdj3x-libvirt-8.6.0/sbin/virtlogd" "-f" "/gnu/store/643zy7mphh9pa8ln0g4abriwb1chzks7-virtlogd.conf")>.
2025-02-17 19:32:07 localhost shepherd[1]: Service virtlogd has been started.
2025-02-17 19:32:08 localhost shepherd[1]: waiting for process termination (processes left: (1 21524 21525))
2025-02-17 19:32:08 localhost shepherd[1]: Service containerd has been started.
2025-02-17 19:32:08 localhost shepherd[1]: Service containerd started.
2025-02-17 19:32:08 localhost shepherd[1]: Service containerd running with value #<<process> id: 21525 command: ("/gnu/store/2yfg543dfxni97fpg46fw4ram0f7dw44-containerd-1.6.22/bin/containerd")>.
2025-02-17 19:32:09 localhost shepherd[1]: waiting for process termination (processes left: (1 21524 21525))
2025-02-17 19:32:10 localhost shepherd[1]: waiting for process termination (processes left: (1 21524 21525))
2025-02-17 19:32:11 localhost shepherd[1]: waiting for process termination (processes left: (1 21524 21525))


And here is the full reboot sequence:

2025-02-17 19:32:01 localhost elogind[1114]: System is rebooting.
2025-02-17 19:32:01 localhost shepherd[1]: Stopping service root...
2025-02-17 19:32:01 localhost shepherd[1]: Exiting shepherd...
2025-02-17 19:32:01 localhost shepherd[1]: Stopping service tlp...
2025-02-17 19:32:01 localhost shepherd[1]: Registering new logger for tlp.
2025-02-17 19:32:01 localhost shepherd[1]: Service tlp might have failed to stop.
2025-02-17 19:32:01 localhost shepherd[1]: Service tlp is now stopped.
2025-02-17 19:32:01 localhost shepherd[1]: Stopping service xorg-server...
2025-02-17 19:32:01 localhost linux: [81763.251947] traps: xss-lock[26216] trap int3 ip:7f37e4301519 sp:7fff642df8e0 error:0 in libglib-2.0.so.0.7800.0[65519,7f37e42be000+94000]
2025-02-17 19:32:01 localhost elogind[1114]: Removed session c11.
2025-02-17 19:32:01 localhost NetworkManager[25418]: <info>  [1739817121.2461] device (wlp2s0): state change: activated -> deactivating (reason 'connection-removed', sys-iface-state: 'managed')
2025-02-17 19:32:01 localhost NetworkManager[25418]: <info>  [1739817121.2466] manager: NetworkManager state is now CONNECTED_LOCAL
2025-02-17 19:32:01 localhost linux: [81763.255979] .volctl-real[26217]: segfault at 1c ip 00007f955bd054d0 sp 00007ffe0a3d4608 error 4 in libcairo.so.2.11800.0[794d0,7f955bca1000+dd000] likely on CPU 0 (core 0, socket 0)
2025-02-17 19:32:01 localhost linux: [81763.255991] Code: e8 a5 c0 f9 ff 48 8d 0d fe b1 08 00 ba 22 0c 00 00 48 8d 35 8a ad 07 00 48 8d 3d eb 91 07 00 e8 86 c0 f9 ff 66 0f 1f 44 00 00 <44> 8b 47 1c 45 85 c0 75 57 41 55 41 54 55 f6 47 30 02 75 5c 89 d0
2025-02-17 19:32:01 localhost dbus-daemon[914]: [system] Activating service name='org.freedesktop.nm_dispatcher' requested by ':1.880' (uid=0 pid=25418 comm="/gnu/store/zav4w5jrcn4yhcawg9l0smv7g2hmyais-networ") (using servicehelper)
2025-02-17 19:32:01 localhost linux: [81763.256098] .nm-applet-real[26234]: segfault at 18 ip 00007f9a00aa2050 sp 00007ffcaa9d2138 error 4 in libgtk-3.so.0.2409.32[2a2050,7f9a00893000+369000] likely on CPU 12 (core 6, socket 0)
2025-02-17 19:32:01 localhost dbus-daemon[914]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
2025-02-17 19:32:01 localhost libvirtd: 1115: error : virNetSocketReadWire:1791 : End of file while reading data: Input/output error
2025-02-17 19:32:01 localhost bluetoothd[1117]: Endpoint unregistered: sender=:1.959 path=/MediaEndpoint/A2DPSink/sbc
2025-02-17 19:32:01 localhost linux: [81763.256107] Code: ea ff e9 76 ff ff ff 0f 1f 00 48 8b 47 18 48 8b 40 10 c3 0f 1f 80 00 00 00 00 48 8b 47 18 48 8b 78 20 e9 b3 b8 e9 ff 0f 1f 00 <48> 8b 47 18 48 8b 40 20 c3 0f 1f 80 00 00 00 00 48 83 ec 08 e8 07
2025-02-17 19:32:01 localhost bluetoothd[1117]: Endpoint unregistered: sender=:1.959 path=/MediaEndpoint/A2DPSource/sbc
2025-02-17 19:32:01 localhost bluetoothd[1117]: Endpoint unregistered: sender=:1.959 path=/MediaEndpoint/A2DPSink/sbc_xq_453
2025-02-17 19:32:01 localhost bluetoothd[1117]: Endpoint unregistered: sender=:1.959 path=/MediaEndpoint/A2DPSource/sbc_xq_453
2025-02-17 19:32:01 localhost bluetoothd[1117]: Endpoint unregistered: sender=:1.959 path=/MediaEndpoint/A2DPSink/sbc_xq_512
2025-02-17 19:32:01 localhost bluetoothd[1117]: Endpoint unregistered: sender=:1.959 path=/MediaEndpoint/A2DPSource/sbc_xq_512
2025-02-17 19:32:01 localhost linux: [81763.266727] wlp2s0: deauthenticating from 70:3a:cb:ba:4b:96 by local choice (Reason: 3=DEAUTH_LEAVING)
2025-02-17 19:32:01 localhost linux: [81763.363730] amdgpu 0000:64:00.0: [drm] REG_WAIT timeout 1us * 100 tries - dcn31_program_compbuf_size line:141
2025-02-17 19:32:01 localhost bluetoothd[1117]: Endpoint unregistered: sender=:1.959 path=/MediaEndpoint/A2DPSink/sbc_xq_552
2025-02-17 19:32:01 localhost bluetoothd[1117]: Endpoint unregistered: sender=:1.959 path=/MediaEndpoint/A2DPSource/sbc_xq_552
2025-02-17 19:32:01 localhost shepherd[1]: Service xorg-server stopped.
2025-02-17 19:32:01 localhost shepherd[1]: Service xorg-server is now stopped.
2025-02-17 19:32:01 localhost shepherd[1]: Stopping service dockerd...
2025-02-17 19:32:01 localhost shepherd[1]: Service dockerd stopped.
2025-02-17 19:32:01 localhost shepherd[1]: Service dockerd is now stopped.
2025-02-17 19:32:01 localhost shepherd[1]: Stopping service avahi-daemon...
2025-02-17 19:32:01 localhost shepherd[1]: Service avahi-daemon stopped.
2025-02-17 19:32:01 localhost shepherd[1]: Service avahi-daemon is now stopped.
2025-02-17 19:32:01 localhost shepherd[1]: Stopping service ntpd...
2025-02-17 19:32:01 localhost ntpd[25611]: ntpd exiting on signal 15 (Terminated)
2025-02-17 19:32:01 localhost shepherd[1]: Service ntpd stopped.
2025-02-17 19:32:01 localhost shepherd[1]: Service ntpd is now stopped.
2025-02-17 19:32:01 localhost shepherd[1]: Stopping service NetworkManager...
2025-02-17 19:32:01 localhost NetworkManager[25418]: <info>  [1739817121.4193] caught SIGTERM, shutting down normally.
2025-02-17 19:32:01 localhost wpa_supplicant[1178]: wlp2s0: CTRL-EVENT-DISCONNECTED bssid=70:3a:cb:ba:4b:96 reason=3 locally_generated=1
2025-02-17 19:32:01 localhost NetworkManager[25418]: <info>  [1739817121.5770] manager: startup complete
2025-02-17 19:32:01 localhost linux: [81763.584623] r8169 0000:01:00.0 enp1s0f0: Link is Down
2025-02-17 19:32:01 localhost NetworkManager[25418]: <info>  [1739817121.5782] device (wlp2s0): state change: deactivating -> unmanaged (reason 'unmanaged', sys-iface-state: 'managed')
2025-02-17 19:32:01 localhost NetworkManager[25418]: <info>  [1739817121.5782] dhcp4 (wlp2s0): canceled DHCP transaction
2025-02-17 19:32:01 localhost NetworkManager[25418]: <info>  [1739817121.5782] dhcp4 (wlp2s0): activation: beginning transaction (timeout in 45 seconds)
2025-02-17 19:32:01 localhost NetworkManager[25418]: <info>  [1739817121.5782] dhcp4 (wlp2s0): state changed no lease
2025-02-17 19:32:01 localhost wpa_supplicant[1178]: wlp2s0: CTRL-EVENT-DSCP-POLICY clear_all
2025-02-17 19:32:01 localhost NetworkManager[25418]: <info>  [1739817121.5896] dns-mgr: Removing DNS information from /gnu/store/139drha7awx79jb2js4m88p2jsl7zasc-openresolv-3.13.2/sbin/resolvconf
2025-02-17 19:32:01 localhost nscd: 16674 monitored file `/etc/resolv.conf` was written to
2025-02-17 19:32:01 localhost dnsmasq[1670]: no servers found in /etc/resolv.conf, will retry
2025-02-17 19:32:01 localhost shepherd[1]: [NetworkManager] Status of nscd:
2025-02-17 19:32:01 localhost shepherd[1]: [NetworkManager]   It is running since 19:12:14 (20 minutes ago).
2025-02-17 19:32:01 localhost shepherd[1]: [NetworkManager]   Main PID: 16674
2025-02-17 19:32:01 localhost shepherd[1]: [NetworkManager]   Command: /gnu/store/hw6g2kjayxnqi8rwpnmpraalxi0djkxc-glibc-2.39/sbin/nscd -f /gnu/store/mam32iva1j0kfrnd1dncj0l4f3bx55l0-nscd.conf --foreground
2025-02-17 19:32:01 localhost shepherd[1]: [NetworkManager]   It is enabled.
2025-02-17 19:32:01 localhost shepherd[1]: [NetworkManager]   Provides: nscd
2025-02-17 19:32:01 localhost shepherd[1]: [NetworkManager]   Requires: user-processes syslogd
2025-02-17 19:32:01 localhost shepherd[1]: [NetworkManager]   Custom actions: configuration statistics invalidate
2025-02-17 19:32:01 localhost shepherd[1]: [NetworkManager]   Will be respawned.
2025-02-17 19:32:01 localhost shepherd[1]: Stopping service nscd...
2025-02-17 19:32:01 localhost shepherd[1]: Service nscd stopped.
2025-02-17 19:32:01 localhost shepherd[1]: Service nscd is now stopped.
2025-02-17 19:32:01 localhost shepherd[1]: Starting service user-homes...
2025-02-17 19:32:01 localhost shepherd[1]: Service user-homes has been started.
2025-02-17 19:32:01 localhost shepherd[1]: Service user-homes started.
2025-02-17 19:32:01 localhost shepherd[1]: Service user-homes running with value #t.
2025-02-17 19:32:01 localhost shepherd[1]: Starting service nscd...
2025-02-17 19:32:01 localhost nscd: 21153 monitoring file `/etc/passwd` (1)
2025-02-17 19:32:01 localhost nscd: 21153 monitoring directory `/etc` (2)
2025-02-17 19:32:01 localhost nscd: 21153 monitoring file `/etc/group` (3)
2025-02-17 19:32:01 localhost nscd: 21153 monitoring directory `/etc` (2)
2025-02-17 19:32:01 localhost nscd: 21153 monitoring file `/etc/hosts` (4)
2025-02-17 19:32:01 localhost nscd: 21153 monitoring directory `/etc` (2)
2025-02-17 19:32:01 localhost nscd: 21153 monitoring file `/etc/resolv.conf` (5)
2025-02-17 19:32:01 localhost nscd: 21153 monitoring directory `/etc` (2)
2025-02-17 19:32:01 localhost nscd: 21153 monitoring file `/etc/services` (6)
2025-02-17 19:32:01 localhost nscd: 21153 monitoring directory `/etc` (2)
2025-02-17 19:32:01 localhost nscd: 21153 monitoring file `/etc/nsswitch.conf` (7)
2025-02-17 19:32:01 localhost nscd: 21153 monitoring directory `/etc` (2)
2025-02-17 19:32:01 localhost nscd: 21153 monitoring file `/etc/nsswitch.conf` (7)
2025-02-17 19:32:01 localhost nscd: 21153 monitoring directory `/etc` (2)
2025-02-17 19:32:01 localhost nscd: 21153 monitoring file `/etc/nsswitch.conf` (7)
2025-02-17 19:32:01 localhost nscd: 21153 monitoring directory `/etc` (2)
2025-02-17 19:32:01 localhost nscd: 21153 monitoring file `/etc/nsswitch.conf` (7)
2025-02-17 19:32:01 localhost nscd: 21153 monitoring directory `/etc` (2)
2025-02-17 19:32:01 localhost linux: [81763.793190] traps: dunst[21076] trap int3 ip:7f25113ae519 sp:7fff4e2a1430 error:0 in libglib-2.0.so.0.7800.0[65519,7f251136b000+94000]
2025-02-17 19:32:01 localhost gdm-x-session: GLib: Source ID 2 was not found when attempting to remove it
2025-02-17 19:32:01 localhost linux: [81763.907639] traps: dunst[21201] trap int3 ip:7f9abd53e519 sp:7ffd0c1f2320 error:0 in libglib-2.0.so.0.7800.0[65519,7f9abd4fb000+94000]
2025-02-17 19:32:02 localhost linux: [81764.018427] traps: dunst[21214] trap int3 ip:7f34acb2d519 sp:7ffee9361640 error:0 in libglib-2.0.so.0.7800.0[65519,7f34acaea000+94000]
2025-02-17 19:32:02 localhost linux: [81764.127489] traps: dunst[21257] trap int3 ip:7fd908ac7519 sp:7ffe9358c850 error:0 in libglib-2.0.so.0.7800.0[65519,7fd908a84000+94000]
2025-02-17 19:32:02 localhost linux: [81764.237848] traps: dunst[21266] trap int3 ip:7fee1d14c519 sp:7ffe05ae2730 error:0 in libglib-2.0.so.0.7800.0[65519,7fee1d109000+94000]
2025-02-17 19:32:02 localhost linux: [81764.346433] traps: dunst[21311] trap int3 ip:7fa781db6519 sp:7ffc79f52500 error:0 in libglib-2.0.so.0.7800.0[65519,7fa781d73000+94000]
2025-02-17 19:32:02 localhost shepherd[1]: Service nscd has been started.
2025-02-17 19:32:02 localhost shepherd[1]: Service nscd started.
2025-02-17 19:32:02 localhost shepherd[1]: Service nscd running with value #<<process> id: 21153 command: ("/gnu/store/hw6g2kjayxnqi8rwpnmpraalxi0djkxc-glibc-2.39/sbin/nscd" "-f" "/gnu/store/mam32iva1j0kfrnd1dncj0l4f3bx55l0-nscd.conf" "--foreground")>.
2025-02-17 19:32:02 localhost shepherd[1]: [NetworkManager] Service user-homes has been started.
2025-02-17 19:32:02 localhost shepherd[1]: [NetworkManager] Service nscd has been started.
2025-02-17 19:32:02 localhost NetworkManager[25418]: <info>  [1739817122.8225] device (p2p-dev-wlp2s0): state change: disconnected -> unmanaged (reason 'unmanaged', sys-iface-state: 'managed')
2025-02-17 19:32:02 localhost NetworkManager[25418]: <info>  [1739817122.8229] device (cdc-wdm0): state change: disconnected -> unmanaged (reason 'unmanaged', sys-iface-state: 'managed')
2025-02-17 19:32:02 localhost NetworkManager[25418]: <info>  [1739817122.8338] exiting (success)
2025-02-17 19:32:02 localhost shepherd[1]: Service NetworkManager stopped.
2025-02-17 19:32:02 localhost shepherd[1]: Service NetworkManager is now stopped.
2025-02-17 19:32:02 localhost shepherd[1]: Stopping service wpa-supplicant...
2025-02-17 19:32:02 localhost wpa_supplicant[1178]: p2p-dev-wlp2s0: CTRL-EVENT-DSCP-POLICY clear_all
2025-02-17 19:32:02 localhost wpa_supplicant[1178]: p2p-dev-wlp2s0: CTRL-EVENT-DSCP-POLICY clear_all
2025-02-17 19:32:02 localhost wpa_supplicant[1178]: nl80211: deinit ifname=p2p-dev-wlp2s0 disabled_11b_rates=0
2025-02-17 19:32:02 localhost wpa_supplicant[1178]: p2p-dev-wlp2s0: CTRL-EVENT-TERMINATING
2025-02-17 19:32:02 localhost wpa_supplicant[1178]: wlp2s0: CTRL-EVENT-DSCP-POLICY clear_all
2025-02-17 19:32:02 localhost wpa_supplicant[1178]: wlp2s0: CTRL-EVENT-DSCP-POLICY clear_all
2025-02-17 19:32:02 localhost wpa_supplicant[1178]: nl80211: deinit ifname=wlp2s0 disabled_11b_rates=0
2025-02-17 19:32:02 localhost wpa_supplicant[1178]: wlp2s0: CTRL-EVENT-TERMINATING
2025-02-17 19:32:02 localhost shepherd[1]: Service wpa-supplicant stopped.
2025-02-17 19:32:02 localhost shepherd[1]: Service wpa-supplicant is now stopped.
2025-02-17 19:32:02 localhost shepherd[1]: Stopping service libvirtd...
2025-02-17 19:32:02 localhost shepherd[1]: Service libvirtd stopped.
2025-02-17 19:32:02 localhost shepherd[1]: Service libvirtd is now stopped.
2025-02-17 19:32:03 localhost shepherd[1]: Stopping service bluetooth...
2025-02-17 19:32:03 localhost bluetoothd[1117]: Terminating
2025-02-17 19:32:03 localhost bluetoothd[1117]: Battery Provider Manager destroyed
2025-02-17 19:32:03 localhost bluetoothd[1117]: Stopping SDP server
2025-02-17 19:32:03 localhost bluetoothd[1117]: Exit
2025-02-17 19:32:03 localhost shepherd[1]: Service bluetooth stopped.
2025-02-17 19:32:03 localhost shepherd[1]: Service bluetooth is now stopped.
2025-02-17 19:32:03 localhost shepherd[1]: Stopping service upower-daemon...
2025-02-17 19:32:03 localhost shepherd[1]: Service upower-daemon stopped.
2025-02-17 19:32:03 localhost shepherd[1]: Service upower-daemon is now stopped.
2025-02-17 19:32:03 localhost shepherd[1]: Stopping service pam...
2025-02-17 19:32:03 localhost shepherd[1]: Service pam stopped.
2025-02-17 19:32:03 localhost shepherd[1]: Service pam is now stopped.
2025-02-17 19:32:03 localhost shepherd[1]: Stopping service elogind...
2025-02-17 19:32:03 localhost elogind[1114]: Received signal 15 [TERM]
2025-02-17 19:32:03 localhost shepherd[1]: Service elogind stopped.
2025-02-17 19:32:03 localhost shepherd[1]: Service elogind is now stopped.
2025-02-17 19:32:03 localhost shepherd[1]: Stopping service dbus-system...
2025-02-17 19:32:03 localhost ModemManager[1704]: <msg> caught signal, shutting down...
2025-02-17 19:32:03 localhost nm-dispatcher: Caught signal 15, shutting down...
2025-02-17 19:32:03 localhost ModemManager[1704]: <msg> ModemManager is shut down
2025-02-17 19:32:03 localhost shepherd[1]: Service dbus-system stopped.
2025-02-17 19:32:03 localhost shepherd[1]: Service dbus-system is now stopped.
2025-02-17 19:32:03 localhost shepherd[1]: Stopping service term-console...
2025-02-17 19:32:03 localhost shepherd[1]: Service term-console stopped.
2025-02-17 19:32:03 localhost shepherd[1]: Service term-console is now stopped.
2025-02-17 19:32:03 localhost shepherd[1]: Stopping service console-font-tty1...
2025-02-17 19:32:03 localhost shepherd[1]: Service console-font-tty1 stopped.
2025-02-17 19:32:03 localhost shepherd[1]: Service console-font-tty1 is now stopped.
2025-02-17 19:32:03 localhost shepherd[1]: Stopping service term-tty1...
2025-02-17 19:32:03 localhost shepherd[1]: Service term-tty1 stopped.
2025-02-17 19:32:03 localhost shepherd[1]: Service term-tty1 is now stopped.
2025-02-17 19:32:03 localhost shepherd[1]: Stopping service console-font-tty2...
2025-02-17 19:32:03 localhost shepherd[1]: Service console-font-tty2 stopped.
2025-02-17 19:32:03 localhost shepherd[1]: Service console-font-tty2 is now stopped.
2025-02-17 19:32:03 localhost shepherd[1]: Stopping service term-tty2...
2025-02-17 19:32:03 localhost shepherd[1]: Service term-tty2 stopped.
2025-02-17 19:32:03 localhost shepherd[1]: Service term-tty2 is now stopped.
2025-02-17 19:32:03 localhost shepherd[1]: Stopping service console-font-tty3...
2025-02-17 19:32:03 localhost shepherd[1]: Service console-font-tty3 stopped.
2025-02-17 19:32:03 localhost shepherd[1]: Service console-font-tty3 is now stopped.
2025-02-17 19:32:03 localhost shepherd[1]: Stopping service term-tty3...
2025-02-17 19:32:03 localhost shepherd[1]: Service term-tty3 stopped.
2025-02-17 19:32:03 localhost shepherd[1]: Service term-tty3 is now stopped.
2025-02-17 19:32:03 localhost shepherd[1]: Stopping service console-font-tty4...
2025-02-17 19:32:03 localhost shepherd[1]: Service console-font-tty4 stopped.
2025-02-17 19:32:03 localhost shepherd[1]: Service console-font-tty4 is now stopped.
2025-02-17 19:32:03 localhost shepherd[1]: Stopping service term-tty4...
2025-02-17 19:32:03 localhost shepherd[1]: Service term-tty4 stopped.
2025-02-17 19:32:03 localhost shepherd[1]: Service term-tty4 is now stopped.
2025-02-17 19:32:03 localhost shepherd[1]: Stopping service console-font-tty5...
2025-02-17 19:32:03 localhost shepherd[1]: Service console-font-tty5 stopped.
2025-02-17 19:32:03 localhost shepherd[1]: Service console-font-tty5 is now stopped.
2025-02-17 19:32:03 localhost shepherd[1]: Stopping service term-tty5...
2025-02-17 19:32:03 localhost shepherd[1]: Service term-tty5 stopped.
2025-02-17 19:32:03 localhost shepherd[1]: Service term-tty5 is now stopped.
2025-02-17 19:32:03 localhost shepherd[1]: Stopping service console-font-tty6...
2025-02-17 19:32:03 localhost shepherd[1]: Service console-font-tty6 stopped.
2025-02-17 19:32:03 localhost shepherd[1]: Service console-font-tty6 is now stopped.
2025-02-17 19:32:03 localhost shepherd[1]: Stopping service term-tty6...
2025-02-17 19:32:03 localhost shepherd[1]: Service term-tty6 stopped.
2025-02-17 19:32:03 localhost shepherd[1]: Service term-tty6 is now stopped.
2025-02-17 19:32:03 localhost shepherd[1]: Stopping service guix-daemon...
2025-02-17 19:32:03 localhost shepherd[1]: Service guix-daemon stopped.
2025-02-17 19:32:03 localhost shepherd[1]: Service guix-daemon is now stopped.
2025-02-17 19:32:03 localhost shepherd[1]: Stopping service nscd...
2025-02-17 19:32:03 localhost shepherd[1]: Service nscd stopped.
2025-02-17 19:32:03 localhost shepherd[1]: Service nscd is now stopped.
2025-02-17 19:32:03 localhost shepherd[1]: Stopping service timer...
2025-02-17 19:32:03 localhost shepherd[1]: Service timer stopped.
2025-02-17 19:32:03 localhost shepherd[1]: Service timer is now stopped.
2025-02-17 19:32:03 localhost shepherd[1]: Stopping service transient...
2025-02-17 19:32:03 localhost shepherd[1]: Service transient stopped.
2025-02-17 19:32:03 localhost shepherd[1]: Service transient is now stopped.
2025-02-17 19:32:03 localhost shepherd[1]: Stopping service log-cleanup...
2025-02-17 19:32:03 localhost shepherd[1]: Terminating timer 'log-cleanup' with 0 processes running.
2025-02-17 19:32:03 localhost shepherd[1]: Service log-cleanup stopped.
2025-02-17 19:32:03 localhost shepherd[1]: Service log-cleanup is now stopped.
2025-02-17 19:32:03 localhost shepherd[1]: Stopping service user-processes...
2025-02-17 19:32:03 localhost dnsmasq[1670]: exiting on receipt of SIGTERM
2025-02-17 19:32:03 localhost shepherd[1]: sending all processes the TERM signal
2025-02-17 19:32:03 localhost shepherd[1]: Service udev has been disabled.
2025-02-17 19:32:03 localhost shepherd[1]: Respawning virtlogd.
2025-02-17 19:32:03 localhost shepherd[1]: Starting service virtlogd...
2025-02-17 19:32:03 localhost shepherd[1]: Service virtlogd started.
2025-02-17 19:32:03 localhost shepherd[1]: Service virtlogd running with value #<<process> id: 21448 command: ("/gnu/store/vazbiad5qrkf35g0kpv647s2s9ixdj3x-libvirt-8.6.0/sbin/virtlogd" "-f" "/gnu/store/643zy7mphh9pa8ln0g4abriwb1chzks7-virtlogd.conf")>.
2025-02-17 19:32:03 localhost shepherd[1]: Service virtlogd has been started.
2025-02-17 19:32:03 localhost shepherd[1]: Respawning containerd.
2025-02-17 19:32:03 localhost shepherd[1]: Starting service containerd...
2025-02-17 19:32:04 localhost shepherd[1]: Service containerd has been started.
2025-02-17 19:32:04 localhost shepherd[1]: Service containerd started.
2025-02-17 19:32:04 localhost shepherd[1]: Service containerd running with value #<<process> id: 21463 command: ("/gnu/store/2yfg543dfxni97fpg46fw4ram0f7dw44-containerd-1.6.22/bin/containerd")>.
2025-02-17 19:32:07 localhost shepherd[1]: waiting for process termination (processes left: (1 2325 21018 21448 21453 21463 26205))
2025-02-17 19:32:07 localhost shepherd[1]: Service virtlogd (PID 21448) terminated with signal 9.
2025-02-17 19:32:07 localhost shepherd[1]: Service containerd (PID 21463) terminated with signal 9.
2025-02-17 19:32:07 localhost shepherd[1]: Respawning virtlogd.
2025-02-17 19:32:07 localhost shepherd[1]: Respawning containerd.
2025-02-17 19:32:07 localhost shepherd[1]: Starting service virtlogd...
2025-02-17 19:32:07 localhost shepherd[1]: Starting service containerd...
2025-02-17 19:32:07 localhost shepherd[1]: Service virtlogd started.
2025-02-17 19:32:07 localhost shepherd[1]: Service virtlogd running with value #<<process> id: 21524 command: ("/gnu/store/vazbiad5qrkf35g0kpv647s2s9ixdj3x-libvirt-8.6.0/sbin/virtlogd" "-f" "/gnu/store/643zy7mphh9pa8ln0g4abriwb1chzks7-virtlogd.conf")>.
2025-02-17 19:32:07 localhost shepherd[1]: Service virtlogd has been started.
2025-02-17 19:32:08 localhost shepherd[1]: waiting for process termination (processes left: (1 21524 21525))
2025-02-17 19:32:08 localhost shepherd[1]: Service containerd has been started.
2025-02-17 19:32:08 localhost shepherd[1]: Service containerd started.
2025-02-17 19:32:08 localhost shepherd[1]: Service containerd running with value #<<process> id: 21525 command: ("/gnu/store/2yfg543dfxni97fpg46fw4ram0f7dw44-containerd-1.6.22/bin/containerd")>.
2025-02-17 19:32:09 localhost shepherd[1]: waiting for process termination (processes left: (1 21524 21525))
2025-02-17 19:32:10 localhost shepherd[1]: waiting for process termination (processes left: (1 21524 21525))
2025-02-17 19:32:11 localhost shepherd[1]: waiting for process termination (processes left: (1 21524 21525))
2025-02-17 19:32:12 localhost shepherd[1]: waiting for process termination (processes left: (1 21524 21525))
2025-02-17 19:32:13 localhost shepherd[1]: waiting for process termination (processes left: (1 21524 21525))
2025-02-17 19:32:14 localhost shepherd[1]: waiting for process termination (processes left: (1 21524 21525))
2025-02-17 19:32:15 localhost shepherd[1]: waiting for process termination (processes left: (1 21524 21525))
2025-02-17 19:32:16 localhost shepherd[1]: waiting for process termination (processes left: (1 21524 21525))
2025-02-17 19:32:17 localhost shepherd[1]: waiting for process termination (processes left: (1 21524 21525))

[signature.asc (application/pgp-signature, inline)]

Message #16 received at 76368 <at> debbugs.gnu.org (full text, mbox):

From: Ludovic Courtès <ludo <at> gnu.org>
To: Dariqq <dariqq <at> posteo.net>
Cc: 76338 <at> debbugs.gnu.org, Richard Sent <richard <at> freakingpenguin.com>,
 Simen Endsjø <contact <at> simendsjo.me>,
 Felix Lechner <felix.lechner <at> lease-up.com>, 76368 <at> debbugs.gnu.org
Subject: Re: bug#76368: Cannot reboot when virtlogd and containerd is running?
Date: Sat, 22 Feb 2025 23:30:09 +0100

Hi,

Ludovic Courtès <ludo <at> gnu.org> skribis:

> I found these:
>
> scheme@(guix scripts system search)> ,pp (map shepherd-service-provision
> 					      (append-map (lambda (services)
> 							    (remove (lambda (service)
> 								      (memq 'user-processes
> 									    (shepherd-service-requirement service)))
> 								    services))
> 							  (map service-type-default-shepherd-services (fold-service-

I’ve just sent a patch in this issue adding ‘user-processes’ to all
these + virtlogd, and another one documenting ‘user-processes’ in the
manual.

I’ll apply it tomorrow if there are no objections.

Ludo’.

Message #19 received at 76368 <at> debbugs.gnu.org (full text, mbox):

From: Maxim Cournoyer <maxim.cournoyer <at> gmail.com>
To: Ludovic Courtès <ludo <at> gnu.org>
Cc: Dariqq <dariqq <at> posteo.net>, 76338 <at> debbugs.gnu.org, 76368 <at> debbugs.gnu.org
Subject: Re: bug#76368: Cannot reboot when virtlogd and containerd is running?
Date: Sun, 23 Feb 2025 10:46:57 +0900

Hi Ludovic,

Ludovic Courtès <ludo <at> gnu.org> writes:

> Hi,
>
> Dariqq <dariqq <at> posteo.net> skribis:
>
>> The issue is that thermald (and possibly other services) don't depend
>> on user-processes service. Don't know why I had no problems until now.
>
> This should be fixed: they should all depend on ‘user-processes’.

I was wondering if this shouldn't be part of an implicit default %shepherd-default-requires that
could be overridden if needed... but I think even in systemd land you
must explicitly specify wanted using e.g. 'WantedBy=multi-user.target'
in your systemd unit definition, so I guess it's fine that it must be
explicitly specified.

It'd be nice though, that in the case where it's missing, if it didn't
cause a hang upon shutdown.  I don't think anything should be able to
hang the system when calling 'shutdown', no matter what (perhaps there
count be some timeout... with a warning like:

--8<---------------cut here---------------start------------->8---
waiting for service X to terminate...
[internal timeout, perhaps defaulting to 60 s or so]
service X forcefully terminated
hint: perhaps X should require the `user-processes' service?
--8<---------------cut here---------------end--------------->8---

If the reason of the hang could be inferred with reasonable certitude
from shepherd's side, even better, though I haven't thought how that
could be done.

LGTM for the two patches you've sent so far!  Thank you.

-- 
Maxim

Message #22 received at 76368 <at> debbugs.gnu.org (full text, mbox):

From: Ludovic Courtès <ludo <at> gnu.org>
To: Maxim Cournoyer <maxim.cournoyer <at> gmail.com>
Cc: Dariqq <dariqq <at> posteo.net>, 76338 <at> debbugs.gnu.org, 76368 <at> debbugs.gnu.org
Subject: Re: bug#76368: Cannot reboot when virtlogd and containerd is running?
Date: Sun, 23 Feb 2025 22:51:49 +0100

Hi Maxim,

Maxim Cournoyer <maxim.cournoyer <at> gmail.com> skribis:

> I was wondering if this shouldn't be part of an implicit default %shepherd-default-requires that
> could be overridden if needed... but I think even in systemd land you
> must explicitly specify wanted using e.g. 'WantedBy=multi-user.target'
> in your systemd unit definition, so I guess it's fine that it must be
> explicitly specified.

I almost changed the default value of ‘requirement’ to '(user-processes)
in <shepherd-service>, but then I thought this would need more testing
and discussion: surely there are places in Guix and in code/config out
there that relies on the current default value.

> It'd be nice though, that in the case where it's missing, if it didn't
> cause a hang upon shutdown.  I don't think anything should be able to
> hang the system when calling 'shutdown', no matter what (perhaps there
> count be some timeout... with a warning like:

Even better: I think shepherd just shouldn’t try to respawn anything
while it’s shutting down.

Not depending on ‘user-processes’ could still cause problems such as
“device or resource busy” when trying to unmount file systems, but at
least it would lead to that infinite loop.

Thanks for your feedback,
Ludo’.

Message #25 received at 76368 <at> debbugs.gnu.org (full text, mbox):

From: Ludovic Courtès <ludo <at> gnu.org>
To: Dariqq <dariqq <at> posteo.net>
Cc: 76338-done <at> debbugs.gnu.org, Richard Sent <richard <at> freakingpenguin.com>,
 Simen Endsjø <contact <at> simendsjo.me>,
 Felix Lechner <felix.lechner <at> lease-up.com>, 76368 <at> debbugs.gnu.org
Subject: Re: bug#76368: Cannot reboot when virtlogd and containerd is running?
Date: Mon, 24 Feb 2025 00:04:45 +0100

Ludovic Courtès <ludo <at> gnu.org> skribis:

> I’ve just sent a patch in this issue adding ‘user-processes’ to all
> these + virtlogd, and another one documenting ‘user-processes’ in the
> manual.
>
> I’ll apply it tomorrow if there are no objections.

Pushed:

  bcb772d7d00 doc: Write about depending on the ‘user-processes’ Shepherd service.
  bd7b9e6a601 services: Add missing Shepherd dependency on ‘user-processes’.

Ludo’.

Message #28 received at 76368 <at> debbugs.gnu.org (full text, mbox):

From: Dariqq <dariqq <at> posteo.net>
To: Ludovic Courtès <ludo <at> gnu.org>,
 Maxim Cournoyer <maxim.cournoyer <at> gmail.com>
Cc: 76338 <at> debbugs.gnu.org, 76368 <at> debbugs.gnu.org
Subject: Re: bug#76368: Cannot reboot when virtlogd and containerd is running?
Date: Mon, 24 Feb 2025 10:59:43 +0000

Hi Ludo,

Thank you for the patch.

On 23.02.25 22:51, Ludovic Courtès wrote:
> Hi Maxim,
> 
> Maxim Cournoyer <maxim.cournoyer <at> gmail.com> skribis:
> 
>> I was wondering if this shouldn't be part of an implicit default %shepherd-default-requires that
>> could be overridden if needed... but I think even in systemd land you
>> must explicitly specify wanted using e.g. 'WantedBy=multi-user.target'
>> in your systemd unit definition, so I guess it's fine that it must be
>> explicitly specified.
> 
> I almost changed the default value of ‘requirement’ to '(user-processes)
> in <shepherd-service>, but then I thought this would need more testing
> and discussion: surely there are places in Guix and in code/config out
> there that relies on the current default value.
> 

I don't think this would have been a good change:

- It breaks services that correctly don't depend on user-processes, i.e. 
filesystem mounts
- It breaks every home-services that (correctly) depends on nothing but 
the home-shepherd .
- It creates a (non obvious) discrepancy between the shepherd service 
API and the Guix layer on top


Imo ideally all services would expose their shepherd requirements (and 
service-autostart, extra daemon args, daemon-environment, extra actions, 
...) in the configuration records which would allows users to workaround 
such a problem themselves.

The current system depends on whether the author of the service added 
it, feels a bit arbitrary and is not flexible if a setting one would 
like to adjust is not configurable.

(i am guilty of this as well for the service i contributed to guix)

But this for a discussion at another time.

> Thanks for your feedback,
> Ludo’.

Previous Next

GNU bug tracking system
Copyright (C) 1999 Darren O. Benham, 1997,2003 nCipher Corporation Ltd, 1994-97 Ian Jackson.