Attachment 677749 Details for Bug 980521

Log of gdm starting up on vt8 breaking gvfsd-fuse mounting

gdm-login-broken-gvfs-1.txt (text/plain), 17.60 KB, created by Martin Szulecki on 2016-05-20 12:10:38 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Martin Szulecki

Created: 2016-05-20 12:10:38 UTC

Size: 17.60 KB

patch

obsolete

>...
>May 20 12:41:47 realbox gnome-session-binary[2170]: DEBUG(+): Using systemd for session tracking
>May 20 12:41:47 realbox org.gtk.vfs.Daemon[2279]: fusermount: failed to access mountpoint /run/user/1000/gvfs: Permission denied
>May 20 12:41:47 realbox org.gtk.vfs.Daemon[2279]: (process:2294): gvfsd-fuse-DEBUG: main: starting...
>May 20 12:41:47 realbox systemd[1]: Child 2294 (gvfsd-fuse) died (code=exited, status=1/FAILURE)
>May 20 12:41:47 realbox systemd[1]: Received SIGCHLD from PID 2294 (gvfsd-fuse).
>May 20 12:41:47 realbox org.gtk.vfs.Daemon[2279]: ** (gvfsd:2289): DEBUG: on_bus_acquired: entered...
>May 20 12:41:47 realbox org.gtk.vfs.Daemon[2279]: ** (gvfsd:2289): DEBUG: main: Entering main_loop...
>May 20 12:41:47 realbox org.gtk.vfs.Daemon[2279]: GDBus-debug:Address: Returning address 'unix:abstract=/tmp/dbus-WEsBUBGVbE,guid=d74e3c1089d58d8d611352ba573ee9eb' for bus type 'session'
>May 20 12:41:47 realbox org.gtk.vfs.Daemon[2279]: GDBus-debug:Address: env var DBUS_STARTER_BUS_TYPE='session'
>May 20 12:41:47 realbox org.gtk.vfs.Daemon[2279]: GDBus-debug:Address: env var DBUS_SYSTEM_BUS_ADDRESS is not set
>May 20 12:41:47 realbox org.gtk.vfs.Daemon[2279]: GDBus-debug:Address: env var DBUS_SESSION_BUS_ADDRESS='unix:abstract=/tmp/dbus-WEsBUBGVbE,guid=d74e3c1089d58d8d611352ba573ee9eb'
>May 20 12:41:47 realbox org.gtk.vfs.Daemon[2279]: GDBus-debug:Address: In g_dbus_address_get_for_bus_sync() for bus type 'session'
>May 20 12:41:47 realbox systemd[1]: systemd-journald.service: Added fd to fd store.
>May 20 12:41:47 realbox systemd[1]: systemd-journald.service: Got notification message from PID 414 (FDSTORE=1)
>May 20 12:41:47 realbox systemd[1]: systemd-journald.service: Added fd to fd store.
>May 20 12:41:47 realbox systemd[1]: systemd-journald.service: Got notification message from PID 414 (FDSTORE=1)
>May 20 12:41:47 realbox gnome-session-binary[2170]: DEBUG(+): Enabling debugging
>May 20 12:41:47 realbox org.gnome.Shell.desktop[1927]: SUCCESS
>May 20 12:41:47 realbox org.gnome.Shell.desktop[1927]: <<<< ASYNC COMPLETE org.freedesktop.DBus.Properties.GetAll() (serial 140)
>May 20 12:41:47 realbox org.gnome.Shell.desktop[1927]: GDBus-debug:Call:
>May 20 12:41:47 realbox org.gnome.Shell.desktop[1927]: ========================================================================
>May 20 12:41:47 realbox gdm-password][2152]: AccountsService: ActUserManager: updating user linux
>May 20 12:41:47 realbox gdm-password][2152]: AccountsService: ActUserManager: sent user-changed signal for user linux
>May 20 12:41:47 realbox gdm-password][2152]: AccountsService: ActUserManager: sending user-changed signal for user linux
>May 20 12:41:47 realbox gdm-launch-environment][1389]: AccountsService: ActUserManager: updating user linux
>May 20 12:41:47 realbox gdm-launch-environment][1389]: AccountsService: ActUserManager: sent user-changed signal for user linux
>May 20 12:41:47 realbox gdm-launch-environment][1389]: AccountsService: ActUserManager: sending user-changed signal for user linux
>May 20 12:41:47 realbox org.gnome.Shell.desktop[1927]: owned by name :1.15 (serial 140)
>May 20 12:41:47 realbox org.gnome.Shell.desktop[1927]: on object /org/freedesktop/Accounts/User1000
>May 20 12:41:47 realbox org.gnome.Shell.desktop[1927]: >>>> ASYNC org.freedesktop.DBus.Properties.GetAll()
>May 20 12:41:47 realbox org.gnome.Shell.desktop[1927]: GDBus-debug:Call:
>May 20 12:41:47 realbox org.gnome.Shell.desktop[1927]: ========================================================================
>May 20 12:41:47 realbox checkproc[2267]: checkproc: can not get session id for process 2373!
>May 20 12:41:47 realbox systemd[1]: systemd-journald.service: Added fd to fd store.
>May 20 12:41:47 realbox systemd[1]: systemd-journald.service: Got notification message from PID 414 (FDSTORE=1)
>May 20 12:41:47 realbox systemd[1]: systemd-journald.service: Added fd to fd store.
>May 20 12:41:47 realbox systemd[1]: systemd-journald.service: Got notification message from PID 414 (FDSTORE=1)
>May 20 12:41:47 realbox org.a11y.atspi.Registry[2259]: GDBus-debug:Address: Returning address 'unix:abstract=/tmp/dbus-Z5CBrn32qu,guid=e5ef0a414d8fd36ebf3c5dc1573ee9ea' for bus type 'session'
>May 20 12:41:47 realbox org.a11y.atspi.Registry[2259]: GDBus-debug:Address: env var DBUS_STARTER_BUS_TYPE='accessibility'
>May 20 12:41:47 realbox org.a11y.atspi.Registry[2259]: GDBus-debug:Address: env var DBUS_SYSTEM_BUS_ADDRESS is not set
>May 20 12:41:47 realbox org.a11y.atspi.Registry[2259]: GDBus-debug:Address: env var DBUS_SESSION_BUS_ADDRESS='unix:abstract=/tmp/dbus-Z5CBrn32qu,guid=e5ef0a414d8fd36ebf3c5dc1573ee9ea'
>May 20 12:41:47 realbox org.a11y.atspi.Registry[2259]: GDBus-debug:Address: In g_dbus_address_get_for_bus_sync() for bus type 'session'
>May 20 12:41:47 realbox org.a11y.atspi.Registry[2259]: SpiRegistry daemon is running with well-known name - org.a11y.atspi.Registry
>May 20 12:41:47 realbox org.gtk.vfs.Daemon[2168]: (process:2242): gvfsd-fuse-DEBUG: register_fuse_cb: Entered
>May 20 12:41:47 realbox org.gtk.vfs.Daemon[2168]: (process:2242): gvfsd-fuse-DEBUG: subthread_main: Running mainloop...
>May 20 12:41:47 realbox org.gtk.vfs.Daemon[2168]: (process:2242): gvfsd-fuse-DEBUG: subthread_main: Connecting mount callbacks
>May 20 12:41:47 realbox org.gtk.vfs.Daemon[2168]: (process:2242): gvfsd-fuse-DEBUG: subthread_main: Entered...
>May 20 12:41:47 realbox org.gtk.vfs.Daemon[2168]: (process:2242): GVFS-DEBUG: g_daemon_volume_monitor_init: connecting mounted/unmounted signals on mount_tracker...
>May 20 12:41:47 realbox org.a11y.Bus[2168]: Successfully activated service 'org.a11y.atspi.Registry'
>May 20 12:41:47 realbox checkproc[2266]: checkproc: can not get session id for process 2603!
>May 20 12:41:47 realbox org.gtk.vfs.Daemon[2168]: (process:2242): GVFS-DEBUG: init_connection_sync: tracker->connection: :1.8
>May 20 12:41:47 realbox org.gtk.vfs.Daemon[2168]: (process:2242): GVFS-DEBUG: g_mount_tracker_constructor: entered...
>May 20 12:41:47 realbox org.gtk.vfs.Daemon[2168]: (process:2242): GVFS-DEBUG: g_mount_tracker_new: async_bus name: :1.8
>May 20 12:41:47 realbox org.gtk.vfs.Daemon[2168]: (process:2242): GVFS-DEBUG: g_mount_tracker_new: entered...
>May 20 12:41:47 realbox org.gtk.vfs.Daemon[2168]: (process:2242): GVFS-DEBUG: g_daemon_volume_monitor_init: creating new g_mount_tracker and retriving daemon_vfs bus...
>May 20 12:41:47 realbox org.gtk.vfs.Daemon[2168]: (process:2242): GVFS-DEBUG: g_daemon_volume_monitor_init: entered...
>May 20 12:41:47 realbox org.gtk.vfs.Daemon[2168]: (process:2242): GVFS-DEBUG: g_daemon_vfs_init: loading GIO modules from /usr/lib64/gvfs/modules...
>May 20 12:41:47 realbox org.a11y.Bus[2168]: ** (process:2249): WARNING **: Failed to register client: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name org.gnome.SessionManager was not provided by any .service files
>May 20 12:41:47 realbox org.a11y.Bus[2168]: ** (process:2249): DEBUG: a11y bus address: unix:abstract=/tmp/dbus-sklC1cWI9o,guid=e484aaa0c50404ad1057379c573ee9eb
>May 20 12:41:47 realbox org.a11y.Bus[2168]: ** (process:2249): DEBUG: Launched a11y bus, child is 2259
>May 20 12:41:47 realbox org.gtk.vfs.Daemon[2168]: (process:2242): GVFS-DEBUG: g_daemon_vfs_init: async_bus name: ':1.8'
>May 20 12:41:47 realbox org.gtk.vfs.Daemon[2168]: (process:2242): GVFS-DEBUG: g_daemon_vfs_init: entered
>May 20 12:41:47 realbox org.gtk.vfs.Daemon[2168]: (process:2242): GVFS-DEBUG: g_io_module_load: loading up as we seem to have a session bus...
>May 20 12:41:47 realbox org.gtk.vfs.Daemon[2168]: (process:2242): GVFS-DEBUG: gvfs_have_session_bus: dbus session bus already running with address 'unix:abstract=/tmp/dbus-Z5CBrn32qu,guid=e5ef0a414d8fd36ebf3c5dc1573ee9ea'
>May 20 12:41:47 realbox org.gtk.vfs.Daemon[2168]: (process:2242): GVFS-DEBUG: g_io_module_load: entered...
>May 20 12:41:47 realbox org.a11y.Bus[2168]: Activating service name='org.a11y.atspi.Registry'
>May 20 12:41:47 realbox /usr/lib/gdm/gdm-x-session[2163]: Successfully activated service 'org.a11y.Bus'
>May 20 12:41:47 realbox org.gtk.vfs.Daemon[2168]: (process:2242): gvfsd-fuse-DEBUG: vfs_init: async_bus name: :1.8
>May 20 12:41:47 realbox org.a11y.Bus[2168]: GDBus-debug:Address: Returning address 'unix:abstract=/tmp/dbus-Z5CBrn32qu,guid=e5ef0a414d8fd36ebf3c5dc1573ee9ea' for bus type 'session'
>May 20 12:41:47 realbox org.a11y.Bus[2168]: GDBus-debug:Address: env var DBUS_STARTER_BUS_TYPE='session'
>May 20 12:41:47 realbox org.a11y.Bus[2168]: GDBus-debug:Address: env var DBUS_SYSTEM_BUS_ADDRESS is not set
>May 20 12:41:47 realbox org.a11y.Bus[2168]: GDBus-debug:Address: env var DBUS_SESSION_BUS_ADDRESS='unix:abstract=/tmp/dbus-Z5CBrn32qu,guid=e5ef0a414d8fd36ebf3c5dc1573ee9ea'
>May 20 12:41:47 realbox org.a11y.Bus[2168]: GDBus-debug:Address: In g_dbus_address_get_for_bus_sync() for bus type 'session'
>May 20 12:41:47 realbox org.gtk.vfs.Daemon[2168]: GDBus-debug:Address: Returning address 'unix:abstract=/tmp/dbus-Z5CBrn32qu,guid=e5ef0a414d8fd36ebf3c5dc1573ee9ea' for bus type 'session'
>May 20 12:41:47 realbox org.gtk.vfs.Daemon[2168]: GDBus-debug:Address: env var DBUS_STARTER_BUS_TYPE='session'
>May 20 12:41:47 realbox org.gtk.vfs.Daemon[2168]: GDBus-debug:Address: env var DBUS_SYSTEM_BUS_ADDRESS is not set
>May 20 12:41:47 realbox org.gtk.vfs.Daemon[2168]: GDBus-debug:Address: env var DBUS_SESSION_BUS_ADDRESS='unix:abstract=/tmp/dbus-Z5CBrn32qu,guid=e5ef0a414d8fd36ebf3c5dc1573ee9ea'
>May 20 12:41:47 realbox org.gtk.vfs.Daemon[2168]: GDBus-debug:Address: In g_dbus_address_get_for_bus_sync() for bus type 'session'
>May 20 12:41:47 realbox org.gtk.vfs.Daemon[2168]: (process:2242): gvfsd-fuse-DEBUG: vfs_init: uid 1000, gid 100
>May 20 12:41:47 realbox org.gtk.vfs.Daemon[2168]: (process:2242): gvfsd-fuse-DEBUG: main: starting...
>...
>May 20 12:41:47 realbox systemd[1]: run-user-1000-gvfs.mount: Changed dead -> mounted
>May 20 12:41:47 realbox systemd[1]: libmount event [rescan: yes]
>...
>May 20 12:41:47 realbox /usr/lib/gdm/gdm-x-session[2163]: Activating service name='org.a11y.Bus'
>May 20 12:41:47 realbox /usr/lib/gdm/gdm-x-session[2163]: Successfully activated service 'org.gtk.vfs.Daemon'
>May 20 12:41:47 realbox org.gtk.vfs.Daemon[2168]: ** (gvfsd:2229): DEBUG: on_bus_acquired: entered...
>May 20 12:41:47 realbox org.gtk.vfs.Daemon[2168]: ** (gvfsd:2229): DEBUG: main: Entering main_loop...
>May 20 12:41:47 realbox org.gtk.vfs.Daemon[2168]: GDBus-debug:Address: Returning address 'unix:abstract=/tmp/dbus-Z5CBrn32qu,guid=e5ef0a414d8fd36ebf3c5dc1573ee9ea' for bus type 'session'
>May 20 12:41:47 realbox org.gtk.vfs.Daemon[2168]: GDBus-debug:Address: env var DBUS_STARTER_BUS_TYPE='session'
>May 20 12:41:47 realbox org.gtk.vfs.Daemon[2168]: GDBus-debug:Address: env var DBUS_SYSTEM_BUS_ADDRESS is not set
>May 20 12:41:47 realbox org.gtk.vfs.Daemon[2168]: GDBus-debug:Address: env var DBUS_SESSION_BUS_ADDRESS='unix:abstract=/tmp/dbus-Z5CBrn32qu,guid=e5ef0a414d8fd36ebf3c5dc1573ee9ea'
>May 20 12:41:47 realbox org.gtk.vfs.Daemon[2168]: GDBus-debug:Address: In g_dbus_address_get_for_bus_sync() for bus type 'session'
>May 20 12:41:47 realbox systemd[1]: systemd-journald.service: Added fd to fd store.
>May 20 12:41:47 realbox systemd[1]: systemd-journald.service: Got notification message from PID 414 (FDSTORE=1)
>May 20 12:41:47 realbox systemd[1]: systemd-journald.service: Added fd to fd store.
>May 20 12:41:47 realbox systemd[1]: systemd-journald.service: Got notification message from PID 414 (FDSTORE=1)
>May 20 12:41:47 realbox /usr/lib/gdm/gdm-x-session[2163]: Activating service name='org.gtk.vfs.Daemon'
>May 20 12:41:47 realbox /usr/lib/gdm/gdm-x-session[2163]: Successfully activated service 'ca.desrt.dconf'
>May 20 12:41:47 realbox ca.desrt.dconf[2168]: GDBus-debug:Address: Returning address 'unix:abstract=/tmp/dbus-Z5CBrn32qu,guid=e5ef0a414d8fd36ebf3c5dc1573ee9ea' for bus type 'session'
>May 20 12:41:47 realbox ca.desrt.dconf[2168]: GDBus-debug:Address: env var DBUS_STARTER_BUS_TYPE='session'
>May 20 12:41:47 realbox ca.desrt.dconf[2168]: GDBus-debug:Address: env var DBUS_SYSTEM_BUS_ADDRESS is not set
>May 20 12:41:47 realbox ca.desrt.dconf[2168]: GDBus-debug:Address: env var DBUS_SESSION_BUS_ADDRESS='unix:abstract=/tmp/dbus-Z5CBrn32qu,guid=e5ef0a414d8fd36ebf3c5dc1573ee9ea'
>May 20 12:41:47 realbox ca.desrt.dconf[2168]: GDBus-debug:Address: In g_dbus_address_get_for_bus_sync() for bus type 'session'
>May 20 12:41:47 realbox systemd[1]: systemd-journald.service: Added fd to fd store.
>May 20 12:41:47 realbox systemd[1]: systemd-journald.service: Got notification message from PID 414 (FDSTORE=1)
>May 20 12:41:47 realbox systemd[1]: systemd-journald.service: Added fd to fd store.
>May 20 12:41:47 realbox systemd[1]: systemd-journald.service: Got notification message from PID 414 (FDSTORE=1)
>May 20 12:41:47 realbox /usr/lib/gdm/gdm-x-session[2163]: Activating service name='ca.desrt.dconf'
>May 20 12:41:46 realbox systemd[1227]: Got disconnect on private connection.
>May 20 12:41:46 realbox systemd[1227]: Sent message type=method_return sender=n/a destination=n/a object=n/a interface=n/a member=n/a cookie=1 reply_cookie=1 error=n/a
>May 20 12:41:46 realbox systemd[1227]: Got message type=method_call sender=n/a destination=org.freedesktop.systemd1 object=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=SetEnvironment cookie=1 reply_cookie=0 error=n/
>May 20 12:41:46 realbox systemd[1227]: Accepted new private connection.
>...
>May 20 12:41:46 realbox /usr/lib/gdm/gdm-x-session[2163]: (II) evdev: USB Keyboard : initialized for relative axes.
>...
>May 20 12:41:46 realbox gdm-x-session[2163]: Running X session
>May 20 12:41:46 realbox gdm[1367]: GdmLocalDisplayFactory: display status changed: 2
>May 20 12:41:46 realbox gdm[1367]: Adding or updating utmp record for login
>May 20 12:41:46 realbox gdm[1367]: Writing wtmp session record to /var/log/wtmp
>May 20 12:41:46 realbox gdm[1367]: using ut_line tty8
>May 20 12:41:46 realbox gdm[1367]: using ut_host :1
>May 20 12:41:46 realbox gdm[1367]: using ut_pid 2163
>May 20 12:41:46 realbox gdm[1367]: using ut_tv time 1463740906
>May 20 12:41:46 realbox gdm[1367]: using ut_type USER_PROCESS
>May 20 12:41:46 realbox gdm[1367]: Writing login record
>May 20 12:41:46 realbox gdm[1367]: using ut_user linux
>May 20 12:41:46 realbox gdm[1367]: GdmSession: Setting display device: /dev/tty8
>May 20 12:41:46 realbox gdm[1367]: GdmManager: trying to register new display
>May 20 12:41:46 realbox gdm-x-session[2163]: Updating activation environment
>May 20 12:41:46 realbox gdm-x-session[2163]: session message bus address is: unix:abstract=/tmp/dbus-Z5CBrn32qu,guid=e5ef0a414d8fd36ebf3c5dc1573ee9ea
>May 20 12:41:46 realbox gdm-x-session[2163]: spawn_bus: new dbus session message bus daemon is running? TRUE
>May 20 12:41:46 realbox gdm-x-session[2163]: spawn_bus: starting new dbus session message bus
>May 20 12:41:46 realbox gdm-x-session[2163]: Running session message bus
>...
>May 20 12:41:46 realbox /usr/lib/gdm/gdm-x-session[2163]: (--) NVIDIA(0):
>...
>May 20 12:41:44 realbox gdm-x-session[2163]: Running X server
>May 20 12:41:44 realbox gdm-x-session[2163]: Preparing auth file for X server
>May 20 12:41:44 realbox gdm-x-session[2163]: Enabling debugging
>May 20 12:41:44 realbox systemd[1]: systemd-journald.service: Added fd to fd store.
>May 20 12:41:44 realbox systemd[1]: systemd-journald.service: Got notification message from PID 414 (FDSTORE=1)
>May 20 12:41:44 realbox systemd[1]: systemd-journald.service: Added fd to fd store.
>May 20 12:41:44 realbox systemd[1]: systemd-journald.service: Got notification message from PID 414 (FDSTORE=1)
>May 20 12:41:44 realbox gdm-password][2163]: GVFS: g_io_module_unload: entered...
>May 20 12:41:44 realbox gdm-password][2163]: GVFS: gvfs_have_session_bus: result: FALSE
>May 20 12:41:44 realbox /usr/lib/gdm/gdm-x-session[1453]: gnome-session-binary[1906]: DEBUG(+): emitting SessionIsActive
>May 20 12:41:44 realbox org.gnome.Shell.desktop[1927]: (gnome-shell:1927): AccountsService-DEBUG: Ignoring non-user session c1 (class greeter)
>May 20 12:41:44 realbox org.gnome.Shell.desktop[1927]: (gnome-shell:1927): AccountsService-DEBUG: ActUserManager: updating user linux
>May 20 12:41:44 realbox org.gnome.Shell.desktop[1927]: (gnome-shell:1927): AccountsService-DEBUG: ActUserManager: sent user-changed signal for user linux
>May 20 12:41:44 realbox org.gnome.Shell.desktop[1927]: (gnome-shell:1927): AccountsService-DEBUG: ActUserManager: sending user-changed signal for user linux
>May 20 12:41:44 realbox /usr/lib/gdm/gdm-x-session[1453]: (II) systemd-logind: got pause for 13:82
>May 20 12:41:44 realbox /usr/lib/gdm/gdm-x-session[1453]: (II) systemd-logind: got pause for 13:67
>May 20 12:41:44 realbox /usr/lib/gdm/gdm-x-session[1453]: (II) systemd-logind: got pause for 13:76
>May 20 12:41:44 realbox /usr/lib/gdm/gdm-x-session[1453]: (II) systemd-logind: got pause for 13:64
>May 20 12:41:44 realbox /usr/lib/gdm/gdm-x-session[1453]: (II) systemd-logind: got pause for 13:65
>May 20 12:41:44 realbox gnome-session-binary[1906]: DEBUG(+): emitting SessionIsActive
>May 20 12:41:44 realbox gdm-password][2152]: AccountsService: ActUserManager: updating user linux
>May 20 12:41:44 realbox gdm-password][2152]: AccountsService: ActUserManager: sent user-changed signal for user linux
>May 20 12:41:44 realbox gdm-password][2152]: AccountsService: ActUserManager: sending user-changed signal for user linux
>May 20 12:41:44 realbox gdm-password][2163]: GVFS: gvfs_have_session_bus: no DBUS_SESSION_BUS_ADDRESS set in environment
>May 20 12:41:44 realbox gdm-password][2163]: GVFS: g_io_module_load: entered...
>May 20 12:41:44 realbox gdm-password][2152]: AccountsService: ActUserManager: ignoring untracked user /org/freedesktop/Accounts/User109
>May 20 12:41:44 realbox gdm[1367]: GdmManager: session started 2163
>May 20 12:41:44 realbox gdm[1367]: GdmSession: Emitting 'session-started' signal with pid '2163'
>May 20 12:41:44 realbox gdm-password][2152]: GdmSession worker: watching pid 2163
>May 20 12:41:44 realbox gdm-password][2152]: GdmSessionWorker: state SESSION_STARTED
>...

Actions: View

Attachments on bug 980521: 677410 | 677749 | 678158