Re: kernel-3.15 series fail to load gnome desktop

[Tom London <selinux@xxxxxxxxx>]

On Sat, Apr 19, 2014 at 8:22 AM, Tom London <selinux@xxxxxxxxx> wrote:

I also had not been able to get a 3-15 kernel to boot-up/display the Gnome desktop (Lenovo X200, Intel graphics).

I had tried my usual tricks:

1. boot with enforcing=0

2. switch to a VT, run "telinit 3" and then "telinit 5".

I noticed that "ps" seemed to be reporting that all was well: all the obvious processes were running, but I did notice more than the usual number of pulseaudio processes.

I also noticed shutdown hanging on "saving sound state" (or some such).

I tried again with the latest  kernel (kernel-3.15.0-0.rc1.git4.1.fc21.x86_64), and when I reached the "black screen" state, I switch to a VT, and as root killed all the pulseaudio processes ('killall -9 pulseaudio'). Within about 5-10 seconds, I got the gdm-greeter login screen. System hung (black screen) again after entering password, so I switched to VT and again killed the pulseaudio processes with 'killall -9 pulseaudio'.  Apparently the "new ones" were spawned by login process.

Lo and behold, got my gnome desktop (of course, no sound).

I have absolutely no idea what relationship pulsaudio/sound has to gdm/gnome/shutting down.

Anyone have any ideas/thoughs?

tom
--
Tom London

See this spew in journal:

Apr 19 08:12:34 tlondon.localhost.org pulseaudio[857]: [pulseaudio] authkey.c: Failed to open cookie file '/var/lib/gdm/.config/pulse/cookie': No such file or directory
Apr 19 08:12:34 tlondon.localhost.org pulseaudio[857]: [pulseaudio] authkey.c: Failed to load authorization key '/var/lib/gdm/.config/pulse/cookie': No such file or director
Apr 19 08:12:34 tlondon.localhost.org rtkit-daemon[648]: Successfully made thread 860 of process 860 (/usr/bin/pulseaudio) owned by '42' high priority at nice level -11.
Apr 19 08:12:34 tlondon.localhost.org rtkit-daemon[648]: Supervising 1 threads of 1 processes of 1 users.
Apr 19 08:12:34 tlondon.localhost.org gdm-Xorg-:0[719]: (II) intel(0): EDID vendor "LEN", prod id 16400
Apr 19 08:12:34 tlondon.localhost.org gdm-Xorg-:0[719]: (II) intel(0): Printing DDC gathered Modelines:
Apr 19 08:12:34 tlondon.localhost.org gdm-Xorg-:0[719]: (II) intel(0): Modeline "1280x800"x0.0   75.30  1280 1296 1344 1512  800 802 804 830 -hsync -vsync (49.8 kHz eP)
Apr 19 08:12:34 tlondon.localhost.org gdm-Xorg-:0[719]: (II) intel(0): Modeline "1280x800"x0.0   59.26  1280 1328 1360 1440  800 803 809 823 -hsync -vsync (41.2 kHz e)
Apr 19 08:12:35 tlondon.localhost.org kernel: usb 1-5.3.4: reset high-speed USB device number 7 using ehci-pci
Apr 19 08:12:35 tlondon.localhost.org pulseaudio[865]: [pulseaudio] authkey.c: Failed to open cookie file '/var/lib/gdm/.config/pulse/cookie': No such file or directory
Apr 19 08:12:35 tlondon.localhost.org pulseaudio[865]: [pulseaudio] authkey.c: Failed to load authorization key '/var/lib/gdm/.config/pulse/cookie': No such file or director
Apr 19 08:12:35 tlondon.localhost.org kernel:
Apr 19 08:12:35 tlondon.localhost.org kernel: =============================================
Apr 19 08:12:35 tlondon.localhost.org kernel: [ INFO: possible recursive locking detected ]
Apr 19 08:12:35 tlondon.localhost.org kernel: 3.15.0-0.rc1.git4.1.fc21.x86_64 #1 Tainted: G        W   
Apr 19 08:12:35 tlondon.localhost.org kernel: ---------------------------------------------
Apr 19 08:12:35 tlondon.localhost.org kernel: pulseaudio/860 is trying to acquire lock:
Apr 19 08:12:35 tlondon.localhost.org kernel:  (&chip->shutdown_rwsem){.+.+.+}, at: [<ffffffffa0613013>] snd_usb_autoresume+0x23/0x60 [snd_usb_audio]
Apr 19 08:12:35 tlondon.localhost.org kernel:
                                              but task is already holding lock:
Apr 19 08:12:35 tlondon.localhost.org kernel:  (&chip->shutdown_rwsem){.+.+.+}, at: [<ffffffffa0613013>] snd_usb_autoresume+0x23/0x60 [snd_usb_audio]
Apr 19 08:12:35 tlondon.localhost.org kernel:
                                              other info that might help us debug this:
Apr 19 08:12:35 tlondon.localhost.org kernel:  Possible unsafe locking scenario:
Apr 19 08:12:35 tlondon.localhost.org kernel:        CPU0
Apr 19 08:12:35 tlondon.localhost.org kernel:        ----
Apr 19 08:12:35 tlondon.localhost.org kernel:   lock(&chip->shutdown_rwsem);
Apr 19 08:12:35 tlondon.localhost.org kernel:   lock(&chip->shutdown_rwsem);
Apr 19 08:12:35 tlondon.localhost.org kernel:
                                               *** DEADLOCK ***
Apr 19 08:12:35 tlondon.localhost.org kernel:  May be due to missing lock nesting notation
Apr 19 08:12:35 tlondon.localhost.org kernel: 2 locks held by pulseaudio/860:
Apr 19 08:12:35 tlondon.localhost.org kernel:  #0:  (&pcm->open_mutex){+.+.+.}, at: [<ffffffffa0480dbc>] snd_pcm_open+0xac/0x290 [snd_pcm]
Apr 19 08:12:35 tlondon.localhost.org kernel:  #1:  (&chip->shutdown_rwsem){.+.+.+}, at: [<ffffffffa0613013>] snd_usb_autoresume+0x23/0x60 [snd_usb_audio]
Apr 19 08:12:35 tlondon.localhost.org kernel:
                                              stack backtrace:
