Bug 109546

Summary: [CI][DRMTIP] igt@kms_cursor_legacy@2x-flip-vs-cursor-legacy - Incomplete
Product: DRI Reporter: Lakshmi <lakshminarayana.vudum>
Component: DRM/IntelAssignee: Intel GFX Bugs mailing list <intel-gfx-bugs>
Status: RESOLVED DUPLICATE QA Contact: Intel GFX Bugs mailing list <intel-gfx-bugs>
Severity: normal    
Priority: highest CC: intel-gfx-bugs
Version: DRI git   
Hardware: Other   
OS: All   
Whiteboard: Triaged, ReadyForDev
i915 platform: BXT, ICL i915 features:

Description Lakshmi 2019-02-04 15:29:55 UTC
https://intel-gfx-ci.01.org/tree/drm-tip/drmtip_203/fi-icl-u2/igt@kms_cursor_legacy@2x-flip-vs-cursor-legacy.html

3> [68.196054] BUG: sleeping function called from invalid context at kernel/sched/completion.c:99
<3> [68.196224] in_atomic(): 0, irqs_disabled(): 0, pid: 32, name: kworker/1:1
<4> [68.196229] INFO: lockdep is turned off.
<3> [68.196232] Preemption disabled at:
<4> [68.196235] [<0000000000000000>]           (null)
<4> [68.196244] CPU: 1 PID: 32 Comm: kworker/1:1 Tainted: G      D           5.0.0-rc4-g864d26b6a3e3-drmtip_203+ #1
<4> [68.196249] Hardware name: Intel Corporation Ice Lake Client Platform/IceLake U DDR4 SODIMM PD RVP TLC, BIOS ICLSFWR1.R00.2402.AD3.1810170014 10/17/2018
<4> [68.196257] Workqueue: events drm_mode_rmfb_work_fn
<4> [68.196262] Call Trace:
<4> [68.196271]  dump_stack+0x67/0x9b
<4> [68.196280]  ___might_sleep+0x167/0x250
<4> [68.196288]  wait_for_common+0x40/0x1f0
<4> [68.196301]  virt_efi_query_variable_info+0x161/0x1b0
<4> [68.196310]  efi_query_variable_store+0xb3/0x1a0
<4> [68.196323]  ? efivar_entry_set_safe+0x19c/0x220
<4> [68.196328]  ? efi_delete_dummy_variable+0x90/0x90
<4> [68.196335]  efivar_entry_set_safe+0x19c/0x220
<4> [68.196347]  ? efi_pstore_write+0x10b/0x150
<4> [68.196353]  ? efi_pstore_write+0xa0/0x150
<4> [68.196359]  efi_pstore_write+0x10b/0x150
<4> [68.196378]  pstore_dump+0x127/0x340
<4> [68.196394]  kmsg_dump+0x87/0x1c0
<4> [68.196401]  oops_end+0x3e/0x90
<4> [68.196409]  general_protection+0x1e/0x30
<4> [68.196471] RIP: 0010:skl_check_pipe_max_pixel_rate+0x8b/0x2d0 [i915]
<4> [68.196479] Code: 00 0f b6 85 80 00 00 00 84 c0 74 36 48 83 7d 10 00 0f 84 e3 01 00 00 48 89 ee 4c 89 ef e8 ed 7c ff ff 48 8b 55 10 48 8b 52 48 <80> 7a 06 08 0f 84 a5 01 00 00 49 8b 95 f8 01 00 00 41 39 c7 44 0f
<4> [68.196486] RSP: 0018:ffff9c95001abb48 EFLAGS: 00010293
<4> [68.196495] RAX: 0000000000010000 RBX: ffff96ec90c50000 RCX: 0000000000010000
<4> [68.196501] RDX: 6b6b6b6b6b6b6b6b RSI: 0000000000000438 RDI: 0000000004380000
<4> [68.196506] RBP: ffff96eca8bb46a8 R08: 0000000000000780 R09: 0000000000000000
<4> [68.196513] R10: ffff96eca49e2548 R11: 0000000000000000 R12: ffff96ec92b60558
<4> [68.196519] R13: ffff96ec9a7c1bf8 R14: ffff96ec9a7c53d8 R15: 0000000000010000
<4> [68.196586]  ? skl_check_pipe_max_pixel_rate+0x83/0x2d0 [i915]
<4> [68.196667]  intel_crtc_atomic_check+0x317/0x4e0 [i915]
<4> [68.196680]  drm_atomic_helper_check_planes+0x14d/0x1f0
<4> [68.196757]  intel_atomic_check+0x643/0x11b0 [i915]
<4> [68.196770]  drm_atomic_check_only+0x551/0x7e0
<4> [68.196781]  drm_atomic_commit+0xe/0x50
<4> [68.196787]  atomic_remove_fb+0x295/0x2c0
<4> [68.196800]  drm_framebuffer_remove+0x67/0x140
<4> [68.196807]  drm_mode_rmfb_work_fn+0x4a/0x60
<4> [68.196814]  process_one_work+0x245/0x610
<4> [68.196824]  worker_thread+0x1d0/0x380
<4> [68.196831]  ? process_one_work+0x610/0x610
<4> [68.196836]  kthread+0x119/0x130
<4> [68.196842]  ? kthread_park+0x80/0x80
<4> [68.196850]  ret_from_fork+0x3a/0x50
<4> [68.212729] WARNING: CPU: 1 PID: 32 at kernel/rcu/tree_plugin.h:337 rcu_note_context_switch+0x86/0x610
<4> [68.212734] Modules linked in: snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic i915 x86_pkg_temp_thermal coretemp snd_hda_intel crct10dif_pclmul snd_hda_codec crc32_pclmul snd_hwdep ghash_clmulni_intel snd_hda_core btusb snd_pcm btrtl e1000e btbcm btintel cdc_ether i2c_i801 bluetooth usbnet mii ecdh_generic prime_numbers
<4> [68.212755] CPU: 1 PID: 32 Comm: kworker/1:1 Tainted: G      D W         5.0.0-rc4-g864d26b6a3e3-drmtip_203+ #1
<4> [68.212758] Hardware name: Intel Corporation Ice Lake Client Platform/IceLake U DDR4 SODIMM PD RVP TLC, BIOS ICLSFWR1.R00.2402.AD3.1810170014 10/17/2018
<4> [68.212764] Workqueue: events drm_mode_rmfb_work_fn
<4> [68.212770] RIP: 0010:rcu_note_context_switch+0x86/0x610
<4> [68.212773] Code: 25 c0 4e 01 00 8b b8 7c 08 00 00 85 ff 0f 84 c4 01 00 00 45 84 ed 8b 85 78 03 00 00 0f 85 bf 00 00 00 85 c0 0f 8e bf 00 00 00 <0f> 0b 80 bd 7c 03 00 00 00 0f 84 d3 01 00 00 48 89 ef e8 03 fd ff
<4> [68.212778] RSP: 0018:ffff9c95001ab5f8 EFLAGS: 00010002
<4> [68.212781] RAX: 0000000000000001 RBX: ffff96ecafeb2c80 RCX: 0000000000000000
<4> [68.212783] RDX: 0000000000000002 RSI: ffffffffa408767b RDI: ffffffffa40aa8d6
<4> [68.212786] RBP: ffff96ecad950040 R08: 0000000000000000 R09: 0000000000000000
<4> [68.212788] R10: ffff9c95001ab6d8 R11: ffffffffa6016238 R12: ffff96ecad950040
<4> [68.212790] R13: 0000000000000000 R14: 0000000000031f40 R15: 0000000000000000
<4> [68.212793] FS:  0000000000000000(0000) GS:ffff96ecafe80000(0000) knlGS:0000000000000000
<4> [68.212795] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
<4> [68.212797] CR2: 00007fec7a737900 CR3: 00000004a8ac8003 CR4: 0000000000760ee0
<4> [68.212800] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
<4> [68.212802] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
<4> [68.212804] PKRU: 55555554
<4> [68.212806] Call Trace:
<4> [68.212813]  __schedule+0xbc/0xb40
<4> [68.212818]  ? wait_for_common+0x116/0x1f0
<4> [68.212822]  schedule+0x2d/0x80
<4> [68.212827]  schedule_timeout+0x236/0x4f0
<4> [68.212832]  ? wait_for_common+0x116/0x1f0
<4> [68.212837]  ? preempt_schedule_common+0x23/0x80
<4> [68.212843]  ? ___preempt_schedule+0x16/0x18
<4> [68.212849]  ? wait_for_common+0x116/0x1f0
<4> [68.212854]  wait_for_common+0x13a/0x1f0
<4> [68.212860]  ? wake_up_q+0x70/0x70
<4> [68.212868]  virt_efi_set_variable+0x151/0x1a0
<4> [68.212873]  efivar_entry_set_safe+0x115/0x220
<4> [68.212879]  ? efi_pstore_write+0x10b/0x150
<4> [68.212883]  ? efi_pstore_write+0xa0/0x150
<4> [68.212887]  efi_pstore_write+0x10b/0x150
<4> [68.212898]  pstore_dump+0x127/0x340
<4> [68.212907]  kmsg_dump+0x87/0x1c0
<4> [68.212911]  oops_end+0x3e/0x90
<4> [68.212916]  general_protection+0x1e/0x30
<4> [68.212960] RIP: 0010:skl_check_pipe_max_pixel_rate+0x8b/0x2d0 [i915]
<4> [68.212965] Code: 00 0f b6 85 80 00 00 00 84 c0 74 36 48 83 7d 10 00 0f 84 e3 01 00 00 48 89 ee 4c 89 ef e8 ed 7c ff ff 48 8b 55 10 48 8b 52 48 <80> 7a 06 08 0f 84 a5 01 00 00 49 8b 95 f8 01 00 00 41 39 c7 44 0f
<4> [68.212968] RSP: 0018:ffff9c95001abb48 EFLAGS: 00010293
<4> [68.212974] RAX: 0000000000010000 RBX: ffff96ec90c50000 RCX: 0000000000010000
<4> [68.212978] RDX: 6b6b6b6b6b6b6b6b RSI: 0000000000000438 RDI: 0000000004380000
<4> [68.212981] RBP: ffff96eca8bb46a8 R08: 0000000000000780 R09: 0000000000000000
<4> [68.212986] R10: ffff96eca49e2548 R11: 0000000000000000 R12: ffff96ec92b60558
<4> [68.212990] R13: ffff96ec9a7c1bf8 R14: ffff96ec9a7c53d8 R15: 0000000000010000
<4> [68.213030]  ? skl_check_pipe_max_pixel_rate+0x83/0x2d0 [i915]
<4> [68.213071]  intel_crtc_atomic_check+0x317/0x4e0 [i915]
<4> [68.213080]  drm_atomic_helper_check_planes+0x14d/0x1f0
<4> [68.213120]  intel_atomic_check+0x643/0x11b0 [i915]
<4> [68.213128]  drm_atomic_check_only+0x551/0x7e0
<4> [68.213135]  drm_atomic_commit+0xe/0x50
<4> [68.213139]  atomic_remove_fb+0x295/0x2c0
<4> [68.213148]  drm_framebuffer_remove+0x67/0x140
<4> [68.213152]  drm_mode_rmfb_work_fn+0x4a/0x60
<4> [68.213157]  process_one_work+0x245/0x610
<4> [68.213164]  worker_thread+0x1d0/0x380
<4> [68.213168]  ? process_one_work+0x610/0x610
<4> [68.213172]  kthread+0x119/0x130
<4> [68.213176]  ? kthread_park+0x80/0x80
<4> [68.213181]  ret_from_fork+0x3a/0x50
<4> [68.213188] irq event stamp: 5273609
<4> [68.213193] hardirqs last  enabled at (5273609): [<ffffffffa301bbd2>] do_general_protection+0x182/0x1c0
<4> [68.213197] hardirqs last disabled at (5273608): [<ffffffffa30019b0>] trace_hardirqs_off_thunk+0x1a/0x1c
<4> [68.213200] softirqs last  enabled at (5273254): [<ffffffffa3c0033a>] __do_softirq+0x33a/0x4b9
<4> [68.213205] softirqs last disabled at (5273245): [<ffffffffa30b9a41>] irq_exit+0xd1/0xe0
<4> [68.213211] WARNING: CPU: 1 PID: 32 at kernel/rcu/tree_plugin.h:337 rcu_note_context_switch+0x86/0x610
<4> [68.213216] ---[ end trace e6a6f03b1ccd3f5a ]---
<4> [68.605215] RIP: 0010:skl_check_pipe_max_pixel_rate+0x8b/0x2d0 [i915]
<4> [68.605223] Code: 00 0f b6 85 80 00 00 00 84 c0 74 36 48 83 7d 10 00 0f 84 e3 01 00 00 48 89 ee 4c 89 ef e8 ed 7c ff ff 48 8b 55 10 48 8b 52 48 <80> 7a 06 08 0f 84 a5 01 00 00 49 8b 95 f8 01 00 00 41 39 c7 44 0f
<4> [68.605227] RSP: 0018:ffff9c95001abb48 EFLAGS: 00010293
<4> [68.605230] RAX: 0000000000010000 RBX: ffff96ec90c50000 RCX: 0000000000010000
<4> [68.605233] RDX: 6b6b6b6b6b6b6b6b RSI: 0000000000000438 RDI: 0000000004380000
<4> [68.605236] RBP: ffff96eca8bb46a8 R08: 0000000000000780 R09: 0000000000000000
<4> [68.605239] R10: ffff96eca49e2548 R11: 0000000000000000 R12: ffff96ec92b60558
<4> [68.605241] R13: ffff96ec9a7c1bf8 R14: ffff96ec9a7c53d8 R15: 0000000000010000
<4> [68.605244] FS:  0000000000000000(0000) GS:ffff96ecafe80000(0000) knlGS:0000000000000000
<4> [68.605247] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
<4> [68.605250] CR2: 00007fec7a737900 CR3: 0000000493b7a006 CR4: 0000000000760ee0
<4> [68.605252] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
<4> [68.605255] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
<4> [68.605257] PKRU: 55555554
<6> [68.605350] kworker/1:1 (32) used greatest stack depth: 12008 bytes left
<7> [70.910784] [drm:edp_panel_vdd_off_sync [i915]] Turning eDP port A VDD off
<7> [70.910985] [drm:edp_panel_vdd_off_sync [i915]] PP_STATUS: 0x80000008 PP_CONTROL: 0x00000067
Comment 2 Chris Wilson 2019-02-04 15:57:42 UTC
[68.196054] BUG: sleeping function called from invalid context at kernel/sched/completion.c:99

