#1108438 Very slow to start with NFS-mounted home directory

Package:
gnome-shell
Source:
gnome-shell
Description:
graphical shell for the GNOME desktop
Submitter:
Ben Hutchings
Date:
2025-08-01 19:13:02 UTC
Severity:
normal
Tags:
#1108438#5
Date:
2025-06-28 16:31:49 UTC
From:
To:
On this desktop system /home is mounted from an NFS server.  This was
usable in bookworm.  After upgrading to trixie, the shell doesn't
appear for several minutes, and then takes over a minute to respond to
some buttons.

The journal showed a number of services timing out (see the bottom of
this message).  After much experimentation I found that masking
localsearch-3.service makes this less bad, but it is still
unacceptably slow to start.

I created a fresh account to test this in isolation from any special
configuration I have that might make this worse, and recorded the
following timings (approximate, in seconds):

                        With           Without
		        localsearch-3  localsearch-3
----------------------------------------------------
Time to fully appear    ~120-500       ~90
Time to respond to          ~200       ~20
 "all apps" button
Time to respond to           ~60       ~60
 quick settings button

The response times are for use of the respective buttons immediately
after the shell appears.  When they are pressed a second time, or a
few minutes after the shell appears, the response is quick (< 1 s).

Ben.

Jun 28 17:55:10 grosvenor systemd[11109]: Started gnome-keyring-daemon.service - GNOME Keyring daemon.
Jun 28 17:55:10 grosvenor gnome-keyring-daemon[22416]: GNOME_KEYRING_CONTROL=/run/user/1003/keyring
Jun 28 17:55:10 grosvenor systemd[11109]: Starting dbus.service - D-Bus User Message Bus...
Jun 28 17:55:10 grosvenor systemd[11109]: Started dbus.service - D-Bus User Message Bus.
Jun 28 17:55:11 grosvenor systemd[11109]: Reached target gnome-session-wayland.target - GNOME Wayland Session.
Jun 28 17:55:11 grosvenor systemd[11109]: Reached target org.gnome.Shell.target - GNOME Shell.
Jun 28 17:55:11 grosvenor systemd[11109]: Starting gnome-session-monitor.service - Monitor Session leader for GNOME Session...
Jun 28 17:55:11 grosvenor systemd[11109]: Starting user-session-migration.service - User Session Migration...
Jun 28 17:55:11 grosvenor systemd[11109]: Starting xdg-desktop-portal-rewrite-launchers.service - Rewrite dynamic launcher portal entries...
Jun 28 17:55:11 grosvenor systemd[11109]: Started gnome-session-monitor.service - Monitor Session leader for GNOME Session.
Jun 28 17:55:11 grosvenor user-session-migration[22461]: Exited with an error
Jun 28 17:55:11 grosvenor user-session-migration[22461]: stdout:
Jun 28 17:55:11 grosvenor user-session-migration[22461]: stderr: rm: cannot remove '.config/autostart/ignore-lid-switch-tweak.desktop': No such file or directory
Jun 28 17:55:11 grosvenor dbus-daemon[22421]: [session uid=1003 pid=22421 pidfd=5] Activating via systemd: service name='org.gtk.vfs.Daemon' unit='gvfs-daemon.service' requested by ':1.6' (uid=1003 pid=22462 comm="/usr/libexec/xdg-desktop-portal-rewrite-launchers")
Jun 28 17:55:11 grosvenor systemd[11109]: Finished user-session-migration.service - User Session Migration.
Jun 28 17:55:11 grosvenor systemd[11109]: Starting gvfs-daemon.service - Virtual filesystem service...
Jun 28 17:55:11 grosvenor dbus-daemon[22421]: [session uid=1003 pid=22421 pidfd=5] Successfully activated service 'org.gtk.vfs.Daemon'
Jun 28 17:55:11 grosvenor systemd[11109]: Started gvfs-daemon.service - Virtual filesystem service.
Jun 28 17:55:11 grosvenor systemd[11109]: Finished xdg-desktop-portal-rewrite-launchers.service - Rewrite dynamic launcher portal entries.
Jun 28 17:55:11 grosvenor systemd[11109]: Reached target graphical-session-pre.target - Session services which should run early before the graphical session is brought up.
Jun 28 17:55:11 grosvenor systemd[11109]: Reached target gnome-session-pre.target - Tasks to be run before GNOME Session starts.
Jun 28 17:55:11 grosvenor systemd[11109]: Starting gnome-session-manager@gnome.service - GNOME Session Manager (session: gnome)...
Jun 28 17:55:11 grosvenor gnome-keyring-daemon[22416]: The Secret Service was already initialized
Jun 28 17:55:11 grosvenor gnome-keyring-d[22416]: The Secret Service was already initialized
Jun 28 17:55:11 grosvenor gnome-keyring-daemon[22507]: discover_other_daemon: 1
Jun 28 17:55:11 grosvenor gnome-keyring-secrets.desktop[22507]: discover_other_daemon: 1GNOME_KEYRING_CONTROL=/run/user/1003/keyring
Jun 28 17:55:11 grosvenor systemd[11109]: Started app-gnome-gnome\x2dkeyring\x2dpkcs11-22504.scope - Application launched by gnome-session-binary.
Jun 28 17:55:11 grosvenor systemd[11109]: app-gnome-gnome\x2dkeyring\x2dsecrets-22498.scope: PID 22498 vanished before we could move it to target cgroup '/user.slice/user-1003.slice/user@1003.service/app.slice/app-gnome-gnome\x2dkeyring\x2dsecrets-22498.scope', skipping: No such process
Jun 28 17:55:11 grosvenor systemd[11109]: app-gnome-gnome\x2dkeyring\x2dsecrets-22498.scope: No PIDs left to attach to the scope's control group, refusing.
Jun 28 17:55:11 grosvenor systemd[11109]: app-gnome-gnome\x2dkeyring\x2dsecrets-22498.scope: Failed with result 'resources'.
Jun 28 17:55:11 grosvenor systemd[11109]: Failed to start app-gnome-gnome\x2dkeyring\x2dsecrets-22498.scope - Application launched by gnome-session-binary.
Jun 28 17:55:11 grosvenor systemd[11109]: Started app-gnome-gnome\x2dkeyring\x2dssh-22502.scope - Application launched by gnome-session-binary.
Jun 28 17:55:11 grosvenor gnome-keyring-daemon[22416]: The PKCS#11 component was already initialized
Jun 28 17:55:11 grosvenor gnome-keyring-daemon[22510]: discover_other_daemon: 1
Jun 28 17:55:11 grosvenor gnome-keyring-ssh.desktop[22510]: discover_other_daemon: 1GNOME_KEYRING_CONTROL=/run/user/1003/keyring
Jun 28 17:55:11 grosvenor gnome-keyring-ssh.desktop[22510]: SSH_AUTH_SOCK=/run/user/1003/keyring/ssh
Jun 28 17:55:11 grosvenor gnome-keyring-d[22416]: The PKCS#11 component was already initialized
Jun 28 17:55:11 grosvenor gnome-keyring-pkcs11.desktop[22511]: discover_other_daemon: 1GNOME_KEYRING_CONTROL=/run/user/1003/keyring
Jun 28 17:55:11 grosvenor gnome-keyring-pkcs11.desktop[22511]: SSH_AUTH_SOCK=/run/user/1003/keyring/ssh
Jun 28 17:55:11 grosvenor gnome-keyring-daemon[22511]: discover_other_daemon: 1
Jun 28 17:55:11 grosvenor systemd[11109]: Started gnome-session-manager@gnome.service - GNOME Session Manager (session: gnome).
Jun 28 17:55:11 grosvenor systemd[11109]: Reached target gnome-session-manager.target - GNOME Session Manager is ready.
Jun 28 17:55:11 grosvenor systemd[11109]: Starting org.gnome.Shell@wayland.service - GNOME Shell on Wayland...
Jun 28 17:55:11 grosvenor systemd[11109]: org.gnome.Shell@x11.service - GNOME Shell on X11 was skipped because of an unmet condition check (ConditionEnvironment=XDG_SESSION_TYPE=x11).
Jun 28 17:55:11 grosvenor systemd[11109]: Started app-gnome-at\x2dspi\x2ddbus\x2dbus-22514.scope - Application launched by gnome-session-binary.
Jun 28 17:55:11 grosvenor systemd[11109]: app-gnome-xdg\x2duser\x2ddirs-22517.scope: PID 22517 vanished before we could move it to target cgroup '/user.slice/user-1003.slice/user@1003.service/app.slice/app-gnome-xdg\x2duser\x2ddirs-22517.scope', skipping: No such process
Jun 28 17:55:11 grosvenor systemd[11109]: app-gnome-xdg\x2duser\x2ddirs-22517.scope: No PIDs left to attach to the scope's control group, refusing.
Jun 28 17:55:11 grosvenor systemd[11109]: app-gnome-xdg\x2duser\x2ddirs-22517.scope: Failed with result 'resources'.
Jun 28 17:55:11 grosvenor systemd[11109]: Failed to start app-gnome-xdg\x2duser\x2ddirs-22517.scope - Application launched by gnome-session-binary.
Jun 28 17:55:11 grosvenor gnome-shell[22515]: Running GNOME Shell (using mutter 48.3.1) as a Wayland display server
Jun 28 17:55:11 grosvenor gnome-shell[22515]: Enabling experimental feature 'scale-monitor-framebuffer'
Jun 28 17:55:11 grosvenor gnome-shell[22515]: Enabling experimental feature 'xwayland-native-scaling'
Jun 28 17:55:11 grosvenor gnome-shell[22515]: Thread 'KMS thread' will be using high priority scheduling
Jun 28 17:55:11 grosvenor gnome-shell[22515]: Device '/dev/dri/card0' prefers shadow buffer
Jun 28 17:55:11 grosvenor gnome-shell[22515]: Added device '/dev/dri/card0' (amdgpu) using atomic mode setting.
Jun 28 17:55:11 grosvenor gnome-shell[22515]: Created gbm renderer for '/dev/dri/card0'
Jun 28 17:55:11 grosvenor gnome-shell[22515]: Boot VGA GPU /dev/dri/card0 selected as primary
Jun 28 17:55:11 grosvenor gnome-shell[22515]: Obtained a high priority EGL context
Jun 28 17:55:12 grosvenor gnome-shell[22515]: Using public X11 display :2, (using :3 for managed services)
Jun 28 17:55:12 grosvenor gnome-shell[22515]: Using Wayland display name 'wayland-0'
Jun 28 17:55:12 grosvenor at-spi-dbus-bus.desktop[22528]: dbus-daemon[22528]: Activating service name='org.a11y.atspi.Registry' requested by ':1.0' (uid=1003 pid=22515 comm="/usr/bin/gnome-shell")
Jun 28 17:55:12 grosvenor at-spi-dbus-bus.desktop[22528]: dbus-daemon[22528]: Successfully activated service 'org.a11y.atspi.Registry'
Jun 28 17:55:12 grosvenor at-spi-dbus-bus.desktop[22578]: SpiRegistry daemon is running with well-known name - org.a11y.atspi.Registry
Jun 28 17:55:12 grosvenor gnome-shell[22515]: Unset XDG_SESSION_ID, getCurrentSessionProxy() called outside a user session. Asking logind directly.
Jun 28 17:55:12 grosvenor dbus-daemon[22421]: [session uid=1003 pid=22421 pidfd=5] Activating service name='org.gnome.Shell.Screencast' requested by ':1.21' (uid=1003 pid=22515 comm="/usr/bin/gnome-shell")
Jun 28 17:55:12 grosvenor dbus-daemon[22421]: [session uid=1003 pid=22421 pidfd=5] Activating via systemd: service name='org.freedesktop.impl.portal.PermissionStore' unit='xdg-permission-store.service' requested by ':1.21' (uid=1003 pid=22515 comm="/usr/bin/gnome-shell")
Jun 28 17:55:12 grosvenor dbus-daemon[22421]: [session uid=1003 pid=22421 pidfd=5] Activating service name='org.gnome.Shell.CalendarServer' requested by ':1.21' (uid=1003 pid=22515 comm="/usr/bin/gnome-shell")
Jun 28 17:55:12 grosvenor systemd[11109]: Starting xdg-permission-store.service - sandboxed app permission store...
Jun 28 17:55:12 grosvenor dbus-daemon[22421]: [session uid=1003 pid=22421 pidfd=5] Activating via systemd: service name='org.gnome.evolution.dataserver.Sources5' unit='evolution-source-registry.service' requested by ':1.26' (uid=1003 pid=22598 comm="/usr/libexec/gnome-shell-calendar-server")
Jun 28 17:55:12 grosvenor systemd[11109]: Starting evolution-source-registry.service - Evolution source registry...
Jun 28 17:55:12 grosvenor dbus-daemon[22421]: [session uid=1003 pid=22421 pidfd=5] Activating service name='org.gnome.Shell.Notifications' requested by ':1.21' (uid=1003 pid=22515 comm="/usr/bin/gnome-shell")
Jun 28 17:55:12 grosvenor dbus-daemon[22421]: [session uid=1003 pid=22421 pidfd=5] Successfully activated service 'org.freedesktop.impl.portal.PermissionStore'
Jun 28 17:55:12 grosvenor systemd[11109]: Started xdg-permission-store.service - sandboxed app permission store.
Jun 28 17:55:12 grosvenor systemd[11109]: Started org.gnome.Shell@wayland.service - GNOME Shell on Wayland.
Jun 28 17:55:12 grosvenor systemd[11109]: Reached target gnome-session-initialized.target - GNOME Session is initialized.
Jun 28 17:55:12 grosvenor systemd[11109]: gnome-session-x11-services.target - GNOME session X11 services is inactive.
Jun 28 17:55:12 grosvenor systemd[11109]: Dependency failed for org.gnome.SettingsDaemon.XSettings.service - GNOME XSettings service.
Jun 28 17:55:12 grosvenor systemd[11109]: org.gnome.SettingsDaemon.XSettings.service: Job org.gnome.SettingsDaemon.XSettings.service/start failed with result 'dependency'.
Jun 28 17:55:12 grosvenor systemd[11109]: gnome-session-x11-services-ready.target: Job gnome-session-x11-services-ready.target/verify-active failed with result 'dependency'.
Jun 28 17:55:12 grosvenor systemd[11109]: Reached target gnome-session@gnome.target - GNOME Session (session: gnome).
Jun 28 17:55:12 grosvenor systemd[11109]: Reached target org.gnome.SettingsDaemon.XSettings.target - GNOME XSettings target.
Jun 28 17:55:12 grosvenor systemd[11109]: Starting gnome-session-signal-init.service - Signal initialization done to GNOME Session Manager...
Jun 28 17:55:12 grosvenor systemd[11109]: Starting org.freedesktop.IBus.session.GNOME.service - IBus Daemon for GNOME...
Jun 28 17:55:12 grosvenor systemd[11109]: Starting org.gnome.SettingsDaemon.A11ySettings.service - GNOME accessibility service...
Jun 28 17:55:12 grosvenor systemd[11109]: Starting org.gnome.SettingsDaemon.Color.service - GNOME color management service...
Jun 28 17:55:12 grosvenor systemd[11109]: Starting org.gnome.SettingsDaemon.Datetime.service - GNOME date & time service...
Jun 28 17:55:12 grosvenor systemd[11109]: Starting org.gnome.SettingsDaemon.Housekeeping.service - GNOME maintenance of expirable data service...
Jun 28 17:55:12 grosvenor systemd[11109]: Starting org.gnome.SettingsDaemon.Keyboard.service - GNOME keyboard configuration service...
Jun 28 17:55:12 grosvenor systemd[11109]: Starting org.gnome.SettingsDaemon.MediaKeys.service - GNOME keyboard shortcuts service...
Jun 28 17:55:12 grosvenor systemd[11109]: Starting org.gnome.SettingsDaemon.Power.service - GNOME power management service...
Jun 28 17:55:12 grosvenor systemd[11109]: Starting org.gnome.SettingsDaemon.PrintNotifications.service - GNOME printer notifications service...
Jun 28 17:55:12 grosvenor systemd[11109]: Starting org.gnome.SettingsDaemon.Rfkill.service - GNOME RFKill support service...
Jun 28 17:55:12 grosvenor systemd[11109]: Starting org.gnome.SettingsDaemon.ScreensaverProxy.service - GNOME FreeDesktop screensaver service...
Jun 28 17:55:12 grosvenor systemd[11109]: Starting org.gnome.SettingsDaemon.Sharing.service - GNOME file sharing service...
Jun 28 17:55:12 grosvenor systemd[11109]: Starting org.gnome.SettingsDaemon.Smartcard.service - GNOME smartcard service...
Jun 28 17:55:12 grosvenor systemd[11109]: Starting org.gnome.SettingsDaemon.Sound.service - GNOME sound sample caching service...
Jun 28 17:55:12 grosvenor systemd[11109]: Starting org.gnome.SettingsDaemon.UsbProtection.service - GNOME USB protection service...
Jun 28 17:55:12 grosvenor systemd[11109]: Starting org.gnome.SettingsDaemon.Wacom.service - GNOME Wacom tablet support service...
Jun 28 17:55:12 grosvenor gnome-session-binary[22481]: Entering running state
Jun 28 17:55:12 grosvenor at-spi2-registr[22578]: Failed to register client: GDBus.Error:org.gnome.SessionManager.AlreadyRegistered: Unable to register client
Jun 28 17:55:12 grosvenor at-spi2-registr[22578]: Unable to register client with session manager
Jun 28 17:55:12 grosvenor systemd[11109]: Finished gnome-session-signal-init.service - Signal initialization done to GNOME Session Manager.
Jun 28 17:55:12 grosvenor dbus-daemon[22421]: [session uid=1003 pid=22421 pidfd=5] Successfully activated service 'org.gnome.Shell.Notifications'
Jun 28 17:55:12 grosvenor gnome-session[22481]: gnome-session-binary[22481]: GnomeDesktop-WARNING: Could not create transient scope for PID 22701: GDBus.Error:org.freedesktop.DBus.Error.UnixProcessIdUnknown: Failed to set unit properties: No such process
Jun 28 17:55:12 grosvenor gnome-session-binary[22481]: GnomeDesktop-WARNING: Could not create transient scope for PID 22701: GDBus.Error:org.freedesktop.DBus.Error.UnixProcessIdUnknown: Failed to set unit properties: No such process
Jun 28 17:55:12 grosvenor systemd[11109]: Started org.gnome.SettingsDaemon.Housekeeping.service - GNOME maintenance of expirable data service.
Jun 28 17:55:12 grosvenor systemd[11109]: Started org.gnome.SettingsDaemon.Rfkill.service - GNOME RFKill support service.
Jun 28 17:55:12 grosvenor systemd[11109]: Started org.gnome.SettingsDaemon.Datetime.service - GNOME date & time service.
Jun 28 17:55:12 grosvenor systemd[11109]: Started org.gnome.SettingsDaemon.A11ySettings.service - GNOME accessibility service.
Jun 28 17:55:12 grosvenor systemd[11109]: Started app-gnome-gnome\x2dsoftware\x2dservice-22663.scope - Application launched by gnome-session-binary.
Jun 28 17:55:12 grosvenor systemd[11109]: Started app-gnome-im\x2dlaunch-22654.scope - Application launched by gnome-session-binary.
Jun 28 17:55:12 grosvenor systemd[11109]: Started app-gnome-org.gnome.Evolution\x2dalarm\x2dnotify-22707.scope - Application launched by gnome-session-binary.
Jun 28 17:55:12 grosvenor systemd[11109]: Started app-gnome-org.gnome.SettingsDaemon.DiskUtilityNotify-22687.scope - Application launched by gnome-session-binary.
Jun 28 17:55:12 grosvenor systemd[11109]: Started app-gnome-org.gnome.Software-22662.scope - Application launched by gnome-session-binary.
Jun 28 17:55:12 grosvenor systemd[11109]: Started app-gnome-user\x2ddirs\x2dupdate\x2dgtk-22703.scope - Application launched by gnome-session-binary.
Jun 28 17:55:12 grosvenor systemd[11109]: Reached target org.gnome.SettingsDaemon.A11ySettings.target - GNOME accessibility target.
Jun 28 17:55:12 grosvenor systemd[11109]: Reached target org.gnome.SettingsDaemon.Datetime.target - GNOME date & time target.
Jun 28 17:55:12 grosvenor systemd[11109]: Reached target org.gnome.SettingsDaemon.Housekeeping.target - GNOME maintenance of expirable data target.
Jun 28 17:55:12 grosvenor systemd[11109]: Reached target org.gnome.SettingsDaemon.Rfkill.target - GNOME RFKill support target.
Jun 28 17:55:12 grosvenor systemd[11109]: Started org.gnome.SettingsDaemon.PrintNotifications.service - GNOME printer notifications service.
Jun 28 17:55:12 grosvenor systemd[11109]: Reached target org.gnome.SettingsDaemon.PrintNotifications.target - GNOME printer notifications target.
Jun 28 17:55:12 grosvenor gnome-software-service.desktop[22663]: Unable to acquire bus name 'org.gnome.Software'
Jun 28 17:55:12 grosvenor systemd[11109]: Started org.gnome.SettingsDaemon.UsbProtection.service - GNOME USB protection service.
Jun 28 17:55:12 grosvenor systemd[11109]: Reached target org.gnome.SettingsDaemon.UsbProtection.target - GNOME USB protection target.
Jun 28 17:55:12 grosvenor systemd[11109]: Started org.gnome.SettingsDaemon.Smartcard.service - GNOME smartcard service.
Jun 28 17:55:12 grosvenor systemd[11109]: Reached target org.gnome.SettingsDaemon.Smartcard.target - GNOME smartcard target.
Jun 28 17:55:12 grosvenor systemd[11109]: Started org.gnome.SettingsDaemon.Sharing.service - GNOME file sharing service.
Jun 28 17:55:12 grosvenor systemd[11109]: Reached target org.gnome.SettingsDaemon.Sharing.target - GNOME file sharing target.
Jun 28 17:55:12 grosvenor systemd[11109]: Started org.gnome.SettingsDaemon.ScreensaverProxy.service - GNOME FreeDesktop screensaver service.
Jun 28 17:55:12 grosvenor systemd[11109]: Reached target org.gnome.SettingsDaemon.ScreensaverProxy.target - GNOME FreeDesktop screensaver target.
Jun 28 17:55:12 grosvenor dbus-daemon[22421]: [session uid=1003 pid=22421 pidfd=5] Activating service name='org.gnome.ScreenSaver' requested by ':1.41' (uid=1003 pid=22692 comm="/usr/libexec/gsd-usb-protection")
Jun 28 17:55:12 grosvenor systemd[11109]: Started org.gnome.SettingsDaemon.Sound.service - GNOME sound sample caching service.
Jun 28 17:55:12 grosvenor systemd[11109]: Reached target org.gnome.SettingsDaemon.Sound.target - GNOME sound sample caching target.
Jun 28 17:55:12 grosvenor dbus-daemon[22421]: [session uid=1003 pid=22421 pidfd=5] Activating service name='org.freedesktop.portal.IBus' requested by ':1.48' (uid=1003 pid=22620 comm="/usr/bin/ibus-daemon --panel disable")
Jun 28 17:55:12 grosvenor systemd[11109]: Started org.freedesktop.IBus.session.GNOME.service - IBus Daemon for GNOME.
Jun 28 17:55:12 grosvenor dbus-daemon[22421]: [session uid=1003 pid=22421 pidfd=5] Successfully activated service 'org.freedesktop.portal.IBus'
Jun 28 17:55:12 grosvenor dbus-daemon[22421]: [session uid=1003 pid=22421 pidfd=5] Successfully activated service 'org.gnome.ScreenSaver'
Jun 28 17:55:12 grosvenor dbus-daemon[22421]: [session uid=1003 pid=22421 pidfd=5] Activating service name='org.gnome.OnlineAccounts' requested by ':1.29' (uid=1003 pid=22610 comm="/usr/libexec/evolution-source-registry")
Jun 28 17:55:12 grosvenor dbus-daemon[22421]: [session uid=1003 pid=22421 pidfd=5] Successfully activated service 'org.gnome.evolution.dataserver.Sources5'
Jun 28 17:55:12 grosvenor systemd[11109]: Started evolution-source-registry.service - Evolution source registry.
Jun 28 17:55:12 grosvenor goa-daemon[22862]: goa-daemon version 3.54.2 starting
Jun 28 17:55:12 grosvenor gnome-shell[22515]: Error looking up permission: GDBus.Error:org.freedesktop.portal.Error.NotFound: No entry for geolocation
Jun 28 17:55:12 grosvenor gnome-shell[22515]: Will monitor session 29
Jun 28 17:55:12 grosvenor dbus-daemon[22421]: [session uid=1003 pid=22421 pidfd=5] Activating service name='org.gnome.Identity' requested by ':1.56' (uid=1003 pid=22862 comm="/usr/libexec/goa-daemon")
Jun 28 17:55:12 grosvenor dbus-daemon[22421]: [session uid=1003 pid=22421 pidfd=5] Activating via systemd: service name='org.gnome.evolution.dataserver.Calendar8' unit='evolution-calendar-factory.service' requested by ':1.26' (uid=1003 pid=22598 comm="/usr/libexec/gnome-shell-calendar-server")
Jun 28 17:55:12 grosvenor gsd-usb-protect[22692]: Failed to fetch USBGuard parameters: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name org.usbguard1 was not provided by any .service files
Jun 28 17:55:12 grosvenor systemd[11109]: Starting evolution-calendar-factory.service - Evolution calendar service...
Jun 28 17:55:12 grosvenor dbus-daemon[22421]: [session uid=1003 pid=22421 pidfd=5] Successfully activated service 'org.gnome.Shell.CalendarServer'
Jun 28 17:55:12 grosvenor dbus-daemon[22421]: [session uid=1003 pid=22421 pidfd=5] Successfully activated service 'org.gnome.OnlineAccounts'
Jun 28 17:55:12 grosvenor dbus-daemon[22421]: [session uid=1003 pid=22421 pidfd=5] Successfully activated service 'org.gnome.Identity'
Jun 28 17:55:12 grosvenor dbus-daemon[22421]: [session uid=1003 pid=22421 pidfd=5] Activating via systemd: service name='org.gtk.vfs.UDisks2VolumeMonitor' unit='gvfs-udisks2-volume-monitor.service' requested by ':1.21' (uid=1003 pid=22515 comm="/usr/bin/gnome-shell")
Jun 28 17:55:12 grosvenor systemd[11109]: Starting gvfs-udisks2-volume-monitor.service - Virtual filesystem service - disk device monitor...
Jun 28 17:55:12 grosvenor dbus-daemon[22421]: [session uid=1003 pid=22421 pidfd=5] Successfully activated service 'org.gtk.vfs.UDisks2VolumeMonitor'
Jun 28 17:55:12 grosvenor systemd[11109]: Started gvfs-udisks2-volume-monitor.service - Virtual filesystem service - disk device monitor.
Jun 28 17:55:12 grosvenor dbus-daemon[22421]: [session uid=1003 pid=22421 pidfd=5] Activating via systemd: service name='org.gtk.vfs.GoaVolumeMonitor' unit='gvfs-goa-volume-monitor.service' requested by ':1.21' (uid=1003 pid=22515 comm="/usr/bin/gnome-shell")
Jun 28 17:55:12 grosvenor systemd[11109]: Starting gvfs-goa-volume-monitor.service - Virtual filesystem service - GNOME Online Accounts monitor...
Jun 28 17:55:12 grosvenor dbus-daemon[22421]: [session uid=1003 pid=22421 pidfd=5] Successfully activated service 'org.gnome.evolution.dataserver.Calendar8'
Jun 28 17:55:12 grosvenor systemd[11109]: Started evolution-calendar-factory.service - Evolution calendar service.
Jun 28 17:55:12 grosvenor dbus-daemon[22421]: [session uid=1003 pid=22421 pidfd=5] Successfully activated service 'org.gtk.vfs.GoaVolumeMonitor'
Jun 28 17:55:12 grosvenor systemd[11109]: Started gvfs-goa-volume-monitor.service - Virtual filesystem service - GNOME Online Accounts monitor.
Jun 28 17:55:12 grosvenor dbus-daemon[22421]: [session uid=1003 pid=22421 pidfd=5] Activating via systemd: service name='org.gtk.vfs.GPhoto2VolumeMonitor' unit='gvfs-gphoto2-volume-monitor.service' requested by ':1.21' (uid=1003 pid=22515 comm="/usr/bin/gnome-shell")
Jun 28 17:55:12 grosvenor dbus-daemon[22421]: [session uid=1003 pid=22421 pidfd=5] Activating via systemd: service name='org.gnome.evolution.dataserver.AddressBook10' unit='evolution-addressbook-factory.service' requested by ':1.58' (uid=1003 pid=22877 comm="/usr/libexec/evolution-calendar-factory")
Jun 28 17:55:12 grosvenor systemd[11109]: Starting evolution-addressbook-factory.service - Evolution address book service...
Jun 28 17:55:12 grosvenor systemd[11109]: Starting gvfs-gphoto2-volume-monitor.service - Virtual filesystem service - digital camera monitor...
Jun 28 17:55:12 grosvenor dbus-daemon[22421]: [session uid=1003 pid=22421 pidfd=5] Successfully activated service 'org.gtk.vfs.GPhoto2VolumeMonitor'
Jun 28 17:55:12 grosvenor systemd[11109]: Started gvfs-gphoto2-volume-monitor.service - Virtual filesystem service - digital camera monitor.
Jun 28 17:55:12 grosvenor dbus-daemon[22421]: [session uid=1003 pid=22421 pidfd=5] Activating via systemd: service name='org.gtk.vfs.AfcVolumeMonitor' unit='gvfs-afc-volume-monitor.service' requested by ':1.21' (uid=1003 pid=22515 comm="/usr/bin/gnome-shell")
Jun 28 17:55:12 grosvenor systemd[11109]: Starting gvfs-afc-volume-monitor.service - Virtual filesystem service - Apple File Conduit monitor...
Jun 28 17:55:12 grosvenor dbus-daemon[22421]: [session uid=1003 pid=22421 pidfd=5] Successfully activated service 'org.gtk.vfs.AfcVolumeMonitor'
Jun 28 17:55:12 grosvenor systemd[11109]: Started gvfs-afc-volume-monitor.service - Virtual filesystem service - Apple File Conduit monitor.
Jun 28 17:55:12 grosvenor dbus-daemon[22421]: [session uid=1003 pid=22421 pidfd=5] Activating via systemd: service name='org.gtk.vfs.MTPVolumeMonitor' unit='gvfs-mtp-volume-monitor.service' requested by ':1.21' (uid=1003 pid=22515 comm="/usr/bin/gnome-shell")
Jun 28 17:55:12 grosvenor systemd[11109]: Starting gvfs-mtp-volume-monitor.service - Virtual filesystem service - Media Transfer Protocol monitor...
Jun 28 17:55:12 grosvenor dbus-daemon[22421]: [session uid=1003 pid=22421 pidfd=5] Successfully activated service 'org.gnome.evolution.dataserver.AddressBook10'
Jun 28 17:55:12 grosvenor systemd[11109]: Started evolution-addressbook-factory.service - Evolution address book service.
Jun 28 17:55:12 grosvenor dbus-daemon[22421]: [session uid=1003 pid=22421 pidfd=5] Successfully activated service 'org.gtk.vfs.MTPVolumeMonitor'
Jun 28 17:55:12 grosvenor systemd[11109]: Started gvfs-mtp-volume-monitor.service - Virtual filesystem service - Media Transfer Protocol monitor.
Jun 28 17:55:13 grosvenor systemd[11109]: Started pipewire.service - PipeWire Multimedia Service.
Jun 28 17:55:13 grosvenor systemd[11109]: Started wireplumber.service - Multimedia Service Session Manager.
Jun 28 17:55:13 grosvenor wireplumber[22963]: wp-internal-comp-loader: Loading profile 'main'
Jun 28 17:55:13 grosvenor systemd[11109]: Started org.gnome.SettingsDaemon.Color.service - GNOME color management service.
Jun 28 17:55:13 grosvenor systemd[11109]: Reached target org.gnome.SettingsDaemon.Color.target - GNOME color management target.
Jun 28 17:55:13 grosvenor systemd[11109]: Started org.gnome.SettingsDaemon.Keyboard.service - GNOME keyboard configuration service.
Jun 28 17:55:13 grosvenor systemd[11109]: Reached target org.gnome.SettingsDaemon.Keyboard.target - GNOME keyboard configuration target.
Jun 28 17:55:13 grosvenor dbus-daemon[22421]: [session uid=1003 pid=22421 pidfd=5] Activating via systemd: service name='org.freedesktop.portal.Desktop' unit='xdg-desktop-portal.service' requested by ':1.40' (uid=1003 pid=22662 comm="/usr/bin/gnome-software --gapplication-service")
Jun 28 17:55:13 grosvenor systemd[11109]: Starting xdg-desktop-portal.service - Portal service...
Jun 28 17:55:13 grosvenor systemd[11109]: Started org.gnome.SettingsDaemon.MediaKeys.service - GNOME keyboard shortcuts service.
Jun 28 17:55:13 grosvenor systemd[11109]: Reached target org.gnome.SettingsDaemon.MediaKeys.target - GNOME keyboard shortcuts target.
Jun 28 17:55:13 grosvenor systemd[11109]: Started org.gnome.SettingsDaemon.Power.service - GNOME power management service.
Jun 28 17:55:13 grosvenor systemd[11109]: Reached target org.gnome.SettingsDaemon.Power.target - GNOME power management target.
Jun 28 17:55:13 grosvenor systemd[11109]: Started org.gnome.SettingsDaemon.Wacom.service - GNOME Wacom tablet support service.
Jun 28 17:55:13 grosvenor systemd[11109]: Reached target org.gnome.SettingsDaemon.Wacom.target - GNOME Wacom tablet support target.
Jun 28 17:55:13 grosvenor systemd[11109]: Reached target gnome-session.target - GNOME Session.
Jun 28 17:55:13 grosvenor systemd[11109]: Reached target gnome-session-wayland@gnome.target - GNOME Wayland Session (session: gnome).
Jun 28 17:55:13 grosvenor systemd[11109]: Reached target graphical-session.target - Current graphical user session.
Jun 28 17:55:13 grosvenor systemd[11109]: gnome-initial-setup-first-login.service - GNOME Initial Setup was skipped because of an unmet condition check (ConditionPathExists=!/home/nfstest/.config/gnome-initial-setup-done).
Jun 28 17:55:13 grosvenor gsd-media-keys[22650]: Failed to grab accelerator for keybinding settings:playback-repeat
Jun 28 17:55:13 grosvenor gsd-media-keys[22650]: Failed to grab accelerator for keybinding settings:hibernate
Jun 28 17:55:13 grosvenor systemd[11109]: Starting localsearch-3.service - Tracker file system data miner...
Jun 28 17:55:13 grosvenor dbus-daemon[22421]: [session uid=1003 pid=22421 pidfd=5] Activating via systemd: service name='org.freedesktop.portal.Documents' unit='xdg-document-portal.service' requested by ':1.78' (uid=1003 pid=23000 comm="/usr/libexec/xdg-desktop-portal")
Jun 28 17:55:13 grosvenor systemd[11109]: Starting xdg-document-portal.service - flatpak document portal service...
Jun 28 17:55:13 grosvenor dbus-daemon[22421]: [session uid=1003 pid=22421 pidfd=5] Successfully activated service 'org.freedesktop.portal.Documents'
Jun 28 17:55:13 grosvenor systemd[11109]: Started xdg-document-portal.service - flatpak document portal service.
Jun 28 17:55:13 grosvenor dbus-daemon[22421]: [session uid=1003 pid=22421 pidfd=5] Activating via systemd: service name='org.freedesktop.impl.portal.desktop.gnome' unit='xdg-desktop-portal-gnome.service' requested by ':1.78' (uid=1003 pid=23000 comm="/usr/libexec/xdg-desktop-portal")
Jun 28 17:55:13 grosvenor systemd[11109]: Starting xdg-desktop-portal-gnome.service - Portal service (GNOME implementation)...
Jun 28 17:55:13 grosvenor systemd[11109]: Reached target gnome-session-x11-services.target - GNOME session X11 services.
Jun 28 17:55:13 grosvenor systemd[11109]: Starting org.gnome.SettingsDaemon.XSettings.service - GNOME XSettings service...
Jun 28 17:55:13 grosvenor gnome-shell[23047]: The XKEYBOARD keymap compiler (xkbcomp) reports:
Jun 28 17:55:13 grosvenor gnome-shell[23047]: > Warning:          Unsupported maximum keycode 708, clipping.
Jun 28 17:55:13 grosvenor gnome-shell[23047]: >                   X11 cannot support keycodes above 255.
Jun 28 17:55:13 grosvenor gnome-shell[23047]: > Warning:          Could not resolve keysym XF86KbdInputAssistPrevgrou
Jun 28 17:55:13 grosvenor gnome-shell[23047]: > Warning:          Could not resolve keysym XF86KbdInputAssistNextgrou
Jun 28 17:55:13 grosvenor gnome-shell[23047]: Errors from xkbcomp are not fatal to the X server
Jun 28 17:55:13 grosvenor dbus-daemon[22421]: [session uid=1003 pid=22421 pidfd=5] Activating service name='org.gnome.Settings.GlobalShortcutsProvider' requested by ':1.80' (uid=1003 pid=23025 comm="/usr/libexec/xdg-desktop-portal-gnome")
Jun 28 17:55:13 grosvenor dbus-daemon[22421]: [session uid=1003 pid=22421 pidfd=5] Successfully activated service 'org.gnome.Settings.GlobalShortcutsProvider'
Jun 28 17:55:13 grosvenor systemd[11109]: Started org.gnome.SettingsDaemon.XSettings.service - GNOME XSettings service.
Jun 28 17:55:13 grosvenor systemd[11109]: Reached target gnome-session-x11-services-ready.target - GNOME session X11 services.
Jun 28 17:55:13 grosvenor gsd-xsettings[23046]: Failed to get current UI scaling factor: GDBus.Error:org.freedesktop.DBus.Error.NameHasNoOwner: Name "org.gnome.Mutter.X11" does not exist
Jun 28 17:55:13 grosvenor gnome-shell[22515]: GNOME Shell started at Sat Jun 28 2025 17:55:12 GMT+0200 (Central European Summer Time)
Jun 28 17:55:13 grosvenor gnome-shell[22515]: Registering session with GDM
Jun 28 17:55:13 grosvenor dbus-daemon[22421]: [session uid=1003 pid=22421 pidfd=5] Successfully activated service 'org.freedesktop.impl.portal.desktop.gnome'
Jun 28 17:55:13 grosvenor systemd[11109]: Started xdg-desktop-portal-gnome.service - Portal service (GNOME implementation).
Jun 28 17:55:13 grosvenor dbus-daemon[22421]: [session uid=1003 pid=22421 pidfd=5] Activating via systemd: service name='org.freedesktop.impl.portal.desktop.gtk' unit='xdg-desktop-portal-gtk.service' requested by ':1.78' (uid=1003 pid=23000 comm="/usr/libexec/xdg-desktop-portal")
Jun 28 17:55:13 grosvenor systemd[11109]: Starting xdg-desktop-portal-gtk.service - Portal service (GTK/GNOME implementation)...
Jun 28 17:55:13 grosvenor wireplumber[22963]: spa.bluez5: BlueZ system service is not available
Jun 28 17:55:13 grosvenor wireplumber[22963]: [1:06:14.970013985] [22963]  INFO Camera camera_manager.cpp:327 libcamera v0.4.0
Jun 28 17:55:38 grosvenor xdg-desktop-por[23000]: Failed to create settings proxy: Error calling StartServiceByName for org.freedesktop.impl.portal.desktop.gtk: Timeout was reached
Jun 28 17:55:41 grosvenor gnome-software[22662]: Only 0 apps for recent list, hiding
Jun 28 17:56:03 grosvenor xdg-desktop-por[23000]: Failed to create inhibit proxy: Error calling StartServiceByName for org.freedesktop.impl.portal.desktop.gtk: Timeout was reached
Jun 28 17:56:03 grosvenor xdg-desktop-por[23000]: No skeleton to export
Jun 28 17:56:28 grosvenor xdg-desktop-por[23000]: Failed to create email proxy: Error calling StartServiceByName for org.freedesktop.impl.portal.desktop.gtk: Timeout was reached
Jun 28 17:56:28 grosvenor xdg-desktop-por[23000]: No skeleton to export
Jun 28 17:56:28 grosvenor dbus-daemon[22421]: [session uid=1003 pid=22421 pidfd=5] Successfully activated service 'org.freedesktop.portal.Desktop'
Jun 28 17:56:28 grosvenor systemd[11109]: Started xdg-desktop-portal.service - Portal service.
Jun 28 17:56:43 grosvenor systemd[11109]: localsearch-3.service: start operation timed out. Terminating.
Jun 28 17:56:43 grosvenor systemd[11109]: localsearch-3.service: Killing process 23147 (gst-pl:sh_opt0) with signal SIGKILL.
Jun 28 17:56:43 grosvenor systemd[11109]: localsearch-3.service: Failed with result 'timeout'.
Jun 28 17:56:43 grosvenor systemd[11109]: Failed to start localsearch-3.service - Tracker file system data miner.
Jun 28 17:56:43 grosvenor systemd[11109]: localsearch-3.service: Scheduled restart job, restart counter is at 1.
Jun 28 17:56:43 grosvenor systemd[11109]: Starting localsearch-3.service - Tracker file system data miner...
Jun 28 17:56:43 grosvenor systemd[11109]: xdg-desktop-portal-gtk.service: start operation timed out. Terminating.
Jun 28 17:56:43 grosvenor systemd[11109]: xdg-desktop-portal-gtk.service: Failed with result 'timeout'.
Jun 28 17:56:43 grosvenor systemd[11109]: Failed to start xdg-desktop-portal-gtk.service - Portal service (GTK/GNOME implementation).
Jun 28 17:57:12 grosvenor dbus-daemon[22421]: [session uid=1003 pid=22421 pidfd=5] Failed to activate service 'org.gnome.Shell.Screencast': timed out (service_start_timeout=120000ms)
Jun 28 17:57:13 grosvenor dbus-daemon[22421]: [session uid=1003 pid=22421 pidfd=5] Failed to activate service 'org.freedesktop.impl.portal.desktop.gtk': timed out (service_start_timeout=120000ms)
Jun 28 17:57:46 grosvenor dbus-daemon[22421]: [session uid=1003 pid=22421 pidfd=5] Activating via systemd: service name='org.gtk.vfs.Metadata' unit='gvfs-metadata.service' requested by ':1.21' (uid=1003 pid=22515 comm="/usr/bin/gnome-shell")
Jun 28 17:57:46 grosvenor systemd[11109]: Starting gvfs-metadata.service - Virtual filesystem metadata service...
Jun 28 17:57:46 grosvenor dbus-daemon[22421]: [session uid=1003 pid=22421 pidfd=5] Successfully activated service 'org.gtk.vfs.Metadata'
Jun 28 17:57:46 grosvenor systemd[11109]: Started gvfs-metadata.service - Virtual filesystem metadata service.
Jun 28 17:57:46 grosvenor gnome-shell[22515]: Error connecting to the screencast service
Jun 28 17:58:13 grosvenor systemd[11109]: localsearch-3.service: start operation timed out. Terminating.
Jun 28 17:58:13 grosvenor systemd[11109]: localsearch-3.service: Killing process 23480 (gst-plugin:sh0) with signal SIGKILL.
Jun 28 17:58:13 grosvenor systemd[11109]: localsearch-3.service: Failed with result 'timeout'.
Jun 28 17:58:13 grosvenor systemd[11109]: Failed to start localsearch-3.service - Tracker file system data miner.
Jun 28 17:58:14 grosvenor systemd[11109]: localsearch-3.service: Scheduled restart job, restart counter is at 2.
Jun 28 17:58:14 grosvenor systemd[11109]: Starting localsearch-3.service - Tracker file system data miner...
Jun 28 17:59:20 grosvenor gsd-media-keys[22650]: Unable to get default source
Jun 28 17:59:20 grosvenor gsd-media-keys[22650]: Unable to get default sink
Jun 28 17:59:44 grosvenor systemd[11109]: localsearch-3.service: start operation timed out. Terminating.
Jun 28 17:59:44 grosvenor systemd[11109]: localsearch-3.service: Killing process 23583 (gst-pl:traceq0) with signal SIGKILL.
Jun 28 17:59:44 grosvenor systemd[11109]: localsearch-3.service: Failed with result 'timeout'.
Jun 28 17:59:44 grosvenor systemd[11109]: Failed to start localsearch-3.service - Tracker file system data miner.
Jun 28 17:59:44 grosvenor systemd[11109]: localsearch-3.service: Scheduled restart job, restart counter is at 3.
Jun 28 17:59:44 grosvenor systemd[11109]: Starting localsearch-3.service - Tracker file system data miner...
Jun 28 17:59:45 grosvenor systemd[11109]: Started localsearch-3.service - Tracker file system data miner.
Jun 28 17:59:49 grosvenor gst-plugin-scan[22609]: gst_va_filter_new: assertion 'GST_IS_VA_DISPLAY (display)' failed
Jun 28 17:59:49 grosvenor gst-plugin-scan[22609]: gst_va_filter_open: assertion 'GST_IS_VA_FILTER (self)' failed
Jun 28 17:59:49 grosvenor gst-plugin-scan[22609]: gst_va_display_get_implementation: assertion 'GST_IS_VA_DISPLAY (self)' failed
Jun 28 17:59:49 grosvenor gst-plugin-scan[22609]: gst_va_display_get_implementation: assertion 'GST_IS_VA_DISPLAY (self)' failed
Jun 28 17:59:49 grosvenor gst-plugin-scan[22609]: gst_object_unref: assertion 'object != NULL' failed
Jun 28 17:59:49 grosvenor gst-plugin-scan[22609]: gst_object_unref: assertion 'object != NULL' failed

