On Sat, Apr 19, 2014 at 8:22 AM, Tom London <selinux(a)gmail.com> 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