Skip to content

Instantly share code, notes, and snippets.

@jmontleon
Last active February 9, 2023 19:08
Show Gist options
  • Save jmontleon/a6dff2ad949cc50bb8f162d7b306b320 to your computer and use it in GitHub Desktop.
Save jmontleon/a6dff2ad949cc50bb8f162d7b306b320 to your computer and use it in GitHub Desktop.
dmesg debug kernel call trace for kbl_rt5663_rt5514_max98927 lockup
[ 175.827296] grub2-set-bootf (1957) used greatest stack depth: 11512 bytes left
[ 247.615582] INFO: task kworker/0:1:14 blocked for more than 122 seconds.
[ 247.615600] Not tainted 6.1.9-200.fc37.x86_64+debug #1
[ 247.615607] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 247.615613] task:kworker/0:1 state:D stack:13080 pid:14 ppid:2 flags:0x00004000
[ 247.615637] Workqueue: pm pm_runtime_work
[ 247.615655] Call Trace:
[ 247.615660] <TASK>
[ 247.615696] __schedule+0x4c5/0x1740
[ 247.615749] schedule+0x5d/0xe0
[ 247.615763] rpm_resume+0x1aa/0x810
[ 247.615776] ? prepare_to_wait_exclusive+0xd0/0xd0
[ 247.615804] __pm_runtime_resume+0x47/0x90
[ 247.615823] codec_exec_verb+0x43/0x100 [snd_hda_codec]
[ 247.615870] snd_hdac_codec_read+0x3f/0x80 [snd_hda_core]
[ 247.615904] hdac_hdmi_runtime_suspend+0x36/0xb0 [snd_soc_hdac_hdmi]
[ 247.615923] ? hdac_hdmi_runtime_resume+0xc0/0xc0 [snd_soc_hdac_hdmi]
[ 247.615940] __rpm_callback+0x41/0x170
[ 247.615951] ? hdac_hdmi_runtime_resume+0xc0/0xc0 [snd_soc_hdac_hdmi]
[ 247.615975] rpm_callback+0x5d/0x70
[ 247.615985] ? hdac_hdmi_runtime_resume+0xc0/0xc0 [snd_soc_hdac_hdmi]
[ 247.616002] rpm_suspend+0x10a/0x6f0
[ 247.616014] ? pm_runtime_work+0x19/0xb0
[ 247.616022] ? lock_acquired+0xe9/0x3c0
[ 247.616050] pm_runtime_work+0xa0/0xb0
[ 247.616063] process_one_work+0x291/0x5b0
[ 247.616105] worker_thread+0x4f/0x3a0
[ 247.616127] ? process_one_work+0x5b0/0x5b0
[ 247.616134] kthread+0xf2/0x120
[ 247.616141] ? kthread_complete_and_exit+0x20/0x20
[ 247.616160] ret_from_fork+0x1f/0x30
[ 247.616221] </TASK>
[ 247.616264] INFO: task pulseaudio:1252 blocked for more than 122 seconds.
[ 247.616271] Not tainted 6.1.9-200.fc37.x86_64+debug #1
[ 247.616277] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 247.616282] task:pulseaudio state:D stack:12824 pid:1252 ppid:1215 flags:0x00000002
[ 247.616368] Call Trace:
[ 247.616375] <TASK>
[ 247.616402] __schedule+0x4c5/0x1740
[ 247.616469] schedule+0x5d/0xe0
[ 247.616486] rpm_resume+0x1aa/0x810
[ 247.616503] ? prepare_to_wait_exclusive+0xd0/0xd0
[ 247.616539] __pm_runtime_resume+0x47/0x90
[ 247.616566] snd_soc_pcm_component_pm_runtime_get+0x30/0xb0 [snd_soc_core]
[ 247.616673] __soc_pcm_open+0x64/0x4f0 [snd_soc_core]
[ 247.616774] dpcm_be_dai_startup+0x105/0x210 [snd_soc_core]
[ 247.616888] dpcm_fe_dai_open+0xea/0x880 [snd_soc_core]
[ 247.616974] ? debug_mutex_init+0x33/0x50
[ 247.617025] snd_pcm_open_substream+0x52f/0x890 [snd_pcm]
[ 247.617093] snd_pcm_open+0x104/0x240 [snd_pcm]
[ 247.617149] ? wake_up_q+0x90/0x90
[ 247.617192] snd_pcm_playback_open+0x3d/0x70 [snd_pcm]
[ 247.617241] chrdev_open+0xb1/0x220
[ 247.617270] ? __unregister_chrdev+0x50/0x50
[ 247.617291] do_dentry_open+0x1fa/0x450
[ 247.617369] path_openat+0x7a0/0xcb0
[ 247.617427] do_filp_open+0x9e/0x130
[ 247.617564] do_sys_openat2+0x96/0x150
[ 247.617600] __x64_sys_openat+0x5c/0x80
[ 247.617625] do_syscall_64+0x58/0x80
[ 247.617652] ? do_syscall_64+0x67/0x80
[ 247.617666] ? lockdep_hardirqs_on+0x7d/0x100
[ 247.617686] ? do_syscall_64+0x67/0x80
[ 247.617696] ? do_syscall_64+0x67/0x80
[ 247.617709] ? lockdep_hardirqs_on+0x7d/0x100
[ 247.617728] ? do_syscall_64+0x67/0x80
[ 247.617740] ? do_syscall_64+0x67/0x80
[ 247.617753] ? lockdep_hardirqs_on+0x7d/0x100
[ 247.617771] ? do_syscall_64+0x67/0x80
[ 247.617792] ? asm_exc_page_fault+0x22/0x30
[ 247.617806] ? lockdep_hardirqs_on+0x7d/0x100
[ 247.617826] entry_SYSCALL_64_after_hwframe+0x63/0xcd
[ 247.617838] RIP: 0033:0x7f5d46b19fb0
[ 247.617899] RSP: 002b:00007ffcaa136260 EFLAGS: 00000293 ORIG_RAX: 0000000000000101
[ 247.617918] RAX: ffffffffffffffda RBX: 0000000000080802 RCX: 00007f5d46b19fb0
[ 247.617929] RDX: 0000000000080802 RSI: 00007ffcaa136300 RDI: 00000000ffffff9c
[ 247.617939] RBP: 00007ffcaa136300 R08: 0000000000000000 R09: 0000000000000069
[ 247.617948] R10: 0000000000000000 R11: 0000000000000293 R12: 0000000000000004
[ 247.617957] R13: 00007ffcaa136740 R14: 00007ffcaa136300 R15: 0000000000000000
[ 247.618041] </TASK>
[ 247.618070]
Showing all locks held in the system:
[ 247.618082] 1 lock held by rcu_tasks_kthre/11:
[ 247.618093] #0: ffffffff9b368e60 (rcu_tasks.tasks_gp_mutex){+.+.}-{3:3}, at: rcu_tasks_one_gp+0x2b/0x3e0
[ 247.618149] 1 lock held by rcu_tasks_rude_/12:
[ 247.618158] #0: ffffffff9b368ba0 (rcu_tasks_rude.tasks_gp_mutex){+.+.}-{3:3}, at: rcu_tasks_one_gp+0x2b/0x3e0
[ 247.618210] 1 lock held by rcu_tasks_trace/13:
[ 247.618218] #0: ffffffff9b3688a0 (rcu_tasks_trace.tasks_gp_mutex){+.+.}-{3:3}, at: rcu_tasks_one_gp+0x2b/0x3e0
[ 247.618269] 2 locks held by kworker/0:1/14:
[ 247.618278] #0: ffff9ad900fd8148 ((wq_completion)pm){+.+.}-{0:0}, at: process_one_work+0x20b/0x5b0
[ 247.618373] #1: ffffa66100097e78 ((work_completion)(&dev->power.work)){+.+.}-{0:0}, at: process_one_work+0x20b/0x5b0
[ 247.618425] 1 lock held by khungtaskd/37:
[ 247.618433] #0: ffffffff9b369960 (rcu_read_lock){....}-{1:2}, at: debug_show_all_locks+0x15/0x16b
[ 247.618491] 4 locks held by systemd-journal/654:
[ 247.618517] 2 locks held by pulseaudio/1252:
[ 247.618526] #0: ffff9ad95d07dc58 (&pcm->open_mutex){+.+.}-{3:3}, at: snd_pcm_open+0xec/0x240 [snd_pcm]
[ 247.618603] #1: ffffffffc19e9058 (&card->pcm_mutex){+.+.}-{3:3}, at: dpcm_fe_dai_open+0x3d/0x880 [snd_soc_core]
[ 247.618743] =============================================
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment