DeterminateSystems / nix-installer

Install Nix and flakes with the fast and reliable Determinate Nix Installer, with over 2 million installs.
GNU Lesser General Public License v2.1
2.01k stars 52 forks source link

Failed to install, black screen on Fedora Kinoite #1104

Open aleph-complex opened 3 weeks ago

aleph-complex commented 3 weeks ago

Unmodified Fedora Kinoite 40.20240820.0 Install fails at INFO Step: Enable (and start) the systemd unit nix.mount* The screen turns to black and nothing happens or the system exits to TTY2 login prompt.

cole-h commented 3 weeks ago

That's an interesting failure mode... If you SSH in and look at your system logs (dmesg and journalctl -eu) do you see anything that might be related?

aleph-complex commented 3 weeks ago

journalctl doesn't show anything particularly unusual to me:

Aug 22 19:19:46 phobos audit[4541]: USER_ACCT pid=4541 uid=1000 auid=1000 ses=3 subj=unconfined_u:unconfined_r:unconfined_t:s0-s0:c0.c1023 msg='op=PAM:accounting grantors=pam_unix acct="aleph" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Aug 22 19:19:46 phobos sudo[4541]:    aleph : TTY=pts/1 ; PWD=/var/home/aleph ; USER=root ; COMMAND=/usr/bin/env SHELL=/bin/bash /tmp/tmp.RrKmb7r6aJ/nix-installer install
Aug 22 19:19:46 phobos audit[4541]: USER_CMD pid=4541 uid=1000 auid=1000 ses=3 subj=unconfined_u:unconfined_r:unconfined_t:s0-s0:c0.c1023 msg='cwd="/var/home/aleph" cmd=656E76205348454C4C3D2F62696E2F62617368202F746D702F746D702E52724B6D62377236614A2F6E69782D696E7374616C6C657220696E7374616C6C exe="/usr/bin/sudo" terminal=tty res=success'
Aug 22 19:19:46 phobos audit[4541]: CRED_REFR pid=4541 uid=1000 auid=1000 ses=3 subj=unconfined_u:unconfined_r:unconfined_t:s0-s0:c0.c1023 msg='op=PAM:setcred grantors=pam_env,pam_fprintd acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Aug 22 19:19:46 phobos sudo[4541]: pam_unix(sudo:session): session opened for user root(uid=0) by aleph(uid=1000)
Aug 22 19:19:46 phobos audit[4541]: USER_START pid=4541 uid=1000 auid=1000 ses=3 subj=unconfined_u:unconfined_r:unconfined_t:s0-s0:c0.c1023 msg='op=PAM:session_open grantors=pam_keyinit,pam_limits,pam_keyinit,pam_limits,pam_systemd,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Aug 22 19:19:51 phobos systemd[1]: Reloading requested from client PID 4588 ('systemctl') (unit user@1000.service)...
Aug 22 19:19:51 phobos systemd[1]: Reloading...
Aug 22 19:19:52 phobos systemd[1]: Reloading finished in 432 ms.
Aug 22 19:19:52 phobos audit: BPF prog-id=144 op=LOAD
Aug 22 19:19:52 phobos audit: BPF prog-id=47 op=UNLOAD
Aug 22 19:19:52 phobos audit: BPF prog-id=145 op=LOAD
Aug 22 19:19:52 phobos audit: BPF prog-id=146 op=LOAD
Aug 22 19:19:52 phobos audit: BPF prog-id=54 op=UNLOAD
Aug 22 19:19:52 phobos audit: BPF prog-id=55 op=UNLOAD
Aug 22 19:19:52 phobos audit: BPF prog-id=147 op=LOAD
Aug 22 19:19:52 phobos audit: BPF prog-id=48 op=UNLOAD
Aug 22 19:19:52 phobos audit: BPF prog-id=148 op=LOAD
Aug 22 19:19:52 phobos audit: BPF prog-id=49 op=UNLOAD
Aug 22 19:19:52 phobos audit: BPF prog-id=149 op=LOAD
Aug 22 19:19:52 phobos audit: BPF prog-id=33 op=UNLOAD
Aug 22 19:19:52 phobos audit: BPF prog-id=150 op=LOAD
Aug 22 19:19:52 phobos audit: BPF prog-id=151 op=LOAD
Aug 22 19:19:52 phobos audit: BPF prog-id=34 op=UNLOAD
Aug 22 19:19:52 phobos audit: BPF prog-id=35 op=UNLOAD
Aug 22 19:19:52 phobos audit: BPF prog-id=152 op=LOAD
Aug 22 19:19:52 phobos audit: BPF prog-id=45 op=UNLOAD
Aug 22 19:19:52 phobos audit: BPF prog-id=153 op=LOAD
Aug 22 19:19:52 phobos audit: BPF prog-id=51 op=UNLOAD
Aug 22 19:19:52 phobos audit: BPF prog-id=154 op=LOAD
Aug 22 19:19:52 phobos audit: BPF prog-id=155 op=LOAD
Aug 22 19:19:52 phobos audit: BPF prog-id=52 op=UNLOAD
Aug 22 19:19:52 phobos audit: BPF prog-id=53 op=UNLOAD
Aug 22 19:19:52 phobos audit: BPF prog-id=156 op=LOAD
Aug 22 19:19:52 phobos audit: BPF prog-id=46 op=UNLOAD
Aug 22 19:19:52 phobos audit: BPF prog-id=157 op=LOAD
Aug 22 19:19:52 phobos audit: BPF prog-id=50 op=UNLOAD
Aug 22 19:19:52 phobos audit: BPF prog-id=158 op=LOAD
Aug 22 19:19:52 phobos audit: BPF prog-id=44 op=UNLOAD
Aug 22 19:19:52 phobos audit: BPF prog-id=159 op=LOAD
Aug 22 19:19:52 phobos audit: BPF prog-id=36 op=UNLOAD
Aug 22 19:19:52 phobos audit: BPF prog-id=160 op=LOAD
Aug 22 19:19:52 phobos audit: BPF prog-id=161 op=LOAD
Aug 22 19:19:52 phobos audit: BPF prog-id=37 op=UNLOAD
Aug 22 19:19:52 phobos audit: BPF prog-id=38 op=UNLOAD
Aug 22 19:19:52 phobos audit: BPF prog-id=162 op=LOAD
Aug 22 19:19:52 phobos audit: BPF prog-id=41 op=UNLOAD
Aug 22 19:19:52 phobos audit: BPF prog-id=163 op=LOAD
Aug 22 19:19:52 phobos audit: BPF prog-id=164 op=LOAD
Aug 22 19:19:52 phobos audit: BPF prog-id=42 op=UNLOAD
Aug 22 19:19:52 phobos audit: BPF prog-id=43 op=UNLOAD
Aug 22 19:19:52 phobos audit: BPF prog-id=165 op=LOAD
Aug 22 19:19:52 phobos audit: BPF prog-id=166 op=LOAD
Aug 22 19:19:52 phobos audit: BPF prog-id=39 op=UNLOAD
Aug 22 19:19:52 phobos audit: BPF prog-id=40 op=UNLOAD
Aug 22 19:19:52 phobos systemd[1]: fstrim.timer: Deactivated successfully.
Aug 22 19:19:52 phobos systemd[1]: Stopped fstrim.timer - Discard unused filesystem blocks once a week.
Aug 22 19:19:52 phobos systemd[1]: logrotate.timer: Deactivated successfully.
Aug 22 19:19:52 phobos systemd[1]: Stopped logrotate.timer - Daily rotation of log files.
Aug 22 19:19:52 phobos systemd[1]: plocate-updatedb.timer: Deactivated successfully.
Aug 22 19:19:52 phobos systemd[1]: Stopped plocate-updatedb.timer - Update the plocate database daily.
Aug 22 19:19:52 phobos systemd[1]: raid-check.timer: Deactivated successfully.
Aug 22 19:19:52 phobos systemd[1]: Stopped raid-check.timer - Weekly RAID setup health check.
Aug 22 19:19:52 phobos systemd[1]: unbound-anchor.timer: Deactivated successfully.
Aug 22 19:19:52 phobos systemd[1]: Stopped unbound-anchor.timer - daily update of the root trust anchor for DNSSEC.
Aug 22 19:19:52 phobos systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes.
Aug 22 19:19:52 phobos systemd[1]: Stopped target graphical.target - Graphical Interface.
Aug 22 19:19:52 phobos systemd[1]: Stopped target local-fs.target - Local File Systems.
Aug 22 19:19:52 phobos systemd[1]: Stopped target multi-user.target - Multi-User System.
Aug 22 19:19:52 phobos systemd[1]: cups.path: Deactivated successfully.
Aug 22 19:19:52 phobos systemd[1]: Stopped cups.path - CUPS Scheduler.
Aug 22 19:19:52 phobos systemd[1]: Stopped target rpc_pipefs.target.
Aug 22 19:19:52 phobos systemd[1]: systemd-rfkill.socket: Deactivated successfully.
Aug 22 19:19:52 phobos systemd[1]: Closed systemd-rfkill.socket - Load/Save RF Kill Switch Status /dev/rfkill Watch.
Aug 22 19:19:52 phobos systemd[1]: Unmounting sysroot-ostree-deploy-fedora-var.mount - /sysroot/ostree/deploy/fedora/var...
Aug 22 19:19:52 phobos systemd[1]: Unmounting usr.mount...
Aug 22 19:19:52 phobos systemd[1]: Unmounting var-lib-nfs-rpc_pipefs.mount - RPC Pipe File System...
Aug 22 19:19:52 phobos systemd[1]: Stopping ModemManager.service - Modem Manager...
Aug 22 19:19:52 phobos systemd[1]: NetworkManager-wait-online.service: Deactivated successfully.
Aug 22 19:19:52 phobos ModemManager[1362]: <msg> caught signal, shutting down...
Aug 22 19:19:52 phobos systemd[1]: Stopped NetworkManager-wait-online.service - Network Manager Wait Online.
Aug 22 19:19:52 phobos audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=NetworkManager-wait-online comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Aug 22 19:19:52 phobos ModemManager[1362]: <msg> ModemManager is shut down
Aug 22 19:19:52 phobos NetworkManager[1373]: <info>  [1724375992.2486] modem-manager: ModemManager no longer available
Aug 22 19:19:52 phobos NetworkManager[1373]: <info>  [1724375992.2489] caught SIGTERM, shutting down normally.
Aug 22 19:19:52 phobos systemd[1]: Stopping NetworkManager.service - Network Manager...
Aug 22 19:19:52 phobos systemd[1]: Stopping accounts-daemon.service - Accounts Service...
Aug 22 19:19:52 phobos systemd[1]: Stopping bluetooth.service - Bluetooth service...
Aug 22 19:19:52 phobos bluetoothd[1276]: Terminating
Aug 22 19:19:52 phobos chronyd[1319]: chronyd exiting
Aug 22 19:19:52 phobos systemd[1]: Stopping chronyd.service - NTP client/server...
Aug 22 19:19:52 phobos NetworkManager[1373]: <info>  [1724375992.2511] device (wlp2s0): state change: activated -> deactivating (reason 'unmanaged', sys-iface-state: 'managed')
Aug 22 19:19:52 phobos NetworkManager[1373]: <info>  [1724375992.2515] manager: NetworkManager state is now DISCONNECTING
Aug 22 19:19:52 phobos systemd[1]: Stopping gssproxy.service - GSSAPI Proxy Daemon...
Aug 22 19:19:52 phobos systemd[1]: nix-directory.service - Enable mount points in / for ostree was skipped because of an unmet condition check (ConditionPathExists=!/nix).
Aug 22 19:19:52 phobos systemd[1]: Stopping power-profiles-daemon.service - Power Profiles daemon...
Aug 22 19:19:52 phobos bluetoothd[1276]: Stopping SDP server
Aug 22 19:19:52 phobos systemd[1]: Stopping rtkit-daemon.service - RealtimeKit Scheduling Policy Service...
Aug 22 19:19:52 phobos bluetoothd[1276]: Exit
Aug 22 19:19:52 phobos kded6[1963]: org.kde.plasma.nm.kded: Unhandled active connection state change:  3
Aug 22 19:19:52 phobos sddm[1455]: Signal received: SIGTERM
Aug 22 19:19:52 phobos systemd[1]: Stopping sddm.service - Simple Desktop Display Manager...
Aug 22 19:19:52 phobos umount[4695]: umount: /usr: target is busy.
Aug 22 19:19:52 phobos sddm-helper[1651]: Signal received: SIGTERM
Aug 22 19:19:52 phobos systemd[1]: Stopping systemd-backlight@backlight:amdgpu_bl1.service - Load/Save Screen Backlight Brightness of backlight:amdgpu_bl1...
Aug 22 19:19:52 phobos systemd[1]: Stopping systemd-backlight@leds:tpacpi::kbd_backlight.service - Load/Save Screen Backlight Brightness of leds:tpacpi::kbd_backlight...
Aug 22 19:19:52 phobos systemd[1]: Stopping systemd-cryptsetup@luks\x2d3f209928\x2d0157\x2d41e6\x2dbadf\x2dd42b4e6602fb.service - Cryptography Setup for luks-3f209928-0157-41e6-badf-d42b4e6602fb...
Aug 22 19:19:52 phobos systemd[1]: Stopping systemd-homed.service - Home Area Manager...
Aug 22 19:19:52 phobos systemd[1673]: Starting systemd-tmpfiles-clean.service - Cleanup of User's Temporary Files and Directories...
Aug 22 19:19:52 phobos systemd[1]: Stopping systemd-journal-flush.service - Flush Journal to Persistent Storage...
Aug 22 19:19:52 phobos systemd[1]: Stopping systemd-oomd.service - Userspace Out-Of-Memory (OOM) Killer...
Aug 22 19:19:52 phobos systemd[1]: Stopping systemd-random-seed.service - Load/Save OS Random Seed...
Aug 22 19:19:52 phobos systemd[1]: Stopping systemd-resolved.service - Network Name Resolution...
Aug 22 19:19:52 phobos systemd[1]: Stopping systemd-update-utmp.service - Record System Boot/Shutdown in UTMP...
Aug 22 19:19:52 phobos systemd[1]: Stopping upower.service - Daemon for power management...

At this point, the ssh connection drops. I checked the logs on the device and it seems that it continues to shut everything down then starts the bare minimum for the system and sends me to TTY. dmesg shows only the frame buffer switch and wifi. Not sure about expected behavior. Mounted nix.mount - Mount /var/home/nix on /nix. shows up a few times. Really lost on this one.

cole-h commented 2 weeks ago

Hm. What happens if you manually run sudo systemctl daemon-reload? It seems like that's what happens directly before systemd starts logging about deactiavting and stopping units. On my system, that doesn't happen (granted it's not running Fedora).

Also, if you could capture the entire output of the installer by piping its stderr and stdout to a file, that might have some information in it...