Message-ID: <b7107a1c-d170-44d9-96c2-4f7051d0721f@collabora.com>
Date: Wed, 30 Oct 2024 18:01:06 +0530
From: Vignesh Raman <vignesh.raman@...labora.com>
To: dri-devel <dri-devel@...ts.freedesktop.org>
Cc: linux-kernel <linux-kernel@...r.kernel.org>
Subject: INFO: task testdisplay:2546 blocked for more than 122 seconds

Hi,

Below issue was seen with drm-ci testing,

Pipeline: https://gitlab.freedesktop.org/vigneshraman/linux/-/jobs/65859386
Linux version: 6.12-rc2
Branch: drm-misc-next

DEBUG - Begin test testdisplay
[ 1229.996702] INFO: task testdisplay:2546 blocked for more than 122 
seconds.
[ 1229.997303]       Not tainted 6.12.0-rc2-g13339fa4b45f #1
[ 1229.997764] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" 
disables this message.
[ 1229.998816]
                Showing all locks held in the system:
[ 1229.998824] 1 lock held by khungtaskd/29:
[ 1229.998830]  #0: ffffffff93d5b2c0 (rcu_read_lock){....}-{1:2}, at: 
debug_show_all_locks+0x36/0x1c0
[ 1229.998870] 1 lock held by dmesg/66:
[ 1229.998875] 3 locks held by kworker/1:0/495:
[ 1229.998881] 2 locks held by kms_concurrent/1623:
[ 1229.998886]  #0: ffffa9fe00b4fd20 
(crtc_ww_class_acquire){+.+.}-{0:0}, at: drm_mode_atomic_ioctl+0x11b/0xc90
[ 1229.998915]  #1: ffff8cc9c140caf0 (crtc_ww_class_mutex){+.+.}-{3:3}, 
at: modeset_lock+0x5c/0x1c0
[ 1229.998937] 1 lock held by testdisplay/2546:
[ 1229.998943]  #0: ffff8cc9c0a1f0b0 (crtc_ww_class_mutex){+.+.}-{3:3}, 
at: drm_modeset_lock+0x1e/0x30

[ 1229.998968] =============================================

[ 1352.876639] INFO: task testdisplay:2546 blocked for more than 245 
seconds.
[ 1352.877238]       Not tainted 6.12.0-rc2-g13339fa4b45f #1
[ 1352.877707] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" 
disables this message.
[ 1352.878582]
                Showing all locks held in the system:
[ 1352.878587] 1 lock held by khungtaskd/29:
[ 1352.878591]  #0: ffffffff93d5b2c0 (rcu_read_lock){....}-{1:2}, at: 
debug_show_all_locks+0x36/0x1c0
[ 1352.878613] 1 lock held by dmesg/66:
[ 1352.878618] 4 locks held by kworker/1:0/495:
[ 1352.878622] 2 locks held by kms_concurrent/1623:
[ 1352.878626]  #0: ffffa9fe00b4fd20 
(crtc_ww_class_acquire){+.+.}-{0:0}, at: drm_mode_atomic_ioctl+0x11b/0xc90
[ 1352.878647]  #1: ffff8cc9c140caf0 (crtc_ww_class_mutex){+.+.}-{3:3}, 
at: modeset_lock+0x5c/0x1c0
[ 1352.878665] 1 lock held by testdisplay/2546:
[ 1352.878669]  #0: ffff8cc9c0a1f0b0 (crtc_ww_class_mutex){+.+.}-{3:3}, 
at: drm_modeset_lock+0x1e/0x30

[ 1352.878689] =============================================

[ 1475.756731] INFO: task testdisplay:2546 blocked for more than 368 
seconds.
[ 1475.757311]       Not tainted 6.12.0-rc2-g13339fa4b45f #1
[ 1475.757863] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" 
disables this message.
[ 1475.758884]
                Showing all locks held in the system:
[ 1475.758890] 1 lock held by khungtaskd/29:
[ 1475.758895]  #0: ffffffff93d5b2c0 (rcu_read_lock){....}-{1:2}, at: 
debug_show_all_locks+0x36/0x1c0
[ 1475.758921] 1 lock held by dmesg/66:
[ 1475.758927] 3 locks held by kworker/1:0/495:
[ 1475.758933] 2 locks held by kms_concurrent/1623:
[ 1475.758938]  #0: ffffa9fe00b4fd20 
(crtc_ww_class_acquire){+.+.}-{0:0}, at: drm_mode_atomic_ioctl+0x11b/0xc90
[ 1475.758963]  #1: ffff8cc9c140caf0 (crtc_ww_class_mutex){+.+.}-{3:3}, 
at: modeset_lock+0x5c/0x1c0
[ 1475.758985] 1 lock held by testdisplay/2546:
[ 1475.758990]  #0: ffff8cc9c0a1f0b0 (crtc_ww_class_mutex){+.+.}-{3:3}, 
at: drm_modeset_lock+0x1e/0x30

[ 1475.759016] =============================================

