search

elementary / gala

Gala Window Manager for elementary OS and Pantheon
https://elementary.io
GNU General Public License v3.0
274 stars 77 forks source link

Gala getting killed (with signal 15?) on lock #1062

Open mcclurgm opened 3 years ago

mcclurgm commented 3 years ago

What Happened

I'm having some difficulties figuring out exactly which log files correspond to this. But sometimes, when I lock my screen using the "Lock" command in the power menu, my session crashes. It then drops me out on the greeter, but the greeter only displays an empty gray screen with indicators, and the login cards don't appear. Looking at the logs, it seems like this is happening because of a signal 15 kill, but I'm not sure.

This seems possibly related to changing my user account picture. It's only happened to me a few times, but they were soon after I changed my picture. This isn't consistent though: I can change the picture a bunch of times, and it won't happen.

Expected Behavior

No crashes. 😁️

Steps to Reproduce

🤷🏻‍♂️️ (Sorry)

Logs

Here's a log from right around when it happened. When I got dropped on an empty login screen, I just shut down my VM, so that sent a bunch of log messages and I can't quite tell what's from the shutdown process, but maybe one of you can figure this out better then me.

12:50:32 systemd: Reached target Exit the Session.
12:50:32 kernel: Error releasing name org.freedesktop.portal.Documents: The connection is closed
12:50:32 systemd: Stopped sandboxed app permission store.
12:50:32 kernel: [19:50:32.470385 WARNING] zeitgeist-daemon.vala:453: The connection is closed
12:50:32 zeitgeist-fts: zeitgeist-fts.vala:252: The connection is closed
12:50:32 systemd: Stopped Sound Service.
12:50:32 gala: Failed to create backend: Unable to open display ':0'
12:50:32 systemd: Starting Sound Service...
12:50:32 gnome-session-b: WARNING: Application 'gala.desktop' killed by signal 1
12:50:32 systemd: xdg-desktop-portal-gtk.service: Failed with result 'exit-code'.
12:50:32 kernel: io.elementary.files.xdg-desktop-portal: Fatal IO error 11 (Resource temporarily unavailable) on X server :0.

12:50:32 xdg-desktop-por: xdg-desktop-portal-gtk: Fatal IO error 11 (Resource temporarily unavailable) on X server :0.

12:50:32 bamfdaemon: bamfdaemon: Fatal IO error 11 (Resource temporarily unavailable) on X server :0.

