search

NixOS / nixpkgs

Nix Packages collection & NixOS
MIT License
17.38k stars 13.6k forks source link

GNOME Session Crashes with Auto-Login #103746

Open kevincox opened 3 years ago

kevincox commented 3 years ago

Describe the bug When booting up with autologin enabled the desktop becomes briefly visible before GNOME crashes.

I rebooted and got the same behaviour but logging in manually worked.

To Reproduce

  1. Boot system.
  2. Provide disk encryption passowrd.

Expected behavior

The system boots to desktop.

Notify maintainers

@hedning @jtojnar @worldofpeace @dasj19

Log

Nov 13 17:04:20 kevinidea systemd[1045]: Reached target GNOME Session.
Nov 13 17:04:20 kevinidea systemd[1045]: Reached target GNOME Wayland Session (session: gnome).
Nov 13 17:04:20 kevinidea systemd[1045]: Reached target Current graphical user session.
Nov 13 17:04:20 kevinidea systemd[1045]: Condition check resulted in GNOME Initial Setup being skipped.
Nov 13 17:04:20 kevinidea systemd[1045]: Condition check resulted in GNOME Welcome Tour being skipped.
Nov 13 17:04:20 kevinidea systemd[1045]: Starting GPaste daemon...
Nov 13 17:04:20 kevinidea dbus-daemon[1052]: [session uid=1000 pid=1052] Activating service name='org.gnome.Shell.Screencast' requested by ':1.50' (uid=1000 pid=1434 comm="/nix/store/5fpcfnc687s44fq9qhcm6nz6gqn10msz-gnome-" label="kernel")
Nov 13 17:04:20 kevinidea dbus-daemon[905]: [system] Successfully activated service 'org.freedesktop.locale1'
Nov 13 17:04:20 kevinidea systemd[1]: Started Locale Service.
Nov 13 17:04:20 kevinidea dbus-daemon[1052]: [session uid=1000 pid=1052] Activating via systemd: service name='org.gtk.vfs.Metadata' unit='gvfs-metadata.service' requested by ':1.64' (uid=1000 pid=1714 comm="/nix/store/wysp2dm0k8rximd7fhfwfa28rq8xvgd5-m>
Nov 13 17:04:20 kevinidea systemd[1045]: Starting Virtual filesystem metadata service...
Nov 13 17:04:20 kevinidea .gsd-media-keys[1434]: Failed to grab accelerator for keybinding settings:playback-random
Nov 13 17:04:20 kevinidea .gsd-media-keys[1434]: Failed to grab accelerator for keybinding settings:rfkill
Nov 13 17:04:20 kevinidea .gsd-media-keys[1434]: Failed to grab accelerator for keybinding settings:hibernate
Nov 13 17:04:20 kevinidea .gsd-media-keys[1434]: Failed to grab accelerator for keybinding settings:playback-repeat
Nov 13 17:04:20 kevinidea .colord-wrapped[1617]: failed to get session [pid 1430]: No data available
Nov 13 17:04:20 kevinidea dbus-daemon[1052]: [session uid=1000 pid=1052] Successfully activated service 'org.gtk.vfs.Metadata'
Nov 13 17:04:20 kevinidea systemd[1045]: Started Virtual filesystem metadata service.
Nov 13 17:04:20 kevinidea .colord-wrapped[1617]: failed to get session [pid 1430]: No data available
Nov 13 17:04:20 kevinidea .colord-wrapped[1617]: failed to get session [pid 1430]: No data available
Nov 13 17:04:20 kevinidea .geoclue-wrappe[1259]: Failed to query location: Forbidden
Nov 13 17:04:20 kevinidea .gnome-shell-wr[1106]: Failed to launch ibus-daemon: Failed to execute child process “ibus-daemon” (No such file or directory)
Nov 13 17:04:20 kevinidea systemd[1045]: Reached target GNOME session X11 services.
Nov 13 17:04:20 kevinidea systemd[1045]: Starting GNOME XSettings service...
Nov 13 17:04:20 kevinidea gnome-shell[1733]: The XKEYBOARD keymap compiler (xkbcomp) reports:
Nov 13 17:04:20 kevinidea gnome-shell[1733]: > Warning:          Unsupported maximum keycode 569, clipping.
Nov 13 17:04:20 kevinidea gnome-shell[1733]: >                   X11 cannot support keycodes above 255.
Nov 13 17:04:20 kevinidea gnome-shell[1733]: > Warning:          Unsupported high keycode 372 for name <I372> ignored
Nov 13 17:04:20 kevinidea gnome-shell[1733]: >                   X11 cannot support keycodes above 255.
Nov 13 17:04:20 kevinidea gnome-shell[1733]: >                   This warning only shows for the first high keycode.
Nov 13 17:04:20 kevinidea gnome-shell[1733]: Errors from xkbcomp are not fatal to the X server
Nov 13 17:04:20 kevinidea systemd[1045]: Started GNOME XSettings service.
Nov 13 17:04:20 kevinidea systemd[1045]: Reached target GNOME session X11 services.
Nov 13 17:04:20 kevinidea .gpaste-daemon-[1668]: Failed to load module "colorreload-gtk-module"
Nov 13 17:04:20 kevinidea gnome-shell[1227]: WARNING: Kernel has no file descriptor comparison support: Function not implemented
Nov 13 17:04:20 kevinidea dbus-daemon[1052]: [session uid=1000 pid=1052] Successfully activated service 'org.gnome.Shell.Screencast'
Nov 13 17:04:20 kevinidea .gnome-shell-wr[1106]: GNOME Shell started at Fri Nov 13 2020 17:04:19 GMT-0500 (EST)
Nov 13 17:04:20 kevinidea .gnome-shell-wr[1106]: Registering session with GDM
Nov 13 17:04:20 kevinidea dbus-daemon[1052]: [session uid=1000 pid=1052] Successfully activated service 'org.gnome.GPaste'
Nov 13 17:04:20 kevinidea systemd[1045]: Started GPaste daemon.
Nov 13 17:04:21 kevinidea avahi-daemon[867]: Registering new address record for 2002:95f8:b674:1:4c76:1547:ffbf:a55e on enp0s20u1u3.*.
Nov 13 17:04:21 kevinidea gnome-keyring-daemon[1055]: asked to register item /org/freedesktop/secrets/collection/login/64, but it's already registered
Nov 13 17:04:21 kevinidea gdm-autologin][1040]: pam_unix(gdm-autologin:session): session closed for user kevincox
Nov 13 17:04:21 kevinidea systemd[1045]: Stopped target GNOME Wayland Session (session: gnome).
Nov 13 17:04:21 kevinidea systemd[1045]: Stopped target GNOME Session.

It isn't very clear what the source is but the following line is not present in the successful session and sounds related to autologin.

Nov 13 17:04:21 kevinidea gnome-keyring-daemon[1055]: asked to register item /org/freedesktop/secrets/collection/login/64, but it's already registered

Metadata Please run nix-shell -p nix-info --run "nix-info -m" and paste the result.

Maintainer information:

module: services.xserver.desktopManager.gnome3
kevincox commented 3 years ago

Hmm, I rebooted and that gnome-keyring-daemon line wasn't present in the failed log. So I guess that wasn't it.

iclanzan commented 3 years ago

I am also experiencing this issue. Here are the logs around the time Gnome crashes:

Nov 13 18:44:25 nixxx .gnome-shell-wr[1882]: GNOME Shell started at Fri Nov 13 2020 18:44:23 GMT-0500 (EST)
Nov 13 18:44:25 nixxx .gnome-shell-wr[1882]: Registering session with GDM
Nov 13 18:44:25 nixxx cupsd[1777]: Expiring subscriptions...
Nov 13 18:44:25 nixxx gdm-autologin][1810]: pam_unix(gdm-autologin:session): session closed for user iclanzan
Nov 13 18:44:25 nixxx systemd[1819]: Stopped target GNOME Wayland Session (session: gnome).
Nov 13 18:44:25 nixxx systemd[1819]: Stopped target GNOME Session.
Nov 13 18:44:25 nixxx systemd[1819]: Stopped target GNOME session X11 services.
Nov 13 18:44:25 nixxx systemd[1819]: Stopped target GNOME Session (session: gnome).

I don’t see anything interesting there, but there are a bunch of these errors above that:

Nov 13 18:44:24 nixxx .gnome-shell-wr[1882]: _clutter_stage_queue_event: assertion 'CLUTTER_IS_STAGE (stage)' failed
Nov 13 18:44:24 nixxx .gnome-shell-wr[1882]: clutter_do_event: Event does not have a stage: discarding.
kevincox commented 3 years ago

I see the clutter messages as well however they appear in both the successful and the failing login.

kevincox commented 3 years ago

I'm lost for debugging.

jtojnar commented 3 years ago

I would try disabling GPaste and other extensions first – the colorreload-gtk-module is suspicious.

kevincox commented 3 years ago

You think the extension could be crashing the shell? Makes sense. I'll give it a shot.

kevincox commented 3 years ago

No dice. I disabled my two extensions and it still doesn't work on the auto-login and does work on the regular login.

kevincox commented 3 years ago

Bit longer chunk of longs from this attempt.

