Results for igt@xe_exec_reset@cm-cat-error

Machine description: shard-adlp-1

Result: Abort 1 Warning(s)

i915_display_info0 igt_runner0 results0.json results0-xe-load.json i915_display_info_post_exec0 boot0 dmesg0

DetailValue
Duration unknown
Hostname 
shard-adlp-1
Igt-Version 
IGT-Version: 1.30-gf0b668833 (x86_64) (Linux: 6.14.0-rc4-xe+ x86_64)
Out 
Using IGT_SRANDOM=1740704116 for randomisation
Opened device: /dev/dri/card0
Starting subtest: cm-cat-error
Stack trace:
  #0 ../lib/igt_core.c:2055 __igt_fail_assert()
  #1 ../tests/intel/xe_exec_reset.c:570 test_compute_mode()
  #2 ../tests/intel/xe_exec_reset.c:806 __igt_unique____real_main758()
  #3 ../tests/intel/xe_exec_reset.c:758 main()
  #4 [__libc_init_first+0x8a]
  #5 [__libc_start_main+0x8b]
  #6 [_start+0x25]
Subtest cm-cat-error: FAIL (1.024s)

This test caused an abort condition: Lockdep not active

/proc/lockdep_stats contents:
 lock-classes:                         2292 [max: 8192]
 direct dependencies:                 26756 [max: 524288]
 indirect dependencies:              191124
 all direct dependencies:            533381
 dependency chains:                   40611 [max: 524288]
 dependency chain hlocks used:       180572 [max: 2621440]
 dependency chain hlocks lost:            0
 in-hardirq chains:                     357
 in-softirq chains:                     815
 in-process chains:                   39439
 stack-trace entries:                277982 [max: 524288]
 number of stack traces:              12925
 number of stack hash chains:          8968
 combined max dependencies:      2931593728
 hardirq-safe locks:                    112
 hardirq-unsafe locks:                 1351
 softirq-safe locks:                    240
 softirq-unsafe locks:                 1248
 irq-safe locks:                        263
 irq-unsafe locks:                     1351
 hardirq-read-safe locks:                 4
 hardirq-read-unsafe locks:             440
 softirq-read-safe locks:                10
 softirq-read-unsafe locks:             435
 irq-read-safe locks:                    10
 irq-read-unsafe locks:                 440
 uncategorized locks:                   373
 unused locks:                            1
 max locking depth:                      18
 max bfs queue depth:                   437
 max lock class index:                 2291
 debug_locks:                             0

 zapped classes:                         63
 zapped lock chains:                   1812
 large chain blocks:                      1
Err 
Starting subtest: cm-cat-error
(xe_exec_reset:3627) CRITICAL: Test assertion failure function test_compute_mode, file ../tests/intel/xe_exec_reset.c:570:
(xe_exec_reset:3627) CRITICAL: Failed assertion: err == 0
(xe_exec_reset:3627) CRITICAL: Last errno: 5, Input/output error
(xe_exec_reset:3627) CRITICAL: error: -5 != 0
Subtest cm-cat-error failed.
**** DEBUG ****
(xe_exec_reset:3627) CRITICAL: Test assertion failure function test_compute_mode, file ../tests/intel/xe_exec_reset.c:570:
(xe_exec_reset:3627) CRITICAL: Failed assertion: err == 0
(xe_exec_reset:3627) CRITICAL: Last errno: 5, Input/output error
(xe_exec_reset:3627) CRITICAL: error: -5 != 0
(xe_exec_reset:3627) igt_core-INFO: Stack trace:
(xe_exec_reset:3627) igt_core-INFO:   #0 ../lib/igt_core.c:2055 __igt_fail_assert()
(xe_exec_reset:3627) igt_core-INFO:   #1 ../tests/intel/xe_exec_reset.c:570 test_compute_mode()
(xe_exec_reset:3627) igt_core-INFO:   #2 ../tests/intel/xe_exec_reset.c:806 __igt_unique____real_main758()
(xe_exec_reset:3627) igt_core-INFO:   #3 ../tests/intel/xe_exec_reset.c:758 main()
(xe_exec_reset:3627) igt_core-INFO:   #4 [__libc_init_first+0x8a]
(xe_exec_reset:3627) igt_core-INFO:   #5 [__libc_start_main+0x8b]
(xe_exec_reset:3627) igt_core-INFO:   #6 [_start+0x25]
****  END  ****
Subtest cm-cat-error: FAIL (1.024s)
Dmesg

