GNOME Bugzilla – Bug 733376
GNOME Classic fails to start if gnome-classic.desktop has GNOME-Classic in DesktopNames
Last modified: 2014-07-18 21:41:02 UTC
Following up on https://bugzilla.gnome.org/show_bug.cgi?id=727566 . This was partially reported by Vadim Rutkovsky in that bug, and further reported somewhat gnomically downstream in Fedora by 'poma': https://bugzilla.redhat.com/show_bug.cgi?id=1120283 Basically, if /usr/share/xsessions/gnome-classic.desktop is as it currently comes from upstream, with: DesktopNames=GNOME-Classic;GNOME or even just: DesktopNames=GNOME-Classic or, well, any combination with GNOME-Classic in it - I've tried GNOME-Classic; , GNOME;GNOME-Classic; , GNOME;GNOME-Classic and all the other obvious tweaks - then GNOME Classic session startup fails. You enter password and hit enter and you just get a black screen. If you make it: DesktopNames=GNOME or: DesktopNames=GNOME; then the session starts up successfully. It seems something, somewhere chokes on 'GNOME-Classic' as a DesktopName. Not sure what, yet, though, the logs don't point a particularly obvious finger. I'll attach logs shortly.
These are the session scope log messages from a failed attempt: -- Logs begin at Fri 2014-07-18 11:57:25 PDT, end at Fri 2014-07-18 12:21:45 PDT. -- Jul 18 12:13:40 localhost.localdomain gdm-password][7448]: pam_unix(gdm-password:session): session opened for user test by (unknown)(uid=0) Jul 18 12:13:40 localhost.localdomain org.a11y.Bus[7553]: Activating service name='org.a11y.atspi.Registry' Jul 18 12:13:40 localhost.localdomain org.a11y.Bus[7553]: Successfully activated service 'org.a11y.atspi.Registry' Jul 18 12:13:40 localhost.localdomain org.a11y.atspi.Registry[7608]: SpiRegistry daemon is running with well-known name - org.a11y.atspi.Registry Jul 18 12:13:40 localhost.localdomain org.a11y.atspi.Registry[7608]: Xlib: extension "XEVIE" missing on display ":0". Jul 18 12:13:41 localhost.localdomain pulseaudio[7640]: Stale PID file, overwriting. Jul 18 12:13:41 localhost.localdomain pulseaudio[7640]: Disabling timer-based scheduling because running inside a VM. Jul 18 12:13:41 localhost.localdomain pulseaudio[7640]: Disabling timer-based scheduling because running inside a VM. Jul 18 12:13:41 localhost.localdomain gnome-session[7540]: Entering running state Jul 18 12:13:41 localhost.localdomain gnome-session[7540]: vmware-user: could not open /proc/fs/vmblock/dev Jul 18 12:13:41 localhost.localdomain vmusr[7710]: [ warning] [vmtoolsd] The vmusr service needs to run inside a virtual machine. Jul 18 12:14:48 localhost.localdomain gnome-session[7540]: seapplet: Fatal IO error 11 (Resource temporarily unavailable) on X server :0. Jul 18 12:14:48 localhost.localdomain org.a11y.atspi.Registry[7608]: XIO: fatal IO error 11 (Resource temporarily unavailable) on X server ":0" Jul 18 12:14:48 localhost.localdomain org.a11y.atspi.Registry[7608]: after 29 requests (29 known processed) with 0 events remaining. Jul 18 12:14:48 localhost.localdomain org.a11y.Bus[7553]: g_dbus_connection_real_closed: Remote peer vanished with error: Underlying GIOStream returned 0 bytes on an async read (g-io-error-quark, 0). Exiting. Jul 18 12:14:48 localhost.localdomain gnome-session[7540]: g_dbus_connection_real_closed: Remote peer vanished with error: Underlying GIOStream returned 0 bytes on an async read (g-io-error-quark, 0). Exiting. Jul 18 12:14:48 localhost.localdomain gnome-session[7540]: (abrt:7702): Gdk-WARNING **: abrt: Fatal IO error 11 (Resource temporarily unavailable) on X server :0. Jul 18 12:14:48 localhost.localdomain gdm-password][7448]: pam_unix(gdm-password:session): session closed for user test and these are from a successful attempt: -- Logs begin at Fri 2014-07-18 11:57:25 PDT, end at Fri 2014-07-18 12:21:45 PDT. -- Jul 18 12:16:31 localhost.localdomain gdm-password][8889]: pam_unix(gdm-password:session): session opened for user test by (unknown)(uid=0) Jul 18 12:16:32 localhost.localdomain org.a11y.Bus[8935]: Activating service name='org.a11y.atspi.Registry' Jul 18 12:16:32 localhost.localdomain org.a11y.Bus[8935]: Successfully activated service 'org.a11y.atspi.Registry' Jul 18 12:16:32 localhost.localdomain org.a11y.atspi.Registry[8995]: SpiRegistry daemon is running with well-known name - org.a11y.atspi.Registry Jul 18 12:16:32 localhost.localdomain org.a11y.atspi.Registry[8995]: Xlib: extension "XEVIE" missing on display ":0". Jul 18 12:16:32 localhost.localdomain gnome-session[8924]: GPG_AGENT_INFO=/run/user/1000/keyring/gpg:0:1 Jul 18 12:16:32 localhost.localdomain gnome-session[8924]: GPG_AGENT_INFO=/run/user/1000/keyring/gpg:0:1 Jul 18 12:16:32 localhost.localdomain gnome-session[8924]: SSH_AUTH_SOCK=/run/user/1000/keyring/ssh Jul 18 12:16:32 localhost.localdomain gnome-session[8924]: GPG_AGENT_INFO=/run/user/1000/keyring/gpg:0:1 Jul 18 12:16:32 localhost.localdomain gnome-session[8924]: SSH_AUTH_SOCK=/run/user/1000/keyring/ssh Jul 18 12:16:32 localhost.localdomain gnome-session[8924]: ** (process:9045): WARNING **: Unable to move /run/gnome-initial-setup/.local/share/keyrings/login.keyring to /home/test/.local/share/keyrings/login.keyring: Target file exists Jul 18 12:16:32 localhost.localdomain pulseaudio[9067]: Stale PID file, overwriting. Jul 18 12:16:33 localhost.localdomain pulseaudio[9067]: Disabling timer-based scheduling because running inside a VM. Jul 18 12:16:33 localhost.localdomain pulseaudio[9067]: Disabling timer-based scheduling because running inside a VM. Jul 18 12:16:33 localhost.localdomain pulseaudio[9169]: [pulseaudio] pid.c: Daemon already running. Jul 18 12:16:34 localhost.localdomain gnome-session[8924]: Window manager warning: Log level 16: The property ClutterStage:color is deprecated and shouldn't be used anymore. It will be removed in a future version. Jul 18 12:16:34 localhost.localdomain gnome-session[8924]: Window manager warning: Log level 16: The property StIcon:icon-name is deprecated and shouldn't be used anymore. It will be removed in a future version. Jul 18 12:16:34 localhost.localdomain org.gnome.evolution.dataserver.Sources3[8935]: Bus name 'org.gnome.evolution.dataserver.Sources3' acquired. Jul 18 12:16:34 localhost.localdomain org.gnome.OnlineAccounts[8935]: goa-daemon-Message: goa-daemon version 3.13.3 starting Jul 18 12:16:34 localhost.localdomain org.gtk.Private.UDisks2VolumeMonitor[8935]: index_parse.c:191: indx_parse(): error opening /run/media/test/Fedora-Live-Workstation-x86_64-2/BDMV/index.bdmv Jul 18 12:16:34 localhost.localdomain org.gtk.Private.UDisks2VolumeMonitor[8935]: index_parse.c:191: indx_parse(): error opening /run/media/test/Fedora-Live-Workstation-x86_64-2/BDMV/BACKUP/index.bdmv Jul 18 12:16:34 localhost.localdomain org.gnome.OnlineAccounts[8935]: (goa-daemon:9296): libgoaidentity-WARNING **: GoaKerberosIdentityManager: Using polling for change notification for credential cache type 'KEYRING' Jul 18 12:16:35 localhost.localdomain org.gtk.Private.AfcVolumeMonitor[8935]: Volume monitor alive Jul 18 12:16:35 localhost.localdomain gnome-session[8924]: Gjs-Message: JS LOG: Failed to add search provider /usr/share/gnome-shell/search-providers/gnome-contacts-search-provider.ini: TypeError: appInfo is null Jul 18 12:16:35 localhost.localdomain gnome-session[8924]: (uint32 1,) Jul 18 12:16:35 localhost.localdomain gnome-session[8924]: Entering running state Jul 18 12:16:35 localhost.localdomain gnome-session[8924]: vmware-user: could not open /proc/fs/vmblock/dev Jul 18 12:16:35 localhost.localdomain vmusr[9445]: [ warning] [vmtoolsd] The vmusr service needs to run inside a virtual machine. Jul 18 12:16:36 localhost.localdomain gnome-session[8924]: (nautilus:9416): GLib-GObject-WARNING **: The property GtkSettings:gtk-button-images is deprecated and shouldn't be used anymore. It will be removed in a future version. Jul 18 12:16:36 localhost.localdomain gnome-session[8924]: Gjs-Message: JS LOG: Extension "window-list@gnome-shell-extensions.gcampax.github.com" had error: TypeError: global.screen.get_active_workspace(...) is null Jul 18 12:16:36 localhost.localdomain gnome-session[8924]: (nautilus:9416): GLib-GObject-WARNING **: The property GtkSettings:gtk-menu-images is deprecated and shouldn't be used anymore. It will be removed in a future version. Jul 18 12:16:36 localhost.localdomain gnome-session[8924]: Failed to play sound: File or data not found Jul 18 12:16:36 localhost.localdomain gnome-session[8924]: (nautilus:9416): GLib-GObject-WARNING **: The property GtkWidget:margin-left is deprecated and shouldn't be used anymore. It will be removed in a future version. Jul 18 12:16:36 localhost.localdomain gnome-session[8924]: (nautilus:9416): GLib-GObject-WARNING **: The property GtkWidget:margin-right is deprecated and shouldn't be used anymore. It will be removed in a future version. Jul 18 12:16:36 localhost.localdomain org.gnome.evolution.dataserver.Calendar4[8935]: Bus name 'org.gnome.evolution.dataserver.Calendar4' acquired. Jul 18 12:16:36 localhost.localdomain gnome-session[8924]: Window manager warning: Log level 16: The property GtkContainer:resize-mode is deprecated and shouldn't be used anymore. It will be removed in a future version. Jul 18 12:16:37 localhost.localdomain gnome-session[8924]: libmediaart-Message: Initializing Storage... Jul 18 12:16:37 localhost.localdomain gnome-session[8924]: libmediaart-Message: Mount monitors set up for to watch for added, removed and pre-unmounts... Jul 18 12:16:37 localhost.localdomain gnome-session[8924]: (gnome-settings-daemon:9031): color-plugin-WARNING **: failed to get edid: unable to get EDID for output Jul 18 12:16:37 localhost.localdomain gnome-session[8924]: GNOME Shell started at Fri Jul 18 2014 12:16:35 GMT-0700 (PDT) Jul 18 12:16:37 localhost.localdomain gnome-session[8924]: (gnome-settings-daemon:9031): color-plugin-WARNING **: unable to get EDID for xrandr-Virtual-0: unable to get EDID for output Jul 18 12:20:02 localhost.localdomain org.gnome.Terminal[8935]: (gnome-terminal-server:9866): GLib-GObject-WARNING **: The property GtkSettings:gtk-can-change-accels is deprecated and shouldn't be used anymore. It will be removed in a future version. Jul 18 12:20:02 localhost.localdomain org.gnome.Terminal[8935]: (gnome-terminal-server:9866): GLib-GObject-WARNING **: The property GtkSettings:gtk-menu-images is deprecated and shouldn't be used anymore. It will be removed in a future version. Jul 18 12:20:02 localhost.localdomain org.gnome.Terminal[8935]: (gnome-terminal-server:9866): GLib-GObject-WARNING **: The property GtkSettings:gtk-scrolled-window-placement is deprecated and shouldn't be used anymore. It will be removed in a future version. Jul 18 12:20:02 localhost.localdomain gnome-session[8924]: Window manager warning: Log level 16: STACK_OP_REMOVE: window X11:2200006 not in stack Jul 18 12:20:02 localhost.localdomain gnome-session[8924]: Window manager warning: Log level 16: STACK_OP_REMOVE: window X11:2200006 not in stack Jul 18 12:20:06 localhost.localdomain su[9914]: (to root) test on pts/0 Jul 18 12:20:06 localhost.localdomain su[9914]: pam_unix(su:session): session opened for user root by test(uid=1000)
Created attachment 281144 [details] full journal extract from a successful login (DesktopNames=GNOME), then a failed one (DesktopNames=GNOME-Classic;GNOME) Here's a fuller log extract (from another boot, with selinux disabled as it was generating a lot of noise). The log starts right when gdm comes up. I booted with the edit that fixes the bug, DesktopNames=GNOME , and logged in successfully; that's the login you see at 12:30:12. I then edited the file back to its upstream state to reproduce the bug - DesktopNames=GNOME-Classic;GNOME - and logged out, then tried to log in, which failed, of course. The failed login is the one you see at 12:31:15. Around 12:31:36 - after 20 seconds in which apparently nothing happens at all - the session is 'removed': Jul 18 12:31:36 localhost.localdomain systemd-logind[616]: Removed session c2. Jul 18 12:31:36 localhost.localdomain systemd[2403]: Stopping Default. Jul 18 12:31:36 localhost.localdomain systemd[2403]: Stopped target Default. Jul 18 12:31:36 localhost.localdomain systemd[2403]: Stopping Basic System. Jul 18 12:31:36 localhost.localdomain systemd[2403]: Stopped target Basic System. Jul 18 12:31:36 localhost.localdomain systemd[2403]: Stopping Paths. Jul 18 12:31:36 localhost.localdomain systemd[2403]: Stopped target Paths. Jul 18 12:31:36 localhost.localdomain systemd[2403]: Stopping Timers. Jul 18 12:31:36 localhost.localdomain systemd[2403]: Stopped target Timers. Jul 18 12:31:36 localhost.localdomain systemd[2403]: Stopping Sockets. Jul 18 12:31:36 localhost.localdomain systemd[2403]: Stopped target Sockets. Jul 18 12:31:36 localhost.localdomain systemd[2403]: Starting Shutdown. Jul 18 12:31:36 localhost.localdomain systemd[2403]: Reached target Shutdown. Jul 18 12:31:36 localhost.localdomain systemd[2403]: Starting Exit the Session... Jul 18 12:31:36 localhost.localdomain systemd[2403]: Received SIGRTMIN+24 from PID 2757 (kill). there's no indication as to *why*, though. I don't think I can find out what PID 2757 was.
There was some fallout from the change that was fixed in bug 729575 - looks like we regressed again :-(
OK, so adding '--debug' to the Exec line in gnome-classic.desktop shows the problem: jul 18 22:23:15 priscilla gnome-session[823]: DEBUG(+): app /org/gnome/SessionManager/App1 not for GNOME-Classic:GNOME That's precisely what we fixed in bug 729575, but gnome-session hasn't had any releases this cycle yet; I'll see to rectify that for 3.13.4. *** This bug has been marked as a duplicate of bug 729575 ***
i'll do a 3.13.3 now.
Mmmh, isn't 3.13.4 due on Monday anyway? But sure, 3.13.3 obviously works as well ...
I didn't want to wait until monday before doing anything since it's apparently been broken for some time already due to me not doing releases... I thought about just rolling 3.13.4 a few days early, but figure might as well give the translators an extra couple days for 3.13.4 if they need it.