Nov 13 19:55:48 kevinidea systemd[1115]: Reached target GNOME Wayland Session.
Nov 13 19:55:48 kevinidea systemd[1115]: Reached target GNOME Shell.
Nov 13 19:55:48 kevinidea systemd[1115]: Condition check resulted in GNOME Initial Setup Copy Worker being skipped.
Nov 13 19:55:48 kevinidea systemd[1115]: Reached target Session services which should run early before the graphical session is brought up.
Nov 13 19:55:48 kevinidea systemd[1115]: Starting Monitor Session leader for GNOME Session...
Nov 13 19:55:48 kevinidea systemd[1115]: Started Monitor Session leader for GNOME Session.
Nov 13 19:55:48 kevinidea systemd[1115]: Reached target Tasks to be run before GNOME Session starts.
Nov 13 19:55:48 kevinidea systemd[1115]: Starting GNOME Session Manager (session: gnome)...
Nov 13 19:55:48 kevinidea gnome-session[1154]: gnome-session-binary[1154]: WARNING: Could not parse desktop file ignore-lid-switch-tweak.desktop or it references a not found TryExec binary
Nov 13 19:55:48 kevinidea gnome-session-binary[1154]: WARNING: Could not parse desktop file ignore-lid-switch-tweak.desktop or it references a not found TryExec binary
Nov 13 19:55:48 kevinidea systemd[1115]: Started Application launched by gnome-session-binary.
Nov 13 19:55:48 kevinidea systemd[1115]: Started Application launched by gnome-session-binary.
Nov 13 19:55:48 kevinidea systemd[1115]: Started Application launched by gnome-session-binary.
Nov 13 19:55:48 kevinidea gnome-keyring-daemon[1167]: couldn't access control socket: /run/user/1000/keyring/control: No such file or directory
Nov 13 19:55:48 kevinidea gnome-keyring-daemon[1165]: couldn't access control socket: /run/user/1000/keyring/control: No such file or directory
Nov 13 19:55:48 kevinidea .gnome-keyring-[1167]: couldn't access control socket: /run/user/1000/keyring/control: No such file or directory
Nov 13 19:55:48 kevinidea gnome-keyring-daemon[1166]: couldn't access control socket: /run/user/1000/keyring/control: No such file or directory
Nov 13 19:55:48 kevinidea .gnome-keyring-[1165]: couldn't access control socket: /run/user/1000/keyring/control: No such file or directory
Nov 13 19:55:48 kevinidea .gnome-keyring-[1166]: couldn't access control socket: /run/user/1000/keyring/control: No such file or directory
Nov 13 19:55:48 kevinidea gnome-keyring-ssh.desktop[1166]: SSH_AUTH_SOCK=/run/user/1000/keyring/ssh
Nov 13 19:55:48 kevinidea gnome-keyring-secrets.desktop[1165]: SSH_AUTH_SOCK=/run/user/1000/keyring/ssh
Nov 13 19:55:48 kevinidea systemd[1115]: Started GNOME Session Manager (session: gnome).
Nov 13 19:55:48 kevinidea systemd[1115]: app-gnome-gnome\x2dkeyring\x2dsecrets-1164.scope: Succeeded.
Nov 13 19:55:48 kevinidea systemd[1115]: app-gnome-gnome\x2dkeyring\x2dssh-1159.scope: Succeeded.
Nov 13 19:55:48 kevinidea systemd[1115]: Reached target GNOME Session Manager is ready.
Nov 13 19:55:48 kevinidea systemd[1115]: Starting GNOME Shell on Wayland...
Nov 13 19:55:48 kevinidea systemd[1115]: Starting GNOME Shell on X11...
Nov 13 19:55:48 kevinidea systemd[1115]: Started Application launched by gnome-session-binary.
Nov 13 19:55:48 kevinidea systemd[1115]: Started Application launched by gnome-session-binary.
Nov 13 19:55:48 kevinidea systemd[1115]: org.gnome.Shell@x11.service: Control process exited, code=exited, status=2/INVALIDARGUMENT
Nov 13 19:55:48 kevinidea systemd[1115]: org.gnome.Shell@x11.service: Skipped due to 'exec-condition'.
Nov 13 19:55:48 kevinidea systemd[1115]: Condition check resulted in GNOME Shell on X11 being skipped.
Nov 13 19:55:48 kevinidea systemd[1115]: org.gnome.Shell@x11.service: Scheduled restart job, restart counter is at 1.
Nov 13 19:55:48 kevinidea systemd[1115]: Stopped GNOME Shell on X11.
Nov 13 19:55:48 kevinidea systemd[1115]: Starting GNOME Shell on X11...
Nov 13 19:55:48 kevinidea systemd[1115]: org.gnome.Shell@x11.service: Control process exited, code=exited, status=2/INVALIDARGUMENT
Nov 13 19:55:48 kevinidea systemd[1115]: org.gnome.Shell@x11.service: Skipped due to 'exec-condition'.
Nov 13 19:55:48 kevinidea systemd[1115]: Condition check resulted in GNOME Shell on X11 being skipped.
Nov 13 19:55:48 kevinidea systemd[1115]: org.gnome.Shell@x11.service: Scheduled restart job, restart counter is at 2.
Nov 13 19:55:48 kevinidea systemd[1115]: Stopped GNOME Shell on X11.
Nov 13 19:55:48 kevinidea systemd[1115]: Starting GNOME Shell on X11...
Nov 13 19:55:48 kevinidea systemd[1115]: org.gnome.Shell@x11.service: Control process exited, code=exited, status=2/INVALIDARGUMENT
Nov 13 19:55:48 kevinidea systemd[1115]: org.gnome.Shell@x11.service: Skipped due to 'exec-condition'.
Nov 13 19:55:48 kevinidea systemd[1115]: Condition check resulted in GNOME Shell on X11 being skipped.
Nov 13 19:55:48 kevinidea systemd[1115]: org.gnome.Shell@x11.service: Scheduled restart job, restart counter is at 3.
Nov 13 19:55:48 kevinidea systemd[1115]: Stopped GNOME Shell on X11.
Nov 13 19:55:48 kevinidea systemd[1115]: org.gnome.Shell@x11.service: Start request repeated too quickly.
Nov 13 19:55:48 kevinidea systemd[1115]: org.gnome.Shell@x11.service: Skipped due to 'exec-condition'.
Nov 13 19:55:48 kevinidea systemd[1115]: Condition check resulted in GNOME Shell on X11 being skipped.
Nov 13 19:55:48 kevinidea systemd[1115]: app-gnome-xdg\x2duser\x2ddirs-1175.scope: Succeeded.
Nov 13 19:55:48 kevinidea systemd[1115]: app-gnome-pulseaudio-1177.scope: Succeeded.
Nov 13 19:55:49 kevinidea .gnome-shell-wr[1179]: Enabling experimental feature 'scale-monitor-framebuffer'
Nov 13 19:55:49 kevinidea .gnome-shell-wr[1179]: Failed to initialize accelerated iGPU/dGPU framebuffer sharing: Do not want to use software renderer (llvmpipe (LLVM 9.0.1, 256 bits)), fall>
Nov 13 19:55:49 kevinidea kernel: IPv6: ADDRCONF(NETDEV_CHANGE): enp0s20u1u3: link becomes ready
Nov 13 19:55:49 kevinidea kernel: r8152 4-1.3:1.0 enp0s20u1u3: carrier on
Nov 13 19:55:49 kevinidea avahi-daemon[937]: Joining mDNS multicast group on interface enp0s20u1u3.IPv6 with address fe80::4335:e2dc:af72:8556.
Nov 13 19:55:49 kevinidea avahi-daemon[937]: New relevant interface enp0s20u1u3.IPv6 for mDNS.
Nov 13 19:55:49 kevinidea avahi-daemon[937]: Registering new address record for fe80::4335:e2dc:af72:8556 on enp0s20u1u3.*.
Nov 13 19:55:49 kevinidea gnome-shell[1179]: libinput error: event17 - Wacom Intuos PT S Pad: libinput bug: missing tablet capabilities: pen resolution. Ignoring this device.
Nov 13 19:55:49 kevinidea dbus-daemon[1122]: [session uid=1000 pid=1122] Activating via systemd: service name='org.gtk.vfs.Daemon' unit='gvfs-daemon.service' requested by ':1.13' (uid=1000 >
Nov 13 19:55:49 kevinidea systemd[1115]: Starting Virtual filesystem service...
Nov 13 19:55:49 kevinidea dbus-daemon[1122]: [session uid=1000 pid=1122] Successfully activated service 'org.gtk.vfs.Daemon'
Nov 13 19:55:49 kevinidea systemd[1115]: Started Virtual filesystem service.
Nov 13 19:55:49 kevinidea gnome-shell[1179]: WARNING: Kernel has no file descriptor comparison support: Function not implemented
Nov 13 19:55:49 kevinidea kernel: fuse: init (API version 7.31)
Nov 13 19:55:49 kevinidea systemd[1]: Mounting FUSE Control File System...
Nov 13 19:55:49 kevinidea systemd[1]: Mounted FUSE Control File System.
Nov 13 19:55:49 kevinidea dbus-daemon[1122]: [session uid=1000 pid=1122] Activating via systemd: service name='org.a11y.Bus' unit='at-spi-dbus-bus.service' requested by ':1.16' (uid=1000 pi>
Nov 13 19:55:49 kevinidea systemd[1115]: Starting Accessibility services bus...
Nov 13 19:55:49 kevinidea dbus-daemon[1122]: [session uid=1000 pid=1122] Successfully activated service 'org.a11y.Bus'
Nov 13 19:55:49 kevinidea systemd[1115]: Started Accessibility services bus.
Nov 13 19:55:50 kevinidea gnome-shell[1239]: [mi] Extension "SECURITY" is not recognized
Nov 13 19:55:50 kevinidea gnome-shell[1239]: [mi] Only the following extensions can be run-time disabled:
Nov 13 19:55:50 kevinidea gnome-shell[1239]: [mi]    Generic Event Extension
Nov 13 19:55:50 kevinidea gnome-shell[1239]: [mi]    MIT-SHM
Nov 13 19:55:50 kevinidea gnome-shell[1239]: [mi]    XTEST
Nov 13 19:55:50 kevinidea gnome-shell[1239]: [mi]    XINERAMA
Nov 13 19:55:50 kevinidea gnome-shell[1239]: [mi]    XFIXES
Nov 13 19:55:50 kevinidea gnome-shell[1239]: [mi]    RENDER
Nov 13 19:55:50 kevinidea gnome-shell[1239]: [mi]    RANDR
Nov 13 19:55:50 kevinidea gnome-shell[1239]: [mi]    COMPOSITE
Nov 13 19:55:50 kevinidea gnome-shell[1239]: [mi]    DAMAGE
Nov 13 19:55:50 kevinidea gnome-shell[1239]: [mi]    MIT-SCREEN-SAVER
Nov 13 19:55:50 kevinidea gnome-shell[1239]: [mi]    DOUBLE-BUFFER
Nov 13 19:55:50 kevinidea gnome-shell[1239]: [mi]    RECORD
Nov 13 19:55:50 kevinidea gnome-shell[1239]: [mi]    DPMS
Nov 13 19:55:50 kevinidea gnome-shell[1239]: [mi]    X-Resource
Nov 13 19:55:50 kevinidea gnome-shell[1239]: [mi]    XVideo
Nov 13 19:55:50 kevinidea gnome-shell[1239]: [mi]    XVideo-MotionCompensation
Nov 13 19:55:50 kevinidea gnome-shell[1239]: [mi]    GLX
Nov 13 19:55:50 kevinidea gnome-shell[1239]: libEGL warning: DRI2: failed to create dri screen
Nov 13 19:55:50 kevinidea gnome-shell[1239]: libEGL warning: DRI2: failed to create dri screen
Nov 13 19:55:50 kevinidea gnome-shell[1239]: glamor: No eglstream capable devices found
Nov 13 19:55:50 kevinidea .gnome-shell-wr[1179]: Some code accessed the property 'CredentialManager' on the module 'credentialManager'. That property was defined with 'let' or 'const' insid>
Nov 13 19:55:50 kevinidea .gnome-shell-wr[1179]: Skipping parental controls support as it’s disabled
Nov 13 19:55:50 kevinidea .gnome-shell-wr[1179]: Unset XDG_SESSION_ID, getCurrentSessionProxy() called outside a user session. Asking logind directly.
Nov 13 19:55:50 kevinidea .gnome-shell-wr[1179]: Will monitor session 1
Nov 13 19:55:50 kevinidea .gnome-shell-wr[1179]: Failed to launch ibus-daemon: Failed to execute child process “ibus-daemon” (No such file or directory)
Nov 13 19:55:50 kevinidea dbus-daemon[1122]: [session uid=1000 pid=1122] Activating via systemd: service name='org.freedesktop.impl.portal.PermissionStore' unit='xdg-permission-store.servic>
Nov 13 19:55:50 kevinidea systemd[1115]: Starting sandboxed app permission store...
Nov 13 19:55:50 kevinidea dbus-daemon[1122]: [session uid=1000 pid=1122] Activating service name='org.gnome.Shell.CalendarServer' requested by ':1.13' (uid=1000 pid=1179 comm="/nix/store/z1>
Nov 13 19:55:50 kevinidea dbus-daemon[1122]: [session uid=1000 pid=1122] Successfully activated service 'org.freedesktop.impl.portal.PermissionStore'
Nov 13 19:55:50 kevinidea systemd[1115]: Started sandboxed app permission store.
Nov 13 19:55:50 kevinidea dbus-daemon[1122]: [session uid=1000 pid=1122] Activating via systemd: service name='org.gnome.evolution.dataserver.Sources5' unit='evolution-source-registry.servi>
Nov 13 19:55:50 kevinidea systemd[1115]: Starting Evolution source registry...
Nov 13 19:55:50 kevinidea dbus-daemon[972]: [system] Activating via systemd: service name='org.freedesktop.UPower' unit='upower.service' requested by ':1.20' (uid=1000 pid=1179 comm="/nix/s>
Nov 13 19:55:50 kevinidea systemd[1]: Starting Daemon for power management...
Nov 13 19:55:50 kevinidea systemd[1115]: Starting Sound Service...
Nov 13 19:55:50 kevinidea avahi-daemon[937]: Leaving mDNS multicast group on interface enp0s20u1u3.IPv6 with address fe80::4335:e2dc:af72:8556.
Nov 13 19:55:50 kevinidea avahi-daemon[937]: Joining mDNS multicast group on interface enp0s20u1u3.IPv6 with address 2002:95f8:b674:1:8da9:1200:4f70:f250.
Nov 13 19:55:50 kevinidea avahi-daemon[937]: Registering new address record for 2002:95f8:b674:1:8da9:1200:4f70:f250 on enp0s20u1u3.*.
Nov 13 19:55:50 kevinidea avahi-daemon[937]: Withdrawing address record for fe80::4335:e2dc:af72:8556 on enp0s20u1u3.
Nov 13 19:55:50 kevinidea dbus-daemon[1122]: [session uid=1000 pid=1122] Activating service name='org.gnome.OnlineAccounts' requested by ':1.20' (uid=1000 pid=1257 comm="/nix/store/mf91dr2v>
Nov 13 19:55:50 kevinidea dbus-daemon[1122]: [session uid=1000 pid=1122] Activating service name='ca.desrt.dconf' requested by ':1.20' (uid=1000 pid=1257 comm="/nix/store/mf91dr2vcqwh6p2dwf>
Nov 13 19:55:50 kevinidea dbus-daemon[972]: [system] Activating via systemd: service name='org.freedesktop.GeoClue2' unit='geoclue.service' requested by ':1.20' (uid=1000 pid=1179 comm="/ni>
Nov 13 19:55:50 kevinidea dbus-daemon[1122]: [session uid=1000 pid=1122] Successfully activated service 'ca.desrt.dconf'
Nov 13 19:55:50 kevinidea systemd[1]: Starting Location Lookup Service...
Nov 13 19:55:50 kevinidea dbus-daemon[972]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.23' (uid=1000 pid=1263 >
Nov 13 19:55:50 kevinidea dbus-daemon[1122]: [session uid=1000 pid=1122] Successfully activated service 'org.gnome.evolution.dataserver.Sources5'
Nov 13 19:55:50 kevinidea systemd[1115]: Started Evolution source registry.
Nov 13 19:55:50 kevinidea systemd[1]: Starting RealtimeKit Scheduling Policy Service...
Nov 13 19:55:50 kevinidea dbus-daemon[972]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1'
Nov 13 19:55:50 kevinidea systemd[1]: Started RealtimeKit Scheduling Policy Service.
Nov 13 19:55:50 kevinidea rtkit-daemon[1276]: Successfully called chroot.
Nov 13 19:55:50 kevinidea rtkit-daemon[1276]: Successfully dropped privileges.
Nov 13 19:55:50 kevinidea rtkit-daemon[1276]: Successfully limited resources.
Nov 13 19:55:50 kevinidea rtkit-daemon[1276]: Running.
Nov 13 19:55:50 kevinidea rtkit-daemon[1276]: Canary thread running.
Nov 13 19:55:50 kevinidea rtkit-daemon[1276]: Watchdog thread running.
Nov 13 19:55:50 kevinidea rtkit-daemon[1276]: Successfully made thread 1263 of process 1263 owned by 'kevincox' high priority at nice level -11.
Nov 13 19:55:50 kevinidea rtkit-daemon[1276]: Supervising 1 threads of 1 processes of 1 users.
Nov 13 19:55:51 kevinidea dbus-daemon[1122]: [session uid=1000 pid=1122] Activating via systemd: service name='org.gnome.evolution.dataserver.Calendar8' unit='evolution-calendar-factory.ser>
Nov 13 19:55:51 kevinidea systemd[1115]: Starting Evolution calendar service...
Nov 13 19:55:51 kevinidea polkitd[1081]: Registered Authentication Agent for unix-session:1 (system bus name :1.20 [/nix/store/z1j4c8745bksny76k0j78hcvki2d91i7-gnome-shell-3.38.1/bin/gnome->
Nov 13 19:55:51 kevinidea dbus-daemon[1122]: [session uid=1000 pid=1122] Successfully activated service 'org.gnome.Shell.CalendarServer'
Nov 13 19:55:51 kevinidea dbus-daemon[972]: [system] Successfully activated service 'org.freedesktop.GeoClue2'
Nov 13 19:55:51 kevinidea systemd[1]: Started Location Lookup Service.
Nov 13 19:55:51 kevinidea dbus-daemon[972]: [system] Activating via systemd: service name='org.freedesktop.ModemManager1' unit='dbus-org.freedesktop.ModemManager1.service' requested by ':1.>
Nov 13 19:55:51 kevinidea systemd[1]: Starting Modem Manager...
Nov 13 19:55:51 kevinidea dbus-daemon[1122]: [session uid=1000 pid=1122] Activating service name='org.freedesktop.Telepathy.AccountManager' requested by ':1.23' (uid=1000 pid=1179 comm="/ni>
Nov 13 19:55:51 kevinidea ModemManager[1290]: <info>  ModemManager (version 1.12.10) starting in system bus...
Nov 13 19:55:51 kevinidea dbus-daemon[1122]: [session uid=1000 pid=1122] Activating via systemd: service name='org.gtk.vfs.UDisks2VolumeMonitor' unit='gvfs-udisks2-volume-monitor.service' r>
Nov 13 19:55:51 kevinidea rtkit-daemon[1276]: Supervising 1 threads of 1 processes of 1 users.
Nov 13 19:55:51 kevinidea systemd[1115]: Starting Virtual filesystem service - disk device monitor...
Nov 13 19:55:51 kevinidea dbus-daemon[1122]: [session uid=1000 pid=1122] Successfully activated service 'org.freedesktop.Telepathy.AccountManager'
Nov 13 19:55:51 kevinidea rtkit-daemon[1276]: Successfully made thread 1304 of process 1263 owned by 'kevincox' RT at priority 5.
Nov 13 19:55:51 kevinidea rtkit-daemon[1276]: Supervising 2 threads of 1 processes of 1 users.
Nov 13 19:55:51 kevinidea .goa-daemon-wra[1266]: goa-daemon version 3.37.90 starting
Nov 13 19:55:51 kevinidea dbus-daemon[972]: [system] Successfully activated service 'org.freedesktop.ModemManager1'
Nov 13 19:55:51 kevinidea systemd[1]: Started Modem Manager.
Nov 13 19:55:51 kevinidea dbus-daemon[972]: [system] Activating via systemd: service name='org.freedesktop.UDisks2' unit='udisks2.service' requested by ':1.30' (uid=1000 pid=1308 comm="/nix>
Nov 13 19:55:51 kevinidea dbus-daemon[1122]: [session uid=1000 pid=1122] Successfully activated service 'org.gnome.evolution.dataserver.Calendar8'
Nov 13 19:55:51 kevinidea systemd[1115]: Started Evolution calendar service.
Nov 13 19:55:51 kevinidea systemd[1]: Starting Disk Manager...
Nov 13 19:55:51 kevinidea dbus-daemon[1122]: [session uid=1000 pid=1122] Activating service name='org.gnome.Identity' requested by ':1.27' (uid=1000 pid=1266 comm="/nix/store/wmwz46avda2x1i>
Nov 13 19:55:51 kevinidea udisksd[1320]: udisks daemon version 2.8.4 starting
Nov 13 19:55:51 kevinidea dbus-daemon[1122]: [session uid=1000 pid=1122] Successfully activated service 'org.gnome.Identity'
Nov 13 19:55:51 kevinidea udisksd[1320]: Failed to load config files: Failed to get contents of the config dir (/etc/libblockdev/conf.d/)Error opening directory “/etc/libblockdev/conf.d/”: >
Nov 13 19:55:51 kevinidea dbus-daemon[1122]: [session uid=1000 pid=1122] Successfully activated service 'org.gnome.OnlineAccounts'
Nov 13 19:55:51 kevinidea dbus-daemon[1122]: [session uid=1000 pid=1122] Activating via systemd: service name='org.gnome.evolution.dataserver.AddressBook10' unit='evolution-addressbook-fact>
Nov 13 19:55:51 kevinidea systemd[1115]: Starting Evolution address book service...
Nov 13 19:55:51 kevinidea rtkit-daemon[1276]: Supervising 2 threads of 1 processes of 1 users.
Nov 13 19:55:51 kevinidea rtkit-daemon[1276]: Successfully made thread 1341 of process 1263 owned by 'kevincox' RT at priority 5.
Nov 13 19:55:51 kevinidea rtkit-daemon[1276]: Supervising 3 threads of 1 processes of 1 users.
Nov 13 19:55:51 kevinidea rtkit-daemon[1276]: Supervising 3 threads of 1 processes of 1 users.
Nov 13 19:55:51 kevinidea rtkit-daemon[1276]: Successfully made thread 1348 of process 1263 owned by 'kevincox' RT at priority 5.
Nov 13 19:55:51 kevinidea rtkit-daemon[1276]: Supervising 4 threads of 1 processes of 1 users.
Nov 13 19:55:51 kevinidea dbus-daemon[972]: [system] Successfully activated service 'org.freedesktop.UDisks2'
Nov 13 19:55:51 kevinidea systemd[1]: Started Disk Manager.
Nov 13 19:55:51 kevinidea udisksd[1320]: Acquired the name org.freedesktop.UDisks2 on the system message bus
Nov 13 19:55:51 kevinidea dbus-daemon[1122]: [session uid=1000 pid=1122] Successfully activated service 'org.gtk.vfs.UDisks2VolumeMonitor'
Nov 13 19:55:51 kevinidea systemd[1115]: Started Virtual filesystem service - disk device monitor.
Nov 13 19:55:51 kevinidea dbus-daemon[1122]: [session uid=1000 pid=1122] Activating via systemd: service name='org.gtk.vfs.GoaVolumeMonitor' unit='gvfs-goa-volume-monitor.service' requested>
Nov 13 19:55:51 kevinidea systemd[1115]: Starting Virtual filesystem service - GNOME Online Accounts monitor...
Nov 13 19:55:51 kevinidea dbus-daemon[1122]: [session uid=1000 pid=1122] Successfully activated service 'org.gnome.evolution.dataserver.AddressBook10'
Nov 13 19:55:51 kevinidea systemd[1115]: Started Evolution address book service.
Nov 13 19:55:51 kevinidea dbus-daemon[1122]: [session uid=1000 pid=1122] Successfully activated service 'org.gtk.vfs.GoaVolumeMonitor'
Nov 13 19:55:51 kevinidea systemd[1115]: Started Virtual filesystem service - GNOME Online Accounts monitor.
Nov 13 19:55:51 kevinidea dbus-daemon[1122]: [session uid=1000 pid=1122] Activating via systemd: service name='org.gtk.vfs.MTPVolumeMonitor' unit='gvfs-mtp-volume-monitor.service' requested>
Nov 13 19:55:51 kevinidea systemd[1115]: Starting Virtual filesystem service - Media Transfer Protocol monitor...
Nov 13 19:55:51 kevinidea systemd[1115]: Started Sound Service.
Nov 13 19:55:51 kevinidea bluetoothd[938]: Endpoint registered: sender=:1.37 path=/MediaEndpoint/A2DPSource/VENDOR/LDAC
Nov 13 19:55:51 kevinidea pulseaudio[1263]: E: [pulseaudio] backend-ofono.c: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name or>
Nov 13 19:55:51 kevinidea bluetoothd[938]: Endpoint registered: sender=:1.37 path=/MediaEndpoint/A2DPSource/VENDOR/APTXHD
Nov 13 19:55:51 kevinidea bluetoothd[938]: Endpoint registered: sender=:1.37 path=/MediaEndpoint/A2DPSource/VENDOR/APTX
Nov 13 19:55:51 kevinidea bluetoothd[938]: Endpoint registered: sender=:1.37 path=/MediaEndpoint/A2DPSource/AAC
Nov 13 19:55:51 kevinidea bluetoothd[938]: Endpoint registered: sender=:1.37 path=/MediaEndpoint/A2DPSource/SBC
Nov 13 19:55:51 kevinidea bluetoothd[938]: Endpoint registered: sender=:1.37 path=/MediaEndpoint/A2DPSink/VENDOR/APTXHD
Nov 13 19:55:51 kevinidea bluetoothd[938]: Endpoint registered: sender=:1.37 path=/MediaEndpoint/A2DPSink/VENDOR/APTX
Nov 13 19:55:51 kevinidea bluetoothd[938]: Endpoint registered: sender=:1.37 path=/MediaEndpoint/A2DPSink/AAC
Nov 13 19:55:51 kevinidea bluetoothd[938]: Endpoint registered: sender=:1.37 path=/MediaEndpoint/A2DPSink/SBC
Nov 13 19:55:51 kevinidea kernel: Bluetooth: RFCOMM TTY layer initialized
Nov 13 19:55:51 kevinidea kernel: Bluetooth: RFCOMM socket layer initialized
Nov 13 19:55:51 kevinidea kernel: Bluetooth: RFCOMM ver 1.11
Nov 13 19:55:51 kevinidea dbus-daemon[1122]: [session uid=1000 pid=1122] Successfully activated service 'org.gtk.vfs.MTPVolumeMonitor'
Nov 13 19:55:51 kevinidea systemd[1115]: Started Virtual filesystem service - Media Transfer Protocol monitor.
Nov 13 19:55:51 kevinidea dbus-daemon[1122]: [session uid=1000 pid=1122] Activating via systemd: service name='org.gtk.vfs.GPhoto2VolumeMonitor' unit='gvfs-gphoto2-volume-monitor.service' r>
Nov 13 19:55:51 kevinidea systemd[1115]: Starting Virtual filesystem service - digital camera monitor...
Nov 13 19:55:51 kevinidea dbus-daemon[1122]: [session uid=1000 pid=1122] Successfully activated service 'org.gtk.vfs.GPhoto2VolumeMonitor'
Nov 13 19:55:51 kevinidea dbus-daemon[1122]: [session uid=1000 pid=1122] Successfully activated service 'org.gtk.vfs.GPhoto2VolumeMonitor'
Nov 13 19:55:51 kevinidea systemd[1115]: Started Virtual filesystem service - digital camera monitor.
Nov 13 19:55:51 kevinidea dbus-daemon[1122]: [session uid=1000 pid=1122] Activating via systemd: service name='org.gtk.vfs.AfcVolumeMonitor' unit='gvfs-afc-volume-monitor.service' requested>
Nov 13 19:55:51 kevinidea systemd[1115]: Starting Virtual filesystem service - Apple File Conduit monitor...
Nov 13 19:55:51 kevinidea dbus-daemon[1122]: [session uid=1000 pid=1122] Successfully activated service 'org.gtk.vfs.AfcVolumeMonitor'
Nov 13 19:55:51 kevinidea systemd[1115]: Started Virtual filesystem service - Apple File Conduit monitor.
Nov 13 19:55:51 kevinidea systemd[1]: systemd-rfkill.service: Succeeded.
Nov 13 19:55:51 kevinidea dbus-daemon[972]: [system] Activating via systemd: service name='org.freedesktop.PackageKit' unit='packagekit.service' requested by ':1.20' (uid=1000 pid=1179 comm>
Nov 13 19:55:51 kevinidea systemd[1]: Starting PackageKit Daemon...
Nov 13 19:55:51 kevinidea PackageKit[1406]: daemon start
Nov 13 19:55:51 kevinidea dbus-daemon[972]: [system] Successfully activated service 'org.freedesktop.PackageKit'
Nov 13 19:55:51 kevinidea systemd[1]: Started PackageKit Daemon.
Nov 13 19:55:51 kevinidea dbus-daemon[1122]: [session uid=1000 pid=1122] Activating service name='org.gnome.Shell.Notifications' requested by ':1.13' (uid=1000 pid=1179 comm="/nix/store/z1j>
Nov 13 19:55:51 kevinidea at-spi-bus-launcher[1230]: dbus-daemon[1230]: Activating service name='org.a11y.atspi.Registry' requested by ':1.0' (uid=1000 pid=1179 comm="/nix/store/z1j4c8745bk>
Nov 13 19:55:51 kevinidea .gnome-shell-wr[1179]: _clutter_stage_queue_event: assertion 'CLUTTER_IS_STAGE (stage)' failed
Nov 13 19:55:51 kevinidea .gnome-shell-wr[1179]: clutter_do_event: Event does not have a stage: discarding.
Nov 13 19:55:51 kevinidea at-spi-bus-launcher[1230]: dbus-daemon[1230]: Successfully activated service 'org.a11y.atspi.Registry'
Nov 13 19:55:51 kevinidea at-spi-bus-launcher[1420]: SpiRegistry daemon is running with well-known name - org.a11y.atspi.Registry
Nov 13 19:55:51 kevinidea systemd[1115]: Started GNOME Shell on Wayland.
Nov 13 19:55:51 kevinidea systemd[1115]: Reached target GNOME Session is initialized.
Nov 13 19:55:51 kevinidea systemd[1115]: GNOME session X11 services is not active.
Nov 13 19:55:51 kevinidea systemd[1115]: Dependency failed for GNOME XSettings service.
Nov 13 19:55:51 kevinidea systemd[1115]: org.gnome.SettingsDaemon.XSettings.service: Job org.gnome.SettingsDaemon.XSettings.service/start failed with result 'dependency'.
Nov 13 19:55:51 kevinidea systemd[1115]: gnome-session-x11-services-ready.target: Job gnome-session-x11-services-ready.target/verify-active failed with result 'dependency'.
Nov 13 19:55:51 kevinidea systemd[1115]: Reached target GNOME Session (session: gnome).
Nov 13 19:55:51 kevinidea systemd[1115]: Reached target GNOME XSettings target.
Nov 13 19:55:51 kevinidea systemd[1115]: Starting Signal initialization done to GNOME Session Manager...
Nov 13 19:55:51 kevinidea systemd[1115]: Starting GNOME accessibility service...
Nov 13 19:55:51 kevinidea systemd[1115]: Starting GNOME color management service...
Nov 13 19:55:51 kevinidea systemd[1115]: Starting GNOME date & time service...
Nov 13 19:55:51 kevinidea systemd[1115]: Starting GNOME maintenance of expirable data service...
Nov 13 19:55:51 kevinidea systemd[1115]: Starting GNOME keyboard configuration service...
Nov 13 19:55:51 kevinidea systemd[1115]: Starting GNOME keyboard shortcuts service...
Nov 13 19:55:51 kevinidea systemd[1115]: Starting GNOME power management service...
Nov 13 19:55:51 kevinidea systemd[1115]: Starting GNOME printer notifications service...
Nov 13 19:55:51 kevinidea systemd[1115]: Starting GNOME RFKill support service...
Nov 13 19:55:51 kevinidea systemd[1115]: Starting GNOME FreeDesktop screensaver service...
Nov 13 19:55:51 kevinidea systemd[1115]: Starting GNOME file sharing service...
Nov 13 19:55:51 kevinidea systemd[1115]: Starting GNOME smartcard service...
Nov 13 19:55:51 kevinidea systemd[1115]: Starting GNOME sound sample caching service...
Nov 13 19:55:51 kevinidea systemd[1115]: Starting GNOME USB protection service...
Nov 13 19:55:51 kevinidea systemd[1115]: Starting GNOME Wacom tablet support service...
Nov 13 19:55:51 kevinidea dbus-daemon[1122]: [session uid=1000 pid=1122] Successfully activated service 'org.gnome.Shell.Notifications'
Nov 13 19:55:51 kevinidea gnome-session-binary[1154]: Entering running state
Nov 13 19:55:51 kevinidea systemd[1115]: gnome-session-signal-init.service: Succeeded.
Nov 13 19:55:51 kevinidea systemd[1115]: Finished Signal initialization done to GNOME Session Manager.
Nov 13 19:55:51 kevinidea systemd[1115]: Started GNOME file sharing service.
Nov 13 19:55:51 kevinidea systemd[1115]: Started GNOME accessibility service.
Nov 13 19:55:51 kevinidea systemd[1115]: Started GNOME maintenance of expirable data service.
Nov 13 19:55:51 kevinidea systemd[1115]: Started GNOME printer notifications service.
Nov 13 19:55:51 kevinidea gnome-session[1154]: gnome-session-binary[1154]: GnomeDesktop-WARNING: Could not create transient scope for PID 1484: GDBus.Error:org.freedesktop.DBus.Error.UnixPr>
Nov 13 19:55:51 kevinidea gnome-session-binary[1154]: GnomeDesktop-WARNING: Could not create transient scope for PID 1484: GDBus.Error:org.freedesktop.DBus.Error.UnixProcessIdUnknown: Proce>
Nov 13 19:55:51 kevinidea dbus-daemon[972]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.41' (uid>
Nov 13 19:55:51 kevinidea kernel: rfkill: input handler disabled
Nov 13 19:55:51 kevinidea systemd[1115]: Started GNOME FreeDesktop screensaver service.
Nov 13 19:55:51 kevinidea systemd[1115]: Started GNOME USB protection service.
Nov 13 19:55:51 kevinidea systemd[1115]: Started GNOME RFKill support service.
Nov 13 19:55:51 kevinidea systemd[1]: Starting Time & Date Service...
Nov 13 19:55:51 kevinidea systemd[1115]: Started Application launched by gnome-session-binary.
Nov 13 19:55:51 kevinidea systemd[1115]: Started Application launched by gnome-session-binary.
Nov 13 19:55:51 kevinidea systemd[1115]: Started Application launched by gnome-session-binary.
Nov 13 19:55:51 kevinidea systemd[1115]: Started Application launched by gnome-session-binary.
Nov 13 19:55:51 kevinidea systemd[1115]: Reached target GNOME accessibility target.
Nov 13 19:55:51 kevinidea systemd[1115]: Reached target GNOME maintenance of expirable data target.
Nov 13 19:55:51 kevinidea systemd[1115]: Reached target GNOME printer notifications target.
Nov 13 19:55:51 kevinidea systemd[1115]: Reached target GNOME RFKill support target.
Nov 13 19:55:51 kevinidea systemd[1115]: Reached target GNOME FreeDesktop screensaver target.
Nov 13 19:55:51 kevinidea systemd[1115]: Reached target GNOME file sharing target.
Nov 13 19:55:51 kevinidea systemd[1115]: Reached target GNOME USB protection target.
Nov 13 19:55:51 kevinidea systemd[1115]: Started GnuPG cryptographic agent and passphrase cache.
Nov 13 19:55:51 kevinidea systemd[1115]: Started GNOME smartcard service.
Nov 13 19:55:51 kevinidea systemd[1115]: Reached target GNOME smartcard target.
Nov 13 19:55:51 kevinidea gpg-agent[1552]: gpg-agent (GnuPG) 2.2.23 starting in supervised mode.
Nov 13 19:55:51 kevinidea gpg-agent[1552]: using fd 3 for std socket (/run/user/1000/gnupg/S.gpg-agent)
Nov 13 19:55:51 kevinidea gpg-agent[1552]: using fd 4 for ssh socket (/run/user/1000/gnupg/S.gpg-agent.ssh)
Nov 13 19:55:51 kevinidea gpg-agent[1552]: listening on: std=3 extra=-1 browser=-1 ssh=4
Nov 13 19:55:51 kevinidea gpg-agent.desktop[1491]: gpg-agent: a gpg-agent is already running - not starting a new one
Nov 13 19:55:51 kevinidea systemd[1115]: app-gnome-gpg\x2dagent-1491.scope: Succeeded.
Nov 13 19:55:51 kevinidea systemd[1115]: Started GNOME sound sample caching service.
Nov 13 19:55:51 kevinidea systemd[1115]: Reached target GNOME sound sample caching target.
Nov 13 19:55:51 kevinidea dbus-daemon[972]: [system] Successfully activated service 'org.freedesktop.UPower'
Nov 13 19:55:51 kevinidea systemd[1]: Started Daemon for power management.
Nov 13 19:55:51 kevinidea .gnome-shell-wr[1179]: _clutter_stage_queue_event: assertion 'CLUTTER_IS_STAGE (stage)' failed
Nov 13 19:55:51 kevinidea .gnome-shell-wr[1179]: clutter_do_event: Event does not have a stage: discarding.
Nov 13 19:55:51 kevinidea dbus-daemon[972]: [system] Successfully activated service 'org.freedesktop.timedate1'
Nov 13 19:55:51 kevinidea systemd[1]: Started Time & Date Service.
Nov 13 19:55:51 kevinidea .gnome-shell-wr[1179]: _clutter_stage_queue_event: assertion 'CLUTTER_IS_STAGE (stage)' failed
Nov 13 19:55:51 kevinidea .gnome-shell-wr[1179]: clutter_do_event: Event does not have a stage: discarding.
Nov 13 19:55:51 kevinidea .gnome-shell-wr[1179]: _clutter_stage_queue_event: assertion 'CLUTTER_IS_STAGE (stage)' failed
Nov 13 19:55:51 kevinidea .gnome-shell-wr[1179]: clutter_do_event: Event does not have a stage: discarding.
Nov 13 19:55:51 kevinidea .gsd-usb-protec[1458]: Failed to fetch USBGuard parameters: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name org.usbguard1 was not provided by any .>
Nov 13 19:55:51 kevinidea avahi-daemon[937]: Joining mDNS multicast group on interface enp0s20u1u3.IPv4 with address 192.168.0.184.
Nov 13 19:55:51 kevinidea avahi-daemon[937]: New relevant interface enp0s20u1u3.IPv4 for mDNS.
Nov 13 19:55:51 kevinidea avahi-daemon[937]: Registering new address record for 192.168.0.184 on enp0s20u1u3.IPv4.
Nov 13 19:55:51 kevinidea dbus-daemon[972]: [system] Activating via systemd: service name='org.freedesktop.resolve1' unit='dbus-org.freedesktop.resolve1.service' requested by ':1.6' (uid=0 >
Nov 13 19:55:51 kevinidea dbus-daemon[972]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.resolve1.service': Unit dbus-org.freedesktop.resolve1.service not found.
Nov 13 19:55:51 kevinidea .gnome-shell-wr[1179]: Error looking up permission: GDBus.Error:org.freedesktop.portal.Error.NotFound: No entry for geolocation
Nov 13 19:55:51 kevinidea .gnome-shell-wr[1179]: _clutter_stage_queue_event: assertion 'CLUTTER_IS_STAGE (stage)' failed
Nov 13 19:55:51 kevinidea .gnome-shell-wr[1179]: clutter_do_event: Event does not have a stage: discarding.
Nov 13 19:55:51 kevinidea .gnome-shell-wr[1179]: _clutter_stage_queue_event: assertion 'CLUTTER_IS_STAGE (stage)' failed
Nov 13 19:55:51 kevinidea .gnome-shell-wr[1179]: clutter_do_event: Event does not have a stage: discarding.
Nov 13 19:55:51 kevinidea .gnome-shell-wr[1179]: No permission to trigger offline updates: Polkit.Error: GDBus.Error:org.freedesktop.PolicyKit1.Error.Failed: Action org.freedesktop.packagek>
Nov 13 19:55:51 kevinidea .gnome-shell-wr[1179]: _clutter_stage_queue_event: assertion 'CLUTTER_IS_STAGE (stage)' failed
Nov 13 19:55:51 kevinidea .gnome-shell-wr[1179]: clutter_do_event: Event does not have a stage: discarding.
Nov 13 19:55:51 kevinidea .gnome-shell-wr[1179]: _clutter_stage_queue_event: assertion 'CLUTTER_IS_STAGE (stage)' failed
Nov 13 19:55:51 kevinidea .gnome-shell-wr[1179]: clutter_do_event: Event does not have a stage: discarding.
Nov 13 19:55:51 kevinidea .gnome-shell-wr[1179]: _clutter_stage_queue_event: assertion 'CLUTTER_IS_STAGE (stage)' failed
Nov 13 19:55:51 kevinidea .gnome-shell-wr[1179]: clutter_do_event: Event does not have a stage: discarding.
Nov 13 19:55:51 kevinidea .gnome-shell-wr[1179]: _clutter_stage_queue_event: assertion 'CLUTTER_IS_STAGE (stage)' failed
Nov 13 19:55:51 kevinidea .gnome-shell-wr[1179]: clutter_do_event: Event does not have a stage: discarding.
Nov 13 19:55:51 kevinidea .gnome-shell-wr[1179]: _clutter_stage_queue_event: assertion 'CLUTTER_IS_STAGE (stage)' failed
Nov 13 19:55:51 kevinidea .gnome-shell-wr[1179]: clutter_do_event: Event does not have a stage: discarding.
Nov 13 19:55:51 kevinidea nscd[1003]: 1003 monitored file `/etc/resolv.conf` was written to
Nov 13 19:55:51 kevinidea systemd[1]: Stopping Name Service Cache Daemon...
Nov 13 19:55:51 kevinidea systemd[1]: nscd.service: Succeeded.
Nov 13 19:55:51 kevinidea systemd[1]: Stopped Name Service Cache Daemon.
Nov 13 19:55:51 kevinidea systemd[1]: nscd.service: Consumed 16ms CPU time, received 0B IP traffic, sent 2.7K IP traffic.
Nov 13 19:55:51 kevinidea systemd[1]: Starting Name Service Cache Daemon...
Nov 13 19:55:51 kevinidea avahi-daemon[937]: Got SIGHUP, reloading.
Nov 13 19:55:51 kevinidea avahi-daemon[937]: Failed to read /etc/avahi/services.
Nov 13 19:55:51 kevinidea nscd[1630]: 1630 monitoring file `/etc/passwd` (1)
Nov 13 19:55:51 kevinidea nscd[1630]: 1630 monitoring directory `/etc` (2)
Nov 13 19:55:51 kevinidea nscd[1630]: 1630 monitoring file `/etc/group` (3)
Nov 13 19:55:51 kevinidea nscd[1630]: 1630 monitoring directory `/etc` (2)
Nov 13 19:55:51 kevinidea nscd[1630]: 1630 monitoring file `/etc/hosts` (4)
Nov 13 19:55:51 kevinidea nscd[1630]: 1630 monitoring directory `/etc` (2)
Nov 13 19:55:51 kevinidea nscd[1630]: 1630 monitoring file `/etc/resolv.conf` (5)
Nov 13 19:55:51 kevinidea nscd[1630]: 1630 monitoring directory `/etc` (2)
Nov 13 19:55:51 kevinidea nscd[1630]: 1630 monitoring file `/etc/services` (6)
Nov 13 19:55:51 kevinidea nscd[1630]: 1630 monitoring directory `/etc` (2)
Nov 13 19:55:51 kevinidea nscd[1630]: 1630 disabled inotify-based monitoring for file `/etc/netgroup': No such file or directory
Nov 13 19:55:51 kevinidea nscd[1630]: 1630 stat failed for file `/etc/netgroup'; will try again later: No such file or directory
Nov 13 19:55:51 kevinidea systemd[1]: Started Name Service Cache Daemon.
Nov 13 19:55:51 kevinidea systemd[1115]: Started GNOME date & time service.
Nov 13 19:55:51 kevinidea systemd[1115]: Reached target GNOME date & time target.
Nov 13 19:55:52 kevinidea gnome-shell[1676]: The XKEYBOARD keymap compiler (xkbcomp) reports:
Nov 13 19:55:52 kevinidea gnome-shell[1676]: > Warning:          Unsupported high keycode 372 for name <I372> ignored
Nov 13 19:55:52 kevinidea gnome-shell[1676]: >                   X11 cannot support keycodes above 255.
Nov 13 19:55:52 kevinidea gnome-shell[1676]: >                   This warning only shows for the first high keycode.
Nov 13 19:55:52 kevinidea gnome-shell[1676]: Errors from xkbcomp are not fatal to the X server
Nov 13 19:55:52 kevinidea dbus-daemon[972]: [system] Activating via systemd: service name='org.freedesktop.ColorManager' unit='colord.service' requested by ':1.50' (uid=1000 pid=1436 comm=">
Nov 13 19:55:52 kevinidea systemd[1115]: Started GNOME color management service.
Nov 13 19:55:52 kevinidea systemd[1115]: Reached target GNOME color management target.
Nov 13 19:55:52 kevinidea systemd[1115]: Started GNOME Wacom tablet support service.
Nov 13 19:55:52 kevinidea systemd[1115]: Reached target GNOME Wacom tablet support target.
Nov 13 19:55:52 kevinidea systemd[1]: Starting Locale Service...
Nov 13 19:55:52 kevinidea systemd[1115]: Started GNOME keyboard configuration service.
Nov 13 19:55:52 kevinidea systemd[1115]: Reached target GNOME keyboard configuration target.
Nov 13 19:55:52 kevinidea systemd[1115]: Started GNOME keyboard shortcuts service.
Nov 13 19:55:52 kevinidea systemd[1115]: Reached target GNOME keyboard shortcuts target.
Nov 13 19:55:52 kevinidea systemd[1115]: Started GNOME power management service.
Nov 13 19:55:52 kevinidea systemd[1115]: Reached target GNOME power management target.
Nov 13 19:55:52 kevinidea systemd[1115]: Reached target GNOME Session.
Nov 13 19:55:52 kevinidea systemd[1115]: Reached target GNOME Wayland Session (session: gnome).
Nov 13 19:55:52 kevinidea systemd[1115]: Reached target Current graphical user session.
Nov 13 19:55:52 kevinidea systemd[1115]: Condition check resulted in GNOME Initial Setup being skipped.
Nov 13 19:55:52 kevinidea systemd[1115]: Condition check resulted in GNOME Welcome Tour being skipped.
Nov 13 19:55:52 kevinidea dbus-daemon[1122]: [session uid=1000 pid=1122] Activating service name='org.gnome.Shell.Screencast' requested by ':1.53' (uid=1000 pid=1441 comm="/nix/store/5fpcfn>
Nov 13 19:55:52 kevinidea dbus-daemon[972]: [system] Successfully activated service 'org.freedesktop.ColorManager'
Nov 13 19:55:52 kevinidea systemd[1]: Started Manage, Install and Generate Color Profiles.
Nov 13 19:55:52 kevinidea dbus-daemon[1122]: [session uid=1000 pid=1122] Activating via systemd: service name='org.gtk.vfs.Metadata' unit='gvfs-metadata.service' requested by ':1.64' (uid=1>
Nov 13 19:55:52 kevinidea systemd[1115]: Starting Virtual filesystem metadata service...
Nov 13 19:55:52 kevinidea dbus-daemon[972]: [system] Successfully activated service 'org.freedesktop.locale1'
Nov 13 19:55:52 kevinidea systemd[1]: Started Locale Service.
Nov 13 19:55:52 kevinidea dbus-daemon[1122]: [session uid=1000 pid=1122] Successfully activated service 'org.gtk.vfs.Metadata'
Nov 13 19:55:52 kevinidea systemd[1115]: Started Virtual filesystem metadata service.
Nov 13 19:55:52 kevinidea .gsd-media-keys[1441]: Failed to grab accelerator for keybinding settings:playback-repeat
Nov 13 19:55:52 kevinidea .gsd-media-keys[1441]: Failed to grab accelerator for keybinding settings:rfkill
Nov 13 19:55:52 kevinidea .gsd-media-keys[1441]: Failed to grab accelerator for keybinding settings:hibernate
Nov 13 19:55:52 kevinidea .gsd-media-keys[1441]: Failed to grab accelerator for keybinding settings:playback-random
Nov 13 19:55:52 kevinidea .gnome-shell-wr[1179]: Failed to launch ibus-daemon: Failed to execute child process “ibus-daemon” (No such file or directory)
Nov 13 19:55:52 kevinidea systemd[1115]: Reached target GNOME session X11 services.
Nov 13 19:55:52 kevinidea systemd[1115]: Starting GNOME XSettings service...
Nov 13 19:55:52 kevinidea gnome-shell[1718]: The XKEYBOARD keymap compiler (xkbcomp) reports:
Nov 13 19:55:52 kevinidea gnome-shell[1718]: > Warning:          Unsupported maximum keycode 569, clipping.
Nov 13 19:55:52 kevinidea gnome-shell[1718]: >                   X11 cannot support keycodes above 255.
Nov 13 19:55:52 kevinidea gnome-shell[1718]: > Warning:          Unsupported high keycode 372 for name <I372> ignored
Nov 13 19:55:52 kevinidea gnome-shell[1718]: >                   X11 cannot support keycodes above 255.
Nov 13 19:55:52 kevinidea gnome-shell[1718]: >                   This warning only shows for the first high keycode.
Nov 13 19:55:52 kevinidea gnome-shell[1718]: Errors from xkbcomp are not fatal to the X server
Nov 13 19:55:52 kevinidea mono[1522]: g_application_get_is_remote: assertion 'application->priv->is_registered' failed
Nov 13 19:55:52 kevinidea .colord-wrapped[1688]: failed to get session [pid 1436]: No data available
Nov 13 19:55:52 kevinidea .colord-wrapped[1688]: failed to get session [pid 1436]: No data available
Nov 13 19:55:52 kevinidea .colord-wrapped[1688]: failed to get session [pid 1436]: No data available
Nov 13 19:55:52 kevinidea systemd[1115]: Started GNOME XSettings service.
Nov 13 19:55:52 kevinidea systemd[1115]: Reached target GNOME session X11 services.
Nov 13 19:55:52 kevinidea avahi-daemon[937]: Registering new address record for 2002:95f8:b674:1:3c24:57d9:937c:6e43 on enp0s20u1u3.*.
Nov 13 19:55:52 kevinidea gnome-shell[1239]: WARNING: Kernel has no file descriptor comparison support: Function not implemented
Nov 13 19:55:52 kevinidea .gnome-shell-wr[1179]: GNOME Shell started at Fri Nov 13 2020 19:55:51 GMT-0500 (EST)
Nov 13 19:55:52 kevinidea .gnome-shell-wr[1179]: Registering session with GDM
Nov 13 19:55:52 kevinidea dbus-daemon[1122]: [session uid=1000 pid=1122] Successfully activated service 'org.gnome.Shell.Screencast'
Nov 13 19:55:53 kevinidea gdm-autologin][1110]: pam_unix(gdm-autologin:session): session closed for user kevincox
Nov 13 19:55:53 kevinidea systemd[1115]: Stopped target GNOME Wayland Session (session: gnome).
Nov 13 19:55:53 kevinidea systemd[1115]: Stopped target GNOME Session.
Nov 13 19:55:53 kevinidea systemd[1115]: Stopped target GNOME session X11 services.
Nov 13 19:55:53 kevinidea systemd[1115]: Stopped target GNOME Session (session: gnome).
kevincox commented 3 years ago

Some possibly relevant config. If a maintainer things seeing my whole config might be useful email me.

        services.xserver = {
            enable = true;
            displayManager = {
                gdm.enable = true;
                gdm.nvidiaWayland = true;
                autoLogin = {
                    enable = true;
                    user = "kevincox";
                };
            };
            desktopManager.gnome3.enable = true;
        };
worldofpeace commented 3 years ago

gdm.nvidiaWayland = true;

I'm not even sure if the wayland session works for nvidia users. I added support but I don't have the means to make sure it works

kevincox commented 3 years ago

It doesn't. But I can flip between Intel and NVIDIA GPUs but flipping the wayland flag and rebuilding.

iclanzan commented 3 years ago

For what it’s worth, I don’t have any extensions installed. Tried both Xorg and Wayland sessions, using Intel GPU.

worldofpeace commented 3 years ago

For what it’s worth, I don’t have any extensions installed. Tried both Xorg and Wayland sessions, using Intel GPU.

Unstable and not 20.09 right?

worldofpeace commented 3 years ago

What's rather interesting is how our tests all use auto-login without a problem...

kevincox commented 3 years ago

Yeah, I'm on unstable.

SCOTT-HAMILTON commented 3 years ago

Could you reproduce your issue with the gnome3 test machine? Not sure if this would be relevant but you could apply this patch (and more to get something as close as possible to your config). And then run

nix-build -A nixosTests.gnome3

I don't get any issues with it but I guess this must be done on your machine as it looks like an hardware specific issue.

iclanzan commented 3 years ago

Unstable and not 20.09 right?

Correct, I am on commit https://github.com/NixOS/nixpkgs/commit/a371c1071161104d329f6a85d922fd92b7cbab63. Gnome 3.36 which is on 20.09 works fine.

spacekitteh commented 3 years ago

I can't even login normally on unstable, it gets the Gnome "oh no!" error. FWIW I don't have Nvidia hardware.

RPGHank commented 3 years ago

I have the same bug but sometimes GNOME automatically restarts after crashing, but into an X11 session despite having set Wayland as the default I discovered that GNOME shell doesn't crash when it takes a long time to boot and logs in normally into a normal Wayland session (which might make the bug harder to reproduce) Maybe services.xserver.displayManager.gdm.autoLogin.delay might help then? I'm on unstable as well btw

kevincox commented 3 years ago

I think I did have my session restarting into X for a while (I didn't actually check it was X but I couldn't configure per-display scaling so it would make sense). However after a recent update (sometime this week) it seems to have been crashing to GDM again.

The delay is an interesting idea. I'll try that out. If you notice that slow boots prevent this issue maybe it is an unspecified dependency that is racing?

RPGHank commented 3 years ago

I meant slow boots prevent this issue

kevincox commented 3 years ago

Sorry, typo, fixed. It makes sense that way because it gives enough time for the dep to start and then it works. If it doesn't start in time it goes boom.

RPGHank commented 3 years ago

While not ideal setting services.xserver.displayManager.gdm.autoLogin.delay = 1; worked well enough for me for now

kevincox commented 3 years ago

Yeah, it seems to be working for me too. Just a bit of slow down on the login screen. I'll have to do more snooping at the logs. Presumably it should be fairly easy to see what services finish startup after GNOME crashes, and those are probably things that should be listed as dependencies.

Or slightly more difficult to spot it could be something that reports that it has started but isn't actually ready.

iclanzan commented 3 years ago

This issue is still present on Gnome 40 with the latest nixpkgs.

iclanzan commented 3 years ago

The workaround of adding a delay doesn’t seem to work anymore either. It just disables autoLogin.

These errors seem related:

gnome-session-binary[1838]: WARNING: Failed to upload environment to systemd: GDBus.Error:org.freedesktop.DBus.Error.NameHasNoOwner: Name "org.freedesktop.systemd1" does not exis
gnome-session-binary[1838]: WARNING: Failed to upload environment to systemd: GDBus.Error:org.freedesktop.DBus.Error.NameHasNoOwner: Name "org.freedesktop.systemd1" does not exist
gnome-session-binary[1838]: WARNING: Failed to reset failed state of units: GDBus.Error:org.freedesktop.DBus.Error.NameHasNoOwner: Name "org.freedesktop.systemd1" does not exist
gnome-session-binary[1838]: WARNING: Failed to reset failed state of units: GDBus.Error:org.freedesktop.DBus.Error.NameHasNoOwner: Name "org.freedesktop.systemd1" does not exist
gnome-session-binary[1838]: WARNING: Falling back to non-systemd startup procedure due to error: GDBus.Error:org.freedesktop.DBus.Error.NameHasNoOwner: Name "org.freedesktop.systemd1" does not exist
gnome-session-binary[1838]: WARNING: Falling back to non-systemd startup procedure due to error: GDBus.Error:org.freedesktop.DBus.Error.NameHasNoOwner: Name "org.freedesktop.systemd1" does not exist
dasj19 commented 3 years ago

I can reproduce this as well. Tested in a VM with the following configuration:

services.xserver.displayManager.autoLogin.user = "daniel";
services.xserver.displayManager.autoLogin.enable = true;

The result is: user lands at a tty login instead of getting to gdm

dasj19 commented 3 years ago

Relevant logs:

May 11 17:04:07 nixos gdm[804]: Gdm: GdmSession: starting conversation gdm-autologin for session (0x2030000)
May 11 17:04:07 nixos gdm[804]: Gdm: GdmSessionWorkerJob: Starting worker...
May 11 17:04:07 nixos gdm[804]: Gdm: GdmSessionWorkerJob: Running session_worker_job process: gdm-session-worker [pam/gdm-autologin] /nix/store/hfkas3kbxmhrly34fq293x8yksdg6dar-gdm-40.0/libexec/gdm-session-worker
May 11 17:04:07 nixos gdm[804]: Gdm: GdmSessionWorkerJob: : SessionWorkerJob on pid 820
May 11 17:04:07 nixos gdm[804]: accountsservice: ActUserManager: finished handling request for user 'daniel'
May 11 17:04:07 nixos gdm[804]: accountsservice: ActUserManager: unrefing manager owned by fetch user request
May 11 17:04:07 nixos gdm-autologin][820]: Gdm: Enabling debugging
May 11 17:04:07 nixos gdm-autologin][820]: Gdm: GdmSessionWorker: connecting to address: unix:abstract=/tmp/dbus-V2mrQeIL
May 11 17:04:07 nixos gdm[804]: GdmDBusServer: new connection 0x1ff5680
May 11 17:04:07 nixos gdm[804]: Gdm: GdmSession: Handling new connection from worker
May 11 17:04:07 nixos gdm[804]: Gdm: GdmSession: Authenticating new connection
May 11 17:04:07 nixos gdm-autologin][820]: accountsservice: ActUserManager: Seat still actively loading, so not setting loaded property
May 11 17:04:07 nixos gdm-autologin][820]: accountsservice: Failed to identify the current session: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name org.freedesktop.ConsoleKit was not provided by any .service files
May 11 17:04:07 nixos gdm-autologin][820]: accountsservice: ActUserManager: seat unloaded, so trying to set loaded property
May 11 17:04:07 nixos gdm-autologin][820]: accountsservice: ActUserManager: Seat wouldn't load, so giving up on it and setting loaded property
May 11 17:04:07 nixos gdm[804]: Gdm: GdmSession: worker connection is 0x1ff5680
May 11 17:04:07 nixos gdm[804]: Gdm: GdmSession: Emitting conversation-started signal
May 11 17:04:07 nixos gdm[804]: Gdm: GdmManager: session conversation started for service gdm-autologin on session
May 11 17:04:07 nixos gdm[804]: Gdm: GdmDisplay: Got automatic login details for display: 1 daniel
May 11 17:04:07 nixos gdm[804]: Gdm: GdmManager: begin auto login for user 'daniel'
May 11 17:04:07 nixos gdm[804]: Gdm: GdmSession: getting session command for file 'gnome.desktop'
May 11 17:04:07 nixos gdm[804]: Gdm: GdmSession: checking if file 'gnome.desktop' is wayland session: yes
May 11 17:04:07 nixos gdm[804]: Gdm: GdmSession: setting session to type 'wayland'
May 11 17:04:07 nixos gdm[804]: Gdm: GdmSession: Set up service gdm-autologin for username daniel on session (0x2030000)
May 11 17:04:07 nixos gdm[804]: Gdm: GdmSession: selecting user 'daniel' for session '(null)' (0x2030000)
May 11 17:04:07 nixos gdm[804]: Gdm: GdmSession: Beginning initialization
May 11 17:04:07 nixos gdm[804]: Gdm: GdmSession: getting session command for file 'gnome.desktop'
May 11 17:04:07 nixos gdm[804]: Gdm: GdmSession: checking if file 'gnome.desktop' is wayland session: yes
May 11 17:04:07 nixos gdm[804]: Gdm: GdmSession: Conversation started
May 11 17:04:07 nixos gdm-autologin][820]: accountsservice: ActUserManager: trying to track new user with username daniel
May 11 17:04:07 nixos gdm-autologin][820]: accountsservice: ActUserManager: finding user 'daniel' state 1
May 11 17:04:07 nixos gdm-autologin][820]: accountsservice: ActUserManager: finding user 'daniel' state 2
May 11 17:04:07 nixos gdm-autologin][820]: accountsservice: ActUserManager: Looking for user 'daniel' in accounts service
May 11 17:04:07 nixos gdm-autologin][820]: accountsservice: ActUserManager: Found object path of user 'daniel': /org/freedesktop/Accounts/User1000
May 11 17:04:07 nixos gdm-autologin][820]: accountsservice: ActUserManager: finding user 'daniel' state 3
May 11 17:04:07 nixos gdm-autologin][820]: accountsservice: ActUserManager: user 'daniel' fetched
May 11 17:04:07 nixos gdm-autologin][820]: accountsservice: ActUserManager: user daniel is now loaded
May 11 17:04:07 nixos gdm-autologin][820]: accountsservice: ActUserManager: user daniel was not yet known, adding it
May 11 17:04:07 nixos gdm-autologin][820]: accountsservice: ActUserManager: tracking user 'daniel'
May 11 17:04:07 nixos gdm-autologin][820]: accountsservice: ActUserManager: not yet loaded, so not emitting user-added signal
May 11 17:04:07 nixos gdm-autologin][820]: accountsservice: ActUserManager: no pending users, trying to set loaded property
May 11 17:04:07 nixos gdm-autologin][820]: accountsservice: ActUserManager: already loaded, so not setting loaded property
May 11 17:04:07 nixos gdm-autologin][820]: Gdm: GdmSessionSettings: saved session is
May 11 17:04:07 nixos gdm-autologin][820]: Gdm: GdmSessionWorker: Saved session is
May 11 17:04:07 nixos gdm-autologin][820]: Gdm: GdmSessionSettings: saved language is
May 11 17:04:07 nixos gdm-autologin][820]: Gdm: GdmSessionWorker: Saved language is
May 11 17:04:07 nixos gdm-autologin][820]: Gdm: GdmSessionWorker: queuing setup for user: daniel (null)
May 11 17:04:07 nixos gdm-autologin][820]: accountsservice: ActUserManager: finished handling request for user 'daniel'
May 11 17:04:07 nixos gdm-autologin][820]: accountsservice: ActUserManager: unrefing manager owned by fetch user request
May 11 17:04:07 nixos gdm-autologin][820]: Gdm: GdmSessionWorker: attempting to change state to SETUP_COMPLETE
May 11 17:04:07 nixos gdm-autologin][820]: Gdm: GdmSessionWorker: initializing PAM; service=gdm-autologin username=daniel seat=seat0
May 11 17:04:07 nixos gdm[804]: Gdm: GdmSession: getting session command for file '.desktop'
May 11 17:04:07 nixos gdm[804]: Gdm: GdmSession: File '.desktop' not found: Valid key file could not be found in search dirs
May 11 17:04:07 nixos gdm[804]: Gdm: GdmSession: not using invalid .dmrc session:
May 11 17:04:07 nixos gdm[804]: Gdm: GdmSession: getting session command for file 'gnome.desktop'
May 11 17:04:07 nixos gdm[804]: Gdm: GdmSession: checking if file 'gnome.desktop' is wayland session: yes
May 11 17:04:07 nixos gdm-autologin][820]: Gdm: GdmSessionWorker: Set PAM environment variable: 'XDG_SEAT=seat0'
May 11 17:04:07 nixos gdm-autologin][820]: Gdm: GdmSessionWorker: state SETUP_COMPLETE
May 11 17:04:07 nixos gdm-autologin][820]: Gdm: GdmSessionWorker: attempting to change state to AUTHENTICATED
May 11 17:04:07 nixos gdm-autologin][820]: Gdm: GdmSessionWorker: authenticating user daniel
May 11 17:04:07 nixos gdm-autologin][820]: Gdm: GdmSessionWorker: state AUTHENTICATED
May 11 17:04:07 nixos gdm-autologin][820]: Gdm: GdmSessionWorker: trying to get updated username
May 11 17:04:07 nixos gdm-autologin][820]: Gdm: GdmSessionWorker: username is 'daniel'
May 11 17:04:07 nixos gdm-autologin][820]: Gdm: GdmSessionWorker: old-username='daniel' new-username='daniel'
May 11 17:04:07 nixos gdm-autologin][820]: Gdm: GdmSessionWorker: attempting to change state to AUTHORIZED
May 11 17:04:07 nixos gdm-autologin][820]: Gdm: GdmSessionWorker: determining if authenticated user (password required:0) is authorized to session
May 11 17:04:07 nixos gdm-autologin][820]: Gdm: GdmSessionWorker: state AUTHORIZED
May 11 17:04:07 nixos gdm-autologin][820]: Gdm: GdmSessionWorker: attempting to change state to ACCREDITED
May 11 17:04:07 nixos gdm-autologin][820]: Gdm: forwarding GDM_X_SERVER_EXTRA_ARGS= -config /nix/store/7wx12hxk7v6p8dhdk3dx924y3gam8a4n-xserver.conf -xkbdir /nix/store/577xacg3cbzzg5gn6s2ccxxr0azd2fyz-xkeyboard-config-2.31/etc/X11/xkb -logfile /dev/null -nolisten tcp
May 11 17:04:07 nixos gdm-autologin][820]: Gdm: GdmSessionWorker: Set PAM environment variable: 'GDM_X_SERVER_EXTRA_ARGS=-config /nix/store/7wx12hxk7v6p8dhdk3dx924y3gam8a4n-xserver.conf -xkbdir /nix/store/577xacg3cbzzg5gn6s2ccxxr0azd2fyz-xkeyboard-config-2.31/etc/X11/xkb -logfile /dev/null -nolisten tcp'
May 11 17:04:07 nixos gdm-autologin][820]: Gdm: GdmSessionWorker: Set PAM environment variable: 'LOGNAME=daniel'
May 11 17:04:07 nixos gdm-autologin][820]: Gdm: GdmSessionWorker: Set PAM environment variable: 'USER=daniel'
May 11 17:04:07 nixos gdm-autologin][820]: Gdm: GdmSessionWorker: Set PAM environment variable: 'USERNAME=daniel'
May 11 17:04:07 nixos gdm-autologin][820]: Gdm: GdmSessionWorker: Set PAM environment variable: 'HOME=/home/daniel'
May 11 17:04:07 nixos gdm-autologin][820]: Gdm: GdmSessionWorker: Set PAM environment variable: 'PWD=/home/daniel'
May 11 17:04:07 nixos gdm-autologin][820]: Gdm: GdmSessionWorker: Set PAM environment variable: 'SHELL=/run/current-system/sw/bin/bash'
May 11 17:04:07 nixos gdm-autologin][820]: Gdm: GdmSessionWorker: Set PAM environment variable: 'PATH=/nix/store/hfkas3kbxmhrly34fq293x8yksdg6dar-gdm-40.0/bin:/usr/local/bin:/usr/local/sbin:/usr/bin:/usr/sbin:/bin:/sbin'
May 11 17:04:07 nixos gdm-autologin][820]: Gdm: GdmSessionWorker: state ACCREDITED
May 11 17:04:07 nixos gdm[804]: Gdm: GdmSession: type wayland, program? no, seat seat0
May 11 17:04:07 nixos gdm-autologin][820]: Gdm: GdmSessionWorker: session display mode set to new-vt
May 11 17:04:07 nixos gdm-autologin][820]: Gdm: GdmSessionWorker: Set PAM environment variable: 'XDG_SESSION_TYPE=wayland'
May 11 17:04:07 nixos gdm-autologin][820]: Gdm: GdmSessionWorker: attempting to change state to ACCOUNT_DETAILS_SAVED
May 11 17:04:07 nixos gdm-autologin][820]: Gdm: GdmSessionWorker: saving account details for user daniel
May 11 17:04:07 nixos gdm[804]: accountsservice: ActUserManager: sending user-changed signal for user daniel
May 11 17:04:07 nixos gdm[804]: accountsservice: ActUserManager: sent user-changed signal for user daniel
May 11 17:04:07 nixos gdm[804]: accountsservice: ActUserManager: updating user daniel
May 11 17:04:07 nixos gdm-autologin][820]: accountsservice: ActUserManager: sending user-changed signal for user daniel
May 11 17:04:07 nixos gdm-autologin][820]: accountsservice: ActUserManager: sent user-changed signal for user daniel
May 11 17:04:07 nixos gdm-autologin][820]: accountsservice: ActUserManager: updating user daniel
May 11 17:04:07 nixos gdm-autologin][820]: Gdm: GdmSessionWorker: attempting to change state to SESSION_OPENED
May 11 17:04:07 nixos gdm-autologin][820]: Gdm: GdmSessionWorker: Set PAM environment variable: 'XDG_VTNR=1'
May 11 17:04:07 nixos gdm-autologin][820]: pam_unix(gdm-autologin:session): session opened for user daniel(uid=1000) by (uid=0)