#1108438#10
Date:
2025-06-29 17:01:28 UTC
From:
To:
that various services' startups are timing out seems like it indicates
some lower-level problem. I suspect that gnome-shell functionality is
blocking on those services' startups, which would happen promptly under
normal circumstances.

One good place to start would be to create a similar fresh account with
an on-disk $HOME (but otherwise the same system-level configuration),
and see whether that is equally slow to start. If this is a fully
diskless system, it could be on tmpfs if necessary; or if it has a real
SSD/HDD, use that (perhaps below /var/local).

Assuming the on-disk or tmpfs $HOME is OK, the next step would be to
narrow down which part of the home directory causes trouble when located
on NFS, by making a hybrid home directory where the top-level directory
is on one storage type but ~/.cache, ~/.config and ~/.local are on the
other.

My first suspicion would be dconf (~/.cache/dconf, ~/.config/dconf)
which uses a format that is designed to be mmap'd; but perhaps that's a
red herring.

Since you've said that everything is much slower with localsearch-3
(formerly Tracker), please try without that - I suspect that it might
just be another victim of the same underlying issue, but I'm also
quite confident that localsearch/Tracker was not designed with a
high-latency $HOME in mind.

This takes a while to start, most likely because it is waiting for the
portal backend x-d-p-gtk to initialize (the other major portal backend,
x-d-p-gnome, seems healthy). I suspect this might be either the root
cause, or at least several steps closer to the root cause than
gnome-shell is. Each of several IPC calls to x-d-p-gtk triggers another
attempt to start x-d-p-gtk, none of which is any more successful than
the first.
...
from the package of the same name, is taking a very long time to start.

Please try re-running it in the session, with verbose log messages
(/usr/libexec/xdg-desktop-portal-gtk -vr), perhaps using strace or
dbus-monitor to see what it's doing and where it gets stuck.

If it starts promptly when restarted within the session itself, you
could try using an edited version of
/usr/lib/systemd/user/xdg-desktop-portal-gtk.service and saving it in
~/.config/systemd/user/ - in this case you would only want the -v option
(not -r), and it might also be useful to set
Environment=G_MESSAGES_DEBUG=all.

This is clearly also getting stuck somewhere. As well as dconf, it makes
me wonder about perhaps GStreamer or Pipewire.

This, too, makes me wonder about GStreamer.

     smcv