Bug 107054

Summary: [CI] Commit "ALSA: hda - Handle error from snd_hda_power_up*()" broke pm_rpm|kms|drv_module_reload|...
Product: DRI Reporter: Tomi Sarvela <tomi.p.sarvela>
Component: DRM/IntelAssignee: Ramesh Babu <ramesh.babu>
Status: CLOSED WORKSFORME QA Contact: Intel GFX Bugs mailing list <intel-gfx-bugs>
Severity: critical    
Priority: highest CC: intel-gfx-bugs
Version: XOrg git   
Hardware: x86-64 (AMD64)   
OS: Linux (All)   
Whiteboard:
i915 platform: i915 features:

Description Tomi Sarvela 2018-06-27 16:16:53 UTC 
https://intel-gfx-ci.01.org/tree/drm-tip/combined-issues-by-run.html

CI_DRM_4385-4387 were affected.

The commit has been reverted in DRM-Tip (next build CI_DRM_4388)
b5f67b0d7dba Revert "ALSA: hda - Handle error from snd_hda_power_up*()"

Examples of breakage:

igt@drv_module_reload@basic-reload-inject

[  503.531576] snd_hda_codec_hdmi hdaudioC0D2: HDMI: failed to get afg sub nodes
[  503.532951] snd_hda_codec_hdmi: probe of hdaudioC0D2 failed with error -22
[  503.540353] snd_hda_codec_hdmi hdaudioC0D2: HDMI: failed to get afg sub nodes
[  503.540457] snd_hda_codec_hdmi: probe of hdaudioC0D2 failed with error -22
[  503.542310] snd_hda_codec_hdmi hdaudioC0D2: HDMI: failed to get afg sub nodes
[  503.542414] snd_hda_codec_hdmi: probe of hdaudioC0D2 failed with error -22
[  503.553230] WARNING: CPU: 1 PID: 4542 at sound/hda/hdac_i915.c:429 snd_hdac_i915_exit+0x54/0x80 [snd_hda_core]
[  503.553233] Modules linked in: snd_hda_intel i915 vgem snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic x86_pkg_temp_thermal intel_powerclamp coretemp crct10dif_pclmul crc32_pclmul snd_hda_codec ghash_clmulni_intel snd_hwdep snd_hda_core snd_pcm e1000e mei_me mei prime_numbers [last unloaded: i915]
[  503.553272] CPU: 1 PID: 4542 Comm: drv_module_relo Tainted: G     U  W         4.18.0-rc2-CI-CI_DRM_4387+ #1
[  503.553273] Hardware name: Micro-Star International Co., Ltd. MS-7B54/Z370M MORTAR (MS-7B54), BIOS 1.00 10/31/2017
[  503.553278] RIP: 0010:snd_hdac_i915_exit+0x54/0x80 [snd_hda_core]
[  503.553279] Code: e8 31 22 56 e1 48 89 ef e8 79 62 13 e1 48 c7 83 48 05 00 00 00 00 00 00 48 c7 05 93 9b 00 00 00 00 00 00 5b 31 c0 5d 41 5c c3 <0f> 0b 85 c0 7e c6 48 8b 45 20 48 85 c0 74 bd 48 8b 40 10 48 8b 7d 
[  503.553373] RSP: 0018:ffffc90000543c68 EFLAGS: 00010202
[  503.553377] RAX: 0000000000000001 RBX: ffff88025c6222a8 RCX: 9885d93300000000
[  503.553379] RDX: 0000000000000000 RSI: 00000000eb748578 RDI: ffff88025c6222a8
[  503.553381] RBP: ffff8801b0684fa8 R08: 00000000b74f0d13 R09: 0000000000000001
[  503.553383] R10: 0000000000000000 R11: ffffffff82232400 R12: ffff8802643fb498
[  503.553385] R13: ffff88025c6233f8 R14: 0000000000000060 R15: ffff880261ed1758
[  503.553387] FS:  00007ffbc352b980(0000) GS:ffff880266240000(0000) knlGS:0000000000000000
[  503.553389] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[  503.553391] CR2: 000055e97a46f038 CR3: 0000000254e66002 CR4: 00000000003606e0
[  503.553393] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[  503.553395] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
[  503.553396] Call Trace:
[  503.553403]  azx_free+0x100/0x170 [snd_hda_intel]
[  503.553407]  __snd_device_free+0x4c/0x80
[  503.553410]  snd_device_free_all+0x5a/0x80
[  503.553416]  release_card_device+0x14/0x70
[  503.553420]  device_release+0x2b/0x80
[  503.553424]  kobject_put+0x85/0x1a0
[  503.553429]  snd_card_free+0x60/0x90
[  503.553436]  pci_device_remove+0x36/0xb0
[  503.553440]  device_release_driver_internal+0x185/0x250
[  503.553445]  unbind_store+0xaf/0x180
[  503.553450]  kernfs_fop_write+0x104/0x190
[  503.553456]  __vfs_write+0x31/0x180
[  503.553461]  ? rcu_read_lock_sched_held+0x6f/0x80
[  503.553463]  ? rcu_sync_lockdep_assert+0x29/0x50
[  503.553466]  ? __sb_start_write+0x152/0x1f0
[  503.553469]  ? __sb_start_write+0x168/0x1f0
[  503.553474]  vfs_write+0xbd/0x1a0
[  503.553478]  ksys_write+0x50/0xc0
[  503.553483]  do_syscall_64+0x55/0x190
[  503.553487]  entry_SYSCALL_64_after_hwframe+0x49/0xbe
[  503.553490] RIP: 0033:0x7ffbc2ec1281
[  503.553491] Code: c3 0f 1f 84 00 00 00 00 00 48 8b 05 59 8d 20 00 c3 0f 1f 84 00 00 00 00 00 8b 05 8a d1 20 00 85 c0 75 16 b8 01 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 57 f3 c3 0f 1f 44 00 00 41 54 55 49 89 d4 53 
[  503.553557] RSP: 002b:00007ffe1ee6b018 EFLAGS: 00000246 ORIG_RAX: 0000000000000001
[  503.553559] RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 00007ffbc2ec1281
[  503.553561] RDX: 000000000000000c RSI: 000055e97a464a4b RDI: 0000000000000003
[  503.553562] RBP: 00007ffe1ee6c100 R08: 0000000000000000 R09: 0000000000000000
[  503.553563] R10: 00007ffbc2c453a0 R11: 0000000000000246 R12: 000055e97863b2f0
[  503.553564] R13: 00007ffe1ee6c2c0 R14: 0000000000000000 R15: 0000000000000000
[  503.553571] irq event stamp: 106636
[  503.553573] hardirqs last  enabled at (106635): [<ffffffff811fa85d>] __slab_free+0x3ed/0x4f0
[  503.553575] hardirqs last disabled at (106636): [<ffffffff81a0111c>] error_entry+0x7c/0x100
[  503.553577] softirqs last  enabled at (106568): [<ffffffff81c0034f>] __do_softirq+0x34f/0x505
[  503.553580] softirqs last disabled at (106551): [<ffffffff8108c959>] irq_exit+0xa9/0xc0
[  503.553583] WARNING: CPU: 1 PID: 4542 at sound/hda/hdac_i915.c:429 snd_hdac_i915_exit+0x54/0x80 [snd_hda_core]
[  503.553585] ---[ end trace 3aa98dcb8a499bb3 ]---
[  503.793586] Setting dangerous option inject_load_failure - tainting kernel
[  504.268425] snd_hda_codec_hdmi hdaudioC0D2: HDMI: failed to get afg sub nodes
[  504.268608] snd_hda_codec_hdmi: probe of hdaudioC0D2 failed with error -22
[  504.268629] hdaudio hdaudioC0D2: Unable to bind the codec
[  504.568365] Setting dangerous option reset - tainting kernel