The entire log here: https://pastebin.com/276jRc6f (expires in 1 year)

juanibiapina commented 3 years ago

I have a crash as well. Setting autoLogin.delay simply disabled autologin.

kevincox commented 3 years ago

Yeah, the delay also doesn't work for my anymore.

kyren commented 3 years ago

Instead of autoLogin.delay which seems to now simply disable auto login, you can do something like services.xserver.displayManager.job.preStart = "sleep 5"; which is even more of a huge hack, but it seems to work.

kevincox commented 3 years ago

Unfortunately job.preStart appears to force X for me. :(

bjornfor commented 3 years ago

Unfortunately job.preStart appears to force X for me. :(

Not for me, I still see "Windowing System - Wayland" in the About dialog (and process listings also confirm this).

I noticed that Ubuntu has ExecStartPre=/usr/lib/gdm3/gdm-wait-for-drm in its display-manager.service file. There's some other differences in that .service file that might be worth looking into.

ius commented 2 years ago

TL;DR

Append the following to configuration.nix for a workaround:

systemd.services."getty@tty1".enable = false;
systemd.services."autovt@tty1".enable = false;

Details

Lines from @dasj19's logs show the issue is gdm-wayland-session is killed by SIGHUP.

May 11 17:04:08 nixos gdm-wayland-session[839]: Gdm: Running wayland session
May 11 17:04:11 nixos gdm-autologin][820]: Gdm: GdmSessionWorker: child (pid:839) done (signal:1)
May 11 17:04:11 nixos gdm[804]: Gdm: GdmManager: session died with signal Hangup

To figure out the source of the SIGHUP I used kernel event tracing, which reveals the signal is sent by agetty. Indeed, there's a getty on tty1 - in fact, gdm spawns the session on the same tty.

May 11 17:04:06 nixos systemd[1]: Started Getty on tty1.
May 11 17:04:07 nixos gdm-autologin][820]: Gdm: GdmSessionWorker: jumping to VT 1