Scroll to first warning
<6> [339.124357] Console: switching to colour dummy device 80x25
<6> [339.124639] [IGT] xe_exec_reset: executing
<6> [339.128383] [IGT] xe_exec_reset: starting subtest cm-cat-error
<7> [339.131934] xe 0000:00:02.0: [drm:xe_guc_exec_queue_memory_cat_error_handler [xe]] GT0: Engine memory cat error: engine_class=rcs, logical_mask: 0x1, guc_id=2
<4> [339.132280]
<4> [339.132284] ======================================================
<4> [339.132286] WARNING: possible circular locking dependency detected
<4> [339.132289] 6.14.0-rc4-xe+ #1 Tainted: G U
<4> [339.132292] ------------------------------------------------------
<4> [339.132294] kworker/u64:8/2497 is trying to acquire lock:
<4> [339.132296] ffffffff834c95c0 (fs_reclaim){+.+.}-{0:0}, at: __kmalloc_cache_noprof+0x58/0x490
<4> [339.132305]
but task is already holding lock:
<4> [339.132308] ffff888152281430 (&ct->lock){+.+.}-{3:3}, at: receive_g2h+0x47/0x100 [xe]
<4> [339.132379]
which lock already depends on the new lock.
<4> [339.132382]
the existing dependency chain (in reverse order) is:
<4> [339.132385]
-> #1 (&ct->lock){+.+.}-{3:3}:
<4> [339.132389] xe_guc_ct_init+0x2b4/0x4c0 [xe]
<4> [339.132444] xe_guc_init+0xe9/0x360 [xe]
<4> [339.132499] xe_uc_init+0x1e/0x1f0 [xe]
<4> [339.132577] xe_gt_init_hwconfig+0x4c/0xb0 [xe]
<4> [339.132629] xe_device_probe+0x3b8/0x7f0 [xe]
<4> [339.132677] xe_pci_probe+0x372/0x5f0 [xe]
<4> [339.132742] local_pci_probe+0x44/0xb0
<4> [339.132747] pci_device_probe+0xf4/0x270
<4> [339.132750] really_probe+0xee/0x3c0
<4> [339.132754] __driver_probe_device+0x8c/0x180
<4> [339.132757] driver_probe_device+0x24/0xd0
<4> [339.132760] __driver_attach+0x10f/0x220
<4> [339.132763] bus_for_each_dev+0x8d/0xf0
<4> [339.132766] driver_attach+0x1e/0x30
<4> [339.132769] bus_add_driver+0x151/0x290
<4> [339.132771] driver_register+0x5e/0x130
<4> [339.132774] __pci_register_driver+0x7d/0x90
<4> [339.132778] xe_register_pci_driver+0x23/0x30 [xe]
<4> [339.132840] soundcore_open+0x83/0x210 [soundcore]
<4> [339.132844] do_one_initcall+0x76/0x400
<4> [339.132847] do_init_module+0x97/0x2a0
<4> [339.132851] load_module+0x2c23/0x2f60
<4> [339.132854] init_module_from_file+0x97/0xe0
<4> [339.132856] idempotent_init_module+0x134/0x350
<4> [339.132859] __x64_sys_finit_module+0x77/0x100
<4> [339.132862] x64_sys_call+0x1f37/0x2650
<4> [339.132865] do_syscall_64+0x91/0x180
<4> [339.132869] entry_SYSCALL_64_after_hwframe+0x76/0x7e
<4> [339.132873]
-> #0 (fs_reclaim){+.+.}-{0:0}:
<4> [339.132877] __lock_acquire+0x1637/0x2810
<4> [339.132881] lock_acquire+0xc9/0x300
<4> [339.132884] fs_reclaim_acquire+0xc5/0x100
<4> [339.132888] __kmalloc_cache_noprof+0x58/0x490
<4> [339.132891] xe_vm_add_ban_entry+0x64/0x2a0 [xe]
<4> [339.132966] xe_guc_exec_queue_memory_cat_error_handler+0x19d/0x230 [xe]
<4> [339.133026] dequeue_one_g2h+0x349/0x900 [xe]
<4> [339.133081] receive_g2h+0x4f/0x100 [xe]
<4> [339.133134] g2h_worker_func+0x15/0x20 [xe]
<4> [339.133236] process_one_work+0x21c/0x740
<4> [339.133240] worker_thread+0x1db/0x3c0
<4> [339.133244] kthread+0x10d/0x270
<4> [339.133247] ret_from_fork+0x44/0x70
<4> [339.133251] ret_from_fork_asm+0x1a/0x30
<4> [339.133255]
other info that might help us debug this:
<4> [339.133259] Possible unsafe locking scenario:
<4> [339.133262] CPU0 CPU1
<4> [339.133264] ---- ----
<4> [339.133266] lock(&ct->lock);
<4> [339.133269] lock(fs_reclaim);
<4> [339.133273] lock(&ct->lock);
<4> [339.133276] lock(fs_reclaim);
<4> [339.133279]
*** DEADLOCK ***
<4> [339.133282] 3 locks held by kworker/u64:8/2497:
<4> [339.133285] #0: ffff888140d81148 ((wq_completion)xe-g2h-wq#3){+.+.}-{0:0}, at: process_one_work+0x444/0x740
<4> [339.133293] #1: ffffc90002a9be20 ((work_completion)(&ct->g2h_worker)){+.+.}-{0:0}, at: process_one_work+0x1da/0x740
<4> [339.133301] #2: ffff888152281430 (&ct->lock){+.+.}-{3:3}, at: receive_g2h+0x47/0x100 [xe]
<4> [339.133373]
stack backtrace:
<4> [339.133376] CPU: 0 UID: 0 PID: 2497 Comm: kworker/u64:8 Tainted: G U 6.14.0-rc4-xe+ #1
<4> [339.133378] Tainted: [U]=USER
<4> [339.133378] Hardware name: Intel Corporation Alder Lake Client Platform/AlderLake-P DDR5 RVP, BIOS RPLPFWI1.R00.4035.A00.2301200723 01/20/2023
<4> [339.133379] Workqueue: xe-g2h-wq g2h_worker_func [xe]
<4> [339.133445] Call Trace:
<4> [339.133445] <TASK>
<4> [339.133446] dump_stack_lvl+0x91/0xf0
<4> [339.133450] dump_stack+0x10/0x20
<4> [339.133451] print_circular_bug+0x285/0x360
<4> [339.133454] check_noncircular+0x150/0x170
<4> [339.133457] __lock_acquire+0x1637/0x2810
<4> [339.133461] lock_acquire+0xc9/0x300
<4> [339.133462] ? __kmalloc_cache_noprof+0x58/0x490
<4> [339.133465] ? _raw_spin_unlock_irqrestore+0x27/0x80
<4> [339.133467] ? xe_vm_add_ban_entry+0x64/0x2a0 [xe]
<4> [339.133553] fs_reclaim_acquire+0xc5/0x100
<4> [339.133554] ? __kmalloc_cache_noprof+0x58/0x490
<4> [339.133556] __kmalloc_cache_noprof+0x58/0x490
<4> [339.133558] xe_vm_add_ban_entry+0x64/0x2a0 [xe]
<4> [339.133637] ? xe_vm_add_ban_entry+0x64/0x2a0 [xe]
<4> [339.133712] ? trace_hardirqs_on+0x1e/0xe0
<4> [339.133714] ? queue_work_on+0x80/0xb0
<4> [339.133717] xe_guc_exec_queue_memory_cat_error_handler+0x19d/0x230 [xe]
<4> [339.133785] dequeue_one_g2h+0x349/0x900 [xe]
<4> [339.133850] ? receive_g2h+0x47/0x100 [xe]
<4> [339.133916] receive_g2h+0x4f/0x100 [xe]
<4> [339.133981] g2h_worker_func+0x15/0x20 [xe]
<4> [339.134045] process_one_work+0x21c/0x740
<4> [339.134049] worker_thread+0x1db/0x3c0
<4> [339.134050] ? __pfx_worker_thread+0x10/0x10
<4> [339.134052] kthread+0x10d/0x270
<4> [339.134053] ? __pfx_kthread+0x10/0x10
<4> [339.134054] ret_from_fork+0x44/0x70
<4> [339.134056] ? __pfx_kthread+0x10/0x10
<4> [339.134057] ret_from_fork_asm+0x1a/0x30
<4> [339.134060] </TASK>
<7> [340.009646] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [340.010250] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [340.010806] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_2
<7> [340.011370] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [340.011856] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [340.012360] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [340.012849] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [340.013336] xe 0000:00:02.0: [drm:intel_tc_port_reset_mode [xe]] Port F/TC#3: TC port mode reset (tbt-alt -> disconnected)
<7> [340.013353] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [340.013933] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [340.014489] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_2
<7> [340.015105] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [340.015105] xe 0000:00:02.0: [drm:intel_tc_port_reset_mode [xe]] Port E/TC#2: TC port mode reset (tbt-alt -> disconnected)
<7> [340.015598] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [340.016094] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [340.016568] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [340.017058] xe 0000:00:02.0: [drm:intel_tc_port_reset_mode [xe]] Port D/TC#1: TC port mode reset (tbt-alt -> disconnected)
<7> [340.071480] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [340.072074] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [340.072649] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_2
<7> [340.073245] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [340.073855] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [340.074358] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [340.074858] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [340.075348] xe 0000:00:02.0: [drm:intel_tc_port_reset_mode [xe]] Port G/TC#4: TC port mode reset (tbt-alt -> disconnected)
<3> [340.135014] xe 0000:00:02.0: [drm] *ERROR* GT0: GuC engine reset request failed on 0:0 because 0x00000000
<6> [340.135056] xe 0000:00:02.0: [drm] GT0: trying reset from xe_guc_exec_queue_reset_failure_handler [xe]
<6> [340.135515] xe 0000:00:02.0: [drm] GT0: reset queued
<7> [340.137020] xe 0000:00:02.0: [drm:xe_hw_engine_snapshot_capture [xe]] GT0: Proceeding with manual engine snapshot
<6> [340.137480] xe 0000:00:02.0: [drm] Xe device coredump has been created
<6> [340.137496] xe 0000:00:02.0: [drm] Check your /sys/class/drm/card0/device/devcoredump/data
<6> [340.137624] xe 0000:00:02.0: [drm] GT0: reset started
<6> [340.137908] xe 0000:00:02.0: [drm] exec queue reset detected
<7> [340.137836] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] GT0: Applying GT save-restore MMIOs
<7> [340.138411] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] GT0: REG[0x9424] = 0xfffffffc
<7> [340.138956] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] GT0: REG[0x9550] = 0x000003ff
<7> [340.139582] xe 0000:00:02.0: [drm:xe_wopcm_init [xe]] WOPCM: 2048K
<7> [340.140174] xe 0000:00:02.0: [drm:xe_wopcm_init [xe]] GuC WOPCM is already locked [592K, 1420K)
<7> [340.143032] xe 0000:00:02.0: [drm:xe_guc_ads_populate [xe]] GT0: ADS capture alloc size changed from 36864 to 32768
<7> [340.144178] xe 0000:00:02.0: [drm:__xe_guc_upload [xe]] GT0: load still in progress, timeouts = 0, freq = 1250MHz (req 1300MHz), status = 0x00000074 [0x3A/00]
<7> [340.144339] xe 0000:00:02.0: [drm:__xe_guc_upload [xe]] GT0: load still in progress, timeouts = 0, freq = 1250MHz (req 1300MHz), status = 0x800005EC [0x76/05]
<6> [340.152666] [IGT] xe_exec_reset: finished subtest cm-cat-error, FAIL
<6> [340.153086] [IGT] xe_exec_reset: exiting, ret=98
<6> [340.153443] Console: switching to colour frame buffer device 240x67
<7> [340.159440] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [340.159613] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [340.165196] xe 0000:00:02.0: [drm:drm_client_dev_restore] intel-fbdev: ret=0
<7> [340.165348] xe 0000:00:02.0: [drm:__xe_guc_upload [xe]] GT0: init took 21ms, freq = 1250MHz (req = 1300MHz), before = 1250MHz, status = 0x8002F0EC, timeouts = 0
<7> [340.165738] xe 0000:00:02.0: [drm:xe_guc_ct_enable [xe]] GT0: GuC CT communication channel enabled
Created at 2025-02-28 01:59:25