Bug 108522

Summary: [CI][BAT] igt@gem_exec_reloc@basic-* / igt@kms_cursor_crc@cursor-*-offscreen - dmesg-warn - pipe_off wait timed out
Product: DRI Reporter: Martin Peres <martin.peres>
Component: DRM/IntelAssignee: Intel GFX Bugs mailing list <intel-gfx-bugs>
Status: CLOSED FIXED QA Contact: Intel GFX Bugs mailing list <intel-gfx-bugs>
Severity: normal    
Priority: highest CC: intel-gfx-bugs
Version: XOrg git   
Hardware: Other   
OS: All   
Whiteboard: ReadyForDev
i915 platform: ICL i915 features: display/Other

Description Martin Peres 2018-10-23 07:01:32 UTC
https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_5019/fi-icl-u/igt@gem_exec_reloc@basic-write-cpu-active.html

https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_5019/fi-icl-u/igt@gem_exec_reloc@basic-gtt-read-active.html

<7> [254.488815] [drm:intel_disable_pipe [i915]] disabling pipe A
<4> [254.590344] ------------[ cut here ]------------
<4> [254.590356] pipe_off wait timed out
<4> [254.590556] WARNING: CPU: 1 PID: 2304 at drivers/gpu/drm/i915/intel_display.c:1049 intel_disable_pipe+0x131/0x170 [i915]
<4> [254.590564] Modules linked in: vgem snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic i915 x86_pkg_temp_thermal coretemp crct10dif_pclmul crc32_pclmul ax88179_178a usbnet ghash_clmulni_intel mii snd_hda_intel snd_hda_codec e1000e snd_hwdep snd_hda_core snd_pcm prime_numbers
<4> [254.590700] CPU: 1 PID: 2304 Comm: gem_exec_reloc Tainted: G     U  W         4.19.0-rc8-CI-CI_DRM_5019+ #1
<4> [254.590708] Hardware name: Intel Corporation Ice Lake Client Platform/IceLake U DDR4 SODIMM PD RVP, BIOS ICLSFWR1.R00.2313.A01.1808012121 08/01/2018
<4> [254.590839] RIP: 0010:intel_disable_pipe+0x131/0x170 [i915]
<4> [254.590850] Code: 6a 00 8d b4 0a 08 00 07 00 31 c9 ba 00 00 00 40 e8 74 29 fe ff 85 c0 5a 0f 84 55 ff ff ff 48 c7 c7 96 98 2e a0 e8 9f 79 e5 e0 <0f> 0b e9 42 ff ff ff 85 d2 0f 84 6c ff ff ff 48 83 c4 08 89 ee 48
<4> [254.590858] RSP: 0018:ffffc90002277c28 EFLAGS: 00010286
<4> [254.590871] RAX: 0000000000000000 RBX: ffff880494700000 RCX: 0000000000000001
<4> [254.590878] RDX: 0000000080000001 RSI: ffffffff820c309e RDI: 00000000ffffffff
<4> [254.590886] RBP: 000000000007f008 R08: 000000006137890a R09: 0000000000000000
<4> [254.590892] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000003
<4> [254.590899] R13: ffff8804a50fd3d8 R14: ffff880494700000 R15: ffff8804a934ae98
<4> [254.590908] FS:  00007fc41f6bb980(0000) GS:ffff8804b0640000(0000) knlGS:0000000000000000
<4> [254.590915] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
<4> [254.590923] CR2: 00007fc41ed91470 CR3: 0000000471a56002 CR4: 0000000000760ee0
<4> [254.590930] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
<4> [254.590937] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
<4> [254.590943] PKRU: 55555554
<4> [254.590949] Call Trace:
<4> [254.591079]  haswell_crtc_disable+0xd9/0x140 [i915]
<4> [254.591209]  intel_atomic_commit_tail+0x7c7/0xd30 [i915]
<4> [254.591348]  intel_atomic_commit+0x244/0x330 [i915]
<4> [254.591373]  restore_fbdev_mode_atomic+0x1da/0x1f0
<4> [254.591416]  drm_fb_helper_restore_fbdev_mode_unlocked+0x42/0x90
<4> [254.591542]  intel_fbdev_restore_mode+0x2b/0x70 [i915]
<4> [254.591556]  drm_lastclose+0x2b/0xe0
<4> [254.591569]  drm_release+0xeb/0x120
<4> [254.591591]  __fput+0xb9/0x1f0
<4> [254.591613]  task_work_run+0x82/0xb0
<4> [254.591633]  exit_to_usermode_loop+0x93/0xa0
<4> [254.591646]  do_syscall_64+0x14c/0x190
<4> [254.591662]  entry_SYSCALL_64_after_hwframe+0x49/0xbe
<4> [254.591673] RIP: 0033:0x7fc41f04d447
<4> [254.591683] Code: 00 00 0f 05 48 3d 00 f0 ff ff 77 3f f3 c3 0f 1f 44 00 00 53 89 fb 48 83 ec 10 e8 c4 fb ff ff 89 df 89 c2 b8 03 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 2b 89 d7 89 44 24 0c e8 06 fc ff ff 8b 44 24
<4> [254.591690] RSP: 002b:00007fffcd7d0860 EFLAGS: 00000293 ORIG_RAX: 0000000000000003
<4> [254.591704] RAX: 0000000000000000 RBX: 0000000000000006 RCX: 00007fc41f04d447
<4> [254.591711] RDX: 0000000000000000 RSI: 00007fffcd7d0680 RDI: 0000000000000006
<4> [254.591718] RBP: 00007fffcd7d0890 R08: 0000000000000000 R09: 0000000000000000
<4> [254.591725] R10: 0000000000000000 R11: 0000000000000293 R12: 00007fc41f036718
<4> [254.591733] R13: 0000000000000003 R14: 00007fc41f03b628 R15: 00007fc41f037d80
<4> [254.591769] irq event stamp: 58188
<4> [254.591782] hardirqs last  enabled at (58187): [<ffffffff810f827a>] console_unlock+0x3fa/0x5f0
<4> [254.591791] hardirqs last disabled at (58188): [<ffffffff81001930>] trace_hardirqs_off_thunk+0x1a/0x1c
<4> [254.591803] softirqs last  enabled at (58134): [<ffffffff81c0031d>] __do_softirq+0x31d/0x483
<4> [254.591815] softirqs last disabled at (58127): [<ffffffff8108c539>] irq_exit+0xa9/0xc0
<4> [254.591937] WARNING: CPU: 1 PID: 2304 at drivers/gpu/drm/i915/intel_display.c:1049 intel_disable_pipe+0x131/0x170 [i915]
<4> [254.591944] ---[ end trace f9c022cd1e7394ef ]---
Comment 1 Martin Peres 2018-10-24 11:47:53 UTC
Seen on igt@kms_cursor_crc tests now: https://intel-gfx-ci.01.org/tree/drm-tip/drmtip_132/fi-icl-u/igt@kms_cursor_crc@cursor-64x21-offscreen.html