... which is somehow (race?) problematic because of:

cat /nix/store/n5j5fjn60nhck658j9ab84k8n9z24n1r-systemd-247.6/example/systemd/system/getty@.service | grep TTY
# the VT is cleared by TTYVTDisallocate
TTYPath=/dev/%I
TTYReset=yes
TTYVHangup=yes
TTYVTDisallocate=yes

TTYVHangup=: Disconnect all clients which have opened the terminal device specified with TTYPath= before and after execution. Defaults to "no".

In fact we're telling gdm to spawn on vt7 (but it doesn't): https://github.com/NixOS/nixpkgs/blob/eebfe7199d9e543acea19de4af15a91ab7774e7c/pkgs/desktops/gnome/core/gdm/default.nix#L169-L171

Upstream ships its systemd unit with Conflicts=tty1@getty.service, which is modified by NixOS to initialVT: 7 in this case. Somehow we're starting on VT 1, which might have a getty spawned (thus killing the session).

Fixing

As for spawning on VT1 instead of VT7: I think this might be somehow related to moving over to Wayland instead of X. Google it and you'll discover that VT1 is where most (if not all) distro's spawn GDM these days.

There's quite some history behind configuring VT7 instead of VT1, all trying to prevent nixos-rebuild switch from killing the graphical session: https://github.com/NixOS/nixpkgs/pull/31820 https://github.com/NixOS/nixpkgs/pull/70357