Apr 19 08:12:35 tlondon.localhost.org kernel: CPU: 0 PID: 860 Comm: pulseaudio Tainted: G        W     3.15.0-0.rc1.git4.1.fc21.x86_64 #1
Apr 19 08:12:35 tlondon.localhost.org kernel: Hardware name: LENOVO 74585FU/74585FU, BIOS 6DET72WW (3.22 ) 10/25/2012
Apr 19 08:12:35 tlondon.localhost.org kernel:  0000000000000000 00000000c0bd0f84 ffff8800a40bd590 ffffffff817e84d0
Apr 19 08:12:35 tlondon.localhost.org kernel:  ffffffff82843b50 ffff8800a40bd668 ffffffff810fa842 ffffffff810db86d
Apr 19 08:12:35 tlondon.localhost.org kernel:  ffff880136fd68c0 0000000000000010 ffff880100000000 00000015009564ab
Apr 19 08:12:35 tlondon.localhost.org kernel: Call Trace:
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffff817e84d0>] dump_stack+0x4d/0x66
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffff810fa842>] __lock_acquire+0x14c2/0x1d10
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffff810db86d>] ? sched_clock_local+0x1d/0x90
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffff810f8d9c>] ? mark_held_locks+0x7c/0xb0
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffff817f0d56>] ? _raw_spin_unlock_irqrestore+0x36/0x70
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffff81022a6e>] ? native_sched_clock+0x2e/0xb0
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffff81022af9>] ? sched_clock+0x9/0x10
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffff810db86d>] ? sched_clock_local+0x1d/0x90
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffff810fb974>] lock_acquire+0xa4/0x1d0
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffffa0613013>] ? snd_usb_autoresume+0x23/0x60 [snd_usb_audio]
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffff817ef951>] down_read+0x51/0xa0
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffffa0613013>] ? snd_usb_autoresume+0x23/0x60 [snd_usb_audio]
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffffa0613013>] snd_usb_autoresume+0x23/0x60 [snd_usb_audio]
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffffa06175be>] snd_usb_mixer_set_ctl_value+0xce/0x260 [snd_usb_audio]
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffffa067fb7d>] ? uvc_set_video_ctrl.isra.10+0x11d/0x1d0 [uvcvideo]
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffffa06177bc>] set_cur_mix_value+0x6c/0xe0 [snd_usb_audio]
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffffa061a66a>] snd_usb_mixer_resume+0xfa/0x130 [snd_usb_audio]
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffffa0612068>] __usb_audio_resume+0x68/0xf0 [snd_usb_audio]
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffff81590f80>] ? usb_runtime_suspend+0x70/0x70
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffffa0612103>] usb_audio_reset_resume+0x13/0x20 [snd_usb_audio]
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffff8158f959>] usb_resume_interface.isra.5+0x69/0x120
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffff8158fc74>] usb_resume_both+0x74/0x140
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffff81590f9a>] usb_runtime_resume+0x1a/0x20
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffff8150ed72>] __rpm_callback+0x32/0x70
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffff8150edd6>] rpm_callback+0x26/0xa0
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffff8150f69e>] rpm_resume+0x49e/0x7f0
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffff8150f4da>] rpm_resume+0x2da/0x7f0
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffff81510ccf>] __pm_runtime_resume+0x4f/0x90
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffff8158f732>] usb_autopm_get_interface+0x22/0x60
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffffa0613031>] snd_usb_autoresume+0x41/0x60 [snd_usb_audio]
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffffa061cfb1>] snd_usb_pcm_open+0x241/0x520 [snd_usb_audio]
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffffa061d2a3>] snd_usb_capture_open+0x13/0x20 [snd_usb_audio]
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffffa0480c54>] snd_pcm_open_substream+0x84/0x140 [snd_pcm]
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffffa0480de2>] snd_pcm_open+0xd2/0x290 [snd_pcm]
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffff810f8fdd>] ? trace_hardirqs_on+0xd/0x10
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffff810d7f60>] ? wake_up_state+0x20/0x20
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffffa0480fe7>] snd_pcm_capture_open+0x47/0x70 [snd_pcm]
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffffa02295d3>] snd_open+0xb3/0x190 [snd]
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffff8123ec8e>] chrdev_open+0xae/0x1d0
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffff8123ebe0>] ? cdev_put+0x30/0x30
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffff812372bd>] do_dentry_open+0x1ed/0x2d0
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffff812374b1>] finish_open+0x41/0x60
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffff8124cadc>] do_last+0xdac/0x14a0
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffff81247189>] ? link_path_walk+0xa9/0x1490
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffff810dbb26>] ? local_clock+0x16/0x30
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffff8124d29e>] path_openat+0xce/0x700
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffff81022a6e>] ? native_sched_clock+0x2e/0xb0
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffff81022a6e>] ? native_sched_clock+0x2e/0xb0
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffff81022af9>] ? sched_clock+0x9/0x10
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffff8124e16d>] do_filp_open+0x4d/0xc0
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffff817f0cc7>] ? _raw_spin_unlock+0x27/0x40
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffff8125e3ef>] ? __alloc_fd+0xaf/0x1c0
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffff812390eb>] do_sys_open+0x13b/0x250
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffff8123921e>] SyS_open+0x1e/0x20
Apr 19 08:12:35 tlondon.localhost.org kernel:  [<ffffffff817fbd69>] system_call_fastpath+0x16/0x1b

And this:

Apr 19 08:15:46 tlondon.localhost.org kernel: INFO: task pulseaudio:860 blocked for more than 120 seconds.
Apr 19 08:15:46 tlondon.localhost.org kernel:       Tainted: G        W     3.15.0-0.rc1.git4.1.fc21.x86_64 #1
Apr 19 08:15:46 tlondon.localhost.org kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Apr 19 08:15:46 tlondon.localhost.org kernel: pulseaudio      D ffff8800b71cb380  3720   860      1 0x00000084
Apr 19 08:15:46 tlondon.localhost.org kernel:  ffff8800a40bd590 0000000000000046 ffff8800b71cb380 00000000001d5dc0
Apr 19 08:15:46 tlondon.localhost.org kernel:  ffff8800a40bdfd8 00000000001d5dc0 ffffffff81e16580 ffff880037033890
Apr 19 08:15:46 tlondon.localhost.org kernel:  ffff8800370339b8 ffff880037033b58 0000000000000000 0000000000000000
Apr 19 08:15:46 tlondon.localhost.org kernel: Call Trace:
Apr 19 08:15:46 tlondon.localhost.org kernel:  [<ffffffff817eb809>] schedule+0x29/0x70
Apr 19 08:15:46 tlondon.localhost.org kernel:  [<ffffffff8150f3c5>] rpm_resume+0x1c5/0x7f0
Apr 19 08:15:46 tlondon.localhost.org kernel:  [<ffffffff810eecd0>] ? abort_exclusive_wait+0xb0/0xb0
Apr 19 08:15:46 tlondon.localhost.org kernel:  [<ffffffff8150f4da>] rpm_resume+0x2da/0x7f0
Apr 19 08:15:46 tlondon.localhost.org kernel:  [<ffffffff81510ccf>] __pm_runtime_resume+0x4f/0x90
Apr 19 08:15:46 tlondon.localhost.org kernel:  [<ffffffff8158f732>] usb_autopm_get_interface+0x22/0x60
Apr 19 08:15:46 tlondon.localhost.org kernel:  [<ffffffffa0613031>] snd_usb_autoresume+0x41/0x60 [snd_usb_audio]
Apr 19 08:15:46 tlondon.localhost.org kernel:  [<ffffffffa06175be>] snd_usb_mixer_set_ctl_value+0xce/0x260 [snd_usb_audio]
Apr 19 08:15:46 tlondon.localhost.org kernel:  [<ffffffffa067fb7d>] ? uvc_set_video_ctrl.isra.10+0x11d/0x1d0 [uvcvideo]
Apr 19 08:15:46 tlondon.localhost.org kernel:  [<ffffffffa06177bc>] set_cur_mix_value+0x6c/0xe0 [snd_usb_audio]
Apr 19 08:15:46 tlondon.localhost.org kernel:  [<ffffffffa061a66a>] snd_usb_mixer_resume+0xfa/0x130 [snd_usb_audio]
Apr 19 08:15:46 tlondon.localhost.org kernel:  [<ffffffffa0612068>] __usb_audio_resume+0x68/0xf0 [snd_usb_audio]
Apr 19 08:15:46 tlondon.localhost.org kernel:  [<ffffffff81590f80>] ? usb_runtime_suspend+0x70/0x70
Apr 19 08:15:46 tlondon.localhost.org kernel:  [<ffffffffa0612103>] usb_audio_reset_resume+0x13/0x20 [snd_usb_audio]
Apr 19 08:15:46 tlondon.localhost.org kernel:  [<ffffffff8158f959>] usb_resume_interface.isra.5+0x69/0x120
Apr 19 08:15:46 tlondon.localhost.org kernel:  [<ffffffff8158fc74>] usb_resume_both+0x74/0x140
Apr 19 08:15:46 tlondon.localhost.org kernel:  [<ffffffff81590f9a>] usb_runtime_resume+0x1a/0x20
Apr 19 08:15:46 tlondon.localhost.org kernel:  [<ffffffff8150ed72>] __rpm_callback+0x32/0x70
Apr 19 08:15:46 tlondon.localhost.org kernel:  [<ffffffff8150edd6>] rpm_callback+0x26/0xa0
Apr 19 08:15:46 tlondon.localhost.org kernel:  [<ffffffff8150f69e>] rpm_resume+0x49e/0x7f0
Apr 19 08:15:46 tlondon.localhost.org kernel:  [<ffffffff8150f4da>] rpm_resume+0x2da/0x7f0
Apr 19 08:15:46 tlondon.localhost.org kernel:  [<ffffffff81510ccf>] __pm_runtime_resume+0x4f/0x90
Apr 19 08:15:46 tlondon.localhost.org kernel:  [<ffffffff8158f732>] usb_autopm_get_interface+0x22/0x60
Apr 19 08:15:46 tlondon.localhost.org kernel:  [<ffffffffa0613031>] snd_usb_autoresume+0x41/0x60 [snd_usb_audio]
Apr 19 08:15:46 tlondon.localhost.org kernel:  [<ffffffffa061cfb1>] snd_usb_pcm_open+0x241/0x520 [snd_usb_audio]
Apr 19 08:15:46 tlondon.localhost.org kernel:  [<ffffffffa061d2a3>] snd_usb_capture_open+0x13/0x20 [snd_usb_audio]
Apr 19 08:15:46 tlondon.localhost.org kernel:  [<ffffffffa0480c54>] snd_pcm_open_substream+0x84/0x140 [snd_pcm]
Apr 19 08:15:46 tlondon.localhost.org kernel:  [<ffffffffa0480de2>] snd_pcm_open+0xd2/0x290 [snd_pcm]
Apr 19 08:15:46 tlondon.localhost.org kernel:  [<ffffffff810f8fdd>] ? trace_hardirqs_on+0xd/0x10
Apr 19 08:15:46 tlondon.localhost.org kernel:  [<ffffffff810d7f60>] ? wake_up_state+0x20/0x20
Apr 19 08:15:46 tlondon.localhost.org kernel:  [<ffffffffa0480fe7>] snd_pcm_capture_open+0x47/0x70 [snd_pcm]
Apr 19 08:15:46 tlondon.localhost.org kernel:  [<ffffffffa02295d3>] snd_open+0xb3/0x190 [snd]
Apr 19 08:15:46 tlondon.localhost.org kernel:  [<ffffffff8123ec8e>] chrdev_open+0xae/0x1d0
Apr 19 08:15:46 tlondon.localhost.org kernel:  [<ffffffff8123ebe0>] ? cdev_put+0x30/0x30
Apr 19 08:15:46 tlondon.localhost.org kernel:  [<ffffffff812372bd>] do_dentry_open+0x1ed/0x2d0
Apr 19 08:15:46 tlondon.localhost.org kernel:  [<ffffffff812374b1>] finish_open+0x41/0x60
Apr 19 08:15:46 tlondon.localhost.org kernel:  [<ffffffff8124cadc>] do_last+0xdac/0x14a0
Apr 19 08:15:46 tlondon.localhost.org kernel:  [<ffffffff81247189>] ? link_path_walk+0xa9/0x1490
Apr 19 08:15:46 tlondon.localhost.org kernel:  [<ffffffff810dbb26>] ? local_clock+0x16/0x30
Apr 19 08:15:46 tlondon.localhost.org kernel:  [<ffffffff8124d29e>] path_openat+0xce/0x700
Apr 19 08:15:46 tlondon.localhost.org kernel:  [<ffffffff81022a6e>] ? native_sched_clock+0x2e/0xb0
Apr 19 08:15:46 tlondon.localhost.org kernel:  [<ffffffff81022a6e>] ? native_sched_clock+0x2e/0xb0
Apr 19 08:15:46 tlondon.localhost.org kernel:  [<ffffffff81022af9>] ? sched_clock+0x9/0x10
Apr 19 08:15:46 tlondon.localhost.org kernel:  [<ffffffff8124e16d>] do_filp_open+0x4d/0xc0

--
Tom London

-- 
test mailing list
test@xxxxxxxxxxxxxxxxxxxxxxx
To unsubscribe:
https://admin.fedoraproject.org/mailman/listinfo/test