[Bug 106525] [CI][IGT] DRM-Tip 4.17-rc5 RIP: 0010:snd_hdac_display_power dmesg splat

bugzilla-daemon at freedesktop.org bugzilla-daemon at freedesktop.org
Wed Dec 5 14:05:18 UTC 2018 

https://bugs.freedesktop.org/show_bug.cgi?id=106525

--- Comment #9 from Martin Peres <martin.peres at free.fr> ---
Also seen on BSW,
https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_5259/fi-bsw-cyan/igt@i915_selftest@live_sanitycheck.html

<4> [478.306426] WARNING: CPU: 3 PID: 3838 at sound/hda/hdac_component.c:85
snd_hdac_display_power+0xe6/0x100 [snd_hda_core]
<4> [478.306488] Modules linked in: snd_hda_intel i915 amdgpu chash gpu_sched
ttm vgem snd_hda_codec_hdmi coretemp crct10dif_pclmul btusb crc32_pclmul btrtl
btbcm btintel ghash_clmulni_intel cdc_ether usbnet bluetooth r8152 mii
ecdh_generic snd_hda_codec snd_hwdep snd_hda_core snd_pcm pinctrl_cherryview
lpc_ich prime_numbers [last unloaded: i915]
<4> [478.306538] CPU: 3 PID: 3838 Comm: i915_selftest Tainted: G     U         
  4.20.0-rc5-CI-CI_DRM_5259+ #1
<4> [478.306544] Hardware name: GOOGLE Cyan/Cyan, BIOS MrChromebox 02/15/2018
<4> [478.306557] RIP: 0010:snd_hdac_display_power+0xe6/0x100 [snd_hda_core]
<4> [478.306564] Code: 09 49 8b 7d 00 e8 2a ca b7 e1 be 01 00 00 00 48 89 df e8
ad fe ff ff 31 f6 48 89 df e8 a3 fe ff ff 5b 89 e8 5d 41 5c 41 5d c3 <0f> 0b c7
83 58 05 00 00 ff ff ff ff eb e9 bd ed ff ff ff eb e2 0f
<4> [478.306570] RSP: 0018:ffffc900005d3c68 EFLAGS: 00010246
<4> [478.306578] RAX: 00000000ffffffff RBX: ffff888076b91158 RCX:
0000000000000000
<4> [478.306584] RDX: 0000000000000006 RSI: 0000000000000000 RDI:
ffff888076b91158
<4> [478.306589] RBP: 0000000000000000 R08: 0000000000000000 R09:
0000000000000000
<4> [478.306594] R10: 0000000000000000 R11: 0000000000000000 R12:
0000000000000000
<4> [478.306600] R13: ffff8880744afcd0 R14: 0000000000000060 R15:
ffff888075e1a7e8
<4> [478.306607] FS:  00007fd2e27f9980(0000) GS:ffff888079980000(0000)
knlGS:0000000000000000
<4> [478.306612] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
<4> [478.306618] CR2: 00007f33b20ecd29 CR3: 00000000620ee000 CR4:
00000000001006e0
<4> [478.306623] Call Trace:
<4> [478.306652]  snd_hda_codec_dev_free+0x1e/0x30 [snd_hda_codec]
<4> [478.306666]  __snd_device_free+0x4c/0x80
<4> [478.306674]  snd_device_free_all+0x36/0x80
<4> [478.306688]  release_card_device+0x14/0x70
<4> [478.306699]  device_release+0x2b/0x80
<4> [478.306711]  kobject_put+0x85/0x1a0
<4> [478.306722]  snd_card_free+0x60/0x90
<4> [478.306745]  pci_device_remove+0x36/0xb0
<4> [478.306757]  device_release_driver_internal+0x185/0x240
<4> [478.306771]  unbind_store+0xaf/0x180
<4> [478.306784]  kernfs_fop_write+0x104/0x190
<4> [478.306799]  __vfs_write+0x31/0x180
<4> [478.306812]  ? rcu_read_lock_sched_held+0x6f/0x80
<4> [478.306819]  ? rcu_sync_lockdep_assert+0x29/0x50
<4> [478.306827]  ? __sb_start_write+0x152/0x1f0
<4> [478.306833]  ? __sb_start_write+0x163/0x1f0
<4> [478.306846]  vfs_write+0xbd/0x1b0
<4> [478.306858]  ksys_write+0x50/0xc0
<4> [478.306873]  do_syscall_64+0x55/0x190
<4> [478.306884]  entry_SYSCALL_64_after_hwframe+0x49/0xbe
<4> [478.306891] RIP: 0033:0x7fd2e20b6154
<4> [478.306898] Code: 89 02 48 c7 c0 ff ff ff ff c3 66 2e 0f 1f 84 00 00 00 00
00 66 90 48 8d 05 b1 07 2e 00 8b 00 85 c0 75 13 b8 01 00 00 00 0f 05 <48> 3d 00
f0 ff ff 77 54 f3 c3 66 90 41 54 55 49 89 d4 53 48 89 f5
<4> [478.306904] RSP: 002b:00007fffc158d408 EFLAGS: 00000246 ORIG_RAX:
0000000000000001
<4> [478.306911] RAX: ffffffffffffffda RBX: 0000000000000000 RCX:
00007fd2e20b6154
<4> [478.306917] RDX: 000000000000000c RSI: 000055fa56a0532b RDI:
0000000000000005
<4> [478.306922] RBP: 00007fffc158e4f0 R08: 0000000000000000 R09:
0000000000000000
<4> [478.306928] R10: 00007fd2e2112560 R11: 0000000000000246 R12:
000055fa5560b750
<4> [478.306933] R13: 00007fffc158e7d0 R14: 0000000000000000 R15:
0000000000000000
<4> [478.306961] irq event stamp: 76936
<4> [478.306971] hardirqs last  enabled at (76935): [<ffffffff810a59fb>]
__flush_work+0x2bb/0x310
<4> [478.306977] hardirqs last disabled at (76936): [<ffffffff810019b0>]
trace_hardirqs_off_thunk+0x1a/0x1c
<4> [478.306986] softirqs last  enabled at (76898): [<ffffffff817fab8c>]
peernet2id+0x4c/0x70
<4> [478.306992] softirqs last disabled at (76896): [<ffffffff817fab6d>]
peernet2id+0x2d/0x70
<4> [478.307006] WARNING: CPU: 3 PID: 3838 at sound/hda/hdac_component.c:85
snd_hdac_display_power+0xe6/0x100 [snd_hda_core]
<4> [478.307012] ---[ end trace 086147dd2631bdcd ]---
<4> [478.340648] WARNING: CPU: 3 PID: 3838 at sound/hda/hdac_component.c:324
snd_hdac_acomp_exit+0x53/0x70 [snd_hda_core]
<4> [478.340658] Modules linked in: snd_hda_intel i915 amdgpu chash gpu_sched
ttm vgem snd_hda_codec_hdmi coretemp crct10dif_pclmul btusb crc32_pclmul btrtl
btbcm btintel ghash_clmulni_intel cdc_ether usbnet bluetooth r8152 mii
ecdh_generic snd_hda_codec snd_hwdep snd_hda_core snd_pcm pinctrl_cherryview
lpc_ich prime_numbers [last unloaded: i915]
<4> [478.340707] CPU: 3 PID: 3838 Comm: i915_selftest Tainted: G     U  W      
  4.20.0-rc5-CI-CI_DRM_5259+ #1