My initial 'fix' was to restore upstream configuration (an Conflicts and After on getty@tty1 and autovt@tty) but it's not that simple: the autologin issue will then be fixed, however nixos-rebuild switch will kill the graphical session because Conflicts works both ways: display-manager is stopped to be able to start getty@tty1 (key problem: I don't understand why a getty is started during switch).

Masking getty@tty1 and autovt@tty1 works around both issues for me. I don't know if this is acceptable as a fix as it might have side effects: I suppose you now need to switch VT to get to a console if gdm fails to start for whatever reason.

EDIT: and it might not suffice for true multi-user setups (ie. fast user switching / secondary gnome-session on tty2)

Alternatively, getting gdm to spawn on VT7 might solve this, but that feels like X11 legacy to me (and diverts from what upstream/other distros do).

On crashing

Note that gnome-shell tends to crash on shutdown due to an unrelated issue: debian/patches: Avoid full meta context finalization

Mention: @jtojnar @hedning for visibility

iclanzan commented 2 years ago

This work-around works for me! Thank you!

vvs- commented 2 years ago

I have a crash on NixOS 21.11 with autologin and disabled Wayland. This seems to be related: local-display-factory: Don't crash if Xorg and Wayland are both unavailable

SuperSamus commented 2 years ago

I don't know if it's related, I have autologin disabled, but I get a crash if I login very quickly. If I wait a bit before logging in, then I get no crash. Is it related, or should I open a new issue? EDIT: The workaround above fixed it, so I guess it is the same problem.

