igt@sysfs_preempt_timeout@timeout@rcs0 - abort - task kworker * blocked for more than 61 seconds
Stdout
Starting dynamic subtest: rcs0
Minimum timeout measured as 7.154ms; setting error threshold to 50ms
runner: This test was killed due to a kernel taint (0x200).
This test caused an abort condition: Kernel badly tainted (0x200, 0x200) (check dmesg for details):
TAINT_WARN: WARN_ON has happened.
Stderr
Starting dynamic subtest: rcs0
Received signal SIGQUIT.
Stack trace:
#0 [fatal_sig_handler+0x10f]
#1 [killpg+0x40]
#2 [__test_timeout+0x1b2]
#3 [test_timeout+0x169]
#4 [dyn_sysfs_engines+0x167]
#5 [__igt_unique____real_main334+0x20f]
#6 [main+0x2d]
#7 [__libc_start_main+0xf3]
#8 [_start+0x2e]
Dmesg
<6> [398.249852] [IGT] sysfs_preempt_timeout: starting dynamic subtest rcs0
<7> [398.250053] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [398.251255] [drm:eb_lookup_vmas [i915]] EINVAL at eb_validate_vma:510
<7> [398.260248] [drm:eb_lookup_vmas [i915]] EINVAL at eb_validate_vma:510
<7> [398.267839] i915 0000:00:02.0: [drm:intel_guc_context_reset_process_msg [i915]] GT0: GUC: Got context reset notification: 0x1002 on rcs0, exiting = no, banned = no
<6> [398.338877] i915 0000:00:02.0: [drm] GPU HANG: ecode 12:1:e75ffefe, in sysfs_preempt_t [1855]
<7> [398.340929] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [398.381503] [drm:eb_lookup_vmas [i915]] EINVAL at eb_validate_vma:510
<7> [398.392160] [drm:eb_lookup_vmas [i915]] EINVAL at eb_validate_vma:510
<5> [418.392344] Fence expiration time out i915-0000:00:02.0:sysfs_preempt_t[1855]:2!
<3> [494.779931] INFO: task kworker/2:1:159 blocked for more than 61 seconds.
<3> [494.786983] Tainted: G W 6.9.0-rc7-CI_DRM_14716-g980de4c8f9c4+ #1
<3> [494.795139] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
<6> [494.803009] task:kworker/2:1 state:D stack:13152 pid:159 tgid:159 ppid:2 flags:0x00004000
<6> [494.803026] Workqueue: i915-unordered intel_gt_watchdog_work [i915]
<6> [494.803304] Call Trace:
<6> [494.803306] <TASK>
<6> [494.803311] __schedule+0x3bb/0xda0
<6> [494.803323] schedule+0x41/0x110
<6> [494.803327] guc_context_cancel_request+0x45f/0x9f0 [i915]
<6> [494.803601] ? __pfx_autoremove_wake_function+0x10/0x10
<6> [494.803611] ? intel_gt_watchdog_work+0x20/0x260 [i915]
<6> [494.803762] intel_gt_watchdog_work+0xd1/0x260 [i915]
<6> [494.803941] process_scheduled_works+0x351/0x690
<6> [494.803954] ? __pfx_worker_thread+0x10/0x10
<6> [494.803958] worker_thread+0x13f/0x2e0
<6> [494.803964] ? __pfx_worker_thread+0x10/0x10
<6> [494.803969] kthread+0xfb/0x130
<6> [494.803973] ? __pfx_kthread+0x10/0x10
<6> [494.803977] ret_from_fork+0x2c/0x50
<6> [494.803981] ? __pfx_kthread+0x10/0x10
<6> [494.803985] ret_from_fork_asm+0x1a/0x30
<6> [494.803995] </TASK>
<4> [494.804022]
Showing all locks held in the system:
<4> [494.804032] 1 lock held by khungtaskd/124:
<4> [494.804035] #0: ffffffff8273b420 (rcu_read_lock){....}-{1:2}, at: debug_show_all_locks+0x36/0x1c0
<4> [494.804051] 2 locks held by kworker/2:1/159:
<4> [494.804054] #0: ffff8881148f7b48 ((wq_completion)i915-unordered){+.+.}-{0:0}, at: process_scheduled_works+0x49a/0x690
<4> [494.804064] #1: ffffc90000767e58 ((work_completion)(>->watchdog.work)){+.+.}-{0:0}, at: process_scheduled_works+0x311/0x690
<4> [494.804077] 2 locks held by in:imklog/431:
<4> [494.804083] 2 locks held by dmesg/1104:
<4> [494.804086]
<4> [494.804088] =============================================
<7> [494.806625] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<6> [494.844867] Console: switching to colour frame buffer device 360x112
<7> [494.855014] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [494.855189] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [494.856067] i915 0000:00:02.0: [drm:drm_client_dev_restore] intel-fbdev: ret=0
<7> [495.052002] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [495.052455] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [495.052859] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02