<4> [57.121486] ------------[ cut here ]------------
<4> [57.121496] pipe_off wait timed out
<4> [57.121694] WARNING: CPU: 7 PID: 1046 at drivers/gpu/drm/i915/intel_display.c:1049 intel_disable_pipe+0x131/0x170 [i915]
<4> [57.121702] Modules linked in: snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic i915 ax88179_178a x86_pkg_temp_thermal usbnet mii snd_hda_intel coretemp snd_hda_codec crct10dif_pclmul crc32_pclmul ghash_clmulni_intel snd_hwdep snd_hda_core e1000e snd_pcm prime_numbers
<4> [57.121825] CPU: 7 PID: 1046 Comm: kms_cursor_crc Tainted: G     U  W         4.19.0-rc8-gae98bc614f3a-drmtip_132+ #1
<4> [57.121832] Hardware name: Intel Corporation Ice Lake Client Platform/IceLake U DDR4 SODIMM PD RVP, BIOS ICLSFWR1.R00.2313.A01.1808012121 08/01/2018
<4> [57.121960] RIP: 0010:intel_disable_pipe+0x131/0x170 [i915]
<4> [57.121970] Code: 6a 00 8d b4 0a 08 00 07 00 31 c9 ba 00 00 00 40 e8 84 29 fe ff 85 c0 5a 0f 84 55 ff ff ff 48 c7 c7 96 78 56 c0 e8 ef d7 bd d7 <0f> 0b e9 42 ff ff ff 85 d2 0f 84 6c ff ff ff 48 83 c4 08 89 ee 48
<4> [57.121977] RSP: 0018:ffff8c8dc04ffa80 EFLAGS: 00010282
<4> [57.121990] RAX: 0000000000000000 RBX: ffff886f51090000 RCX: 0000000000000001
<4> [57.121997] RDX: 0000000080000001 RSI: ffffffff99080d70 RDI: 00000000ffffffff
<4> [57.122004] RBP: 000000000007f008 R08: 0000000042664592 R09: 0000000000000000
<4> [57.122010] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000003
<4> [57.122016] R13: ffff886f56ab2548 R14: ffff886f51090000 R15: ffff886f56ab0958
<4> [57.122024] FS:  00007fadd7769980(0000) GS:ffff886f707c0000(0000) knlGS:0000000000000000
<4> [57.122031] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
<4> [57.122038] CR2: 0000563402c009c0 CR3: 00000004ab5aa002 CR4: 0000000000760ee0
<4> [57.122045] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
<4> [57.122051] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
<4> [57.122057] PKRU: 55555554
<4> [57.122063] Call Trace:
<4> [57.122189]  haswell_crtc_disable+0xd9/0x140 [i915]
<4> [57.122317]  intel_atomic_commit_tail+0x7c7/0xd30 [i915]
<4> [57.122449]  intel_atomic_commit+0x244/0x330 [i915]
<4> [57.122471]  drm_atomic_helper_set_config+0x7b/0x90
<4> [57.122488]  drm_mode_setcrtc+0x195/0x6b0
<4> [57.122545]  ? drm_mode_getcrtc+0x180/0x180
<4> [57.122556]  drm_ioctl_kernel+0x81/0xf0
<4> [57.122575]  drm_ioctl+0x2e6/0x3a0
<4> [57.122590]  ? drm_mode_getcrtc+0x180/0x180
<4> [57.122634]  do_vfs_ioctl+0xa0/0x6d0
<4> [57.122652]  ? __task_pid_nr_ns+0xb9/0x1f0
<4> [57.122673]  ksys_ioctl+0x35/0x60
<4> [57.122691]  __x64_sys_ioctl+0x11/0x20
<4> [57.122702]  do_syscall_64+0x55/0x190
<4> [57.122717]  entry_SYSCALL_64_after_hwframe+0x49/0xbe
<4> [57.122727] RIP: 0033:0x7fadd68e15d7
<4> [57.122737] Code: b3 66 90 48 8b 05 b1 48 2d 00 64 c7 00 26 00 00 00 48 c7 c0 ff ff ff ff c3 66 2e 0f 1f 84 00 00 00 00 00 b8 10 00 00 00 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d 81 48 2d 00 f7 d8 64 89 01 48
<4> [57.122744] RSP: 002b:00007ffef74012f8 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
<4> [57.122758] RAX: ffffffffffffffda RBX: 00007ffef7401330 RCX: 00007fadd68e15d7
<4> [57.122765] RDX: 00007ffef7401330 RSI: 00000000c06864a2 RDI: 0000000000000005
<4> [57.122771] RBP: 00007ffef7401330 R08: 0000000000000000 R09: 0000000000000000
<4> [57.122778] R10: 0000000000000000 R11: 0000000000000246 R12: 00000000c06864a2
<4> [57.122785] R13: 0000000000000005 R14: 0000000000000000 R15: 0000000000000000
<4> [57.122819] irq event stamp: 214356
<4> [57.122831] hardirqs last  enabled at (214355): [<ffffffff980fc05a>] console_unlock+0x3fa/0x5f0
<4> [57.122841] hardirqs last disabled at (214356): [<ffffffff98001930>] trace_hardirqs_off_thunk+0x1a/0x1c
<4> [57.122851] softirqs last  enabled at (214334): [<ffffffff98c0031d>] __do_softirq+0x31d/0x483
<4> [57.122861] softirqs last disabled at (214327): [<ffffffff98090279>] irq_exit+0xa9/0xc0
<4> [57.122973] WARNING: CPU: 7 PID: 1046 at drivers/gpu/drm/i915/intel_display.c:1049 intel_disable_pipe+0x131/0x170 [i915]
<4> [57.122980] ---[ end trace 3fafb1d1d4289d5c ]---
Comment 2 Jani Saarinen 2018-10-26 10:36:22 UTC
Need to wait BIOS 2392.
Comment 3 Jani Saarinen 2018-10-26 11:08:05 UTC
System has now
<6>[    0.000000] DMI: Intel Corporation Ice Lake Client Platform/IceLake U DDR4 SODIMM PD RVP, BIOS ICLSFWR1.R00.2392.A04.1809260455 09/26/2018

So might have been fixed.
Comment 4 Martin Peres 2018-11-13 15:53:39 UTC
(In reply to Jani Saarinen from comment #3)
> System has now
> <6>[    0.000000] DMI: Intel Corporation Ice Lake Client Platform/IceLake U
> DDR4 SODIMM PD RVP, BIOS ICLSFWR1.R00.2392.A04.1809260455 09/26/2018
> 
> So might have been fixed.

Seen 3 times in 16 runs, then nothing for 280 runs. I guess this was it!

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.