[ 1598.636617] INFO: task testdisplay:2546 blocked for more than 491 
seconds.
[ 1598.637199]       Not tainted 6.12.0-rc2-g13339fa4b45f #1
[ 1598.637638] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" 
disables this message.
[ 1598.638466]
                Showing all locks held in the system:
[ 1598.638471] 1 lock held by khungtaskd/29:
[ 1598.638476]  #0: ffffffff93d5b2c0 (rcu_read_lock){....}-{1:2}, at: 
debug_show_all_locks+0x36/0x1c0
[ 1598.638498] 1 lock held by dmesg/66:
[ 1598.638502] 2 locks held by kworker/1:0/495:
[ 1598.638507] 2 locks held by kms_concurrent/1623:
[ 1598.638511]  #0: ffffa9fe00b4fd20 
(crtc_ww_class_acquire){+.+.}-{0:0}, at: drm_mode_atomic_ioctl+0x11b/0xc90
[ 1598.638531]  #1: ffff8cc9c140caf0 (crtc_ww_class_mutex){+.+.}-{3:3}, 
at: modeset_lock+0x5c/0x1c0
[ 1598.638561] 1 lock held by testdisplay/2546:
[ 1598.638565]  #0: ffff8cc9c0a1f0b0 (crtc_ww_class_mutex){+.+.}-{3:3}, 
at: drm_modeset_lock+0x1e/0x30

[ 1598.638586] =============================================

[ 1721.516713] INFO: task testdisplay:2546 blocked for more than 614 
seconds.
[ 1721.517446]       Not tainted 6.12.0-rc2-g13339fa4b45f #1
[ 1721.518039] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" 
disables this message.
[ 1721.519117]
                Showing all locks held in the system:
[ 1721.519124] 1 lock held by khungtaskd/29:
[ 1721.519130]  #0: ffffffff93d5b2c0 (rcu_read_lock){....}-{1:2}, at: 
debug_show_all_locks+0x36/0x1c0
[ 1721.519160] 1 lock held by dmesg/66:
[ 1721.519167] 3 locks held by kworker/1:0/495:
[ 1721.519173] 2 locks held by kms_concurrent/1623:
[ 1721.519179]  #0: ffffa9fe00b4fd20 
(crtc_ww_class_acquire){+.+.}-{0:0}, at: drm_mode_atomic_ioctl+0x11b/0xc90
[ 1721.519207]  #1: ffff8cc9c140caf0 (crtc_ww_class_mutex){+.+.}-{3:3}, 
at: modeset_lock+0x5c/0x1c0
[ 1721.519231] 1 lock held by testdisplay/2546:
[ 1721.519237]  #0: ffff8cc9c0a1f0b0 (crtc_ww_class_mutex){+.+.}-{3:3}, 
at: drm_modeset_lock+0x1e/0x30

[ 1721.519267] =============================================

[ 1844.396721] INFO: task testdisplay:2546 blocked for more than 737 
seconds.
[ 1844.397422]       Not tainted 6.12.0-rc2-g13339fa4b45f #1
[ 1844.397950] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" 
disables this message.
[ 1844.398996]
                Showing all locks held in the system:
[ 1844.399004] 1 lock held by khungtaskd/29:
[ 1844.399010]  #0: ffffffff93d5b2c0 (rcu_read_lock){....}-{1:2}, at: 
debug_show_all_locks+0x36/0x1c0
[ 1844.399041] 1 lock held by dmesg/66:
[ 1844.399048] 4 locks held by kworker/1:0/495:
[ 1844.399053] 2 locks held by kms_concurrent/1623:
[ 1844.399058]  #0: ffffa9fe00b4fd20 
(crtc_ww_class_acquire){+.+.}-{0:0}, at: drm_mode_atomic_ioctl+0x11b/0xc90
[ 1844.399084]  #1: ffff8cc9c140caf0 (crtc_ww_class_mutex){+.+.}-{3:3}, 
at: modeset_lock+0x5c/0x1c0
[ 1844.399108] 1 lock held by testdisplay/2546:
[ 1844.399113]  #0: ffff8cc9c0a1f0b0 (crtc_ww_class_mutex){+.+.}-{3:3}, 
at: drm_modeset_lock+0x1e/0x30

[ 1844.399141] =============================================

[ 1967.276701] INFO: task testdisplay:2546 blocked for more than 860 
seconds.
[ 1967.277282]       Not tainted 6.12.0-rc2-g13339fa4b45f #1
[ 1967.277735] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" 
disables this message.
[ 1967.278559]
                Showing all locks held in the system:
[ 1967.278576] 1 lock held by khungtaskd/29:
[ 1967.278580]  #0: ffffffff93d5b2c0 (rcu_read_lock){....}-{1:2}, at: 
debug_show_all_locks+0x36/0x1c0
[ 1967.278602] 1 lock held by dmesg/66:
[ 1967.278606] 2 locks held by kworker/1:0/495:
[ 1967.278610] 2 locks held by kms_concurrent/1623:
[ 1967.278614]  #0: ffffa9fe00b4fd20 
(crtc_ww_class_acquire){+.+.}-{0:0}, at: drm_mode_atomic_ioctl+0x11b/0xc90
[ 1967.278633]  #1: ffff8cc9c140caf0 (crtc_ww_class_mutex){+.+.}-{3:3}, 
at: modeset_lock+0x5c/0x1c0
[ 1967.278650] 1 lock held by testdisplay/2546:
[ 1967.278654]  #0: ffff8cc9c0a1f0b0 (crtc_ww_class_mutex){+.+.}-{3:3}, 
at: drm_modeset_lock+0x1e/0x30

[ 1967.278674] =============================================

[ 2090.156642] INFO: task testdisplay:2546 blocked for more than 983 
seconds.
[ 2090.157632]       Not tainted 6.12.0-rc2-g13339fa4b45f #1
[ 2090.158241] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" 
disables this message.
[ 2090.159102]
                Showing all locks held in the system:
[ 2090.159107] 1 lock held by khungtaskd/29:
[ 2090.159111]  #0: ffffffff93d5b2c0 (rcu_read_lock){....}-{1:2}, at: 
debug_show_all_locks+0x36/0x1c0
[ 2090.159133] 1 lock held by dmesg/66:
[ 2090.159137] 3 locks held by kworker/1:0/495:
[ 2090.159141] 2 locks held by kms_concurrent/1623:
[ 2090.159145]  #0: ffffa9fe00b4fd20 
(crtc_ww_class_acquire){+.+.}-{0:0}, at: drm_mode_atomic_ioctl+0x11b/0xc90
[ 2090.159165]  #1: ffff8cc9c140caf0 (crtc_ww_class_mutex){+.+.}-{3:3}, 
at: modeset_lock+0x5c/0x1c0
[ 2090.159182] 1 lock held by testdisplay/2546:
[ 2090.159189]  #0: ffff8cc9c0a1f0b0 (crtc_ww_class_mutex){+.+.}-{3:3}, 
at: drm_modeset_lock+0x1e/0x30

[ 2090.159209] =============================================

[ 2213.036658] INFO: task testdisplay:2546 blocked for more than 1105 
seconds.
[ 2213.037250]       Not tainted 6.12.0-rc2-g13339fa4b45f #1
[ 2213.037744] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" 
disables this message.
[ 2213.038593]
                Showing all locks held in the system:
[ 2213.038598] 1 lock held by khungtaskd/29:
[ 2213.038602]  #0: ffffffff93d5b2c0 (rcu_read_lock){....}-{1:2}, at: 
debug_show_all_locks+0x36/0x1c0
[ 2213.038624] 1 lock held by dmesg/66:
[ 2213.038629] 3 locks held by kworker/1:0/495:
[ 2213.038633] 2 locks held by kms_concurrent/1623:
[ 2213.038637]  #0: ffffa9fe00b4fd20 
(crtc_ww_class_acquire){+.+.}-{0:0}, at: drm_mode_atomic_ioctl+0x11b/0xc90
[ 2213.038657]  #1: ffff8cc9c140caf0 (crtc_ww_class_mutex){+.+.}-{3:3}, 
at: modeset_lock+0x5c/0x1c0
[ 2213.038674] 1 lock held by testdisplay/2546:
[ 2213.038678]  #0: ffff8cc9c0a1f0b0 (crtc_ww_class_mutex){+.+.}-{3:3}, 
at: drm_modeset_lock+0x1e/0x30

[ 2213.038699] =============================================

[ 2335.916691] INFO: task testdisplay:2546 blocked for more than 1228 
seconds.
[ 2335.917751]       Not tainted 6.12.0-rc2-g13339fa4b45f #1
[ 2335.918166] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" 
disables this message.
[ 2335.919035]
                Showing all locks held in the system:
[ 2335.919040] 1 lock held by khungtaskd/29:
[ 2335.919044]  #0: ffffffff93d5b2c0 (rcu_read_lock){....}-{1:2}, at: 
debug_show_all_locks+0x36/0x1c0
[ 2335.919066] 1 lock held by dmesg/66:
[ 2335.919071] 3 locks held by kworker/1:0/495:
[ 2335.919075] 2 locks held by kms_concurrent/1623:
[ 2335.919079]  #0: ffffa9fe00b4fd20 
(crtc_ww_class_acquire){+.+.}-{0:0}, at: drm_mode_atomic_ioctl+0x11b/0xc90
[ 2335.919099]  #1: ffff8cc9c140caf0 (crtc_ww_class_mutex){+.+.}-{3:3}, 
at: modeset_lock+0x5c/0x1c0
[ 2335.919116] 1 lock held by testdisplay/2546:
[ 2335.919120]  #0: ffff8cc9c0a1f0b0 (crtc_ww_class_mutex){+.+.}-{3:3}, 
at: drm_modeset_lock+0x1e/0x30

[ 2335.919140] =============================================

Regards,
Vignesh

Powered by blists - more mailing lists