igt@pm_rpm@basic-rte

(pm_rpm:4122) CRITICAL: Test assertion failure function basic_subtest, file ../tests/pm_rpm.c:733:
(pm_rpm:4122) CRITICAL: Failed assertion: wait_for_suspended()
Subtest basic-rte failed.
**** DEBUG ****
(pm_rpm:4122) CRITICAL: Test assertion failure function basic_subtest, file ../tests/pm_rpm.c:733:
(pm_rpm:4122) CRITICAL: Failed assertion: wait_for_suspended()
(pm_rpm:4122) igt_core-INFO: Stack trace:
(pm_rpm:4122) igt_core-INFO:   #0 [__igt_fail_assert+0x180]
(pm_rpm:4122) igt_core-INFO:   #1 [basic_subtest+0x4b]
(pm_rpm:4122) igt_core-INFO:   #2 [main+0xcf]
(pm_rpm:4122) igt_core-INFO:   #3 [__libc_start_main+0xe7]
(pm_rpm:4122) igt_core-INFO:   #4 [_start+0x2a]
****  END  ****

igt@kms_busy@basic-flip-c

[  414.145394] Setting dangerous option reset - tainting kernel
[  414.185633] ------------[ cut here ]------------
[  414.185642] WARN_ON((dev_priv->uncore.funcs.mmio_readl(dev_priv, (((const i915_reg_t){ .reg = ((((const u32 []){ (dev_priv->info.display_mmio_offset + 0x6014), (dev_priv->info.display_mmio_offset + 0x6018), (dev_priv->info.display_mmio_offset + 0x6030) })[(PIPE_B)])) })), true) & (1 << 28)) == 0)
[  414.185824] WARNING: CPU: 1 PID: 3431 at drivers/gpu/drm/i915/intel_display.c:1487 chv_enable_pll+0x2bd/0x2e0 [i915]
[  414.185829] Modules linked in: vgem btusb btrtl btbcm btintel bluetooth ecdh_generic intel_powerclamp coretemp crct10dif_pclmul crc32_pclmul ghash_clmulni_intel snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic r8169 lpc_ich mii i915 snd_hda_intel snd_hda_codec snd_hwdep snd_hda_core snd_pcm pinctrl_cherryview prime_numbers
[  414.185942] CPU: 1 PID: 3431 Comm: kms_busy Tainted: G     U  W         4.18.0-rc2-CI-CI_DRM_4387+ #1
[  414.185947] Hardware name:  /NUC5CPYB, BIOS PYBSWCEL.86A.0058.2016.1102.1842 11/02/2016
[  414.186045] RIP: 0010:chv_enable_pll+0x2bd/0x2e0 [i915]
[  414.186050] Code: fd ff ff 44 89 ee 48 c7 c7 9f 46 20 a0 e8 4b 73 4c e1 e9 aa fd ff ff 48 c7 c6 98 86 22 a0 48 c7 c7 da 3c 20 a0 e8 73 37 f3 e0 <0f> 0b e9 3d fe ff ff 41 bf 38 81 00 00 e9 e1 fe ff ff e8 3c 3a f3 
[  414.186301] RSP: 0018:ffffc90000a7fa58 EFLAGS: 00010286
[  414.186310] RAX: 0000000000000000 RBX: ffff880167040000 RCX: 0000000000000001
[  414.186315] RDX: 0000000080000001 RSI: ffffffff820c8c5c RDI: 00000000ffffffff
[  414.186319] RBP: 0000000000000002 R08: 00000000c1db806d R09: 0000000000000000
[  414.186324] R10: 0000000000000000 R11: 0000000000000000 R12: ffff88017a8292a8
[  414.186329] R13: 0000000000000002 R14: ffff880167040000 R15: 0000000000000002
[  414.186334] FS:  00007f0acc470980(0000) GS:ffff88017fd00000(0000) knlGS:0000000000000000
[  414.186339] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[  414.186344] CR2: 00007f0acbbe78a0 CR3: 00000001745f0000 CR4: 00000000001006e0
[  414.186348] Call Trace:
[  414.186470]  valleyview_crtc_enable+0x1b9/0x1f0 [i915]
[  414.186580]  intel_update_crtc+0x45/0x80 [i915]
[  414.186685]  intel_update_crtcs+0x42/0x60 [i915]
[  414.186786]  intel_atomic_commit_tail+0x1c9/0xd00 [i915]
[  414.186912]  intel_atomic_commit+0x240/0x320 [i915]
[  414.186933]  restore_fbdev_mode_atomic+0x1c7/0x1e0
[  414.186975]  drm_fb_helper_restore_fbdev_mode_unlocked+0x42/0x90
[  414.186986]  drm_fb_helper_set_par+0x24/0x50
[  414.187085]  intel_fbdev_set_par+0x11/0x40 [i915]
[  414.187098]  fbcon_init+0x4db/0x680
[  414.187121]  visual_init+0xd0/0x130
[  414.187132]  do_bind_con_driver+0x1ef/0x3f0
[  414.187157]  store_bind+0x133/0x1a0
[  414.187174]  kernfs_fop_write+0x104/0x190
[  414.187191]  __vfs_write+0x31/0x180
[  414.187206]  ? rcu_read_lock_sched_held+0x6f/0x80
[  414.187212]  ? rcu_sync_lockdep_assert+0x29/0x50
[  414.187219]  ? __sb_start_write+0x152/0x1f0
[  414.187225]  ? __sb_start_write+0x168/0x1f0
[  414.187240]  vfs_write+0xbd/0x1a0
[  414.187253]  ksys_write+0x50/0xc0
[  414.187271]  do_syscall_64+0x55/0x190
[  414.187282]  entry_SYSCALL_64_after_hwframe+0x49/0xbe
[  414.187288] RIP: 0033:0x7f0acbbfc281
[  414.187292] Code: c3 0f 1f 84 00 00 00 00 00 48 8b 05 59 8d 20 00 c3 0f 1f 84 00 00 00 00 00 8b 05 8a d1 20 00 85 c0 75 16 b8 01 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 57 f3 c3 0f 1f 44 00 00 41 54 55 49 89 d4 53 
[  414.187541] RSP: 002b:00007ffc6e1827f8 EFLAGS: 00000246 ORIG_RAX: 0000000000000001
[  414.187550] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f0acbbfc281
[  414.187555] RDX: 0000000000000002 RSI: 00007f0acc073a81 RDI: 0000000000000005
[  414.187560] RBP: 00007ffc6e183840 R08: 000055f95fb6bce3 R09: 0000000000000006
[  414.187564] R10: 0000000000000000 R11: 0000000000000246 R12: 00007f0acbbe5718
[  414.187569] R13: 0000000000000003 R14: 00007f0acbbea628 R15: 00007f0acbbe6d80
[  414.187606] irq event stamp: 211274
[  414.187613] hardirqs last  enabled at (211273): [<ffffffff810f9f9c>] vprintk_emit+0x4bc/0x4d0
[  414.187620] hardirqs last disabled at (211274): [<ffffffff81a0111c>] error_entry+0x7c/0x100
[  414.187626] softirqs last  enabled at (211244): [<ffffffff81c0034f>] __do_softirq+0x34f/0x505
[  414.187633] softirqs last disabled at (211223): [<ffffffff8108c959>] irq_exit+0xa9/0xc0
[  414.187732] WARNING: CPU: 1 PID: 3431 at drivers/gpu/drm/i915/intel_display.c:1487 chv_enable_pll+0x2bd/0x2e0 [i915]
[  414.187737] ---[ end trace fa0e1aad48d8e509 ]---
Comment 1 Chris Wilson 2018-06-27 18:29:38 UTC 
Problematic series was dropped from sound/for-next (upstream), CI_DRM_4389.
Comment 2 Jani Saarinen 2018-06-29 06:33:58 UTC 
closing now.

Use of freedesktop.org services, including Bugzilla, is subject to our Code of Conduct. How we collect and use information is described in our Privacy Policy.