[gdm-list] Failed to set slave bus name on parent: Timeout was reached (again)

[Fabio Erculiani <lxnay sabayon org>]

Hi,
following the Oct 28 post by Eugen on this ML, which has been left
with no answers, I wanted to add that I'm running into the same exact
problem since I upgraded to GNOME 3.6 (thus gdm-3.6) in Sabayon Linux.
GDM takes more than 25 seconds to start and there seem to be a dbus
synchronous call timing out during that time.

This is what happens (running inside gdb):

localhost ~ # gdb /usr/sbin/gdm-binary
GNU gdb (Gentoo 7.5 p1) 7.5
Copyright (C) 2012 Free Software Foundation, Inc.
License GPLv3+: GNU GPL version 3 or later <http://gnu.org/licenses/gpl.html>
This is free software: you are free to change and redistribute it.
There is NO WARRANTY, to the extent permitted by law.  Type "show copying"
and "show warranty" for details.
This GDB was configured as "x86_64-pc-linux-gnu".
For bug reporting instructions, please see:
<http://bugs.gentoo.org/>...
Reading symbols from /usr/sbin/gdm-binary...Reading symbols from
/usr/lib64/debug/usr/sbin/gdm-binary.debug...done.
done.
(gdb) set args --nodaemon
(gdb) r
Starting program: /usr/sbin/gdm-binary --nodaemon
warning: Could not load shared library symbols for linux-vdso.so.1.
Do you need "set solib-search-path" or "set sysroot"?
[Thread debugging using libthread_db enabled]
Using host libthread_db library "/lib64/libthread_db.so.1".
[New Thread 0x7ffff5722700 (LWP 12600)]
gdm-binary[12597]: DEBUG(+): Enabling debugging
gdm-binary[12597]: DEBUG(+): Changing user:group to gdm:gdm
gdm-binary[12597]: DEBUG(+): GdmSignalHandler: Adding handler 1: signum=4 (nil)
gdm-binary[12597]: DEBUG(+): GdmSignalHandler: Registering for 4 signals
gdm-binary[12597]: DEBUG(+): GdmSignalHandler: Adding handler 2: signum=7 (nil)
gdm-binary[12597]: DEBUG(+): GdmSignalHandler: Registering for 7 signals
gdm-binary[12597]: DEBUG(+): GdmSignalHandler: Adding handler 3: signum=11 (nil)
gdm-binary[12597]: DEBUG(+): GdmSignalHandler: Registering for 11 signals
gdm-binary[12597]: DEBUG(+): GdmSignalHandler: Adding handler 4: signum=6 (nil)
gdm-binary[12597]: DEBUG(+): GdmSignalHandler: Registering for 6 signals
gdm-binary[12597]: DEBUG(+): GdmSignalHandler: Adding handler 5: signum=5 (nil)
gdm-binary[12597]: DEBUG(+): GdmSignalHandler: Registering for 5 signals
gdm-binary[12597]: DEBUG(+): GdmSignalHandler: Adding handler 6:
signum=15 0x40a373
gdm-binary[12597]: DEBUG(+): GdmSignalHandler: Registering for 15 signals
gdm-binary[12597]: DEBUG(+): GdmSignalHandler: Adding handler 7:
signum=2 0x40a373
gdm-binary[12597]: DEBUG(+): GdmSignalHandler: Registering for 2 signals
gdm-binary[12597]: DEBUG(+): GdmSignalHandler: Adding handler 8:
signum=8 0x40a373
gdm-binary[12597]: DEBUG(+): GdmSignalHandler: Registering for 8 signals
gdm-binary[12597]: DEBUG(+): GdmSignalHandler: Adding handler 9:
signum=1 0x40a373
gdm-binary[12597]: DEBUG(+): GdmSignalHandler: Registering for 1 signals
gdm-binary[12597]: DEBUG(+): GdmSignalHandler: Adding handler 10:
signum=10 0x40a373
gdm-binary[12597]: DEBUG(+): GdmSignalHandler: Registering for 10 signals
gdm-binary[12597]: DEBUG(+): Successfully connected to D-Bus
gdm-binary[12597]: DEBUG(+): GdmManager: GDM starting to manage displays
gdm-binary[12597]: DEBUG(+): GdmLocalDisplayFactory: Adding display on
seat /org/freedesktop/ConsoleKit/Seat1
gdm-binary[12597]: DEBUG(+): GdmLocalDisplayFactory: Reserving X display: 0
gdm-binary[12597]: DEBUG(+): GdmDisplayStore: Adding display
/org/gnome/DisplayManager/Displays/_0 to store
gdm-binary[12597]: DEBUG(+): GdmDisplay: Managing display:
/org/gnome/DisplayManager/Displays/_0
gdm-binary[12597]: DEBUG(+): GdmDisplay: manage display
gdm-binary[12597]: DEBUG(+): GdmDisplay: Preparing display:
/org/gnome/DisplayManager/Displays/_0
gdm-binary[12597]: DEBUG(+): GdmDisplay: prepare display
gdm-binary[12597]: DEBUG(+): GdmDisplayAccessFile: creating xauth
directory /var/run/gdm/auth-for-gdm-XXXXXX
gdm-binary[12597]: DEBUG(+): GdmDisplayAccessFile: chowning
/var/run/gdm/auth-for-gdm-8PSSK9 to 109:999
gdm-binary[12597]: DEBUG(+): GdmDisplayAccessFile: creating
/var/run/gdm/auth-for-gdm-8PSSK9/database
gdm-binary[12597]: DEBUG(+): GdmDisplayAccessFile: chowning
/var/run/gdm/auth-for-gdm-8PSSK9/database to 109:999
gdm-binary[12597]: DEBUG(+): GdmDisplayAccessFile: chmoding
/var/run/gdm/auth-for-gdm-8PSSK9 to 0711
gdm-binary[12597]: DEBUG(+): GdmLocalDisplayFactory: display status changed: 1
gdm-binary[12597]: DEBUG(+): GdmSlaveProxy: Killing slave
gdm-binary[12597]: DEBUG(+): GdmSlaveProxy: Running command:
/usr/libexec/gdm-simple-slave --display-id
/org/gnome/DisplayManager/Displays/_0
gdm-binary[12597]: DEBUG(+): GdmSlaveProxy: Started slave with pid 12602
[New Thread 0x7fffeffff700 (LWP 12603)]
gdm-binary[12597]: DEBUG(+): GdmDisplay: Setting slave bus name::1.88
on display :0
^Z
Program received signal SIGTSTP, Stopped (user).
0x00007ffff660daa3 in poll () from /lib64/libc.so.6
(gdb) bt
#0  0x00007ffff660daa3 in poll () from /lib64/libc.so.6
#1  0x00007ffff7154ae6 in g_main_context_iterate.isra.25 () from
/usr/lib64/libglib-2.0.so.0
#2  0x00007ffff7154f4a in g_main_loop_run () from /usr/lib64/libglib-2.0.so.0
#3  0x00007ffff774bdcc in initable_init () from /usr/lib64/libgio-2.0.so.0
#4  0x00007ffff76daf2f in g_initable_new_valist () from
/usr/lib64/libgio-2.0.so.0
#5  0x00007ffff76db019 in g_initable_new () from /usr/lib64/libgio-2.0.so.0
#6  0x0000000000422738 in gdm_dbus_slave_proxy_new_sync
(connection=0x657030, flags=G_DBUS_PROXY_FLAGS_NONE, name=0x677a20
":1.88", object_path=0x42cfe8 "/org/gnome/DisplayManager/Slave",
cancellable=0x0, error=0x0)
    at gdm-slave-glue.c:1066