bjornfor commented 2 years ago

@SuperSamus: IDK, but IMHO it's best to create a new issue and link it to this one. If it turns out to be a duplicate it's easy to close, instead of creating potential noise in this one.

PaulGrandperrin commented 2 years ago

For information, @ius 's trick used to work perfectly for me on 21.11 but after upgrading to nixos-unstable/22.05/gnome 42, it fails again :-/

vvs- commented 2 years ago

Another problem with this bug is that it's not 100% reproducible. It used to crash here on 21.11. But then it suddenly stopped crashing with no apparent reason (no updates to Gnome) and I didn't see it ever since.

PaulGrandperrin commented 2 years ago

Yes if i recall correctly, it's a race condition somewhere. On my slower computer, never any problem, but on the fast one, always. And they share the exact same NixOS conf

On Fri, May 6, 2022, 20:14 vvs- @.***> wrote:

Another problem with this bug is that it's not 100% reproducible. It used to crash here on 21.11. But then it suddenly stopped crashing with no apparent reason (no updates to Gnome) and I didn't see it ever since.

— Reply to this email directly, view it on GitHub https://github.com/NixOS/nixpkgs/issues/103746#issuecomment-1119868825, or unsubscribe https://github.com/notifications/unsubscribe-auth/AANK7SFRHZVGFUQRV227S4DVIVORDANCNFSM4TVDLN7Q . You are receiving this because you are subscribed to this thread.Message ID: @.***>

