On Sat, 19 Apr 2014 09:32:00 -0700
Tom London <selinux@xxxxxxxxx> wrote:
> 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
>
>
May be a red herring. The file does not exist on my machine booted
with kernel-3.14.
--
test mailing list
test@xxxxxxxxxxxxxxxxxxxxxxx
To unsubscribe:
https://admin.fedoraproject.org/mailman/listinfo/test