#7  0x000000000040ddeb in gdm_display_real_set_slave_bus_name
(display=0x657130, name=0x677a20 ":1.88", error=<optimized out>) at
gdm-display.c:312
#8  0x000000000040f414 in gdm_display_set_slave_bus_name
(display=0x657130, name=0x677a20 ":1.88", error=0x7fffffffd2a8) at
gdm-display.c:338
#9  0x000000000040f460 in handle_set_slave_bus_name
(skeleton=0x664170, invocation=0x7ffff0003f60, bus_name=<optimized
out>, display=<optimized out>) at gdm-display.c:1205
#10 0x00007ffff5cfae5c in ffi_call_unix64 () from /usr/lib64/libffi.so.6
#11 0x00007ffff5cfa8c5 in ffi_call () from /usr/lib64/libffi.so.6
#12 0x00007ffff7440e38 in g_cclosure_marshal_generic () from
/usr/lib64/libgobject-2.0.so.0
#13 0x00007ffff74405f2 in g_closure_invoke () from
/usr/lib64/libgobject-2.0.so.0
#14 0x00007ffff74519e1 in signal_emit_unlocked_R () from
/usr/lib64/libgobject-2.0.so.0
#15 0x00007ffff7458e79 in g_signal_emitv () from /usr/lib64/libgobject-2.0.so.0
#16 0x000000000041c02e in
_gdm_dbus_display_skeleton_handle_method_call (connection=<optimized
out>, sender=<optimized out>, object_path=<optimized out>,
interface_name=0x7ffff0004d00 "org.gnome.DisplayManager.Display",
    method_name=0x7ffff0002fe0 "SetSlaveBusName",
parameters=<optimized out>, invocation=0x7ffff0003f60,
user_data=0x664170) at gdm-display-glue.c:3131
#17 0x00007ffff7756c77 in skeleton_intercept_handle_method_call ()
from /usr/lib64/libgio-2.0.so.0
#18 0x00007ffff773f0eb in call_in_idle_cb () from /usr/lib64/libgio-2.0.so.0
#19 0x00007ffff7154803 in g_main_context_dispatch () from
/usr/lib64/libglib-2.0.so.0
#20 0x00007ffff7154b50 in g_main_context_iterate.isra.25 () from
/usr/lib64/libglib-2.0.so.0
#21 0x00007ffff7154f4a in g_main_loop_run () from /usr/lib64/libglib-2.0.so.0
#22 0x000000000040adb4 in main (argc=1, argv=0x7fffffffdc08) at main.c:594


gdm_dbus_slave_proxy_new_sync takes forever and I really don't know
what's going on. You are using gdbus-codegen, which makes the
understanding of what's going on even harder.
I have the same issues when using both systemd and OpenRC and it does
not seem directly related to ConsoleKit.

GDM is 3.6.2. Let me know what other piece of information you need.

-- 
Fabio Erculiani