Attachment 66886 Details for Bug 148229

debug log file

Y (text/plain), 67.21 KB, created by Andreas Kleen on 2006-02-07 22:01:10 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Andreas Kleen

Created: 2006-02-07 22:01:10 UTC

Size: 67.21 KB

patch

obsolete

>Dec 17 12:32:34 alberich useradd[12401]: new account added - account=gdm, uid=50, gid=15, home=/var/lib/gdm, shell=/bin/bash, by=0
>Dec 17 12:32:34 alberich useradd[12401]: running USERADD_CMD command - script=/usr/sbin/useradd.local, account=gdm, uid=50, gid=15, home=/var/lib/gdm, by=0
>Jan 20 22:36:18 alberich useradd[14229]: account already exists - account=gdm, by=0
>Feb  4 08:11:37 alberich useradd[14759]: account already exists - account=gdm, by=0
>Feb  4 08:56:00 alberich gdm[25106]: failsafe dialog failed (inhibitions: 0 0)
>Feb  4 08:56:07 alberich gdm[25106]: failsafe dialog failed (inhibitions: 0 1)
>Feb  4 08:56:14 alberich gdm[25106]: failsafe dialog failed (inhibitions: 1 1)
>Feb  4 08:56:14 alberich gdm[25106]: Error reinitilizing server
>Feb  4 09:05:28 alberich gdm[27048]: GDM detected a shutdown or reboot in progress.
>Feb  4 11:32:01 alberich gdm[11262]: Error reinitilizing server
>Feb  6 08:24:51 alberich gdm[4319]: gdm_main: Here we go...
>Feb  6 08:24:51 alberich gdm[4319]: gdm_start_first_unborn_local: Starting :0
>Feb  6 08:24:51 alberich gdm[4319]: gdm_display_manage: Managing :0
>Feb  6 08:24:51 alberich gdm[4319]: loop check: last_start 0, last_loop 0, now: 1139210691, retry_count: 0
>Feb  6 08:24:51 alberich gdm[4319]: Resetting counts for loop of death detection
>Feb  6 08:24:51 alberich gdm[4319]: gdm_display_manage: Forked slave: 4322
>Feb  6 08:24:51 alberich gdm[4322]: gdm_slave_start: Starting slave process for :0
>Feb  6 08:24:51 alberich gdm[4322]: gdm_slave_start: Loop Thingie
>Feb  6 08:24:51 alberich gdm[4322]: Sending VT_NUM == -1 for slave 4322
>Feb  6 08:24:51 alberich gdm[4319]: Handling message: 'VT_NUM 4322 -1'
>Feb  6 08:24:51 alberich gdm[4319]: Got VT_NUM == -1
>Feb  6 08:24:51 alberich gdm[4322]: gdm_server_start: :0
>Feb  6 08:24:51 alberich gdm[4322]: gdm_auth_secure_display: Setting up access for :0
>Feb  6 08:24:51 alberich gdm[4322]: gdm_auth_secure_display: Setting up access
>Feb  6 08:24:51 alberich gdm[4322]: gdm_auth_secure_display: Setting up access for :0 - 1 entries
>Feb  6 08:24:51 alberich gdm[4322]: Sending COOKIE == <secret> for slave 4322
>Feb  6 08:24:51 alberich gdm[4319]: Handling message: 'COOKIE 4322 81...'
>Feb  6 08:24:51 alberich gdm[4319]: Got COOKIE == <secret>
>Feb  6 08:24:51 alberich gdm[4322]: Sending AUTHFILE == <secret> for slave 4322
>Feb  6 08:24:51 alberich gdm[4319]: Handling message: 'AUTHFILE 4322 /var/lib/gdm/:0.Xauth'
>Feb  6 08:24:51 alberich gdm[4319]: Got AUTHFILE == /var/lib/gdm/:0.Xauth
>Feb  6 08:24:51 alberich gdm[4337]: gdm_server_spawn: '/usr/X11R6/bin/X :0 -audit 0 -auth /var/lib/gdm/:0.Xauth -nolisten tcp vt7'
>Feb  6 08:24:51 alberich gdm[4322]: gdm_server_spawn: Forked server on pid 4337
>Feb  6 08:24:51 alberich gdm[4322]: gdm_server_start: Completed :0!
>Feb  6 08:24:51 alberich gdm[4322]: Sending VT_NUM == 7 for slave 4322
>Feb  6 08:24:51 alberich gdm[4319]: Handling message: 'VT_NUM 4322 7'
>Feb  6 08:24:51 alberich gdm[4319]: Got VT_NUM == 7
>Feb  6 08:24:51 alberich gdm[4322]: Sending XPID == 4337 for slave 4322
>Feb  6 08:24:51 alberich gdm[4319]: Handling message: 'XPID 4322 4337'
>Feb  6 08:24:51 alberich gdm[4319]: Got XPID == 4337
>Feb  6 08:24:51 alberich gdm[4322]: gdm_slave_run: Opening display :0
>Feb  6 08:24:54 alberich gdm[4319]: Handling message: 'START_NEXT_LOCAL'
>Feb  6 08:24:54 alberich gdm[4322]: gdm_slave_greeter: Running greeter on :0
>Feb  6 08:24:54 alberich gdm[4322]: gdm_slave_greeter: Greeter on pid 4350
>Feb  6 08:24:54 alberich gdm[4322]: Sending GREETPID == 4350 for slave 4322
>Feb  6 08:24:54 alberich gdm[4319]: Handling message: 'GREETPID 4322 4350'
>Feb  6 08:24:54 alberich gdm[4319]: Got GREETPID == 4350
>Feb  6 08:24:54 alberich gdm[4319]: gdm_socket_handler: Accepting new connection fd 8
>Feb  6 08:24:54 alberich gdm[4319]: Handling user message: 'VERSION'
>Feb  6 08:24:54 alberich gdm[4319]: Handling user message: 'GET_CONFIG_FILE'
>Feb  6 08:24:54 alberich gdm[4319]: Handling user message: 'CLOSE'
>Feb  6 08:24:55 alberich gdm[4322]: gdm_slave_wait_for_login: In loop
>Feb  6 08:25:00 alberich gdm[4322]: pam_unix2(gdm:auth): bad username []
>Feb  6 08:25:01 alberich gdm[4322]: Couldn't authenticate user
>Feb  6 08:25:01 alberich gdm[4322]: gdm_slave_wait_for_login: end verify for ''
>Feb  6 08:25:01 alberich gdm[4322]: gdm_slave_wait_for_login: No login/Bad login
>Feb  6 08:25:01 alberich gdm[4322]: gdm_slave_wait_for_login: In loop
>Feb  6 08:25:08 alberich gdm[4322]: term_quit: Final cleanup
>Feb  6 08:25:08 alberich gdm[4322]: gdm_slave_quick_exit: Will kill everything from the display
>Feb  6 08:25:08 alberich gdm[4322]: Running gdm_verify_cleanup and pamh != NULL
>Feb  6 08:25:08 alberich gdm[4322]: gdm_server_stop: Server for :0 going down!
>Feb  6 08:25:08 alberich gdm[4322]: gdm_server_stop: Killing server pid 4337
>Feb  6 08:25:08 alberich gdm[4322]: gdm_server_stop: Server pid 4337 dead
>Feb  6 08:25:08 alberich gdm[4322]: gdm_slave_quick_exit: Killed everything from the display
>Feb  6 08:25:08 alberich gdm[4319]: mainloop_sig_callback: Got signal 17
>Feb  6 08:25:08 alberich gdm[4319]: gdm_cleanup_children: child 4322 returned 65
>Feb  6 08:25:08 alberich gdm[4319]: gdm_child_action: In remanage
>Feb  6 08:25:08 alberich gdm[4319]: gdm_display_manage: Managing :0
>Feb  6 08:25:08 alberich gdm[4319]: loop check: last_start 1139210691, last_loop 1139210691, now: 1139210708, retry_count: 1
>Feb  6 08:25:08 alberich gdm[4353]: gdm_slave_start: Starting slave process for :0
>Feb  6 08:25:08 alberich gdm[4353]: gdm_slave_start: Loop Thingie
>Feb  6 08:25:08 alberich gdm[4353]: gdm_slave_run: Sleeping 1 seconds before server start
>Feb  6 08:25:08 alberich gdm[4319]: gdm_display_manage: Forked slave: 4353
>Feb  6 08:25:09 alberich gdm[4353]: Sending VT_NUM == -1 for slave 4353
>Feb  6 08:25:09 alberich gdm[4319]: Handling message: 'VT_NUM 4353 -1'
>Feb  6 08:25:09 alberich gdm[4319]: Got VT_NUM == -1
>Feb  6 08:25:09 alberich gdm[4353]: gdm_server_start: :0
>Feb  6 08:25:09 alberich gdm[4353]: gdm_auth_secure_display: Setting up access for :0
>Feb  6 08:25:09 alberich gdm[4353]: gdm_auth_secure_display: Setting up access
>Feb  6 08:25:09 alberich gdm[4353]: gdm_auth_secure_display: Setting up access for :0 - 1 entries
>Feb  6 08:25:09 alberich gdm[4353]: Sending COOKIE == <secret> for slave 4353
>Feb  6 08:25:09 alberich gdm[4319]: Handling message: 'COOKIE 4353 3c...'
>Feb  6 08:25:09 alberich gdm[4319]: Got COOKIE == <secret>
>Feb  6 08:25:09 alberich gdm[4353]: Sending AUTHFILE == <secret> for slave 4353
>Feb  6 08:25:09 alberich gdm[4319]: Handling message: 'AUTHFILE 4353 /var/lib/gdm/:0.Xauth'
>Feb  6 08:25:09 alberich gdm[4319]: Got AUTHFILE == /var/lib/gdm/:0.Xauth
>Feb  6 08:25:09 alberich gdm[4354]: gdm_server_spawn: '/usr/X11R6/bin/X :0 -audit 0 -auth /var/lib/gdm/:0.Xauth -nolisten tcp vt7'
>Feb  6 08:25:09 alberich gdm[4353]: gdm_server_spawn: Forked server on pid 4354
>Feb  6 08:25:09 alberich gdm[4353]: gdm_server_start: Completed :0!
>Feb  6 08:25:09 alberich gdm[4353]: Sending VT_NUM == 7 for slave 4353
>Feb  6 08:25:09 alberich gdm[4319]: Handling message: 'VT_NUM 4353 7'
>Feb  6 08:25:09 alberich gdm[4319]: Got VT_NUM == 7
>Feb  6 08:25:09 alberich gdm[4353]: Sending XPID == 4354 for slave 4353
>Feb  6 08:25:09 alberich gdm[4319]: Handling message: 'XPID 4353 4354'
>Feb  6 08:25:09 alberich gdm[4319]: Got XPID == 4354
>Feb  6 08:25:09 alberich gdm[4353]: gdm_slave_run: Opening display :0
>Feb  6 08:25:12 alberich gdm[4319]: Handling message: 'START_NEXT_LOCAL'
>Feb  6 08:25:12 alberich gdm[4353]: gdm_slave_greeter: Running greeter on :0
>Feb  6 08:25:12 alberich gdm[4353]: gdm_slave_greeter: Greeter on pid 4366
>Feb  6 08:25:12 alberich gdm[4353]: Sending GREETPID == 4366 for slave 4353
>Feb  6 08:25:12 alberich gdm[4319]: Handling message: 'GREETPID 4353 4366'
>Feb  6 08:25:12 alberich gdm[4319]: Got GREETPID == 4366
>Feb  6 08:25:12 alberich gdm[4319]: gdm_socket_handler: Accepting new connection fd 8
>Feb  6 08:25:12 alberich gdm[4319]: Handling user message: 'VERSION'
>Feb  6 08:25:12 alberich gdm[4319]: Handling user message: 'GET_CONFIG_FILE'
>Feb  6 08:25:12 alberich gdm[4319]: Handling user message: 'CLOSE'
>Feb  6 08:25:13 alberich gdm[4353]: gdm_slave_wait_for_login: In loop
>Feb  6 08:25:16 alberich gdm[4353]: term_quit: Final cleanup
>Feb  6 08:25:16 alberich gdm[4353]: gdm_slave_quick_exit: Will kill everything from the display
>Feb  6 08:25:16 alberich gdm[4353]: Running gdm_verify_cleanup and pamh != NULL
>Feb  6 08:25:16 alberich gdm[4353]: gdm_server_stop: Server for :0 going down!
>Feb  6 08:25:16 alberich gdm[4353]: gdm_server_stop: Killing server pid 4354
>Feb  6 08:25:16 alberich gdm[4353]: gdm_server_stop: Server pid 4354 dead
>Feb  6 08:25:16 alberich gdm[4353]: gdm_slave_quick_exit: Killed everything from the display
>Feb  6 08:25:16 alberich gdm[4319]: mainloop_sig_callback: Got signal 17
>Feb  6 08:25:16 alberich gdm[4319]: gdm_cleanup_children: child 4353 returned 65
>Feb  6 08:25:16 alberich gdm[4319]: gdm_child_action: In remanage
>Feb  6 08:25:16 alberich gdm[4319]: gdm_display_manage: Managing :0
>Feb  6 08:25:16 alberich gdm[4319]: loop check: last_start 1139210708, last_loop 1139210691, now: 1139210716, retry_count: 2
>Feb  6 08:25:16 alberich gdm[4369]: gdm_slave_start: Starting slave process for :0
>Feb  6 08:25:16 alberich gdm[4369]: gdm_slave_start: Loop Thingie
>Feb  6 08:25:16 alberich gdm[4369]: gdm_slave_run: Sleeping 1 seconds before server start
>Feb  6 08:25:16 alberich gdm[4319]: gdm_display_manage: Forked slave: 4369
>Feb  6 08:25:17 alberich gdm[4369]: Sending VT_NUM == -1 for slave 4369
>Feb  6 08:25:17 alberich gdm[4319]: Handling message: 'VT_NUM 4369 -1'
>Feb  6 08:25:17 alberich gdm[4319]: Got VT_NUM == -1
>Feb  6 08:25:17 alberich gdm[4369]: gdm_server_start: :0
>Feb  6 08:25:17 alberich gdm[4369]: gdm_auth_secure_display: Setting up access for :0
>Feb  6 08:25:17 alberich gdm[4369]: gdm_auth_secure_display: Setting up access
>Feb  6 08:25:17 alberich gdm[4369]: gdm_auth_secure_display: Setting up access for :0 - 1 entries
>Feb  6 08:25:17 alberich gdm[4369]: Sending COOKIE == <secret> for slave 4369
>Feb  6 08:25:17 alberich gdm[4319]: Handling message: 'COOKIE 4369 83...'
>Feb  6 08:25:17 alberich gdm[4319]: Got COOKIE == <secret>
>Feb  6 08:25:17 alberich gdm[4369]: Sending AUTHFILE == <secret> for slave 4369
>Feb  6 08:25:17 alberich gdm[4319]: Handling message: 'AUTHFILE 4369 /var/lib/gdm/:0.Xauth'
>Feb  6 08:25:17 alberich gdm[4319]: Got AUTHFILE == /var/lib/gdm/:0.Xauth
>Feb  6 08:25:17 alberich gdm[4370]: gdm_server_spawn: '/usr/X11R6/bin/X :0 -audit 0 -auth /var/lib/gdm/:0.Xauth -nolisten tcp vt7'
>Feb  6 08:25:17 alberich gdm[4369]: gdm_server_spawn: Forked server on pid 4370
>Feb  6 08:25:17 alberich gdm[4369]: gdm_server_start: Completed :0!
>Feb  6 08:25:17 alberich gdm[4369]: Sending VT_NUM == 7 for slave 4369
>Feb  6 08:25:17 alberich gdm[4319]: Handling message: 'VT_NUM 4369 7'
>Feb  6 08:25:17 alberich gdm[4319]: Got VT_NUM == 7
>Feb  6 08:25:17 alberich gdm[4369]: Sending XPID == 4370 for slave 4369
>Feb  6 08:25:17 alberich gdm[4319]: Handling message: 'XPID 4369 4370'
>Feb  6 08:25:17 alberich gdm[4319]: Got XPID == 4370
>Feb  6 08:25:17 alberich gdm[4369]: gdm_slave_run: Opening display :0
>Feb  6 08:25:20 alberich gdm[4319]: Handling message: 'START_NEXT_LOCAL'
>Feb  6 08:25:20 alberich gdm[4369]: gdm_slave_greeter: Running greeter on :0
>Feb  6 08:25:20 alberich gdm[4369]: gdm_slave_greeter: Greeter on pid 4382
>Feb  6 08:25:20 alberich gdm[4369]: Sending GREETPID == 4382 for slave 4369
>Feb  6 08:25:20 alberich gdm[4319]: Handling message: 'GREETPID 4369 4382'
>Feb  6 08:25:20 alberich gdm[4319]: Got GREETPID == 4382
>Feb  6 08:25:27 alberich gdm[4319]: gdm_socket_handler: Accepting new connection fd 8
>Feb  6 08:25:27 alberich gdm[4319]: Handling user message: 'VERSION'
>Feb  6 08:25:27 alberich gdm[4319]: Handling user message: 'GET_CONFIG_FILE'
>Feb  6 08:25:27 alberich gdm[4319]: Handling user message: 'CLOSE'
>Feb  6 08:25:29 alberich gdm[4369]: gdm_slave_wait_for_login: In loop
>Feb  6 08:25:42 alberich gdm[4319]: mainloop_sig_callback: Got signal 15
>Feb  6 08:25:42 alberich gdm[4319]: mainloop_sig_callback: Got TERM/INT. Going down!
>Feb  6 08:25:42 alberich gdm[4319]: gdm_final_cleanup
>Feb  6 08:25:42 alberich gdm[4319]: gdm_display_unmanage: Stopping :0 (slave pid: 4369)
>Feb  6 08:25:42 alberich gdm[4369]: term_quit: Final cleanup
>Feb  6 08:25:42 alberich gdm[4369]: gdm_slave_quick_exit: Will kill everything from the display
>Feb  6 08:25:42 alberich gdm[4369]: Running gdm_verify_cleanup and pamh != NULL
>Feb  6 08:25:42 alberich gdm[4369]: gdm_server_stop: Server for :0 going down!
>Feb  6 08:25:42 alberich gdm[4369]: gdm_server_stop: Killing server pid 4370
>Feb  6 08:25:43 alberich gdm[4369]: gdm_server_stop: Server pid 4370 dead
>Feb  6 08:25:43 alberich gdm[4369]: gdm_slave_quick_exit: Killed everything from the display
>Feb  6 08:25:43 alberich gdm[4319]: gdm_display_unmanage: Display stopped
>Feb  6 08:27:04 alberich gdm[3991]: gdm_main: Here we go...
>Feb  6 08:27:04 alberich gdm[3991]: gdm_start_first_unborn_local: Starting :0
>Feb  6 08:27:04 alberich gdm[3991]: gdm_display_manage: Managing :0
>Feb  6 08:27:04 alberich gdm[3991]: loop check: last_start 0, last_loop 0, now: 1139210824, retry_count: 0
>Feb  6 08:27:04 alberich gdm[3991]: Resetting counts for loop of death detection
>Feb  6 08:27:04 alberich gdm[4152]: gdm_slave_start: Starting slave process for :0
>Feb  6 08:27:04 alberich gdm[4152]: gdm_slave_start: Loop Thingie
>Feb  6 08:27:04 alberich gdm[4152]: Sending VT_NUM == -1 for slave 4152
>Feb  6 08:27:04 alberich gdm[3991]: gdm_display_manage: Forked slave: 4152
>Feb  6 08:27:04 alberich gdm[3991]: Handling message: 'VT_NUM 4152 -1'
>Feb  6 08:27:04 alberich gdm[3991]: Got VT_NUM == -1
>Feb  6 08:27:04 alberich gdm[4152]: gdm_server_start: :0
>Feb  6 08:27:04 alberich gdm[4152]: gdm_auth_secure_display: Setting up access for :0
>Feb  6 08:27:05 alberich gdm[4152]: gdm_auth_secure_display: Setting up access
>Feb  6 08:27:05 alberich gdm[4152]: gdm_auth_secure_display: Setting up access for :0 - 1 entries
>Feb  6 08:27:05 alberich gdm[4152]: Sending COOKIE == <secret> for slave 4152
>Feb  6 08:27:05 alberich gdm[3991]: Handling message: 'COOKIE 4152 d5...'
>Feb  6 08:27:05 alberich gdm[3991]: Got COOKIE == <secret>
>Feb  6 08:27:05 alberich gdm[4152]: Sending AUTHFILE == <secret> for slave 4152
>Feb  6 08:27:05 alberich gdm[3991]: Handling message: 'AUTHFILE 4152 /var/lib/gdm/:0.Xauth'
>Feb  6 08:27:05 alberich gdm[3991]: Got AUTHFILE == /var/lib/gdm/:0.Xauth
>Feb  6 08:27:05 alberich gdm[4152]: gdm_server_spawn: Forked server on pid 4158
>Feb  6 08:27:05 alberich gdm[4152]: do_server_wait: Before mainloop waiting for server
>Feb  6 08:27:05 alberich gdm[4158]: gdm_server_spawn: '/usr/X11R6/bin/X :0 -audit 0 -auth /var/lib/gdm/:0.Xauth -nolisten tcp vt7'
>Feb  6 08:27:06 alberich gdm[4152]: gdm_server_start: After mainloop waiting for server
>Feb  6 08:27:06 alberich gdm[4152]: gdm_server_start: Completed :0!
>Feb  6 08:27:06 alberich gdm[4152]: Sending VT_NUM == 7 for slave 4152
>Feb  6 08:27:06 alberich gdm[3991]: Handling message: 'VT_NUM 4152 7'
>Feb  6 08:27:06 alberich gdm[3991]: Got VT_NUM == 7
>Feb  6 08:27:06 alberich gdm[4152]: Sending XPID == 4158 for slave 4152
>Feb  6 08:27:06 alberich gdm[3991]: Handling message: 'XPID 4152 4158'
>Feb  6 08:27:06 alberich gdm[3991]: Got XPID == 4158
>Feb  6 08:27:06 alberich gdm[4152]: gdm_slave_run: Opening display :0
>Feb  6 08:27:11 alberich gdm[3991]: Handling message: 'START_NEXT_LOCAL'
>Feb  6 08:27:11 alberich gdm[4152]: gdm_slave_greeter: Running greeter on :0
>Feb  6 08:27:12 alberich gdm[4152]: gdm_slave_greeter: Greeter on pid 4181
>Feb  6 08:27:12 alberich gdm[4152]: Sending GREETPID == 4181 for slave 4152
>Feb  6 08:27:12 alberich gdm[3991]: Handling message: 'GREETPID 4152 4181'
>Feb  6 08:27:12 alberich gdm[3991]: Got GREETPID == 4181
>Feb  6 08:27:23 alberich gdm[3991]: gdm_socket_handler: Accepting new connection fd 8
>Feb  6 08:27:23 alberich gdm[3991]: Handling user message: 'VERSION'
>Feb  6 08:27:23 alberich gdm[3991]: Handling user message: 'GET_CONFIG_FILE'
>Feb  6 08:27:23 alberich gdm[3991]: Handling user message: 'CLOSE'
>Feb  6 08:27:26 alberich gdm[4152]: gdm_slave_wait_for_login: In loop
>Feb  6 15:42:53 alberich gdm[3991]: mainloop_sig_callback: Got signal 15
>Feb  6 15:42:53 alberich gdm[3991]: mainloop_sig_callback: Got TERM/INT. Going down!
>Feb  6 15:42:53 alberich gdm[3991]: gdm_final_cleanup
>Feb  6 15:42:53 alberich gdm[3991]: gdm_display_unmanage: Stopping :0 (slave pid: 4152)
>Feb  6 15:42:53 alberich gdm[4152]: term_quit: Final cleanup
>Feb  6 15:42:53 alberich gdm[4152]: gdm_slave_quick_exit: Will kill everything from the display
>Feb  6 15:42:53 alberich gdm[4152]: Running gdm_verify_cleanup and pamh != NULL
>Feb  6 15:42:53 alberich gdm[4152]: gdm_server_stop: Server for :0 going down!
>Feb  6 15:42:53 alberich gdm[4152]: gdm_server_stop: Killing server pid 4158
>Feb  6 15:42:53 alberich gdm[4152]: gdm_server_stop: Server pid 4158 dead
>Feb  6 15:42:53 alberich gdm[4152]: gdm_slave_quick_exit: Killed everything from the display
>Feb  6 15:42:53 alberich gdm[3991]: gdm_display_unmanage: Display stopped
>Feb  7 10:05:13 alberich gdm[3914]: gdm_main: Here we go...
>Feb  7 10:05:13 alberich gdm[3914]: gdm_start_first_unborn_local: Starting :0
>Feb  7 10:05:13 alberich gdm[3914]: gdm_display_manage: Managing :0
>Feb  7 10:05:13 alberich gdm[3914]: loop check: last_start 0, last_loop 0, now: 1139303113, retry_count: 0
>Feb  7 10:05:13 alberich gdm[3914]: Resetting counts for loop of death detection
>Feb  7 10:05:13 alberich gdm[4107]: gdm_slave_start: Starting slave process for :0
>Feb  7 10:05:13 alberich gdm[4107]: gdm_slave_start: Loop Thingie
>Feb  7 10:05:13 alberich gdm[4107]: Sending VT_NUM == -1 for slave 4107
>Feb  7 10:05:13 alberich gdm[3914]: gdm_display_manage: Forked slave: 4107
>Feb  7 10:05:13 alberich gdm[3914]: Handling message: 'VT_NUM 4107 -1'
>Feb  7 10:05:13 alberich gdm[3914]: Got VT_NUM == -1
>Feb  7 10:05:13 alberich gdm[4107]: gdm_server_start: :0
>Feb  7 10:05:13 alberich gdm[4107]: gdm_auth_secure_display: Setting up access for :0
>Feb  7 10:05:13 alberich gdm[4107]: gdm_auth_secure_display: Setting up access
>Feb  7 10:05:14 alberich gdm[4107]: gdm_auth_secure_display: Setting up access for :0 - 1 entries
>Feb  7 10:05:14 alberich gdm[4107]: Sending COOKIE == <secret> for slave 4107
>Feb  7 10:05:14 alberich gdm[3914]: Handling message: 'COOKIE 4107 18...'
>Feb  7 10:05:14 alberich gdm[3914]: Got COOKIE == <secret>
>Feb  7 10:05:14 alberich gdm[4107]: Sending AUTHFILE == <secret> for slave 4107
>Feb  7 10:05:14 alberich gdm[3914]: Handling message: 'AUTHFILE 4107 /var/lib/gdm/:0.Xauth'
>Feb  7 10:05:14 alberich gdm[3914]: Got AUTHFILE == /var/lib/gdm/:0.Xauth
>Feb  7 10:05:14 alberich gdm[4107]: gdm_server_spawn: Forked server on pid 4110
>Feb  7 10:05:14 alberich gdm[4107]: do_server_wait: Before mainloop waiting for server
>Feb  7 10:05:14 alberich gdm[4110]: gdm_server_spawn: '/usr/X11R6/bin/X :0 -audit 0 -auth /var/lib/gdm/:0.Xauth -nolisten tcp vt7'
>Feb  7 10:05:15 alberich gdm[4107]: gdm_server_start: After mainloop waiting for server
>Feb  7 10:05:15 alberich gdm[4107]: gdm_server_start: Completed :0!
>Feb  7 10:05:15 alberich gdm[4107]: Sending VT_NUM == 7 for slave 4107
>Feb  7 10:05:15 alberich gdm[3914]: Handling message: 'VT_NUM 4107 7'
>Feb  7 10:05:15 alberich gdm[3914]: Got VT_NUM == 7
>Feb  7 10:05:15 alberich gdm[4107]: Sending XPID == 4110 for slave 4107
>Feb  7 10:05:15 alberich gdm[3914]: Handling message: 'XPID 4107 4110'
>Feb  7 10:05:15 alberich gdm[3914]: Got XPID == 4110
>Feb  7 10:05:15 alberich gdm[4107]: gdm_slave_run: Opening display :0
>Feb  7 10:05:22 alberich gdm[3914]: Handling message: 'START_NEXT_LOCAL'
>Feb  7 10:05:22 alberich gdm[4107]: gdm_slave_greeter: Running greeter on :0
>Feb  7 10:05:24 alberich gdm[4107]: gdm_slave_greeter: Greeter on pid 4136
>Feb  7 10:05:24 alberich gdm[4107]: Sending GREETPID == 4136 for slave 4107
>Feb  7 10:05:24 alberich gdm[3914]: Handling message: 'GREETPID 4107 4136'
>Feb  7 10:05:24 alberich gdm[3914]: Got GREETPID == 4136
>Feb  7 10:05:35 alberich gdm[3914]: gdm_socket_handler: Accepting new connection fd 8
>Feb  7 10:05:35 alberich gdm[3914]: Handling user message: 'VERSION'
>Feb  7 10:05:35 alberich gdm[3914]: Handling user message: 'GET_CONFIG_FILE'
>Feb  7 10:05:35 alberich gdm[3914]: Handling user message: 'CLOSE'
>Feb  7 10:05:43 alberich gdm[4107]: gdm_slave_wait_for_login: In loop
>Feb  7 10:15:38 alberich gdm[4107]: pam_unix2(gdm:auth): bad username []
>Feb  7 10:15:39 alberich gdm[4107]: Couldn't authenticate user
>Feb  7 10:15:39 alberich gdm[4107]: gdm_slave_wait_for_login: end verify for ''
>Feb  7 10:15:39 alberich gdm[4107]: gdm_slave_wait_for_login: No login/Bad login
>Feb  7 10:15:39 alberich gdm[4107]: gdm_slave_wait_for_login: In loop
>Feb  7 13:55:02 alberich gdm[4107]: term_quit: Final cleanup
>Feb  7 13:55:02 alberich gdm[4107]: gdm_slave_quick_exit: Will kill everything from the display
>Feb  7 13:55:02 alberich gdm[4107]: Running gdm_verify_cleanup and pamh != NULL
>Feb  7 13:55:02 alberich gdm[4107]: gdm_server_stop: Server for :0 going down!
>Feb  7 13:55:02 alberich gdm[4107]: gdm_server_stop: Killing server pid 4110
>Feb  7 13:55:02 alberich gdm[4107]: gdm_server_stop: Server pid 4110 dead
>Feb  7 13:55:02 alberich gdm[4107]: gdm_slave_quick_exit: Killed everything from the display
>Feb  7 13:55:02 alberich gdm[3914]: mainloop_sig_callback: Got signal 17
>Feb  7 13:55:02 alberich gdm[3914]: gdm_cleanup_children: child 4107 returned 65
>Feb  7 13:55:02 alberich gdm[3914]: gdm_child_action: In remanage
>Feb  7 13:55:02 alberich gdm[3914]: gdm_display_manage: Managing :0
>Feb  7 13:55:02 alberich gdm[3914]: loop check: last_start 1139303113, last_loop 1139303113, now: 1139316902, retry_count: 1
>Feb  7 13:55:02 alberich gdm[3914]: Resetting counts for loop of death detection, 90 seconds elapsed since loop started or session lasted more then 30 seconds.
>Feb  7 13:55:02 alberich gdm[3914]: gdm_display_manage: Forked slave: 5481
>Feb  7 13:55:02 alberich gdm[5481]: gdm_slave_start: Starting slave process for :0
>Feb  7 13:55:02 alberich gdm[5481]: gdm_slave_start: Loop Thingie
>Feb  7 13:55:02 alberich gdm[5481]: gdm_slave_run: Sleeping 1 seconds before server start
>Feb  7 13:55:03 alberich gdm[5481]: Sending VT_NUM == -1 for slave 5481
>Feb  7 13:55:03 alberich gdm[3914]: Handling message: 'VT_NUM 5481 -1'
>Feb  7 13:55:03 alberich gdm[3914]: Got VT_NUM == -1
>Feb  7 13:55:03 alberich gdm[5481]: gdm_server_start: :0
>Feb  7 13:55:03 alberich gdm[5481]: gdm_auth_secure_display: Setting up access for :0
>Feb  7 13:55:03 alberich gdm[5481]: gdm_auth_secure_display: Setting up access
>Feb  7 13:55:03 alberich gdm[5481]: gdm_auth_secure_display: Setting up access for :0 - 1 entries
>Feb  7 13:55:03 alberich gdm[5481]: Sending COOKIE == <secret> for slave 5481
>Feb  7 13:55:03 alberich gdm[3914]: Handling message: 'COOKIE 5481 83...'
>Feb  7 13:55:03 alberich gdm[3914]: Got COOKIE == <secret>
>Feb  7 13:55:03 alberich gdm[5481]: Sending AUTHFILE == <secret> for slave 5481
>Feb  7 13:55:03 alberich gdm[3914]: Handling message: 'AUTHFILE 5481 /var/lib/gdm/:0.Xauth'
>Feb  7 13:55:03 alberich gdm[3914]: Got AUTHFILE == /var/lib/gdm/:0.Xauth
>Feb  7 13:55:03 alberich gdm[5482]: gdm_server_spawn: '/usr/X11R6/bin/X :0 -audit 0 -auth /var/lib/gdm/:0.Xauth -nolisten tcp vt7'
>Feb  7 13:55:03 alberich gdm[5481]: gdm_server_spawn: Forked server on pid 5482
>Feb  7 13:55:03 alberich gdm[5481]: gdm_server_start: Completed :0!
>Feb  7 13:55:04 alberich gdm[5481]: Sending VT_NUM == 7 for slave 5481
>Feb  7 13:55:04 alberich gdm[3914]: Handling message: 'VT_NUM 5481 7'
>Feb  7 13:55:04 alberich gdm[3914]: Got VT_NUM == 7
>Feb  7 13:55:04 alberich gdm[5481]: Sending XPID == 5482 for slave 5481
>Feb  7 13:55:04 alberich gdm[3914]: Handling message: 'XPID 5481 5482'
>Feb  7 13:55:04 alberich gdm[3914]: Got XPID == 5482
>Feb  7 13:55:04 alberich gdm[5481]: gdm_slave_run: Opening display :0
>Feb  7 13:55:06 alberich gdm[3914]: Handling message: 'START_NEXT_LOCAL'
>Feb  7 13:55:06 alberich gdm[5481]: gdm_slave_greeter: Running greeter on :0
>Feb  7 13:55:06 alberich gdm[5481]: gdm_slave_greeter: Greeter on pid 5494
>Feb  7 13:55:06 alberich gdm[5481]: Sending GREETPID == 5494 for slave 5481
>Feb  7 13:55:06 alberich gdm[3914]: Handling message: 'GREETPID 5481 5494'
>Feb  7 13:55:06 alberich gdm[3914]: Got GREETPID == 5494
>Feb  7 13:55:06 alberich gdm[3914]: gdm_socket_handler: Accepting new connection fd 8
>Feb  7 13:55:06 alberich gdm[3914]: Handling user message: 'VERSION'
>Feb  7 13:55:06 alberich gdm[3914]: Handling user message: 'GET_CONFIG_FILE'
>Feb  7 13:55:06 alberich gdm[3914]: Handling user message: 'CLOSE'
>Feb  7 13:55:07 alberich gdm[5481]: gdm_slave_wait_for_login: In loop
>Feb  7 13:55:08 alberich gdm[5481]: term_quit: Final cleanup
>Feb  7 13:55:08 alberich gdm[5481]: gdm_slave_quick_exit: Will kill everything from the display
>Feb  7 13:55:08 alberich gdm[5481]: Running gdm_verify_cleanup and pamh != NULL
>Feb  7 13:55:08 alberich gdm[5481]: gdm_server_stop: Server for :0 going down!
>Feb  7 13:55:08 alberich gdm[5481]: gdm_server_stop: Killing server pid 5482
>Feb  7 13:55:08 alberich gdm[5481]: gdm_server_stop: Server pid 5482 dead
>Feb  7 13:55:08 alberich gdm[5481]: gdm_slave_quick_exit: Killed everything from the display
>Feb  7 13:55:08 alberich gdm[3914]: mainloop_sig_callback: Got signal 17
>Feb  7 13:55:08 alberich gdm[3914]: gdm_cleanup_children: child 5481 returned 65
>Feb  7 13:55:08 alberich gdm[3914]: gdm_child_action: In remanage
>Feb  7 13:55:08 alberich gdm[3914]: gdm_display_manage: Managing :0
>Feb  7 13:55:08 alberich gdm[3914]: loop check: last_start 1139316902, last_loop 1139316902, now: 1139316908, retry_count: 1
>Feb  7 13:55:08 alberich gdm[3914]: gdm_display_manage: Forked slave: 5497
>Feb  7 13:55:08 alberich gdm[5497]: gdm_slave_start: Starting slave process for :0
>Feb  7 13:55:08 alberich gdm[5497]: gdm_slave_start: Loop Thingie
>Feb  7 13:55:08 alberich gdm[5497]: gdm_slave_run: Sleeping 1 seconds before server start
>Feb  7 13:55:09 alberich gdm[5497]: Sending VT_NUM == -1 for slave 5497
>Feb  7 13:55:09 alberich gdm[3914]: Handling message: 'VT_NUM 5497 -1'
>Feb  7 13:55:09 alberich gdm[3914]: Got VT_NUM == -1
>Feb  7 13:55:09 alberich gdm[5497]: gdm_server_start: :0
>Feb  7 13:55:09 alberich gdm[5497]: gdm_auth_secure_display: Setting up access for :0
>Feb  7 13:55:09 alberich gdm[5497]: gdm_auth_secure_display: Setting up access
>Feb  7 13:55:09 alberich gdm[5497]: gdm_auth_secure_display: Setting up access for :0 - 1 entries
>Feb  7 13:55:09 alberich gdm[5497]: Sending COOKIE == <secret> for slave 5497
>Feb  7 13:55:09 alberich gdm[3914]: Handling message: 'COOKIE 5497 0f...'
>Feb  7 13:55:09 alberich gdm[3914]: Got COOKIE == <secret>
>Feb  7 13:55:09 alberich gdm[5497]: Sending AUTHFILE == <secret> for slave 5497
>Feb  7 13:55:09 alberich gdm[3914]: Handling message: 'AUTHFILE 5497 /var/lib/gdm/:0.Xauth'
>Feb  7 13:55:09 alberich gdm[3914]: Got AUTHFILE == /var/lib/gdm/:0.Xauth
>Feb  7 13:55:09 alberich gdm[5498]: gdm_server_spawn: '/usr/X11R6/bin/X :0 -audit 0 -auth /var/lib/gdm/:0.Xauth -nolisten tcp vt7'
>Feb  7 13:55:09 alberich gdm[5497]: gdm_server_spawn: Forked server on pid 5498
>Feb  7 13:55:09 alberich gdm[5497]: gdm_server_start: Completed :0!
>Feb  7 13:55:09 alberich gdm[5497]: Sending VT_NUM == 7 for slave 5497
>Feb  7 13:55:09 alberich gdm[3914]: Handling message: 'VT_NUM 5497 7'
>Feb  7 13:55:09 alberich gdm[3914]: Got VT_NUM == 7
>Feb  7 13:55:09 alberich gdm[5497]: Sending XPID == 5498 for slave 5497
>Feb  7 13:55:09 alberich gdm[3914]: Handling message: 'XPID 5497 5498'
>Feb  7 13:55:09 alberich gdm[3914]: Got XPID == 5498
>Feb  7 13:55:09 alberich gdm[5497]: gdm_slave_run: Opening display :0
>Feb  7 13:55:12 alberich gdm[3914]: Handling message: 'START_NEXT_LOCAL'
>Feb  7 13:55:12 alberich gdm[5497]: gdm_slave_greeter: Running greeter on :0
>Feb  7 13:55:12 alberich gdm[5497]: gdm_slave_greeter: Greeter on pid 5510
>Feb  7 13:55:12 alberich gdm[5497]: Sending GREETPID == 5510 for slave 5497
>Feb  7 13:55:12 alberich gdm[3914]: Handling message: 'GREETPID 5497 5510'
>Feb  7 13:55:12 alberich gdm[3914]: Got GREETPID == 5510
>Feb  7 13:55:14 alberich gdm[3914]: gdm_socket_handler: Accepting new connection fd 8
>Feb  7 13:55:14 alberich gdm[3914]: Handling user message: 'VERSION'
>Feb  7 13:55:14 alberich gdm[3914]: Handling user message: 'GET_CONFIG_FILE'
>Feb  7 13:55:14 alberich gdm[3914]: Handling user message: 'CLOSE'
>Feb  7 13:55:16 alberich gdm[5497]: gdm_slave_wait_for_login: In loop
>Feb  7 13:55:22 alberich gdm[3914]: mainloop_sig_callback: Got signal 15
>Feb  7 13:55:22 alberich gdm[3914]: mainloop_sig_callback: Got TERM/INT. Going down!
>Feb  7 13:55:22 alberich gdm[3914]: gdm_final_cleanup
>Feb  7 13:55:22 alberich gdm[3914]: gdm_display_unmanage: Stopping :0 (slave pid: 5497)
>Feb  7 13:55:22 alberich gdm[5497]: term_quit: Final cleanup
>Feb  7 13:55:22 alberich gdm[5497]: gdm_slave_quick_exit: Will kill everything from the display
>Feb  7 13:55:22 alberich gdm[5497]: Running gdm_verify_cleanup and pamh != NULL
>Feb  7 13:55:22 alberich gdm[5497]: gdm_server_stop: Server for :0 going down!
>Feb  7 13:55:22 alberich gdm[5497]: gdm_server_stop: Killing server pid 5498
>Feb  7 13:55:22 alberich gdm[5497]: gdm_server_stop: Server pid 5498 dead
>Feb  7 13:55:22 alberich gdm[5497]: gdm_slave_quick_exit: Killed everything from the display
>Feb  7 13:55:22 alberich gdm[3914]: gdm_display_unmanage: Display stopped
>Feb  7 13:55:25 alberich gdm[5701]: gdm_main: Here we go...
>Feb  7 13:55:25 alberich gdm[5701]: gdm_start_first_unborn_local: Starting :0
>Feb  7 13:55:25 alberich gdm[5701]: gdm_display_manage: Managing :0
>Feb  7 13:55:25 alberich gdm[5701]: loop check: last_start 0, last_loop 0, now: 1139316925, retry_count: 0
>Feb  7 13:55:25 alberich gdm[5701]: Resetting counts for loop of death detection
>Feb  7 13:55:25 alberich gdm[5701]: gdm_display_manage: Forked slave: 5703
>Feb  7 13:55:25 alberich gdm[5703]: gdm_slave_start: Starting slave process for :0
>Feb  7 13:55:25 alberich gdm[5703]: gdm_slave_start: Loop Thingie
>Feb  7 13:55:25 alberich gdm[5703]: Sending VT_NUM == -1 for slave 5703
>Feb  7 13:55:25 alberich gdm[5701]: Handling message: 'VT_NUM 5703 -1'
>Feb  7 13:55:25 alberich gdm[5701]: Got VT_NUM == -1
>Feb  7 13:55:25 alberich gdm[5703]: gdm_server_start: :0
>Feb  7 13:55:25 alberich gdm[5703]: gdm_auth_secure_display: Setting up access for :0
>Feb  7 13:55:25 alberich gdm[5703]: gdm_auth_secure_display: Setting up access
>Feb  7 13:55:25 alberich gdm[5703]: gdm_auth_secure_display: Setting up access for :0 - 1 entries
>Feb  7 13:55:25 alberich gdm[5703]: Sending COOKIE == <secret> for slave 5703
>Feb  7 13:55:25 alberich gdm[5701]: Handling message: 'COOKIE 5703 4d...'
>Feb  7 13:55:25 alberich gdm[5701]: Got COOKIE == <secret>
>Feb  7 13:55:25 alberich gdm[5703]: Sending AUTHFILE == <secret> for slave 5703
>Feb  7 13:55:25 alberich gdm[5701]: Handling message: 'AUTHFILE 5703 /var/lib/gdm/:0.Xauth'
>Feb  7 13:55:25 alberich gdm[5701]: Got AUTHFILE == /var/lib/gdm/:0.Xauth
>Feb  7 13:55:25 alberich gdm[5703]: gdm_server_spawn: Forked server on pid 5721
>Feb  7 13:55:25 alberich gdm[5721]: gdm_server_spawn: '/usr/X11R6/bin/X :0 -audit 0 -auth /var/lib/gdm/:0.Xauth -nolisten tcp vt7'
>Feb  7 13:55:25 alberich gdm[5703]: do_server_wait: Before mainloop waiting for server
>Feb  7 13:55:26 alberich gdm[5703]: gdm_server_start: After mainloop waiting for server
>Feb  7 13:55:26 alberich gdm[5703]: gdm_server_start: Completed :0!
>Feb  7 13:55:26 alberich gdm[5703]: Sending VT_NUM == 7 for slave 5703
>Feb  7 13:55:26 alberich gdm[5701]: Handling message: 'VT_NUM 5703 7'
>Feb  7 13:55:26 alberich gdm[5701]: Got VT_NUM == 7
>Feb  7 13:55:26 alberich gdm[5703]: Sending XPID == 5721 for slave 5703
>Feb  7 13:55:26 alberich gdm[5701]: Handling message: 'XPID 5703 5721'
>Feb  7 13:55:26 alberich gdm[5701]: Got XPID == 5721
>Feb  7 13:55:26 alberich gdm[5703]: gdm_slave_run: Opening display :0
>Feb  7 13:55:28 alberich gdm[5701]: Handling message: 'START_NEXT_LOCAL'
>Feb  7 13:55:28 alberich gdm[5703]: gdm_slave_greeter: Running greeter on :0
>Feb  7 13:55:28 alberich gdm[5703]: gdm_slave_greeter: Greeter on pid 5732
>Feb  7 13:55:28 alberich gdm[5703]: Sending GREETPID == 5732 for slave 5703
>Feb  7 13:55:28 alberich gdm[5701]: Handling message: 'GREETPID 5703 5732'
>Feb  7 13:55:28 alberich gdm[5701]: Got GREETPID == 5732
>Feb  7 13:55:28 alberich gdm[5701]: gdm_socket_handler: Accepting new connection fd 8
>Feb  7 13:55:28 alberich gdm[5701]: Handling user message: 'VERSION'
>Feb  7 13:55:28 alberich gdm[5701]: Handling user message: 'GET_CONFIG_FILE'
>Feb  7 13:55:28 alberich gdm[5701]: Handling user message: 'CLOSE'
>Feb  7 13:55:29 alberich gdm[5703]: gdm_slave_wait_for_login: In loop
>Feb  7 13:55:33 alberich gdm[5703]: Sending QUERYLOGIN == <secret> for slave 5703
>Feb  7 13:55:33 alberich gdm[5701]: Handling message: 'QUERYLOGIN 5703 andi'
>Feb  7 13:55:33 alberich gdm[5701]: Got QUERYLOGIN andi
>Feb  7 13:55:33 alberich gdm[5703]: gdm_slave_wait_for_login: end verify for 'andi'
>Feb  7 13:55:33 alberich gdm[5703]: gdm_slave_wait_for_login: got_login for 'andi'
>Feb  7 13:55:33 alberich gdm[5703]: Sending LOGGED_IN == 1 for slave 5703
>Feb  7 13:55:33 alberich gdm[5701]: Handling message: 'LOGGED_IN 5703 1'
>Feb  7 13:55:33 alberich gdm[5701]: Got logged in == TRUE
>Feb  7 13:55:33 alberich gdm[5703]: Sending LOGIN == <secret> for slave 5703
>Feb  7 13:55:33 alberich gdm[5701]: Handling message: 'LOGIN 5703 andi'
>Feb  7 13:55:33 alberich gdm[5701]: Got LOGIN == andi
>Feb  7 13:55:33 alberich gdm[5703]: gdm_slave_session_start: Attempting session for user 'andi'
>Feb  7 13:55:33 alberich gdm[5703]: Initial setting: session: 'kde' language: ''
>Feb  7 13:55:33 alberich gdm[5703]: gdm_slave_session_start: Authentication completed. Whacking greeter
>Feb  7 13:55:33 alberich gdm[5703]: slave_waitpid: waiting on 5732
>Feb  7 13:55:33 alberich gdm[5703]: slave_waitpid: done_waiting
>Feb  7 13:55:33 alberich gdm[5703]: Sending GREETPID == 0 for slave 5703
>Feb  7 13:55:33 alberich gdm[5701]: Handling message: 'GREETPID 5703 0'
>Feb  7 13:55:33 alberich gdm[5701]: Got GREETPID == 0
>Feb  7 13:55:33 alberich gdm[5703]: gdm_auth_user_add: Adding cookie for 1000
>Feb  7 13:55:33 alberich gdm[5703]: get_local_auths: Setting up socket access
>Feb  7 13:55:33 alberich gdm[5703]: get_local_auths: Setting up network access
>Feb  7 13:55:33 alberich gdm[5703]: get_local_auths: Setting up access for :0 - 2 entries
>Feb  7 13:55:33 alberich gdm[5703]: gdm_auth_user_add: Using /home/andi/.Xauthority for cookies
>Feb  7 13:55:33 alberich gdm[5703]: gdm_auth_purge: :0
>Feb  7 13:55:33 alberich gdm[5703]: gdm_auth_user_add: Done
>Feb  7 13:55:33 alberich gdm[5703]: Sending WRITE_X_SERVERS == 0 for slave 5703
>Feb  7 13:55:33 alberich gdm[5701]: Handling message: 'WRITE_X_SERVERS 5703 0'
>Feb  7 13:55:33 alberich gdm[5703]: Sending SESSPID == 5734 for slave 5703
>Feb  7 13:55:33 alberich gdm[5701]: Handling message: 'SESSPID 5703 5734'
>Feb  7 13:55:33 alberich gdm[5701]: Got SESSPID == 5734
>Feb  7 13:55:33 alberich gdm[5703]: slave_waitpid: waiting on 5734
>Feb  7 13:55:33 alberich gdm[5734]: Running /bin/bash --login /etc/opt/gnome/gdm/Xsession /opt/kde3/bin/startkde no-fake-login  for andi on :0
>Feb  7 13:55:40 alberich gdm[5701]: gdm_socket_handler: Accepting new connection fd 8
>Feb  7 13:55:40 alberich gdm[5701]: Handling user message: 'AUTH_LOCAL 3141d1825ca4d813176511e65aaccd4b'
>Feb  7 13:55:40 alberich gdm[5701]: Handling user message: 'AUTH_LOCAL 4da0e7cb51615b00c7ec1551fa63a7e9'
>Feb  7 13:55:40 alberich gdm[5701]: Handling user message: 'QUERY_VT'
>Feb  7 13:55:40 alberich gdm[5701]: close_if_needed: Got HUP/ERR on 8
>Feb  7 13:55:44 alberich gdm[5701]: gdm_socket_handler: Accepting new connection fd 8
>Feb  7 13:55:44 alberich gdm[5701]: Handling user message: 'AUTH_LOCAL 3141d1825ca4d813176511e65aaccd4b'
>Feb  7 13:55:44 alberich gdm[5701]: close_if_needed: Got HUP/ERR on 8
>Feb  7 13:55:44 alberich gdm[5701]: Handling message: 'XPID 5703 0'
>Feb  7 13:55:44 alberich gdm[5701]: Got XPID == 0
>Feb  7 13:55:44 alberich gdm[5701]: Handling message: 'SESSPID 5703 0'
>Feb  7 13:55:44 alberich gdm[5701]: Got SESSPID == 0
>Feb  7 13:55:44 alberich gdm[5703]: slave_waitpid: done_waiting
>Feb  7 13:55:44 alberich gdm[5703]: Session: start_time: 1139316933 end_time: 1139316944
>Feb  7 13:55:44 alberich gdm[5703]: Sending SESSPID == 0 for slave 5703
>Feb  7 13:55:44 alberich gdm[5701]: Handling message: 'SESSPID 5703 0'
>Feb  7 13:55:44 alberich gdm[5701]: Got SESSPID == 0
>Feb  7 13:55:44 alberich gdm[5703]: gdm_slave_session_stop: andi on :0
>Feb  7 13:55:44 alberich gdm[5703]: Fatal X error detected.  Ignoring same during session shutdown.
>Feb  7 13:55:44 alberich gdm[5703]: gdm_slave_session_stop: back here from xioerror
>Feb  7 13:55:44 alberich gdm[5703]: gdm_slave_session_stop: Running post session script
>Feb  7 13:55:44 alberich gdm[5703]: gdm_auth_user_remove: Removing cookie from /home/andi/.Xauthority (0)
>Feb  7 13:55:44 alberich gdm[5703]: gdm_auth_purge: :0
>Feb  7 13:55:44 alberich gdm[5703]: Running gdm_verify_cleanup and pamh != NULL
>Feb  7 13:55:44 alberich gdm[5703]: Running pam_close_session
>Feb  7 13:55:44 alberich gdm[5703]: Running pam_setcred with PAM_DELETE_CRED
>Feb  7 13:55:44 alberich gdm[5703]: gdm_slave_session_start: Session ended OK (now all finished)
>Feb  7 13:55:44 alberich gdm[5703]: Sending LOGGED_IN == 0 for slave 5703
>Feb  7 13:55:44 alberich gdm[5701]: Handling message: 'LOGGED_IN 5703 0'
>Feb  7 13:55:44 alberich gdm[5701]: Got logged in == FALSE
>Feb  7 13:55:44 alberich gdm[5703]: Sending LOGIN == <secret> for slave 5703
>Feb  7 13:55:44 alberich gdm[5701]: Handling message: 'LOGIN 5703 '
>Feb  7 13:55:44 alberich gdm[5701]: Got LOGIN == 
>Feb  7 13:55:44 alberich gdm[5703]: gdm_slave_start: Reinitializing things
>Feb  7 13:55:44 alberich gdm[5703]: gdm_auth_secure_display: Setting up access for :0
>Feb  7 13:55:44 alberich gdm[5703]: gdm_auth_secure_display: Setting up access
>Feb  7 13:55:44 alberich gdm[5703]: gdm_auth_secure_display: Setting up access for :0 - 1 entries
>Feb  7 13:55:44 alberich gdm[5703]: Sending COOKIE == <secret> for slave 5703
>Feb  7 13:55:44 alberich gdm[5701]: Handling message: 'COOKIE 5703 c1...'
>Feb  7 13:55:44 alberich gdm[5701]: Got COOKIE == <secret>
>Feb  7 13:55:44 alberich gdm[5703]: Sending AUTHFILE == <secret> for slave 5703
>Feb  7 13:55:44 alberich gdm[5701]: Handling message: 'AUTHFILE 5703 /var/lib/gdm/:0.Xauth'
>Feb  7 13:55:44 alberich gdm[5701]: Got AUTHFILE == /var/lib/gdm/:0.Xauth
>Feb  7 13:55:44 alberich gdm[5703]: Error reinitilizing server
>Feb  7 13:55:44 alberich gdm[5703]: gdm_slave_quick_exit: Will kill everything from the display
>Feb  7 13:55:44 alberich gdm[5703]: gdm_slave_quick_exit: Killed everything from the display
>Feb  7 13:55:44 alberich gdm[5701]: mainloop_sig_callback: Got signal 17
>Feb  7 13:55:45 alberich gdm[5701]: gdm_cleanup_children: child 5703 returned 2
>Feb  7 13:55:45 alberich gdm[5701]: gdm_child_action: In remanage
>Feb  7 13:55:45 alberich gdm[5701]: gdm_display_manage: Managing :0
>Feb  7 13:55:45 alberich gdm[5701]: loop check: last_start 1139316925, last_loop 1139316925, now: 1139316945, retry_count: 1
>Feb  7 13:55:45 alberich gdm[5853]: gdm_slave_start: Starting slave process for :0
>Feb  7 13:55:45 alberich gdm[5701]: gdm_display_manage: Forked slave: 5853
>Feb  7 13:55:45 alberich gdm[5853]: gdm_slave_start: Loop Thingie
>Feb  7 13:55:45 alberich gdm[5853]: gdm_slave_run: Sleeping 1 seconds before server start
>Feb  7 13:55:46 alberich gdm[5853]: Sending VT_NUM == -1 for slave 5853
>Feb  7 13:55:46 alberich gdm[5701]: Handling message: 'VT_NUM 5853 -1'
>Feb  7 13:55:46 alberich gdm[5701]: Got VT_NUM == -1
>Feb  7 13:55:46 alberich gdm[5853]: gdm_server_start: :0
>Feb  7 13:55:46 alberich gdm[5853]: gdm_auth_secure_display: Setting up access for :0
>Feb  7 13:55:46 alberich gdm[5853]: gdm_auth_secure_display: Setting up access
>Feb  7 13:55:46 alberich gdm[5853]: gdm_auth_secure_display: Setting up access for :0 - 1 entries
>Feb  7 13:55:46 alberich gdm[5853]: Sending COOKIE == <secret> for slave 5853
>Feb  7 13:55:46 alberich gdm[5701]: Handling message: 'COOKIE 5853 4d...'
>Feb  7 13:55:46 alberich gdm[5701]: Got COOKIE == <secret>
>Feb  7 13:55:46 alberich gdm[5853]: Sending AUTHFILE == <secret> for slave 5853
>Feb  7 13:55:46 alberich gdm[5701]: Handling message: 'AUTHFILE 5853 /var/lib/gdm/:0.Xauth'
>Feb  7 13:55:46 alberich gdm[5701]: Got AUTHFILE == /var/lib/gdm/:0.Xauth
>Feb  7 13:55:46 alberich gdm[5853]: gdm_server_spawn: Forked server on pid 5855
>Feb  7 13:55:46 alberich gdm[5855]: gdm_server_spawn: '/usr/X11R6/bin/X :0 -audit 0 -auth /var/lib/gdm/:0.Xauth -nolisten tcp vt7'
>Feb  7 13:55:46 alberich gdm[5853]: do_server_wait: Before mainloop waiting for server
>Feb  7 13:55:46 alberich gdm[5853]: gdm_server_start: After mainloop waiting for server
>Feb  7 13:55:46 alberich gdm[5853]: gdm_server_start: Completed :0!
>Feb  7 13:55:46 alberich gdm[5853]: Sending VT_NUM == 7 for slave 5853
>Feb  7 13:55:46 alberich gdm[5701]: Handling message: 'VT_NUM 5853 7'
>Feb  7 13:55:46 alberich gdm[5701]: Got VT_NUM == 7
>Feb  7 13:55:46 alberich gdm[5853]: Sending XPID == 5855 for slave 5853
>Feb  7 13:55:46 alberich gdm[5701]: Handling message: 'XPID 5853 5855'
>Feb  7 13:55:46 alberich gdm[5701]: Got XPID == 5855
>Feb  7 13:55:46 alberich gdm[5853]: gdm_slave_run: Opening display :0
>Feb  7 13:55:48 alberich gdm[5701]: Handling message: 'START_NEXT_LOCAL'
>Feb  7 13:55:48 alberich gdm[5853]: gdm_slave_greeter: Running greeter on :0
>Feb  7 13:55:48 alberich gdm[5853]: gdm_slave_greeter: Greeter on pid 5866
>Feb  7 13:55:48 alberich gdm[5853]: Sending GREETPID == 5866 for slave 5853
>Feb  7 13:55:48 alberich gdm[5701]: Handling message: 'GREETPID 5853 5866'
>Feb  7 13:55:48 alberich gdm[5701]: Got GREETPID == 5866
>Feb  7 13:55:48 alberich gdm[5701]: gdm_socket_handler: Accepting new connection fd 8
>Feb  7 13:55:48 alberich gdm[5701]: Handling user message: 'VERSION'
>Feb  7 13:55:48 alberich gdm[5701]: Handling user message: 'GET_CONFIG_FILE'
>Feb  7 13:55:48 alberich gdm[5701]: Handling user message: 'CLOSE'
>Feb  7 13:55:49 alberich gdm[5853]: gdm_slave_wait_for_login: In loop
>Feb  7 13:56:00 alberich gdm[5853]: Sending QUERYLOGIN == <secret> for slave 5853
>Feb  7 13:56:00 alberich gdm[5701]: Handling message: 'QUERYLOGIN 5853 andi'
>Feb  7 13:56:00 alberich gdm[5701]: Got QUERYLOGIN andi
>Feb  7 13:56:00 alberich gdm[5853]: gdm_slave_wait_for_login: end verify for 'andi'
>Feb  7 13:56:00 alberich gdm[5853]: gdm_slave_wait_for_login: got_login for 'andi'
>Feb  7 13:56:00 alberich gdm[5853]: Sending LOGGED_IN == 1 for slave 5853
>Feb  7 13:56:00 alberich gdm[5701]: Handling message: 'LOGGED_IN 5853 1'
>Feb  7 13:56:00 alberich gdm[5701]: Got logged in == TRUE
>Feb  7 13:56:00 alberich gdm[5853]: Sending LOGIN == <secret> for slave 5853
>Feb  7 13:56:00 alberich gdm[5701]: Handling message: 'LOGIN 5853 andi'
>Feb  7 13:56:00 alberich gdm[5701]: Got LOGIN == andi
>Feb  7 13:56:00 alberich gdm[5853]: gdm_slave_session_start: Attempting session for user 'andi'
>Feb  7 13:56:03 alberich gdm[5853]: Initial setting: session: 'gnome' language: ''
>Feb  7 13:56:03 alberich gdm[5853]: gdm_slave_session_start: Authentication completed. Whacking greeter
>Feb  7 13:56:03 alberich gdm[5853]: slave_waitpid: waiting on 5866
>Feb  7 13:56:03 alberich gdm[5853]: slave_waitpid: done_waiting
>Feb  7 13:56:03 alberich gdm[5853]: Sending GREETPID == 0 for slave 5853
>Feb  7 13:56:03 alberich gdm[5701]: Handling message: 'GREETPID 5853 0'
>Feb  7 13:56:03 alberich gdm[5701]: Got GREETPID == 0
>Feb  7 13:56:03 alberich gdm[5853]: gdm_auth_user_add: Adding cookie for 1000
>Feb  7 13:56:03 alberich gdm[5853]: get_local_auths: Setting up socket access
>Feb  7 13:56:03 alberich gdm[5853]: get_local_auths: Setting up network access
>Feb  7 13:56:03 alberich gdm[5853]: get_local_auths: Setting up access for :0 - 2 entries
>Feb  7 13:56:03 alberich gdm[5853]: gdm_auth_user_add: Using /home/andi/.Xauthority for cookies
>Feb  7 13:56:03 alberich gdm[5853]: gdm_auth_purge: :0
>Feb  7 13:56:03 alberich gdm[5853]: gdm_auth_user_add: Done
>Feb  7 13:56:03 alberich gdm[5853]: Sending WRITE_X_SERVERS == 0 for slave 5853
>Feb  7 13:56:03 alberich gdm[5701]: Handling message: 'WRITE_X_SERVERS 5853 0'
>Feb  7 13:56:03 alberich gdm[5853]: Sending SESSPID == 5868 for slave 5853
>Feb  7 13:56:03 alberich gdm[5868]: Running /bin/bash --login /etc/opt/gnome/gdm/Xsession gnome no-fake-login  for andi on :0
>Feb  7 13:56:03 alberich gdm[5701]: Handling message: 'SESSPID 5853 5868'
>Feb  7 13:56:03 alberich gdm[5701]: Got SESSPID == 5868
>Feb  7 13:56:04 alberich gdm[5853]: slave_waitpid: waiting on 5868
>Feb  7 13:56:14 alberich gdm[5853]: slave_waitpid: done_waiting
>Feb  7 13:56:14 alberich gdm[5853]: Session: start_time: 1139316963 end_time: 1139316974
>Feb  7 13:56:14 alberich gdm[5853]: Sending SESSPID == 0 for slave 5853
>Feb  7 13:56:14 alberich gdm[5701]: Handling message: 'SESSPID 5853 0'
>Feb  7 13:56:14 alberich gdm[5701]: Got SESSPID == 0
>Feb  7 13:56:14 alberich gdm[5853]: gdm_slave_session_stop: andi on :0
>Feb  7 13:56:14 alberich gdm[5853]: Fatal X error detected.  Ignoring same during session shutdown.
>Feb  7 13:56:14 alberich gdm[5853]: gdm_slave_session_stop: back here from xioerror
>Feb  7 13:56:14 alberich gdm[5853]: gdm_slave_session_stop: Running post session script
>Feb  7 13:56:14 alberich gdm[5853]: gdm_auth_user_remove: Removing cookie from /home/andi/.Xauthority (0)
>Feb  7 13:56:14 alberich gdm[5853]: gdm_auth_purge: :0
>Feb  7 13:56:14 alberich gdm[5853]: Running gdm_verify_cleanup and pamh != NULL
>Feb  7 13:56:14 alberich gdm[5701]: Handling message: 'XPID 5853 0'
>Feb  7 13:56:16 alberich gdm[5701]: Got XPID == 0
>Feb  7 13:56:14 alberich gdm[5853]: Running pam_close_session
>Feb  7 13:56:16 alberich gdm[5853]: Running pam_setcred with PAM_DELETE_CRED
>Feb  7 13:56:16 alberich gdm[5853]: gdm_slave_session_start: Session ended OK (now all finished)
>Feb  7 13:56:16 alberich gdm[5853]: Sending LOGGED_IN == 0 for slave 5853
>Feb  7 13:56:16 alberich gdm[5701]: Handling message: 'LOGGED_IN 5853 0'
>Feb  7 13:56:16 alberich gdm[5701]: Got logged in == FALSE
>Feb  7 13:56:16 alberich gdm[5853]: Sending LOGIN == <secret> for slave 5853
>Feb  7 13:56:16 alberich gdm[5701]: Handling message: 'LOGIN 5853 '
>Feb  7 13:56:16 alberich gdm[5701]: Got LOGIN == 
>Feb  7 13:56:16 alberich gdm[5853]: gdm_slave_start: Reinitializing things
>Feb  7 13:56:16 alberich gdm[5853]: gdm_auth_secure_display: Setting up access for :0
>Feb  7 13:56:16 alberich gdm[5853]: gdm_auth_secure_display: Setting up access
>Feb  7 13:56:16 alberich gdm[5853]: gdm_auth_secure_display: Setting up access for :0 - 1 entries
>Feb  7 13:56:16 alberich gdm[5853]: Sending COOKIE == <secret> for slave 5853
>Feb  7 13:56:16 alberich gdm[5701]: Handling message: 'COOKIE 5853 14...'
>Feb  7 13:56:16 alberich gdm[5701]: Got COOKIE == <secret>
>Feb  7 13:56:16 alberich gdm[5853]: Sending AUTHFILE == <secret> for slave 5853
>Feb  7 13:56:16 alberich gdm[5701]: Handling message: 'AUTHFILE 5853 /var/lib/gdm/:0.Xauth'
>Feb  7 13:56:16 alberich gdm[5701]: Got AUTHFILE == /var/lib/gdm/:0.Xauth
>Feb  7 13:56:16 alberich gdm[5853]: Error reinitilizing server
>Feb  7 13:56:16 alberich gdm[5853]: gdm_slave_quick_exit: Will kill everything from the display
>Feb  7 13:56:16 alberich gdm[5853]: gdm_slave_quick_exit: Killed everything from the display
>Feb  7 13:56:16 alberich gdm[5701]: mainloop_sig_callback: Got signal 17
>Feb  7 13:56:16 alberich gdm[5701]: gdm_cleanup_children: child 5853 returned 2
>Feb  7 13:56:16 alberich gdm[5701]: gdm_child_action: In remanage
>Feb  7 13:56:16 alberich gdm[5701]: gdm_display_manage: Managing :0
>Feb  7 13:56:16 alberich gdm[5701]: loop check: last_start 1139316945, last_loop 1139316925, now: 1139316976, retry_count: 2
>Feb  7 13:56:16 alberich gdm[5701]: Resetting counts for loop of death detection, 90 seconds elapsed since loop started or session lasted more then 30 seconds.
>Feb  7 13:56:16 alberich gdm[6068]: gdm_slave_start: Starting slave process for :0
>Feb  7 13:56:16 alberich gdm[5701]: gdm_display_manage: Forked slave: 6068
>Feb  7 13:56:16 alberich gdm[6068]: gdm_slave_start: Loop Thingie
>Feb  7 13:56:16 alberich gdm[6068]: gdm_slave_run: Sleeping 1 seconds before server start
>Feb  7 13:56:17 alberich gdm[6068]: Sending VT_NUM == -1 for slave 6068
>Feb  7 13:56:17 alberich gdm[5701]: Handling message: 'VT_NUM 6068 -1'
>Feb  7 13:56:17 alberich gdm[5701]: Got VT_NUM == -1
>Feb  7 13:56:17 alberich gdm[6068]: gdm_server_start: :0
>Feb  7 13:56:17 alberich gdm[6068]: gdm_auth_secure_display: Setting up access for :0
>Feb  7 13:56:17 alberich gdm[6068]: gdm_auth_secure_display: Setting up access
>Feb  7 13:56:17 alberich gdm[6068]: gdm_auth_secure_display: Setting up access for :0 - 1 entries
>Feb  7 13:56:17 alberich gdm[6068]: Sending COOKIE == <secret> for slave 6068
>Feb  7 13:56:17 alberich gdm[5701]: Handling message: 'COOKIE 6068 f1...'
>Feb  7 13:56:17 alberich gdm[5701]: Got COOKIE == <secret>
>Feb  7 13:56:17 alberich gdm[6068]: Sending AUTHFILE == <secret> for slave 6068
>Feb  7 13:56:17 alberich gdm[5701]: Handling message: 'AUTHFILE 6068 /var/lib/gdm/:0.Xauth'
>Feb  7 13:56:17 alberich gdm[5701]: Got AUTHFILE == /var/lib/gdm/:0.Xauth
>Feb  7 13:56:18 alberich gdm[6069]: gdm_server_spawn: '/usr/X11R6/bin/X :0 -audit 0 -auth /var/lib/gdm/:0.Xauth -nolisten tcp vt7'
>Feb  7 13:56:18 alberich gdm[6068]: gdm_server_spawn: Forked server on pid 6069
>Feb  7 13:56:18 alberich gdm[6068]: gdm_server_start: Completed :0!
>Feb  7 13:56:18 alberich gdm[6068]: Sending VT_NUM == 7 for slave 6068
>Feb  7 13:56:18 alberich gdm[5701]: Handling message: 'VT_NUM 6068 7'
>Feb  7 13:56:18 alberich gdm[5701]: Got VT_NUM == 7
>Feb  7 13:56:18 alberich gdm[6068]: Sending XPID == 6069 for slave 6068
>Feb  7 13:56:18 alberich gdm[5701]: Handling message: 'XPID 6068 6069'
>Feb  7 13:56:18 alberich gdm[5701]: Got XPID == 6069
>Feb  7 13:56:18 alberich gdm[6068]: gdm_slave_run: Opening display :0
>Feb  7 13:56:20 alberich gdm[5701]: Handling message: 'START_NEXT_LOCAL'
>Feb  7 13:56:20 alberich gdm[6068]: gdm_slave_greeter: Running greeter on :0
>Feb  7 13:56:20 alberich gdm[6068]: gdm_slave_greeter: Greeter on pid 6081
>Feb  7 13:56:20 alberich gdm[6068]: Sending GREETPID == 6081 for slave 6068
>Feb  7 13:56:20 alberich gdm[5701]: Handling message: 'GREETPID 6068 6081'
>Feb  7 13:56:20 alberich gdm[5701]: Got GREETPID == 6081
>Feb  7 13:56:20 alberich gdm[5701]: gdm_socket_handler: Accepting new connection fd 8
>Feb  7 13:56:20 alberich gdm[5701]: Handling user message: 'VERSION'
>Feb  7 13:56:20 alberich gdm[5701]: Handling user message: 'GET_CONFIG_FILE'
>Feb  7 13:56:20 alberich gdm[5701]: Handling user message: 'CLOSE'
>Feb  7 13:56:21 alberich gdm[6068]: gdm_slave_wait_for_login: In loop
>Feb  7 13:56:32 alberich gdm[6068]: Sending QUERYLOGIN == <secret> for slave 6068
>Feb  7 13:56:32 alberich gdm[5701]: Handling message: 'QUERYLOGIN 6068 andi'
>Feb  7 13:56:32 alberich gdm[5701]: Got QUERYLOGIN andi
>Feb  7 13:56:32 alberich gdm[6068]: gdm_slave_wait_for_login: end verify for 'andi'
>Feb  7 13:56:32 alberich gdm[6068]: gdm_slave_wait_for_login: got_login for 'andi'
>Feb  7 13:56:32 alberich gdm[6068]: Sending LOGGED_IN == 1 for slave 6068
>Feb  7 13:56:32 alberich gdm[5701]: Handling message: 'LOGGED_IN 6068 1'
>Feb  7 13:56:32 alberich gdm[5701]: Got logged in == TRUE
>Feb  7 13:56:32 alberich gdm[6068]: Sending LOGIN == <secret> for slave 6068
>Feb  7 13:56:32 alberich gdm[5701]: Handling message: 'LOGIN 6068 andi'
>Feb  7 13:56:32 alberich gdm[5701]: Got LOGIN == andi
>Feb  7 13:56:32 alberich gdm[6068]: gdm_slave_session_start: Attempting session for user 'andi'
>Feb  7 13:56:35 alberich gdm[6068]: Initial setting: session: 'gnome' language: ''
>Feb  7 13:56:35 alberich gdm[6068]: gdm_slave_session_start: Authentication completed. Whacking greeter
>Feb  7 13:56:35 alberich gdm[6068]: slave_waitpid: waiting on 6081
>Feb  7 13:56:35 alberich gdm[6068]: slave_waitpid: done_waiting
>Feb  7 13:56:35 alberich gdm[6068]: Sending GREETPID == 0 for slave 6068
>Feb  7 13:56:35 alberich gdm[5701]: Handling message: 'GREETPID 6068 0'
>Feb  7 13:56:35 alberich gdm[5701]: Got GREETPID == 0
>Feb  7 13:56:35 alberich gdm[6068]: gdm_auth_user_add: Adding cookie for 1000
>Feb  7 13:56:35 alberich gdm[6068]: get_local_auths: Setting up socket access
>Feb  7 13:56:35 alberich gdm[6068]: get_local_auths: Setting up network access
>Feb  7 13:56:35 alberich gdm[6068]: get_local_auths: Setting up access for :0 - 2 entries
>Feb  7 13:56:35 alberich gdm[6068]: gdm_auth_user_add: Using /home/andi/.Xauthority for cookies
>Feb  7 13:56:35 alberich gdm[6068]: gdm_auth_purge: :0
>Feb  7 13:56:35 alberich gdm[6068]: gdm_auth_user_add: Done
>Feb  7 13:56:35 alberich gdm[6068]: Sending WRITE_X_SERVERS == 0 for slave 6068
>Feb  7 13:56:35 alberich gdm[5701]: Handling message: 'WRITE_X_SERVERS 6068 0'
>Feb  7 13:56:35 alberich gdm[6068]: Sending SESSPID == 6083 for slave 6068
>Feb  7 13:56:35 alberich gdm[6083]: Running /bin/bash --login /etc/opt/gnome/gdm/Xsession gnome no-fake-login  for andi on :0
>Feb  7 13:56:35 alberich gdm[5701]: Handling message: 'SESSPID 6068 6083'
>Feb  7 13:56:35 alberich gdm[5701]: Got SESSPID == 6083
>Feb  7 13:56:35 alberich gdm[6068]: slave_waitpid: waiting on 6083
>Feb  7 13:56:43 alberich gdm[6068]: slave_waitpid: done_waiting
>Feb  7 13:56:43 alberich gdm[6068]: Session: start_time: 1139316995 end_time: 1139317003
>Feb  7 13:56:43 alberich gdm[6068]: Session less than 10 seconds!
>Feb  7 13:56:43 alberich gdm[6068]: failsafe dialog failed (inhibitions: 0 0)
>Feb  7 13:56:55 alberich gdm[5701]: Handling message: 'XPID 6068 0'
>Feb  7 13:56:55 alberich gdm[5701]: Got XPID == 0
>Feb  7 13:56:55 alberich gdm[6068]: failsafe dialog failed (inhibitions: 0 1)
>Feb  7 13:57:07 alberich gdm[6068]: failsafe dialog failed (inhibitions: 1 1)
>Feb  7 13:57:07 alberich gdm[6068]: Sending SESSPID == 0 for slave 6068
>Feb  7 13:57:07 alberich gdm[5701]: Handling message: 'SESSPID 6068 0'
>Feb  7 13:57:07 alberich gdm[5701]: Got SESSPID == 0
>Feb  7 13:57:07 alberich gdm[6068]: gdm_slave_session_stop: andi on :0
>Feb  7 13:57:07 alberich gdm[6068]: Fatal X error detected.  Ignoring same during session shutdown.
>Feb  7 13:57:07 alberich gdm[6068]: gdm_slave_session_stop: back here from xioerror
>Feb  7 13:57:07 alberich gdm[6068]: gdm_slave_session_stop: Running post session script
>Feb  7 13:57:07 alberich gdm[6068]: gdm_auth_user_remove: Removing cookie from /home/andi/.Xauthority (0)
>Feb  7 13:57:07 alberich gdm[6068]: gdm_auth_purge: :0
>Feb  7 13:57:07 alberich gdm[6068]: Running gdm_verify_cleanup and pamh != NULL
>Feb  7 13:57:07 alberich gdm[6068]: Running pam_close_session
>Feb  7 13:57:07 alberich gdm[6068]: Running pam_setcred with PAM_DELETE_CRED
>Feb  7 13:57:07 alberich gdm[6068]: gdm_slave_session_start: Session ended OK (now all finished)
>Feb  7 13:57:07 alberich gdm[6068]: Sending LOGGED_IN == 0 for slave 6068
>Feb  7 13:57:07 alberich gdm[5701]: Handling message: 'LOGGED_IN 6068 0'
>Feb  7 13:57:07 alberich gdm[5701]: Got logged in == FALSE
>Feb  7 13:57:07 alberich gdm[6068]: Sending LOGIN == <secret> for slave 6068
>Feb  7 13:57:07 alberich gdm[5701]: Handling message: 'LOGIN 6068 '
>Feb  7 13:57:07 alberich gdm[5701]: Got LOGIN == 
>Feb  7 13:57:07 alberich gdm[6068]: gdm_slave_start: Reinitializing things
>Feb  7 13:57:07 alberich gdm[6068]: gdm_auth_secure_display: Setting up access for :0
>Feb  7 13:57:07 alberich gdm[6068]: gdm_auth_secure_display: Setting up access
>Feb  7 13:57:07 alberich gdm[6068]: gdm_auth_secure_display: Setting up access for :0 - 1 entries
>Feb  7 13:57:07 alberich gdm[6068]: Sending COOKIE == <secret> for slave 6068
>Feb  7 13:57:07 alberich gdm[5701]: Handling message: 'COOKIE 6068 58...'
>Feb  7 13:57:07 alberich gdm[5701]: Got COOKIE == <secret>
>Feb  7 13:57:07 alberich gdm[6068]: Sending AUTHFILE == <secret> for slave 6068
>Feb  7 13:57:07 alberich gdm[5701]: Handling message: 'AUTHFILE 6068 /var/lib/gdm/:0.Xauth'
>Feb  7 13:57:07 alberich gdm[5701]: Got AUTHFILE == /var/lib/gdm/:0.Xauth
>Feb  7 13:57:07 alberich gdm[6068]: Error reinitilizing server
>Feb  7 13:57:07 alberich gdm[6068]: gdm_slave_quick_exit: Will kill everything from the display
>Feb  7 13:57:07 alberich gdm[6068]: gdm_slave_quick_exit: Killed everything from the display
>Feb  7 13:57:07 alberich gdm[5701]: mainloop_sig_callback: Got signal 17
>Feb  7 13:57:07 alberich gdm[5701]: gdm_cleanup_children: child 6068 returned 2
>Feb  7 13:57:07 alberich gdm[5701]: gdm_child_action: In remanage
>Feb  7 13:57:07 alberich gdm[5701]: gdm_display_manage: Managing :0
>Feb  7 13:57:07 alberich gdm[5701]: loop check: last_start 1139316976, last_loop 1139316976, now: 1139317027, retry_count: 1
>Feb  7 13:57:07 alberich gdm[5701]: Resetting counts for loop of death detection, 90 seconds elapsed since loop started or session lasted more then 30 seconds.
>Feb  7 13:57:08 alberich gdm[6224]: gdm_slave_start: Starting slave process for :0
>Feb  7 13:57:08 alberich gdm[5701]: gdm_display_manage: Forked slave: 6224
>Feb  7 13:57:08 alberich gdm[6224]: gdm_slave_start: Loop Thingie
>Feb  7 13:57:08 alberich gdm[6224]: gdm_slave_run: Sleeping 1 seconds before server start
>Feb  7 13:57:09 alberich gdm[6224]: Sending VT_NUM == -1 for slave 6224
>Feb  7 13:57:09 alberich gdm[5701]: Handling message: 'VT_NUM 6224 -1'
>Feb  7 13:57:09 alberich gdm[5701]: Got VT_NUM == -1
>Feb  7 13:57:09 alberich gdm[6224]: gdm_server_start: :0
>Feb  7 13:57:09 alberich gdm[6224]: gdm_auth_secure_display: Setting up access for :0
>Feb  7 13:57:09 alberich gdm[6224]: gdm_auth_secure_display: Setting up access
>Feb  7 13:57:09 alberich gdm[6224]: gdm_auth_secure_display: Setting up access for :0 - 1 entries
>Feb  7 13:57:09 alberich gdm[6224]: Sending COOKIE == <secret> for slave 6224
>Feb  7 13:57:09 alberich gdm[5701]: Handling message: 'COOKIE 6224 9e...'
>Feb  7 13:57:09 alberich gdm[5701]: Got COOKIE == <secret>
>Feb  7 13:57:09 alberich gdm[6224]: Sending AUTHFILE == <secret> for slave 6224
>Feb  7 13:57:09 alberich gdm[5701]: Handling message: 'AUTHFILE 6224 /var/lib/gdm/:0.Xauth'
>Feb  7 13:57:09 alberich gdm[5701]: Got AUTHFILE == /var/lib/gdm/:0.Xauth
>Feb  7 13:57:09 alberich gdm[6225]: gdm_server_spawn: '/usr/X11R6/bin/X :0 -audit 0 -auth /var/lib/gdm/:0.Xauth -nolisten tcp vt7'
>Feb  7 13:57:09 alberich gdm[6224]: gdm_server_spawn: Forked server on pid 6225
>Feb  7 13:57:09 alberich gdm[6224]: gdm_server_start: Completed :0!
>Feb  7 13:57:09 alberich gdm[6224]: Sending VT_NUM == 7 for slave 6224
>Feb  7 13:57:09 alberich gdm[5701]: Handling message: 'VT_NUM 6224 7'
>Feb  7 13:57:09 alberich gdm[5701]: Got VT_NUM == 7
>Feb  7 13:57:09 alberich gdm[6224]: Sending XPID == 6225 for slave 6224
>Feb  7 13:57:09 alberich gdm[5701]: Handling message: 'XPID 6224 6225'
>Feb  7 13:57:09 alberich gdm[5701]: Got XPID == 6225
>Feb  7 13:57:09 alberich gdm[6224]: gdm_slave_run: Opening display :0
>Feb  7 13:57:11 alberich gdm[5701]: Handling message: 'START_NEXT_LOCAL'
>Feb  7 13:57:11 alberich gdm[6224]: gdm_slave_greeter: Running greeter on :0
>Feb  7 13:57:11 alberich gdm[6224]: gdm_slave_greeter: Greeter on pid 6237
>Feb  7 13:57:11 alberich gdm[6224]: Sending GREETPID == 6237 for slave 6224
>Feb  7 13:57:11 alberich gdm[5701]: Handling message: 'GREETPID 6224 6237'
>Feb  7 13:57:11 alberich gdm[5701]: Got GREETPID == 6237
>Feb  7 13:57:11 alberich gdm[5701]: gdm_socket_handler: Accepting new connection fd 8
>Feb  7 13:57:11 alberich gdm[5701]: Handling user message: 'VERSION'
>Feb  7 13:57:11 alberich gdm[5701]: Handling user message: 'GET_CONFIG_FILE'
>Feb  7 13:57:11 alberich gdm[5701]: Handling user message: 'CLOSE'
>Feb  7 13:57:12 alberich gdm[6224]: gdm_slave_wait_for_login: In loop
>Feb  7 13:57:48 alberich gdm[6224]: Sending QUERYLOGIN == <secret> for slave 6224
>Feb  7 13:57:48 alberich gdm[5701]: Handling message: 'QUERYLOGIN 6224 andi'
>Feb  7 13:57:48 alberich gdm[5701]: Got QUERYLOGIN andi
>Feb  7 13:57:48 alberich gdm[6224]: gdm_slave_wait_for_login: end verify for 'andi'
>Feb  7 13:57:48 alberich gdm[6224]: gdm_slave_wait_for_login: got_login for 'andi'
>Feb  7 13:57:48 alberich gdm[6224]: Sending LOGGED_IN == 1 for slave 6224
>Feb  7 13:57:48 alberich gdm[5701]: Handling message: 'LOGGED_IN 6224 1'
>Feb  7 13:57:48 alberich gdm[5701]: Got logged in == TRUE
>Feb  7 13:57:48 alberich gdm[6224]: Sending LOGIN == <secret> for slave 6224
>Feb  7 13:57:48 alberich gdm[5701]: Handling message: 'LOGIN 6224 andi'
>Feb  7 13:57:48 alberich gdm[5701]: Got LOGIN == andi
>Feb  7 13:57:48 alberich gdm[6224]: gdm_slave_session_start: Attempting session for user 'andi'
>Feb  7 13:57:48 alberich gdm[6224]: Initial setting: session: 'gnome' language: ''
>Feb  7 13:57:48 alberich gdm[6224]: gdm_slave_session_start: Authentication completed. Whacking greeter
>Feb  7 13:57:48 alberich gdm[6224]: slave_waitpid: waiting on 6237
>Feb  7 13:57:48 alberich gdm[6224]: slave_waitpid: done_waiting
>Feb  7 13:57:48 alberich gdm[6224]: Sending GREETPID == 0 for slave 6224
>Feb  7 13:57:48 alberich gdm[5701]: Handling message: 'GREETPID 6224 0'
>Feb  7 13:57:48 alberich gdm[5701]: Got GREETPID == 0
>Feb  7 13:57:48 alberich gdm[6224]: gdm_auth_user_add: Adding cookie for 1000
>Feb  7 13:57:48 alberich gdm[6224]: get_local_auths: Setting up socket access
>Feb  7 13:57:48 alberich gdm[6224]: get_local_auths: Setting up network access
>Feb  7 13:57:48 alberich gdm[6224]: get_local_auths: Setting up access for :0 - 2 entries
>Feb  7 13:57:48 alberich gdm[6224]: gdm_auth_user_add: Using /home/andi/.Xauthority for cookies
>Feb  7 13:57:48 alberich gdm[6224]: gdm_auth_purge: :0
>Feb  7 13:57:48 alberich gdm[6224]: gdm_auth_user_add: Done
>Feb  7 13:57:48 alberich gdm[6224]: Sending WRITE_X_SERVERS == 0 for slave 6224
>Feb  7 13:57:48 alberich gdm[5701]: Handling message: 'WRITE_X_SERVERS 6224 0'
>Feb  7 13:57:48 alberich gdm[6224]: Sending SESSPID == 6239 for slave 6224
>Feb  7 13:57:48 alberich gdm[6239]: Running /bin/bash --login /etc/opt/gnome/gdm/Xsession gnome no-fake-login  for andi on :0
>Feb  7 13:57:48 alberich gdm[5701]: Handling message: 'SESSPID 6224 6239'
>Feb  7 13:57:48 alberich gdm[5701]: Got SESSPID == 6239
>Feb  7 13:57:48 alberich gdm[6224]: slave_waitpid: waiting on 6239
>Feb  7 13:57:53 alberich gdm[6224]: slave_waitpid: done_waiting
>Feb  7 13:57:53 alberich gdm[6224]: Session: start_time: 1139317068 end_time: 1139317073
>Feb  7 13:57:53 alberich gdm[6224]: Session less than 10 seconds!
>Feb  7 13:57:53 alberich gdm[6224]: failsafe dialog failed (inhibitions: 0 0)
>Feb  7 13:58:05 alberich gdm[5701]: Handling message: 'XPID 6224 0'
>Feb  7 13:58:05 alberich gdm[5701]: Got XPID == 0
>Feb  7 13:58:05 alberich gdm[6224]: failsafe dialog failed (inhibitions: 0 1)
>Feb  7 13:58:17 alberich gdm[6224]: failsafe dialog failed (inhibitions: 1 1)
>Feb  7 13:58:17 alberich gdm[6224]: Sending SESSPID == 0 for slave 6224
>Feb  7 13:58:17 alberich gdm[5701]: Handling message: 'SESSPID 6224 0'
>Feb  7 13:58:17 alberich gdm[5701]: Got SESSPID == 0
>Feb  7 13:58:17 alberich gdm[6224]: gdm_slave_session_stop: andi on :0
>Feb  7 13:58:17 alberich gdm[6224]: Fatal X error detected.  Ignoring same during session shutdown.
>Feb  7 13:58:17 alberich gdm[6224]: gdm_slave_session_stop: back here from xioerror
>Feb  7 13:58:17 alberich gdm[6224]: gdm_slave_session_stop: Running post session script
>Feb  7 13:58:17 alberich gdm[6224]: gdm_auth_user_remove: Removing cookie from /home/andi/.Xauthority (0)
>Feb  7 13:58:17 alberich gdm[6224]: gdm_auth_purge: :0
>Feb  7 13:58:17 alberich gdm[6224]: Running gdm_verify_cleanup and pamh != NULL
>Feb  7 13:58:17 alberich gdm[6224]: Running pam_close_session
>Feb  7 13:58:17 alberich gdm[6224]: Running pam_setcred with PAM_DELETE_CRED
>Feb  7 13:58:17 alberich gdm[6224]: gdm_slave_session_start: Session ended OK (now all finished)
>Feb  7 13:58:17 alberich gdm[6224]: Sending LOGGED_IN == 0 for slave 6224
>Feb  7 13:58:17 alberich gdm[5701]: Handling message: 'LOGGED_IN 6224 0'
>Feb  7 13:58:17 alberich gdm[5701]: Got logged in == FALSE
>Feb  7 13:58:17 alberich gdm[6224]: Sending LOGIN == <secret> for slave 6224
>Feb  7 13:58:17 alberich gdm[5701]: Handling message: 'LOGIN 6224 '
>Feb  7 13:58:17 alberich gdm[5701]: Got LOGIN == 
>Feb  7 13:58:17 alberich gdm[6224]: gdm_slave_start: Reinitializing things
>Feb  7 13:58:17 alberich gdm[6224]: gdm_auth_secure_display: Setting up access for :0
>Feb  7 13:58:17 alberich gdm[6224]: gdm_auth_secure_display: Setting up access
>Feb  7 13:58:17 alberich gdm[6224]: gdm_auth_secure_display: Setting up access for :0 - 1 entries
>Feb  7 13:58:17 alberich gdm[6224]: Sending COOKIE == <secret> for slave 6224
>Feb  7 13:58:17 alberich gdm[5701]: Handling message: 'COOKIE 6224 96...'
>Feb  7 13:58:17 alberich gdm[5701]: Got COOKIE == <secret>
>Feb  7 13:58:17 alberich gdm[6224]: Sending AUTHFILE == <secret> for slave 6224
>Feb  7 13:58:17 alberich gdm[5701]: Handling message: 'AUTHFILE 6224 /var/lib/gdm/:0.Xauth'
>Feb  7 13:58:17 alberich gdm[5701]: Got AUTHFILE == /var/lib/gdm/:0.Xauth
>Feb  7 13:58:17 alberich gdm[6224]: Error reinitilizing server
>Feb  7 13:58:17 alberich gdm[6224]: gdm_slave_quick_exit: Will kill everything from the display
>Feb  7 13:58:17 alberich gdm[6224]: gdm_slave_quick_exit: Killed everything from the display
>Feb  7 13:58:17 alberich gdm[5701]: mainloop_sig_callback: Got signal 17
>Feb  7 13:58:17 alberich gdm[5701]: gdm_cleanup_children: child 6224 returned 2
>Feb  7 13:58:17 alberich gdm[5701]: gdm_child_action: In remanage
>Feb  7 13:58:17 alberich gdm[5701]: gdm_display_manage: Managing :0
>Feb  7 13:58:17 alberich gdm[5701]: loop check: last_start 1139317027, last_loop 1139317027, now: 1139317097, retry_count: 1
>Feb  7 13:58:17 alberich gdm[5701]: Resetting counts for loop of death detection, 90 seconds elapsed since loop started or session lasted more then 30 seconds.
>Feb  7 13:58:17 alberich gdm[6379]: gdm_slave_start: Starting slave process for :0
>Feb  7 13:58:17 alberich gdm[5701]: gdm_display_manage: Forked slave: 6379
>Feb  7 13:58:17 alberich gdm[6379]: gdm_slave_start: Loop Thingie
>Feb  7 13:58:17 alberich gdm[6379]: gdm_slave_run: Sleeping 1 seconds before server start
>Feb  7 13:58:18 alberich gdm[6379]: Sending VT_NUM == -1 for slave 6379
>Feb  7 13:58:18 alberich gdm[5701]: Handling message: 'VT_NUM 6379 -1'
>Feb  7 13:58:18 alberich gdm[5701]: Got VT_NUM == -1
>Feb  7 13:58:18 alberich gdm[6379]: gdm_server_start: :0
>Feb  7 13:58:18 alberich gdm[6379]: gdm_auth_secure_display: Setting up access for :0
>Feb  7 13:58:18 alberich gdm[6379]: gdm_auth_secure_display: Setting up access
>Feb  7 13:58:18 alberich gdm[6379]: gdm_auth_secure_display: Setting up access for :0 - 1 entries
>Feb  7 13:58:18 alberich gdm[6379]: Sending COOKIE == <secret> for slave 6379
>Feb  7 13:58:18 alberich gdm[5701]: Handling message: 'COOKIE 6379 93...'
>Feb  7 13:58:18 alberich gdm[5701]: Got COOKIE == <secret>
>Feb  7 13:58:18 alberich gdm[6379]: Sending AUTHFILE == <secret> for slave 6379
>Feb  7 13:58:18 alberich gdm[5701]: Handling message: 'AUTHFILE 6379 /var/lib/gdm/:0.Xauth'
>Feb  7 13:58:18 alberich gdm[5701]: Got AUTHFILE == /var/lib/gdm/:0.Xauth
>Feb  7 13:58:18 alberich gdm[6380]: gdm_server_spawn: '/usr/X11R6/bin/X :0 -audit 0 -auth /var/lib/gdm/:0.Xauth -nolisten tcp vt7'
>Feb  7 13:58:18 alberich gdm[6379]: gdm_server_spawn: Forked server on pid 6380
>Feb  7 13:58:18 alberich gdm[6379]: gdm_server_start: Completed :0!
>Feb  7 13:58:18 alberich gdm[6379]: Sending VT_NUM == 7 for slave 6379
>Feb  7 13:58:18 alberich gdm[5701]: Handling message: 'VT_NUM 6379 7'
>Feb  7 13:58:18 alberich gdm[5701]: Got VT_NUM == 7
>Feb  7 13:58:18 alberich gdm[6379]: Sending XPID == 6380 for slave 6379
>Feb  7 13:58:18 alberich gdm[5701]: Handling message: 'XPID 6379 6380'
>Feb  7 13:58:18 alberich gdm[5701]: Got XPID == 6380
>Feb  7 13:58:18 alberich gdm[6379]: gdm_slave_run: Opening display :0
>Feb  7 13:58:21 alberich gdm[5701]: Handling message: 'START_NEXT_LOCAL'
>Feb  7 13:58:21 alberich gdm[6379]: gdm_slave_greeter: Running greeter on :0
>Feb  7 13:58:21 alberich gdm[6379]: gdm_slave_greeter: Greeter on pid 6392
>Feb  7 13:58:21 alberich gdm[6379]: Sending GREETPID == 6392 for slave 6379
>Feb  7 13:58:21 alberich gdm[5701]: Handling message: 'GREETPID 6379 6392'
>Feb  7 13:58:21 alberich gdm[5701]: Got GREETPID == 6392
>Feb  7 13:58:21 alberich gdm[5701]: gdm_socket_handler: Accepting new connection fd 8
>Feb  7 13:58:21 alberich gdm[5701]: Handling user message: 'VERSION'
>Feb  7 13:58:21 alberich gdm[5701]: Handling user message: 'GET_CONFIG_FILE'
>Feb  7 13:58:21 alberich gdm[5701]: Handling user message: 'CLOSE'
>Feb  7 13:58:22 alberich gdm[6379]: gdm_slave_wait_for_login: In loop

Actions: View

Attachments on bug 148229: 66886