is a secondary error. These appears to be quite different bugs, a mixture of unrelated use-after-free.
Comment 3 CI Bug Log 2019-02-05 08:15:14 UTC
A CI Bug Log filter associated to this bug has been updated:

{- BXT ICL: igt@kms_cursor_legacy@*|igt@gem_mmap_gtt@hand - Incomplete/dmesg-fail BUG: sleeping function called from invalid context at kernel/sched/completion.c -}
{+ ICL: igt@kms_cursor_legacy@2x-flip-vs-cursor-legacy - Incomplete +}

 No new failures caught with the new filter
Comment 4 Lakshmi 2019-02-05 08:19:07 UTC
(In reply to Chris Wilson from comment #2)
> [68.196054] BUG: sleeping function called from invalid context at
> kernel/sched/completion.c:99
> 
> is a secondary error. These appears to be quite different bugs, a mixture of
> unrelated use-after-free.

Ok, thanks for your quick comments. Separate bug (Bug 109555 and Bug 109469) are created for the above failures. This bug is for 'incomplete' failure on ICL platform.
Comment 5 Martin Peres 2019-04-23 11:54:38 UTC
Raising to highest, since it looks like a use-after-free.
Comment 6 James Ausmus 2019-04-24 22:45:13 UTC
This is the exact same backtrace as 109084, only difference is 2x-flip-vs-legacy vs 2x-long-flip-vs-legacy. Marking duplicate of 109084

*** This bug has been marked as a duplicate of bug 109084 ***

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.