austinbutler commented 2 years ago

Instead of autoLogin.delay which seems to now simply disable auto login, you can do something like services.xserver.displayManager.job.preStart = "sleep 5"; which is even more of a huge hack, but it seems to work.

Still happening with latest unstable. This sleep works around it for me, though.

nixos-discourse commented 1 year ago

This issue has been mentioned on NixOS Discourse. There might be relevant details there:

https://discourse.nixos.org/t/how-to-configure-nixos-for-kiosk-or-fullscreen-applications/21855/3

futile commented 1 year ago

Also running into this problem with NixOS 22.11, encrypted ZFS, gdm on X and autoLogin: Gnome becomes visible for a second, then the screen turns black, and that's it. Actually I can't even switch to a terminal with ctrl+alt+f1,f2,... anymore either, only rebooting and booting from an earlier generation allowed me to disable autoLogin again. Haven't tried any of the workarounds, I think I'll just leave autoLogin disabled for now.

pwaller commented 1 year ago

~This looks like a possible smoking gun. Everything begins to stop following an error relating to dbus and resolve1, Unit dbus-org.freedesktop.resolve1.service not found. If I set gdm.settings.debug.enable = true;, in an effort to debug whats happening it is less likely to happen for some reason.~

I now doubt this analysis; please disregard. The workaround above works well.

