"vblank wait timed out" on Intel 965GME/GLE

Ali Gholami Rudi aligrudi at gmail.com
Mon Nov 28 06:44:40 UTC 2016


Hi,

In linux-4.8.11, I get the following in my dmesg (and a
20-second delay):

[    1.932295] Linux agpgart interface v0.103
[    1.932448] [drm] Initialized drm 1.1.0 20060810
[    1.932799] pci 0000:00:00.0: Intel 965GME/GLE Chipset
[    1.932902] pci 0000:00:00.0: detected gtt size: 524288K total, 262144K mappable
[    1.933562] pci 0000:00:00.0: detected 8192K stolen memory
[    1.933731] [drm] Memory usable by graphics device = 512M
[    1.933820] [drm] Replacing VGA console driver
[    1.934697] Console: switching to colour dummy device 80x25
[    1.940123] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
[    1.940133] [drm] Driver supports precise vblank timestamp query.
[    1.942520] vgaarb: device changed decodes: PCI:0000:00:02.0,olddecodes=io+mem,decodes=io+mem:owns=io+mem
[    1.979784] ACPI: Deprecated procfs I/F for battery is loaded, please retry with CONFIG_ACPI_PROCFS_POWER cleared
[    1.979798] ACPI: Battery Slot [C245] (battery present)
[    2.292889] [drm] RC6 disabled, disabling runtime PM support
[    2.292902] [drm] initialized overlay support
[    2.342197] ACPI Exception: AE_AML_PACKAGE_LIMIT, Index (0x000000004) is beyond end of object (length 0x4) (20160422/exoparg2-427)
[    2.342215] ACPI Error: Method parse/execution failed [\_SB.C003.C09E._DOD] (Node ffff88003d83ab68), AE_AML_PACKAGE_LIMIT (20160422/psparse-542)
[    2.342232] ACPI Exception: AE_AML_PACKAGE_LIMIT, Evaluating _DOD (20160422/video-1254)
[    2.342429] ACPI: Video Device [C09E] (multi-head: yes  rom: no  post: no)
[    2.344549] acpi device:01: registered as cooling_device7
[    2.344694] input: Video Bus as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/LNXVIDEO:00/input/input3
[    2.344746] [drm] Initialized i915 1.6.0 20160711 for 0000:00:02.0 on minor 0
[    3.819223] ALSA device list:
[    3.819236]   No soundcards found.
[   12.640067] [drm:drm_atomic_helper_commit_cleanup_done] *ERROR* [CRTC:26:pipe A] flip_done timed out
[   12.742064] ------------[ cut here ]------------
[   12.742086] WARNING: CPU: 0 PID: 6 at drivers/gpu/drm/drm_irq.c:1224 drm_wait_one_vblank+0xf9/0x150
[   12.742106] vblank wait timed out on crtc 0
[   12.742118] Modules linked in:
[   12.742136] CPU: 0 PID: 6 Comm: kworker/u4:0 Not tainted 4.8.11 #1
[   12.742149] Hardware name: Hewlett-Packard Compaq 610/308A, BIOS 68PVU Ver. F.05 07/28/2009
[   12.742174] Workqueue: events_unbound async_run_entry_fn
[   12.742188]  0000000000000000 ffff88003d8d7978 ffffffff81223c01 ffff88003d8d79d8
[   12.742214]  0000000000000000 ffff88003d8d79c8 ffffffff8104278b ffff88003d8d7a28
[   12.742238]  000004c88161412c ffff88003d8d79d8 0000000000000000 ffff88003d090000
[   12.742262] Call Trace:
[   12.742281]  [<ffffffff81223c01>] dump_stack+0x4d/0x6c
[   12.742297]  [<ffffffff8104278b>] __warn+0xdb/0x100
[   12.742312]  [<ffffffff81042854>] warn_slowpath_fmt+0x44/0x50
[   12.742329]  [<ffffffff81074f02>] ? finish_wait+0x62/0x80
[   12.742345]  [<ffffffff81302729>] drm_wait_one_vblank+0xf9/0x150
[   12.742361]  [<ffffffff81074df0>] ? add_wait_queue+0x60/0x60
[   12.742379]  [<ffffffff8139e08f>] intel_get_load_detect_pipe+0x64f/0x6c0
[   12.742397]  [<ffffffff813da413>] intel_tv_detect+0x113/0x530
[   12.742413]  [<ffffffff81612ff1>] ? mutex_lock+0x11/0x32
[   12.742431]  [<ffffffff812ed102>] drm_helper_probe_single_connector_modes+0x252/0x4d0
[   12.742453]  [<ffffffff812fa2ce>] drm_fb_helper_initial_config+0x8e/0x400
[   12.742470]  [<ffffffff813b4b57>] intel_fbdev_initial_config+0x17/0x30
[   12.742486]  [<ffffffff8105f336>] async_run_entry_fn+0x36/0xf0
[   12.742502]  [<ffffffff81057c03>] process_one_work+0x133/0x330
[   12.742517]  [<ffffffff81057f65>] worker_thread+0x125/0x470
[   12.742532]  [<ffffffff81057e40>] ? process_scheduled_works+0x40/0x40
[   12.742548]  [<ffffffff8105d094>] kthread+0xc4/0xe0
[   12.742564]  [<ffffffff8161504f>] ret_from_fork+0x1f/0x40
[   12.742580]  [<ffffffff8105cfd0>] ? kthread_freezable_should_stop+0x70/0x70
[   12.742595] ---[ end trace 96eae8324e71c040 ]---
[   12.927424] ------------[ cut here ]------------
[   12.927445] WARNING: CPU: 0 PID: 6 at drivers/gpu/drm/drm_irq.c:1224 drm_wait_one_vblank+0xf9/0x150
[   12.927466] vblank wait timed out on crtc 0
[   12.927477] Modules linked in:
[   12.927493] CPU: 0 PID: 6 Comm: kworker/u4:0 Tainted: G        W       4.8.11 #1
[   12.927513] Hardware name: Hewlett-Packard Compaq 610/308A, BIOS 68PVU Ver. F.05 07/28/2009
[   12.927535] Workqueue: events_unbound async_run_entry_fn
[   12.927549]  0000000000000000 ffff88003d8d7818 ffffffff81223c01 ffff88003d8d7878
[   12.927574]  0000000000000000 ffff88003d8d7868 ffffffff8104278b ffff88003d8d78c8
[   12.927598]  000004c88161412c 01ff880000000000 0000000000000000 ffff88003d090000
[   12.927622] Call Trace:
[   12.927637]  [<ffffffff81223c01>] dump_stack+0x4d/0x6c
[   12.927652]  [<ffffffff8104278b>] __warn+0xdb/0x100
[   12.927667]  [<ffffffff81042854>] warn_slowpath_fmt+0x44/0x50
[   12.927683]  [<ffffffff81074f02>] ? finish_wait+0x62/0x80
[   12.927699]  [<ffffffff81302729>] drm_wait_one_vblank+0xf9/0x150
[   12.927714]  [<ffffffff81074df0>] ? add_wait_queue+0x60/0x60
[   12.927730]  [<ffffffff8139718c>] intel_pre_plane_update+0x18c/0x1b0
[   12.927746]  [<ffffffff81397800>] intel_atomic_commit_tail+0xd0/0x1090
[   12.927760]  [<ffffffff81612ff1>] ? mutex_lock+0x11/0x32
[   12.927776]  [<ffffffff813a9496>] ? intel_fbc_choose_crtc+0x76/0x1b0
[   12.927792]  [<ffffffff813a0633>] ? intel_prepare_plane_fb+0xa3/0x2a0
[   12.927808]  [<ffffffff81398a8c>] intel_atomic_commit+0x2cc/0x500
[   12.927824]  [<ffffffff81319a91>] ? drm_atomic_check_only+0x181/0x640
[   12.927840]  [<ffffffff81074f02>] ? finish_wait+0x62/0x80
[   12.927855]  [<ffffffff81319feb>] drm_atomic_commit+0x3b/0x60
[   12.927871]  [<ffffffff8139e129>] intel_release_load_detect_pipe+0x29/0x90
[   12.927887]  [<ffffffff813da626>] intel_tv_detect+0x326/0x530
[   12.927904]  [<ffffffff812ed102>] drm_helper_probe_single_connector_modes+0x252/0x4d0
[   12.927926]  [<ffffffff812fa2ce>] drm_fb_helper_initial_config+0x8e/0x400
[   12.927943]  [<ffffffff813b4b57>] intel_fbdev_initial_config+0x17/0x30
[   12.927958]  [<ffffffff8105f336>] async_run_entry_fn+0x36/0xf0
[   12.927973]  [<ffffffff81057c03>] process_one_work+0x133/0x330
[   12.927988]  [<ffffffff81057f65>] worker_thread+0x125/0x470
[   12.928003]  [<ffffffff81057e40>] ? process_scheduled_works+0x40/0x40
[   12.928019]  [<ffffffff8105d094>] kthread+0xc4/0xe0
[   12.928035]  [<ffffffff8161504f>] ret_from_fork+0x1f/0x40
[   12.928050]  [<ffffffff8105cfd0>] ? kthread_freezable_should_stop+0x70/0x70
[   12.928065] ---[ end trace 96eae8324e71c041 ]---
[   12.990925] fbcon: inteldrmfb (fb0) is primary device
[   23.720131] random: crng init done
[   24.160072] [drm:drm_atomic_helper_commit_cleanup_done] *ERROR* [CRTC:26:pipe A] flip_done timed out
[   24.267768] ------------[ cut here ]------------
[   24.267778] WARNING: CPU: 1 PID: 6 at drivers/gpu/drm/drm_irq.c:1224 drm_wait_one_vblank+0xf9/0x150
[   24.267780] vblank wait timed out on crtc 0
[   24.267784] Modules linked in:
[   24.267790] CPU: 1 PID: 6 Comm: kworker/u4:0 Tainted: G        W       4.8.11 #1
[   24.267792] Hardware name: Hewlett-Packard Compaq 610/308A, BIOS 68PVU Ver. F.05 07/28/2009
[   24.267800] Workqueue: events_unbound async_run_entry_fn
[   24.267808]  0000000000000000 ffff88003d8d75c8 ffffffff81223c01 ffff88003d8d7628
[   24.267813]  0000000000000000 ffff88003d8d7618 ffffffff8104278b ffff88003d8d7678
[   24.267818]  000004c88161412c ffff88003d8d7628 0000000000000000 ffff88003d090000
[   24.267820] Call Trace:
[   24.267829]  [<ffffffff81223c01>] dump_stack+0x4d/0x6c
[   24.267835]  [<ffffffff8104278b>] __warn+0xdb/0x100
[   24.267839]  [<ffffffff81042854>] warn_slowpath_fmt+0x44/0x50
[   24.267845]  [<ffffffff81074f02>] ? finish_wait+0x62/0x80
[   24.267850]  [<ffffffff81302729>] drm_wait_one_vblank+0xf9/0x150
[   24.267855]  [<ffffffff81074df0>] ? add_wait_queue+0x60/0x60
[   24.267862]  [<ffffffff8139e08f>] intel_get_load_detect_pipe+0x64f/0x6c0
[   24.267868]  [<ffffffff813da413>] intel_tv_detect+0x113/0x530
[   24.267873]  [<ffffffff81612ff1>] ? mutex_lock+0x11/0x32
[   24.267881]  [<ffffffff812ed102>] drm_helper_probe_single_connector_modes+0x252/0x4d0
[   24.267886]  [<ffffffff812fa0ec>] drm_fb_helper_hotplug_event+0xbc/0x140
[   24.267890]  [<ffffffff812fa1cd>] drm_fb_helper_restore_fbdev_mode_unlocked+0x5d/0x80
[   24.267895]  [<ffffffff812fa21e>] drm_fb_helper_set_par+0x2e/0x50
[   24.267900]  [<ffffffff813b4015>] intel_fbdev_set_par+0x15/0x60
[   24.267906]  [<ffffffff8126938a>] fbcon_init+0x4ea/0x560
[   24.267912]  [<ffffffff812cc082>] visual_init+0xd2/0x130
[   24.267917]  [<ffffffff812ce1c0>] do_bind_con_driver+0x150/0x310
[   24.267921]  [<ffffffff812ce49e>] do_take_over_console+0x11e/0x180
[   24.267927]  [<ffffffff810bc8fb>] ? printk+0x41/0x43
[   24.267932]  [<ffffffff81266595>] do_fbcon_takeover+0x55/0xb0
[   24.267937]  [<ffffffff8126a477>] fbcon_event_notify+0x767/0x8a0
[   24.267942]  [<ffffffff8105dd2b>] notifier_call_chain+0x4b/0x70
[   24.267946]  [<ffffffff8105df63>] __blocking_notifier_call_chain+0x53/0x80
[   24.267950]  [<ffffffff8105dfa1>] blocking_notifier_call_chain+0x11/0x20
[   24.267955]  [<ffffffff8126dd46>] fb_notifier_call_chain+0x16/0x20
[   24.267959]  [<ffffffff8126fb26>] register_framebuffer+0x226/0x310
[   24.267965]  [<ffffffff812fa4a4>] drm_fb_helper_initial_config+0x264/0x400
[   24.267969]  [<ffffffff813b4b57>] intel_fbdev_initial_config+0x17/0x30
[   24.267974]  [<ffffffff8105f336>] async_run_entry_fn+0x36/0xf0
[   24.267978]  [<ffffffff81057c03>] process_one_work+0x133/0x330
[   24.267983]  [<ffffffff81057f65>] worker_thread+0x125/0x470
[   24.267988]  [<ffffffff81057e40>] ? process_scheduled_works+0x40/0x40
[   24.267993]  [<ffffffff8105d094>] kthread+0xc4/0xe0
[   24.267998]  [<ffffffff8161504f>] ret_from_fork+0x1f/0x40
[   24.268003]  [<ffffffff8105cfd0>] ? kthread_freezable_should_stop+0x70/0x70
[   24.268007] ---[ end trace 96eae8324e71c042 ]---
[   24.452808] ------------[ cut here ]------------
[   24.452817] WARNING: CPU: 1 PID: 6 at drivers/gpu/drm/drm_irq.c:1224 drm_wait_one_vblank+0xf9/0x150
[   24.452818] vblank wait timed out on crtc 0
[   24.452821] Modules linked in:
[   24.452825] CPU: 1 PID: 6 Comm: kworker/u4:0 Tainted: G        W       4.8.11 #1
[   24.452827] Hardware name: Hewlett-Packard Compaq 610/308A, BIOS 68PVU Ver. F.05 07/28/2009
[   24.452833] Workqueue: events_unbound async_run_entry_fn
[   24.452839]  0000000000000000 ffff88003d8d7468 ffffffff81223c01 ffff88003d8d74c8
[   24.452844]  0000000000000000 ffff88003d8d74b8 ffffffff8104278b ffff88003d8d7518
[   24.452849]  000004c88161412c ffff88003d8d74a8 0000000000000000 ffff88003d090000
[   24.452851] Call Trace:
[   24.452856]  [<ffffffff81223c01>] dump_stack+0x4d/0x6c
[   24.452860]  [<ffffffff8104278b>] __warn+0xdb/0x100
[   24.452865]  [<ffffffff81042854>] warn_slowpath_fmt+0x44/0x50
[   24.452870]  [<ffffffff81074f02>] ? finish_wait+0x62/0x80
[   24.452875]  [<ffffffff81302729>] drm_wait_one_vblank+0xf9/0x150
[   24.452880]  [<ffffffff81074df0>] ? add_wait_queue+0x60/0x60
[   24.452885]  [<ffffffff8139718c>] intel_pre_plane_update+0x18c/0x1b0
[   24.452889]  [<ffffffff81397800>] intel_atomic_commit_tail+0xd0/0x1090
[   24.452893]  [<ffffffff81612ff1>] ? mutex_lock+0x11/0x32
[   24.452898]  [<ffffffff813a9496>] ? intel_fbc_choose_crtc+0x76/0x1b0
[   24.452903]  [<ffffffff813a0633>] ? intel_prepare_plane_fb+0xa3/0x2a0
[   24.452908]  [<ffffffff81398a8c>] intel_atomic_commit+0x2cc/0x500
[   24.452913]  [<ffffffff81319a91>] ? drm_atomic_check_only+0x181/0x640
[   24.452918]  [<ffffffff81074f02>] ? finish_wait+0x62/0x80
[   24.452922]  [<ffffffff81319feb>] drm_atomic_commit+0x3b/0x60
[   24.452927]  [<ffffffff8139e129>] intel_release_load_detect_pipe+0x29/0x90
[   24.452931]  [<ffffffff813da626>] intel_tv_detect+0x326/0x530
[   24.452939]  [<ffffffff812ed102>] drm_helper_probe_single_connector_modes+0x252/0x4d0
[   24.452944]  [<ffffffff812fa0ec>] drm_fb_helper_hotplug_event+0xbc/0x140
[   24.452949]  [<ffffffff812fa1cd>] drm_fb_helper_restore_fbdev_mode_unlocked+0x5d/0x80
[   24.452953]  [<ffffffff812fa21e>] drm_fb_helper_set_par+0x2e/0x50
[   24.452957]  [<ffffffff813b4015>] intel_fbdev_set_par+0x15/0x60
[   24.452962]  [<ffffffff8126938a>] fbcon_init+0x4ea/0x560
[   24.452967]  [<ffffffff812cc082>] visual_init+0xd2/0x130
[   24.452971]  [<ffffffff812ce1c0>] do_bind_con_driver+0x150/0x310
[   24.452976]  [<ffffffff812ce49e>] do_take_over_console+0x11e/0x180
[   24.452981]  [<ffffffff810bc8fb>] ? printk+0x41/0x43
[   24.452985]  [<ffffffff81266595>] do_fbcon_takeover+0x55/0xb0
[   24.452990]  [<ffffffff8126a477>] fbcon_event_notify+0x767/0x8a0
[   24.452995]  [<ffffffff8105dd2b>] notifier_call_chain+0x4b/0x70
[   24.452999]  [<ffffffff8105df63>] __blocking_notifier_call_chain+0x53/0x80
[   24.453004]  [<ffffffff8105dfa1>] blocking_notifier_call_chain+0x11/0x20
[   24.453008]  [<ffffffff8126dd46>] fb_notifier_call_chain+0x16/0x20
[   24.453012]  [<ffffffff8126fb26>] register_framebuffer+0x226/0x310
[   24.453017]  [<ffffffff812fa4a4>] drm_fb_helper_initial_config+0x264/0x400
[   24.453022]  [<ffffffff813b4b57>] intel_fbdev_initial_config+0x17/0x30
[   24.453027]  [<ffffffff8105f336>] async_run_entry_fn+0x36/0xf0
[   24.453031]  [<ffffffff81057c03>] process_one_work+0x133/0x330
[   24.453035]  [<ffffffff81057f65>] worker_thread+0x125/0x470
[   24.453040]  [<ffffffff81057e40>] ? process_scheduled_works+0x40/0x40
[   24.453044]  [<ffffffff8105d094>] kthread+0xc4/0xe0
[   24.453050]  [<ffffffff8161504f>] ret_from_fork+0x1f/0x40
[   24.453055]  [<ffffffff8105cfd0>] ? kthread_freezable_should_stop+0x70/0x70
[   24.453058] ---[ end trace 96eae8324e71c043 ]---
[   24.546012] Console: switching to colour frame buffer device 170x48
[   24.795223] i915 0000:00:02.0: fb0: inteldrmfb frame buffer device

I did not encounter this in linux-4.7.

Thanks,
Ali



More information about the dri-devel mailing list