12:50:32 systemd: pulseaudio.service: Failed with result 'exit-code'.
12:50:32 gnome-session-b: WARNING: Application 'io.elementary.wingpanel.desktop' killed by signal 15
12:50:32 pulseaudio: X connection to :0 broken (explicit kill or server shutdown).
12:50:32 gnome-session-b: WARNING: Application 'gala-daemon.desktop' killed by signal 15
12:50:32 at-spi2-registr: X connection to :0 broken (explicit kill or server shutdown).
12:50:32 plank: [WARN 12:50:32.275016] [AbstractMain:42] Caught signal (15), exiting
12:50:20 gsd-color: unable to get EDID for xrandr-Virtual-1: unable to get EDID for output
12:47:38 systemd: run-user-114.mount: Succeeded.
12:47:28 spice-vdagent: vdagent-audio: (playback-right) 65535 (%99.00)
12:47:28 gsd-color: unable to get EDID for xrandr-Virtual-1: unable to get EDID for output
12:47:28 spice-vdagent: vdagent_audio_playback_sync mute=no nchannels=2
12:47:22 gsd-color: unable to get EDID for xrandr-Virtual-1: unable to get EDID for output
12:47:18 gala: meta_window_is_focusable: assertion '!window->unmanaging' failed
12:47:18 gnome-session-b: Entering running state
12:47:05 io.elementary.s: gtk_window_set_titlebar() called on a realized window
12:47:03 systemd: Started Virtual filesystem service - Media Transfer Protocol monitor.
12:47:03 dbus-daemon: [session uid=1000 pid=966] Successfully activated service 'org.gtk.vfs.MTPVolumeMonitor'
12:47:03 systemd: Starting Virtual filesystem service - Media Transfer Protocol monitor...
12:47:03 dbus-daemon: [session uid=1000 pid=966] Activating via systemd: service name='org.gtk.vfs.MTPVolumeMonitor' unit='gvfs-mtp-volume-monitor.service' requested by ':1.116' (uid=1000 pid=2236 comm="io.elementary.switchboard settings://applications " label="unconfined")
12:47:03 systemd: Started Virtual filesystem service - Apple File Conduit monitor.
12:47:03 dbus-daemon: [session uid=1000 pid=966] Successfully activated service 'org.gtk.vfs.AfcVolumeMonitor'
12:47:03 systemd: Starting Virtual filesystem service - Apple File Conduit monitor...
12:47:03 dbus-daemon: [session uid=1000 pid=966] Activating via systemd: service name='org.gtk.vfs.AfcVolumeMonitor' unit='gvfs-afc-volume-monitor.service' requested by ':1.116' (uid=1000 pid=2236 comm="io.elementary.switchboard settings://applications " label="unconfined")
12:47:03 systemd: Started Virtual filesystem service - GNOME Online Accounts monitor.
12:47:03 dbus-daemon: [session uid=1000 pid=966] Successfully activated service 'org.gtk.vfs.GoaVolumeMonitor'
12:47:03 systemd: Starting Virtual filesystem service - GNOME Online Accounts monitor...
12:47:03 dbus-daemon: [session uid=1000 pid=966] Activating via systemd: service name='org.gtk.vfs.GoaVolumeMonitor' unit='gvfs-goa-volume-monitor.service' requested by ':1.116' (uid=1000 pid=2236 comm="io.elementary.switchboard settings://applications " label="unconfined")
12:47:03 systemd: Started Virtual filesystem service - digital camera monitor.
12:47:03 dbus-daemon: [session uid=1000 pid=966] Successfully activated service 'org.gtk.vfs.GPhoto2VolumeMonitor'
12:47:03 systemd: Starting Virtual filesystem service - digital camera monitor...
12:47:03 dbus-daemon: [session uid=1000 pid=966] Activating via systemd: service name='org.gtk.vfs.GPhoto2VolumeMonitor' unit='gvfs-gphoto2-volume-monitor.service' requested by ':1.116' (uid=1000 pid=2236 comm="io.elementary.switchboard settings://applications " label="unconfined")
12:47:03 systemd: Started Virtual filesystem service - disk device monitor.
12:47:03 dbus-daemon: [session uid=1000 pid=966] Successfully activated service 'org.gtk.vfs.UDisks2VolumeMonitor'
12:47:03 systemd: Starting Virtual filesystem service - disk device monitor...
12:47:03 dbus-daemon: [session uid=1000 pid=966] Activating via systemd: service name='org.gtk.vfs.UDisks2VolumeMonitor' unit='gvfs-udisks2-volume-monitor.service' requested by ':1.116' (uid=1000 pid=2236 comm="io.elementary.switchboard settings://applications " label="unconfined")
12:46:51 io.elementary.s: ../../../gobject/gbinding.c:976: The target object of type SwitchboardPlugUserAccountsWidgetsUserItem has no property called 'icon-file'
12:46:51 guest-session-t: Failed to open configuration directory /usr/local/share/lightdm/lightdm.conf.d: Error opening directory ?/usr/local/share/lightdm/lightdm.conf.d?: No such file or directory
12:46:38 ksystemlog: QMutex: destroying locked mutex

On another instance of this, I saw a message Unrecoverable failure in required component gala.desktop right after gala-daemon: Fatal IO error 11 (Resource temporarily unavailable) on X server :0.. This may or may not be related, because it appears in the log after Gala was killed a couple times by signal 15.

Platform Information

Odin daily (updated 2/25/21)

mcclurgm commented 3 years ago

I just got this again, nothing to do with manual locking but when my VM went to sleep and woke back up. (My actual computer went to sleep and woke back up in the interim, not sure if that makes a difference.) In my logs, most programs on my system log something like this:

17:34:16 gnome-session-b: WARNING: App 'gala-daemon.desktop' exited with code 1
17:34:16 kernel: xdg-desktop-portal-gtk: Fatal IO error 11 (Resource temporarily unavailable) on X server :0.

So it looks like the issue is something about X failing to communicate with a resource. I'm not sure if this is unique to VMs, or if it's on us (I saw some messages from gnome-session-binary), but hopefully that info will help.

davidmhewitt commented 3 years ago

Kind of looks like the X server is crashing and taking everything down with it. Anything in your Xorg logs?

mcclurgm commented 3 years ago

I had to reboot the VM so I lost the old log (I think?). I'll check the log next time this happens and update this issue. Thanks for the pointer.

mcclurgm commented 3 years ago

I'm not sure if this is the correct one, but here's an Xorg log that's from roughly the same time as a recent crash. (It's only a fraction of a second before the next log, and they're both at the right time, so I assume this is the right log.) Hope this has some useful info. https://gist.github.com/mcclurgm/05a8949c02eacb13c47f0d8e730be052