Bug 102996 - [BAT] igt@kms_pipe_crc_basic@nonblocking-crc-pipe-a - fail:drmWaitVBlank(drm_fd, &wait_vbl) == 0 or dmesg-warn: vblank wait timed out on crtc 1
Summary: [BAT] igt@kms_pipe_crc_basic@nonblocking-crc-pipe-a - fail:drmWaitVBlank(drm_...
Status: CLOSED WORKSFORME
Alias: None
Product: DRI
Classification: Unclassified
Component: DRM/Intel (show other bugs)
Version: DRI git
Hardware: Other All
: medium normal
Assignee: Intel GFX Bugs mailing list
QA Contact: Intel GFX Bugs mailing list
URL:
Whiteboard: ReadyForDev
Keywords:
Depends on:
Blocks:
 
Reported: 2017-09-26 10:05 UTC by Marta Löfstedt
Modified: 2018-06-19 14:25 UTC (History)
1 user (show)

See Also:
i915 platform: GM45
i915 features: display/Other


Attachments

Description Marta Löfstedt 2017-09-26 10:05:42 UTC
CI_DRM_3135 fi-ctg-p8600 fail:	

(kms_pipe_crc_basic:3716) igt-kms-CRITICAL: Test assertion failure function igt_wait_for_vblank_count, file igt_kms.c:3153:
(kms_pipe_crc_basic:3716) igt-kms-CRITICAL: Failed assertion: drmWaitVBlank(drm_fd, &wait_vbl) == 0
(kms_pipe_crc_basic:3716) igt-kms-CRITICAL: Last errno: 16, Device or resource busy
Subtest nonblocking-crc-pipe-A failed.

from dmesg:
[  402.437945] [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:54:VGA-1] status updated from connected to disconnected
[  402.437961] [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:54:VGA-1] disconnected
[  412.640055] [drm:drm_atomic_helper_wait_for_flip_done] *ERROR* [CRTC:36:pipe A] flip_done timed out
[  422.880136] [drm:drm_atomic_helper_wait_for_dependencies] *ERROR* [CRTC:36:pipe A] flip_done timed out
[  433.120137] [drm:drm_atomic_helper_wait_for_dependencies] *ERROR* [PLANE:27:primary A] flip_done timed out
[  443.360135] [drm:drm_atomic_helper_wait_for_flip_done] *ERROR* [CRTC:36:pipe A] flip_done timed out
[  454.112108] [drm:drm_atomic_helper_wait_for_dependencies] *ERROR* [CRTC:36:pipe A] flip_done timed out
[  464.352112] [drm:drm_atomic_helper_wait_for_dependencies] *ERROR* [PLANE:27:primary A] flip_done timed out
[  474.592111] [drm:drm_atomic_helper_wait_for_flip_done] *ERROR* [CRTC:36:pipe A] flip_done timed out

https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3135/fi-ctg-p8600/igt@kms_pipe_crc_basic@nonblocking-crc-pipe-a.html
Comment 1 Marta Löfstedt 2017-09-26 10:12:07 UTC
Incomplete in BUG 102997 for the test coming after, is probably related to this issue.
Comment 2 Marta Löfstedt 2017-10-05 07:15:25 UTC
Here is a similar pattern:
dmesg-warn on: https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3174/fi-ctg-p8600/igt@kms_pipe_crc_basic@nonblocking-crc-pipe-b.html
and:
https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3174/fi-ctg-p8600/igt@kms_pipe_crc_basic@nonblocking-crc-pipe-b-frame-sequence.html

then incomplete at:
https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3174/fi-ctg-p8600/igt@kms_pipe_crc_basic@read-crc-pipe-b.html

This time we also this a WARNING after 
[  431.072209] [drm:drm_atomic_helper_wait_for_flip_done] *ERROR* [CRTC:46:pipe B] flip_done timed out
[  444.384178] [drm:drm_atomic_helper_wait_for_dependencies] *ERROR* [CRTC:46:pipe B] flip_done timed out
[  444.488160] vblank wait timed out on crtc 1
[  444.488190] ------------[ cut here ]------------
[  444.488199] WARNING: CPU: 0 PID: 3608 at drivers/gpu/drm/drm_vblank.c:1066 drm_wait_one_vblank+0x196/0x1a0
[  444.488203] Modules linked in: vgem i915 coretemp lpc_ich snd_hda_codec_generic snd_hda_intel snd_hda_codec snd_hwdep snd_hda_core snd_pcm e1000e mei_me ptp mei pps_core prime_numbers
[  444.488280] CPU: 0 PID: 3608 Comm: kms_pipe_crc_ba Tainted: G     U  W       4.14.0-rc3-CI-CI_DRM_3174+ #1
[  444.488283] Hardware name: LENOVO 74591P0/74591P0, BIOS 6DET28WW (1.05 ) 07/30/2008
[  444.488286] task: ffff88012844a8c0 task.stack: ffffc90000fc4000
[  444.488290] RIP: 0010:drm_wait_one_vblank+0x196/0x1a0
[  444.488292] RSP: 0018:ffffc90000fc7948 EFLAGS: 00010296
[  444.488298] RAX: 000000000000001f RBX: ffff8801286d0000 RCX: 0000000000000006
[  444.488300] RDX: 0000000000001617 RSI: ffffffff81d0e20c RDI: ffffffff81cc2046
[  444.488303] RBP: ffffc90000fc79a0 R08: 0000000000000000 R09: 0000000000000001
[  444.488306] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000001
[  444.488308] R13: 0000000000000238 R14: 0000000000000000 R15: 000000000000098f
[  444.488311] FS:  00007fd7d00e1a40(0000) GS:ffff88013bc00000(0000) knlGS:0000000000000000
[  444.488314] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[  444.488317] CR2: 00007fd1f557b780 CR3: 000000012fc1c000 CR4: 00000000000406f0
[  444.488320] Call Trace:
[  444.488327]  ? wait_woken+0x90/0x90
[  444.488366]  intel_pre_plane_update+0xef/0x140 [i915]
[  444.488404]  intel_atomic_commit_tail+0xe3/0xbf0 [i915]
[  444.488446]  intel_atomic_commit+0x21f/0x2b0 [i915]
[  444.488454]  drm_atomic_commit+0x4b/0x50
[  444.488459]  restore_fbdev_mode_atomic+0x193/0x1f0
[  444.488472]  restore_fbdev_mode+0x32/0x120
[  444.488477]  drm_fb_helper_restore_fbdev_mode_unlocked.part.19+0x2a/0x80
[  444.488482]  drm_fb_helper_set_par+0x43/0x70
[  444.488517]  intel_fbdev_set_par+0x1a/0x70 [i915]
[  444.488523]  fbcon_init+0x59b/0x620
[  444.488531]  visual_init+0xd6/0x130
[  444.488536]  do_bind_con_driver+0x1cf/0x3c0
[  444.488545]  do_unbind_con_driver+0x1b0/0x210
[  444.488554]  store_bind+0xbb/0x1b0
[  444.488561]  dev_attr_store+0x18/0x30
[  444.488566]  sysfs_kf_write+0x45/0x60
[  444.488571]  kernfs_fop_write+0x124/0x1c0
[  444.488577]  __vfs_write+0x28/0x130
[  444.488581]  ? rcu_read_lock_sched_held+0x7a/0x90
[  444.488585]  ? rcu_sync_lockdep_assert+0x2f/0x60
[  444.488589]  ? __sb_start_write+0x108/0x200
[  444.488596]  vfs_write+0xcb/0x1c0
[  444.488602]  SyS_write+0x49/0xb0
[  444.488609]  entry_SYSCALL_64_fastpath+0x1c/0xb1
[  444.488612] RIP: 0033:0x7fd7ce5b9670
[  444.488615] RSP: 002b:00007ffe8bb9e7a8 EFLAGS: 00000246 ORIG_RAX: 0000000000000001
[  444.488620] RAX: ffffffffffffffda RBX: ffffffff81493a03 RCX: 00007fd7ce5b9670
[  444.488623] RDX: 0000000000000002 RSI: 000055764b1809c8 RDI: 0000000000000009
[  444.488626] RBP: ffffc90000fc7f88 R08: 000055764ccdff93 R09: 0000000000000020
[  444.488628] R10: 0000000000000073 R11: 0000000000000246 R12: 000055764ccdff93
[  444.488631] R13: 000055764b180976 R14: 000055764b1809ac R15: 000055764ccdff20
[  444.488637]  ? __this_cpu_preempt_check+0x13/0x20
[  444.488646] Code: c0 e9 23 ff ff ff 48 8b 7d a8 48 8d 75 b0 e8 f2 03 ac ff 45 85 f6 0f 85 12 ff ff ff 44 89 e6 48 c7 c7 b0 73 ce 81 e8 2b 5c ae ff <0f> ff e9 fc fe ff ff 0f 1f 00 66 66 66 66 90 55 8b b7 08 01 00 
[  444.488797] ---[ end trace ac6a6ca98408a28a ]---
Comment 3 Elizabeth 2017-11-14 20:07:29 UTC
Rising priority since it is BAT.
Comment 5 Marta Löfstedt 2018-03-06 06:24:10 UTC
(In reply to Elizabeth from comment #4)
> This still is appearing sporadically in CI results:
> https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3851/fi-ctg-p8600/
> igt@kms_pipe_crc_basic@nonblocking-crc-pipe-b-frame-sequence.html

Yes, we know that no reason to spam the bug about that
Comment 6 Marta Löfstedt 2018-04-10 12:39:21 UTC
why has this high priority it is CTG
Comment 7 Martin Peres 2018-06-15 08:33:27 UTC
Last seen: CI_DRM_3678 (4 months, 3 weeks / 1589 runs ago)

Closing!
Comment 8 Jani Saarinen 2018-06-19 14:25:52 UTC
Closing, thanks.


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.