Log fragment ``` Jul 08 10:12:09 , no messages follow Jul 08 10:12:11 monster dbus-daemon[1770]: [system] Activating via systemd: service name='org.freedesktop.resolve1' unit='dbus-org.freedesktop.resolve1.service' requested by ':1.7' (uid=0 pid=1795 comm="/nix/store/9p8dxnsfnbk6ffrxqrm5q0fhm735vcwg-networ" label="kernel") Jul 08 10:12:11 monster dbus-daemon[1770]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.resolve1.service': Unit dbus-org.freedesktop.resolve1.service not found. Jul 08 10:12:11 monster systemd[1]: Stopped target Host and Network Name Lookups. Jul 08 10:12:11 monster systemd[1]: Stopping Host and Network Name Lookups... Jul 08 10:12:11 monster systemd[1]: Stopped target User and Group Name Lookups. Jul 08 10:12:11 monster systemd[1]: Stopping User and Group Name Lookups... Jul 08 10:12:11 monster systemd[1]: Stopping Name Service Cache Daemon (nsncd)... Jul 08 10:12:11 monster systemd[1]: nscd.service: Deactivated successfully. Jul 08 10:12:11 monster avahi-daemon[1697]: Got SIGHUP, reloading. Jul 08 10:12:11 monster avahi-daemon[1697]: No service file found in /etc/avahi/services. Jul 08 10:12:11 monster avahi-daemon[1697]: Joining mDNS multicast group on interface eno1.IPv4 with address 192.168.1.60. Jul 08 10:12:11 monster avahi-daemon[1697]: New relevant interface eno1.IPv4 for mDNS. Jul 08 10:12:11 monster systemd[1]: Stopped Name Service Cache Daemon (nsncd). Jul 08 10:12:11 monster systemd[1]: Starting Name Service Cache Daemon (nsncd)... Jul 08 10:12:11 monster systemd[1]: Started Name Service Cache Daemon (nsncd). Jul 08 10:12:11 monster nsncd[2847]: Jul 08 10:12:11.323 INFO started, handoff_timeout: 3s, worker_count: 8, path: "/var/run/nscd/socket" Jul 08 10:12:11 monster systemd[1]: Reached target Host and Network Name Lookups. Jul 08 10:12:11 monster systemd[1]: Reached target User and Group Name Lookups. Jul 08 10:12:11 monster gdm-autologin][2121]: pam_unix(gdm-autologin:session): session closed for user pwaller Jul 08 10:12:11 monster systemd[2145]: Stopped target GNOME X11 Session (session: gnome). ```
nixos-discourse commented 9 months ago

This issue has been mentioned on NixOS Discourse. There might be relevant details there:

https://discourse.nixos.org/t/help-with-this-error/36757/5

nixos-discourse commented 4 months ago

This issue has been mentioned on NixOS Discourse. There might be relevant details there:

https://discourse.nixos.org/t/help-needed-with-nvidia-config-on-gnome/43751/4

AidanNotFunny commented 4 months ago

TL;DR

Append the following to configuration.nix for a workaround:

systemd.services."getty@tty1".enable = false;
systemd.services."autovt@tty1".enable = false;

Details

Lines from @dasj19's logs show the issue is gdm-wayland-session is killed by SIGHUP.

May 11 17:04:08 nixos gdm-wayland-session[839]: Gdm: Running wayland session
May 11 17:04:11 nixos gdm-autologin][820]: Gdm: GdmSessionWorker: child (pid:839) done (signal:1)
May 11 17:04:11 nixos gdm[804]: Gdm: GdmManager: session died with signal Hangup

To figure out the source of the SIGHUP I used kernel event tracing, which reveals the signal is sent by agetty. Indeed, there's a getty on tty1 - in fact, gdm spawns the session on the same tty.

May 11 17:04:06 nixos systemd[1]: Started Getty on tty1.
May 11 17:04:07 nixos gdm-autologin][820]: Gdm: GdmSessionWorker: jumping to VT 1

... which is somehow (race?) problematic because of:

cat /nix/store/n5j5fjn60nhck658j9ab84k8n9z24n1r-systemd-247.6/example/systemd/system/getty@.service | grep TTY
# the VT is cleared by TTYVTDisallocate
TTYPath=/dev/%I
TTYReset=yes
TTYVHangup=yes
TTYVTDisallocate=yes

TTYVHangup=: Disconnect all clients which have opened the terminal device specified with TTYPath= before and after execution. Defaults to "no".

In fact we're telling gdm to spawn on vt7 (but it doesn't):

https://github.com/NixOS/nixpkgs/blob/eebfe7199d9e543acea19de4af15a91ab7774e7c/pkgs/desktops/gnome/core/gdm/default.nix#L169-L171

Upstream ships its systemd unit with Conflicts=tty1@getty.service, which is modified by NixOS to initialVT: 7 in this case. Somehow we're starting on VT 1, which might have a getty spawned (thus killing the session).

Fixing

As for spawning on VT1 instead of VT7: I think this might be somehow related to moving over to Wayland instead of X. Google it and you'll discover that VT1 is where most (if not all) distro's spawn GDM these days.

There's quite some history behind configuring VT7 instead of VT1, all trying to prevent nixos-rebuild switch from killing the graphical session: #31820 #70357

My initial 'fix' was to restore upstream configuration (an Conflicts and After on getty@tty1 and autovt@tty) but it's not that simple: the autologin issue will then be fixed, however nixos-rebuild switch will kill the graphical session because Conflicts works both ways: display-manager is stopped to be able to start getty@tty1 (key problem: I don't understand why a getty is started during switch).

Masking getty@tty1 and autovt@tty1 works around both issues for me. I don't know if this is acceptable as a fix as it might have side effects: I suppose you now need to switch VT to get to a console if gdm fails to start for whatever reason.

EDIT: and it might not suffice for true multi-user setups (ie. fast user switching / secondary gnome-session on tty2)

Alternatively, getting gdm to spawn on VT7 might solve this, but that feels like X11 legacy to me (and diverts from what upstream/other distros do).

On crashing

Note that gnome-shell tends to crash on shutdown due to an unrelated issue: debian/patches: Avoid full meta context finalization

Mention: @jtojnar @hedning for visibility

Worked great for me. Thank you so much!

nixos-discourse commented 1 week ago

This issue has been mentioned on NixOS Discourse. There might be relevant details there:

https://discourse.nixos.org/t/gnome-display-manager-fails-to-login-until-wi-fi-connection-is-established/50513/14

nixos-discourse commented 1 week ago

This issue has been mentioned on NixOS Discourse. There might be relevant details there:

https://discourse.nixos.org/t/gnome-display-manager-fails-to-login-until-wi-fi-connection-is-established/50513/15