<4> [478.340713] Hardware name: GOOGLE Cyan/Cyan, BIOS MrChromebox 02/15/2018
<4> [478.340725] RIP: 0010:snd_hdac_acomp_exit+0x53/0x70 [snd_hda_core]
<4> [478.340732] Code: d3 c6 5a e1 48 c7 83 50 05 00 00 00 00 00 00 48 89 ef 31
c9 31 d2 48 c7 c6 60 4a 08 a0 e8 b5 73 5b e1 31 c0 5b 5d c3 31 c0 c3 <0f> 0b 85
d2 7e c5 48 8b 50 08 48 85 d2 74 bc 48 8b 52 10 48 8b 38
<4> [478.340738] RSP: 0018:ffffc900005d3c70 EFLAGS: 00010286
<4> [478.340745] RAX: ffff8880744afcd0 RBX: ffff888076b91158 RCX:
a77cd00700000000
<4> [478.340751] RDX: 00000000ffffffff RSI: 000000007aabcc49 RDI:
ffff888076b91158
<4> [478.340756] RBP: ffff888078055748 R08: 00000000ccaf10e7 R09:
0000000000000001
<4> [478.340762] R10: 0000000000000000 R11: ffffffff82233340 R12:
ffff888075e52448
<4> [478.340768] R13: ffff888075e522a8 R14: 0000000000000060 R15:
ffff888075e1a7e8
<4> [478.340774] FS:  00007fd2e27f9980(0000) GS:ffff888079980000(0000)
knlGS:0000000000000000
<4> [478.340779] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
<4> [478.340785] CR2: 00007f33b20ecd29 CR3: 00000000620ee000 CR4:
00000000001006e0
<4> [478.340790] Call Trace:
<4> [478.340807]  azx_free+0x107/0x180 [snd_hda_intel]
<4> [478.340820]  __snd_device_free+0x4c/0x80
<4> [478.340828]  snd_device_free_all+0x5a/0x80
<4> [478.340841]  release_card_device+0x14/0x70
<4> [478.340851]  device_release+0x2b/0x80
<4> [478.340864]  kobject_put+0x85/0x1a0
<4> [478.340874]  snd_card_free+0x60/0x90
<4> [478.340897]  pci_device_remove+0x36/0xb0
<4> [478.340908]  device_release_driver_internal+0x185/0x240
<4> [478.340921]  unbind_store+0xaf/0x180
<4> [478.340934]  kernfs_fop_write+0x104/0x190
<4> [478.340949]  __vfs_write+0x31/0x180
<4> [478.340963]  ? rcu_read_lock_sched_held+0x6f/0x80
<4> [478.340969]  ? rcu_sync_lockdep_assert+0x29/0x50
<4> [478.340978]  ? __sb_start_write+0x152/0x1f0
<4> [478.340984]  ? __sb_start_write+0x163/0x1f0
<4> [478.340997]  vfs_write+0xbd/0x1b0
<4> [478.341009]  ksys_write+0x50/0xc0
<4> [478.341024]  do_syscall_64+0x55/0x190
<4> [478.341035]  entry_SYSCALL_64_after_hwframe+0x49/0xbe
<4> [478.341042] RIP: 0033:0x7fd2e20b6154
<4> [478.341049] Code: 89 02 48 c7 c0 ff ff ff ff c3 66 2e 0f 1f 84 00 00 00 00
00 66 90 48 8d 05 b1 07 2e 00 8b 00 85 c0 75 13 b8 01 00 00 00 0f 05 <48> 3d 00
f0 ff ff 77 54 f3 c3 66 90 41 54 55 49 89 d4 53 48 89 f5
<4> [478.341054] RSP: 002b:00007fffc158d408 EFLAGS: 00000246 ORIG_RAX:
0000000000000001
<4> [478.341062] RAX: ffffffffffffffda RBX: 0000000000000000 RCX:
00007fd2e20b6154
<4> [478.341067] RDX: 000000000000000c RSI: 000055fa56a0532b RDI:
0000000000000005
<4> [478.341073] RBP: 00007fffc158e4f0 R08: 0000000000000000 R09:
0000000000000000
<4> [478.341078] R10: 00007fd2e2112560 R11: 0000000000000246 R12:
000055fa5560b750
<4> [478.341083] R13: 00007fffc158e7d0 R14: 0000000000000000 R15:
0000000000000000
<4> [478.341111] irq event stamp: 89460
<4> [478.341119] hardirqs last  enabled at (89459): [<ffffffff81200088>]
__slab_free+0x3e8/0x510
<4> [478.341126] hardirqs last disabled at (89460): [<ffffffff810019b0>]
trace_hardirqs_off_thunk+0x1a/0x1c
<4> [478.341133] softirqs last  enabled at (89074): [<ffffffff81c0033a>]
__do_softirq+0x33a/0x4b9
<4> [478.341141] softirqs last disabled at (89067): [<ffffffff8108e6a9>]
irq_exit+0xa9/0xc0
<4> [478.341153] WARNING: CPU: 3 PID: 3838 at sound/hda/hdac_component.c:324
snd_hdac_acomp_exit+0x53/0x70 [snd_hda_core]
<4> [478.341159] ---[ end trace 086147dd2631bdce ]---

(In reply to Takashi Iwai from comment #8)
> (In reply to Martin Peres from comment #7)
> > Has a bug on bugzilla.kernel.org been written or a patch been made for this
> > issue?
> 
> Nothing I know of, so far.

ok, thanks! We'll do so :)

> 
> > It seems to happen every 1.5 months. Last time it was seen was 1.5 months
> > ago, so I guess we'll soon see if progress has been made!
> 
> Heh, 6 times per year isn't a bad bet :)

New statistics is indeed once every 2 months... It isn't too bad, but that
probably means we should write tests to better catch these sorts of issues.

If all goes well, we should soon reach the point where audio would become the
next obvious thing to improve testing for anyway, so let's see what we can do
:)

-- 
You are receiving this mail because:
You are on the CC list for the bug.
You are the QA Contact for the bug.
You are the assignee for the bug.
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://lists.freedesktop.org/archives/intel-gfx-bugs/attachments/20181205/beaa273b/attachment-0001.html>

More information about the intel-gfx-bugs mailing list