igt@xe_eudebug_online@reset-with-attention on bat-lnl-1@xkb-pr-main-259-472-bdfb513933c9e46b47cce1b2e6673956abb62fcb Test details

Detail	Value
Duration	0.66 seconds
Igt-Version	IGT-Version: 2.3-g105262d67 (x86_64) (Linux: 6.17.13-lgci-xe-xkb-pr-main-259-472-bdfb5+ x86_64)
Out	Using IGT_SRANDOM=1770724081 for randomisation Opened device: /dev/dri/card0 Using IGT_SRANDOM=1770724081 for randomisation Starting subtest: reset-with-attention Starting dynamic subtest: DRM_XE_ENGINE_CLASS_RENDER0 client running with pid 27953 client running with pid 27961 Stack trace: #0 ../lib/igt_core.c:2075 __igt_fail_assert() #1 ../tests/intel/xe_eudebug_online.c:1386 online_session_check() #2 ../tests/intel/xe_eudebug_online.c:1877 __igt_unique____real_main2671() #3 ../tests/intel/xe_eudebug_online.c:2671 main() #4 ../sysdeps/nptl/libc_start_call_main.h:74 __libc_start_call_main() #5 ../csu/libc-start.c:128 __libc_start_main@@GLIBC_2.34() #6 [_start+0x25] Dynamic subtest DRM_XE_ENGINE_CLASS_RENDER0: FAIL (0.660s) Subtest reset-with-attention: FAIL (0.662s)
Err	Starting subtest: reset-with-attention Starting dynamic subtest: DRM_XE_ENGINE_CLASS_RENDER0 (xe_eudebug_online:27951) CRITICAL: Test assertion failure function online_session_check, file ../tests/intel/xe_eudebug_online.c:1381: (xe_eudebug_online:27951) CRITICAL: Failed assertion: sum == data->thread_hit_count (xe_eudebug_online:27951) CRITICAL: error: 1024 != 512 Dynamic subtest DRM_XE_ENGINE_CLASS_RENDER0 failed. ** DEBUG (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: debugger: 27951 waiting for token 'client pid' (xe_eudebug_online:27951) xe/xe_eudebug-INFO: client running with pid 27953 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: added trigger 0x5c68630576f0 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: added trigger 0x5c6863057720 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: debugger connected to 27953 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: 27951 signalling token 'client run' with value '27953' (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: debugger: 27951 waiting for token 'client fini' (xe_eudebug_online:27951) [thread:27952] intel_allocator_simple-DEBUG: Using simple allocator (xe_eudebug_online:27951) [thread:27954] xe/xe_eudebug-DEBUG: delivering ack for event: (10) vm_bind_ufence(8):create\|ack: vm_bind_ref_seqno=7 (xe_eudebug_online:27951) [thread:27954] DEBUG: EVENT[11] eu-attention with reset; threads=448 client[1], exec_queue[1], lrc[1], bitmask_size[56] (xe_eudebug_online:27951) [thread:27954] DEBUG: bitmask[0] = 0xffffffffffffffff (xe_eudebug_online:27951) [thread:27954] DEBUG: bitmask[1] = 0xffffffffffffffff (xe_eudebug_online:27951) [thread:27954] DEBUG: bitmask[2] = 0xffffffffffffffff (xe_eudebug_online:27951) [thread:27954] DEBUG: bitmask[3] = 0xffffffffffffffff (xe_eudebug_online:27951) [thread:27954] DEBUG: bitmask[4] = 0xffffffffffffffff (xe_eudebug_online:27951) [thread:27954] DEBUG: bitmask[5] = 0xffffffffffffffff (xe_eudebug_online:27951) [thread:27954] DEBUG: bitmask[6] = 0xffffffffffffffff (xe_eudebug_online:27951) [thread:27952] intel_allocator_simple-DEBUG: <ial: 0x790314000c20, fd: 7> objects: 0, reserved_areas: 1 (xe_eudebug_online:27951) [thread:27952] intel_allocator_simple-DEBUG: <ial: 0x790314000c20, fd: 7> objects: 0, reserved_areas: 1 (xe_eudebug_online:27951) [thread:27954] DEBUG: EVENT[12] eu-attention with reset; threads=448 client[1], exec_queue[1], lrc[1], bitmask_size[56] (xe_eudebug_online:27951) [thread:27954] DEBUG: bitmask[0] = 0xffffffffffffffff (xe_eudebug_online:27951) [thread:27954] DEBUG: bitmask[1] = 0xffffffffffffffff (xe_eudebug_online:27951) [thread:27954] DEBUG: bitmask[2] = 0xffffffffffffffff (xe_eudebug_online:27951) [thread:27954] DEBUG: bitmask[3] = 0xffffffffffffffff (xe_eudebug_online:27951) [thread:27954] DEBUG: bitmask[4] = 0xffffffffffffffff (xe_eudebug_online:27951) [thread:27954] DEBUG: bitmask[5] = 0xffffffffffffffff (xe_eudebug_online:27951) [thread:27954] DEBUG: bitmask[6] = 0xffffffffffffffff (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: event log 'debugger' (984 bytes): (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (1) client(2):create: handle=1 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (2) metadata(9):create: client_handle=1, metadata_handle=1, type=0, len=16 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (3) metadata(9):create: client_handle=1, metadata_handle=2, type=1, len=16 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (4) vm(3):create: client_handle=1, handle=1 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (5) exec_queue(4):create: client_handle=1, vm_handle=1, exec_queue_handle=1, engine_class=0, exec_queue_width=1 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (6) exec_queue_placements(13):create: client_handle=1, vm_handle=1, exec_queue_handle=1, lrc_handle=1, num_placements=1, gt_id=0, mask=[rcs0 pad0] (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (7) vm_bind(6):state-change: client_handle=1, vm_handle=1, flags=0x1, num_binds=2 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (8) vm_bind_op(7):create: vm_bind_ref_seqno=7, addr=000000001b000000, range=12288 num_extensions=0 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (9) vm_bind_op(7):create: vm_bind_ref_seqno=7, addr=000000001a000000, range=8192 num_extensions=0 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (10) vm_bind_ufence(8):create\|ack: vm_bind_ref_seqno=7 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (11) attention(5):state-change: client_handle=1, exec_queue_handle=1, lrc_handle=1, bitmask_size=56 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (12) attention(5):state-change: client_handle=1, exec_queue_handle=1, lrc_handle=1, bitmask_size=56 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (13) exec_queue(4):destroy: client_handle=1, vm_handle=1, exec_queue_handle=1, engine_class=0, exec_queue_width=1 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (14) vm(3):destroy: client_handle=1, handle=1 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (15) metadata(9):destroy: client_handle=1, metadata_handle=1, type=0, len=16 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (16) metadata(9):destroy: client_handle=1, metadata_handle=2, type=1, len=16 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (17) client(2):destroy: handle=1 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: event log 'client' (552 bytes): (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (1) client(2):create: handle=7 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (2) metadata(9):create: client_handle=7, metadata_handle=1, type=0, len=16 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (3) metadata(9):create: client_handle=7, metadata_handle=2, type=1, len=16 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (4) vm(3):create: client_handle=7, handle=1 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (5) exec_queue(4):create: client_handle=7, vm_handle=1, exec_queue_handle=1, engine_class=0, exec_queue_width=1 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (6) exec_queue_placements(13):create: client_handle=7, vm_handle=1, exec_queue_handle=1, lrc_handle=0, num_placements=1, gt_id=0, mask=[rcs0 pad0] (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (7) exec_queue(4):destroy: client_handle=7, vm_handle=1, exec_queue_handle=1, engine_class=0, exec_queue_width=1 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (8) vm(3):destroy: client_handle=7, handle=1 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (9) metadata(9):destroy: client_handle=7, metadata_handle=1, type=0, len=16 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (10) metadata(9):destroy: client_handle=7, metadata_handle=2, type=1, len=16 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (11) client(2):destroy: handle=7 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: 27951 signalling token 'client stop' with value '27953' (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: debugger: 27951 waiting for token 'client pid' (xe_eudebug_online:27951) xe/xe_eudebug-INFO: client running with pid 27961 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: added trigger 0x5c6863058bf0 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: added trigger 0x5c6863058c80 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: debugger connected to 27961 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: 27951 signalling token 'client run' with value '27961' (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: debugger: 27951 waiting for token 'client fini' (xe_eudebug_online:27951) [thread:27952] intel_allocator_simple-DEBUG: Using simple allocator (xe_eudebug_online:27951) [thread:27962] xe/xe_eudebug-DEBUG: delivering ack for event: (10) vm_bind_ufence(8):create\|ack: vm_bind_ref_seqno=7 (xe_eudebug_online:27951) [thread:27962] DEBUG: EU CONTROL[12]: stopped (xe_eudebug_online:27951) [thread:27962] DEBUG: EU CONTROL[14]: resume (xe_eudebug_online:27951) [thread:27962] DEBUG: EU CONTROL[16]: stopped (xe_eudebug_online:27951) [thread:27952] intel_allocator_simple-DEBUG: <ial: 0x790314001140, fd: 7> objects: 0, reserved_areas: 1 (xe_eudebug_online:27951) [thread:27952] intel_allocator_simple-DEBUG: <ial: 0x790314001140, fd: 7> objects: 0, reserved_areas: 1 (xe_eudebug_online:27951) [thread:27962] DEBUG: EU CONTROL[18]: resume (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: event log 'debugger' (1208 bytes): (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (1) client(2):create: handle=1 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (2) metadata(9):create: client_handle=1, metadata_handle=1, type=0, len=16 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (3) metadata(9):create: client_handle=1, metadata_handle=2, type=1, len=16 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (4) vm(3):create: client_handle=1, handle=1 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (5) exec_queue(4):create: client_handle=1, vm_handle=1, exec_queue_handle=1, engine_class=0, exec_queue_width=1 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (6) exec_queue_placements(13):create: client_handle=1, vm_handle=1, exec_queue_handle=1, lrc_handle=1, num_placements=1, gt_id=0, mask=[rcs0 pad0] (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (7) vm_bind(6):state-change: client_handle=1, vm_handle=1, flags=0x1, num_binds=2 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (8) vm_bind_op(7):create: vm_bind_ref_seqno=7, addr=000000001b000000, range=12288 num_extensions=0 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (9) vm_bind_op(7):create: vm_bind_ref_seqno=7, addr=000000001a000000, range=8192 num_extensions=0 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (10) vm_bind_ufence(8):create\|ack: vm_bind_ref_seqno=7 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (11) attention(5):state-change: client_handle=1, exec_queue_handle=1, lrc_handle=1, bitmask_size=56 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (13) attention(5):state-change: client_handle=1, exec_queue_handle=1, lrc_handle=1, bitmask_size=56 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (15) attention(5):state-change: client_handle=1, exec_queue_handle=1, lrc_handle=1, bitmask_size=56 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (17) attention(5):state-change: client_handle=1, exec_queue_handle=1, lrc_handle=1, bitmask_size=56 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (19) exec_queue(4):destroy: client_handle=1, vm_handle=1, exec_queue_handle=1, engine_class=0, exec_queue_width=1 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (20) vm(3):destroy: client_handle=1, handle=1 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (21) metadata(9):destroy: client_handle=1, metadata_handle=1, type=0, len=16 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (22) metadata(9):destroy: client_handle=1, metadata_handle=2, type=1, len=16 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (23) client(2):destroy: handle=1 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: event log 'client' (552 bytes): (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (1) client(2):create: handle=7 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (2) metadata(9):create: client_handle=7, metadata_handle=1, type=0, len=16 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (3) metadata(9):create: client_handle=7, metadata_handle=2, type=1, len=16 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (4) vm(3):create: client_handle=7, handle=1 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (5) exec_queue(4):create: client_handle=7, vm_handle=1, exec_queue_handle=1, engine_class=0, exec_queue_width=1 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (6) exec_queue_placements(13):create: client_handle=7, vm_handle=1, exec_queue_handle=1, lrc_handle=0, num_placements=1, gt_id=0, mask=[rcs0 pad0] (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (7) exec_queue(4):destroy: client_handle=7, vm_handle=1, exec_queue_handle=1, engine_class=0, exec_queue_width=1 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (8) vm(3):destroy: client_handle=7, handle=1 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (9) metadata(9):destroy: client_handle=7, metadata_handle=1, type=0, len=16 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (10) metadata(9):destroy: client_handle=7, metadata_handle=2, type=1, len=16 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (11) client(2):destroy: handle=7 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: client: 7 -> 1 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: comparing client 1 vs debugger 1 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: comparing client 2 vs debugger 2 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: comparing client 3 vs debugger 3 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: comparing client 4 vs debugger 4 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: comparing client 5 vs debugger 5 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: comparing client 6 vs debugger 6 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: comparing client 7 vs debugger 19 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: comparing client 8 vs debugger 20 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: comparing client 9 vs debugger 21 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: comparing client 10 vs debugger 22 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: comparing client 11 vs debugger 23 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: client: 1 -> 7 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: comparing debugger 1 vs client 1 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: comparing debugger 2 vs client 2 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: comparing debugger 3 vs client 3 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: comparing debugger 4 vs client 4 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: comparing debugger 5 vs client 5 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: comparing debugger 6 vs client 6 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: comparing debugger 19 vs client 7 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: comparing debugger 20 vs client 8 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: comparing debugger 21 vs client 9 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: comparing debugger 22 vs client 10 (xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: comparing debugger 23 vs client 11 (xe_eudebug_online:27951) CRITICAL: Test assertion failure function online_session_check, file ../tests/intel/xe_eudebug_online.c:1381: (xe_eudebug_online:27951) CRITICAL: Failed assertion: sum == data->thread_hit_count (xe_eudebug_online:27951) CRITICAL: error: 1024 != 512 (xe_eudebug_online:27951) igt_core-INFO: Stack trace: (xe_eudebug_online:27951) igt_core-INFO: #0 ../lib/igt_core.c:2075 __igt_fail_assert() (xe_eudebug_online:27951) igt_core-INFO: #1 ../tests/intel/xe_eudebug_online.c:1386 online_session_check() (xe_eudebug_online:27951) igt_core-INFO: #2 ../tests/intel/xe_eudebug_online.c:1877 __igt_unique____real_main2671() (xe_eudebug_online:27951) igt_core-INFO: #3 ../tests/intel/xe_eudebug_online.c:2671 main() (xe_eudebug_online:27951) igt_core-INFO: #4 ../sysdeps/nptl/libc_start_call_main.h:74 __libc_start_call_main() (xe_eudebug_online:27951) igt_core-INFO: #5 ../csu/libc-start.c:128 __libc_start_main@@GLIBC_2.34() (xe_eudebug_online:27951) igt_core-INFO: #6 [_start+0x25] END ** Dynamic subtest DRM_XE_ENGINE_CLASS_RENDER0: FAIL (0.660s) Subtest reset-with-attention: FAIL (0.662s)
Dmesg	<6> [366.213619] Console: switching to colour dummy device 80x25 <6> [366.213871] [IGT] xe_eudebug_online: executing <6> [366.218516] [IGT] xe_eudebug_online: starting subtest reset-with-attention <6> [366.218796] [IGT] xe_eudebug_online: starting dynamic subtest DRM_XE_ENGINE_CLASS_RENDER0 <7> [366.222322] xe 0000:00:02.0: [drm:prelim_xe_eudebug_connect_ioctl [xe]] eudbg: 3277:0: (27951/27951) -> (27953/27953): "connected session 3277" <7> [366.222518] xe 0000:00:02.0: [drm:discovery_work_fn [xe]] eudbg: 3277:0: (8399/8399) -> (27953/27953): "Discovery start for 3277\n" <7> [366.222638] xe 0000:00:02.0: [drm:discovery_work_fn [xe]] eudbg: 3277:0: (8399/8399) -> (27953/27953): "Discovery end for 3277\n" <7> [366.223410] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3277:1: (27954/27951) -> (27953/27953): "event read=0: type=2, flags=0x1, seqno=1" <7> [366.224459] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3277:3: (27954/27951) -> (27953/27953): "event read=0: type=9, flags=0x1, seqno=2" <7> [366.224598] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3277:3: (27954/27951) -> (27953/27953): "event read=0: type=9, flags=0x1, seqno=3" <7> [366.225554] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3277:4: (27954/27951) -> (27953/27953): "event read=0: type=3, flags=0x1, seqno=4" <7> [366.226426] xe 0000:00:02.0: [drm:xe_lrc_create [xe]] read CTX_CONTEXT_CONTROL: 0xffff0088 <7> [366.226507] xe 0000:00:02.0: [drm:xe_lrc_create [xe]] written CTX_CONTEXT_CONTROL: 0xffff0088 <7> [366.226633] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3277:6: (27954/27951) -> (27953/27953): "event read=0: type=4, flags=0x1, seqno=5" <7> [366.226727] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3277:6: (27954/27951) -> (27953/27953): "event read=0: type=13, flags=0x1, seqno=6" <7> [366.227883] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3277:9: (27954/27951) -> (27953/27953): "event read=0: type=6, flags=0x4, seqno=7" <7> [366.227917] xe 0000:00:02.0: [drm:prelim_xe_eudebug_vm_bind_ufence [xe]] eudbg: 3277:10: (8399/8399) -> (27953/27953): "ACK: seqno=10: tracking started" <7> [366.228013] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3277:10: (27954/27951) -> (27953/27953): "event read=0: type=7, flags=0x1, seqno=8" <7> [366.228117] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3277:10: (27954/27951) -> (27953/27953): "event read=0: type=7, flags=0x1, seqno=9" <7> [366.228216] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3277:10: (27954/27951) -> (27953/27953): "event read=0: type=8, flags=0x9, seqno=10" <7> [366.228470] xe 0000:00:02.0: [drm:handle_ack [xe]] eudbg: 3277:10: (27954/27951) -> (27953/27953): "ACK: seqno=10: signalled by 10 (debugger) (held 554us)" <7> [366.228581] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3277:10: (27954/27951) -> (27953/27953): "ioctl cmd=EVENT_ACK ret=0\n" <7> [366.325522] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone RCU_DEBUG_1 = 0x00000280 <7> [366.325863] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine rcs0: runalone=active, context=active <7> [366.326142] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone engine rcs0 found <7> [366.326466] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine ccs0: runalone=inactive, context=inactive <7> [366.326964] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone RCU_DEBUG_1 = 0x00000280 <7> [366.327116] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3277:11: (27954/27951) -> (27953/27953): "event read=0: type=5, flags=0x4, seqno=11" <7> [366.327226] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine rcs0: runalone=active, context=active <7> [366.327596] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone engine rcs0 found <7> [366.327880] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine ccs0: runalone=inactive, context=inactive <7> [366.328195] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone RCU_DEBUG_1 = 0x00000280 <7> [366.328506] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine rcs0: runalone=active, context=active <7> [366.328803] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone engine rcs0 found <7> [366.329092] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine ccs0: runalone=inactive, context=inactive <6> [366.338456] xe 0000:00:02.0: [drm] Tile0: GT0: trying reset from force_reset_show [xe] <6> [366.338756] xe 0000:00:02.0: [drm] Tile0: GT0: reset queued <6> [366.338921] xe 0000:00:02.0: [drm] Tile0: GT0: reset started <7> [366.338959] xe 0000:00:02.0: [drm:guc_ct_change_state [xe]] Tile0: GT0: GuC CT communication channel stopped <6> [366.339347] xe 0000:00:02.0: [drm] exec queue reset detected <7> [366.339457] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: Applying GT save-restore MMIOs <7> [366.339710] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x4148] = 0x00000000 <7> [366.339957] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x8828] = 0x00800000 <7> [366.340166] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0xb104] = 0x08104440 <7> [366.340739] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0xb108] = 0x30200000 <7> [366.340786] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3277:14: (27954/27951) -> (27953/27953): "event read=0: type=5, flags=0x4, seqno=12" <7> [366.341019] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0xb154] = 0x00020000 <7> [366.341314] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0xb158] = 0x0000007f <7> [366.341589] xe 0000:00:02.0: [drm:xe_wopcm_init [xe]] WOPCM: 2048K <7> [366.341943] xe 0000:00:02.0: [drm:xe_wopcm_init [xe]] GuC WOPCM is already locked [6144K, 832K) <7> [366.342356] xe 0000:00:02.0: [drm:guc_ct_change_state [xe]] Tile0: GT0: GuC CT communication channel disabled <7> [366.344086] xe 0000:00:02.0: [drm:xe_guc_ads_populate [xe]] Tile0: GT0: Updated ADS capture size 20480 (was 45056) <6> [366.348159] xe 0000:00:02.0: [drm] Tile0: GT0: trying reset from force_reset_show [xe] <7> [366.349134] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3277:17: (27954/27951) -> (27953/27953): "event read=0: type=4, flags=0x2, seqno=13" <7> [366.349274] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3277:17: (27954/27951) -> (27953/27953): "event read=0: type=3, flags=0x2, seqno=14" <7> [366.349388] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3277:17: (27954/27951) -> (27953/27953): "event read=0: type=9, flags=0x2, seqno=15" <7> [366.349498] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3277:17: (27954/27951) -> (27953/27953): "event read=0: type=9, flags=0x2, seqno=16" <7> [366.349605] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3277:17: (27954/27951) -> (27953/27953): "event read=0: type=2, flags=0x2, seqno=17" <7> [366.355559] xe 0000:00:02.0: [drm:guc_wait_ucode [xe]] Tile0: GT0: GuC load: init took 10ms, freq = 1850MHz (req = 1850MHz), before = 1850MHz, status = 0x8002F034 <7> [366.355915] xe 0000:00:02.0: [drm:guc_ct_change_state [xe]] Tile0: GT0: GuC CT communication channel enabled <7> [366.356954] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: flag:0x1 <7> [366.357062] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: mocs entries: 16 <7> [366.357140] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[0] 0x4000 0xc <7> [366.357216] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[1] 0x4004 0x10c <7> [366.357318] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[2] 0x4008 0x130 <7> [366.357389] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[3] 0x400c 0x13c <7> [366.357457] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[4] 0x4010 0x100 <7> [366.357524] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[5] 0x4014 0x100 <7> [366.357591] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[6] 0x4018 0x100 <7> [366.357657] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[7] 0x401c 0x100 <7> [366.357721] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[8] 0x4020 0x100 <7> [366.357785] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[9] 0x4024 0x100 <7> [366.357850] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[10] 0x4028 0x100 <7> [366.357915] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[11] 0x402c 0x100 <7> [366.357980] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[12] 0x4030 0x100 <7> [366.358047] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[13] 0x4034 0x100 <7> [366.358114] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[14] 0x4038 0x100 <7> [366.358182] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[15] 0x403c 0x100 <7> [366.358259] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: Applying rcs0 save-restore MMIOs <7> [366.358343] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x2050] = 0x10001000 <7> [366.358430] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x20c4] = 0x3f7e0306 <7> [366.358512] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x20d4] = 0xc080c080 <7> [366.358592] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x24d0] = 0x1000dafc <7> [366.358666] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x24d4] = 0x1000db01 <7> [366.358739] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x24d8] = 0x0000db1c <7> [366.358815] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0xe194] = 0x00400040 <7> [366.358901] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0xe400] = 0x00000095 <7> [366.358979] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0xe48c] = 0x02000200 <7> [366.359055] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0xe49c] = 0x40004000 <7> [366.359134] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0xe4c4] = 0x08400840 <7> [366.359211] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0xe4f0] = 0x00020002 <7> [366.359313] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0xe7c8] = 0x00002000 <7> [366.359406] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0xe7cc] = 0x00009000 <7> [366.359490] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: Applying bcs0 save-restore MMIOs <7> [366.359568] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x22050] = 0x10001000 <7> [366.359645] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x220c4] = 0x3f7e0306 <7> [366.359731] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x220d4] = 0xc080c080 <7> [366.359815] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: Applying bcs8 save-restore MMIOs <7> [366.359899] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x3ee050] = 0x10001000 <7> [366.359984] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x3ee0c4] = 0x3f7e0306 <7> [366.360065] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x3ee0d4] = 0xc080c080 <7> [366.360150] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: Applying ccs0 save-restore MMIOs <7> [366.360233] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x1a050] = 0x10001000 <7> [366.360353] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x1a0c4] = 0x3f7e0306 <7> [366.360437] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x1a0d4] = 0xc080c080 <7> [366.360532] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x1a4d0] = 0x1000dafc <7> [366.360627] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x1a4d4] = 0x1000db01 <7> [366.360710] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x1a4d8] = 0x0000db1c <6> [366.360845] xe 0000:00:02.0: [drm] Tile0: GT0: reset done <7> [366.450808] xe 0000:00:02.0: [drm:xe_eudebug_detach [xe]] eudbg: 3277:17:disconnected (27951/27951) -> (27953/27953): "session 3277 detached with 0" <7> [366.451176] xe 0000:00:02.0: [drm:xe_eudebug_release [xe]] eudbg: 3277:17:disconnected (27951/27951) -> (27953/27953): "Session closed (0)" <7> [366.478204] xe 0000:00:02.0: [drm:prelim_xe_eudebug_connect_ioctl [xe]] eudbg: 3278:0: (27951/27951) -> (27961/27961): "connected session 3278" <7> [366.478677] xe 0000:00:02.0: [drm:discovery_work_fn [xe]] eudbg: 3278:0: (8400/8400) -> (27961/27961): "Discovery start for 3278\n" <7> [366.479082] xe 0000:00:02.0: [drm:discovery_work_fn [xe]] eudbg: 3278:0: (8400/8400) -> (27961/27961): "Discovery end for 3278\n" <7> [366.480166] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:1: (27962/27951) -> (27961/27961): "event read=0: type=2, flags=0x1, seqno=1" <7> [366.481233] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:2: (27962/27951) -> (27961/27961): "event read=0: type=9, flags=0x1, seqno=2" <7> [366.481698] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:3: (27962/27951) -> (27961/27961): "event read=0: type=9, flags=0x1, seqno=3" <7> [366.483373] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:4: (27962/27951) -> (27961/27961): "event read=0: type=3, flags=0x1, seqno=4" <7> [366.484888] xe 0000:00:02.0: [drm:xe_lrc_create [xe]] read CTX_CONTEXT_CONTROL: 0xffff0088 <7> [366.485229] xe 0000:00:02.0: [drm:xe_lrc_create [xe]] written CTX_CONTEXT_CONTROL: 0xffff0088 <7> [366.485758] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:6: (27962/27951) -> (27961/27961): "event read=0: type=4, flags=0x1, seqno=5" <7> [366.486121] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:6: (27962/27951) -> (27961/27961): "event read=0: type=13, flags=0x1, seqno=6" <7> [366.489334] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:9: (27962/27951) -> (27961/27961): "event read=0: type=6, flags=0x4, seqno=7" <7> [366.489413] xe 0000:00:02.0: [drm:prelim_xe_eudebug_vm_bind_ufence [xe]] eudbg: 3278:10: (8400/8400) -> (27961/27961): "ACK: seqno=10: tracking started" <7> [366.489682] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:10: (27962/27951) -> (27961/27961): "event read=0: type=7, flags=0x1, seqno=8" <7> [366.490057] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:10: (27962/27951) -> (27961/27961): "event read=0: type=7, flags=0x1, seqno=9" <7> [366.490436] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:10: (27962/27951) -> (27961/27961): "event read=0: type=8, flags=0x9, seqno=10" <7> [366.490905] xe 0000:00:02.0: [drm:handle_ack [xe]] eudbg: 3278:10: (27962/27951) -> (27961/27961): "ACK: seqno=10: signalled by 10 (debugger) (held 1495us)" <7> [366.491274] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:10: (27962/27951) -> (27961/27961): "ioctl cmd=EVENT_ACK ret=0\n" <7> [366.581494] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone RCU_DEBUG_1 = 0x00000280 <7> [366.581827] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine rcs0: runalone=active, context=active <7> [366.582102] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone engine rcs0 found <7> [366.582418] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine ccs0: runalone=inactive, context=inactive <7> [366.582916] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone RCU_DEBUG_1 = 0x00000280 <7> [366.583069] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:11: (27962/27951) -> (27961/27961): "event read=0: type=5, flags=0x4, seqno=11" <7> [366.583195] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine rcs0: runalone=active, context=active <7> [366.583520] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone engine rcs0 found <7> [366.583504] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:11: (27962/27951) -> (27961/27961): "eu_control: client_handle=1, cmd=1, flags=0x0, exec_queue_handle=1, bitmask_size=56\n" <7> [366.583794] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine ccs0: runalone=inactive, context=inactive <7> [366.583901] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone RCU_DEBUG_1 = 0x00000280 <7> [366.584130] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone RCU_DEBUG_1 = 0x00000280 <7> [366.584212] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine rcs0: runalone=active, context=active <7> [366.584441] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine rcs0: runalone=active, context=active <7> [366.584615] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone engine rcs0 found <7> [366.584738] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone engine rcs0 found <7> [366.584911] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine ccs0: runalone=inactive, context=inactive <7> [366.585008] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine ccs0: runalone=inactive, context=inactive <7> [366.585400] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:13: (27962/27951) -> (27961/27961): "eu_control: client_handle=1, cmd=1, flags=0x0, exec_queue_handle=1, bitmask_size=56 ret=0\n" <7> [366.585683] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:13: (27962/27951) -> (27961/27961): "ioctl cmd=EU_CONTROL ret=0\n" <7> [366.586133] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:13: (27962/27951) -> (27961/27961): "eu_control: client_handle=1, cmd=2, flags=0x0, exec_queue_handle=1, bitmask_size=56\n" <7> [366.586508] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone RCU_DEBUG_1 = 0x00000280 <7> [366.586786] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine rcs0: runalone=active, context=active <7> [366.587089] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone engine rcs0 found <7> [366.587390] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine ccs0: runalone=inactive, context=inactive <7> [366.587700] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3278:13: (27962/27951) -> (27961/27961): "EU_ATT_CLR: (0:0:0:0): 0xffffffff\n" <7> [366.588019] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3278:13: (27962/27951) -> (27961/27961): "EU_ATT_CLR: (0:0:0:1): 0xffffffff\n" <7> [366.588306] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3278:13: (27962/27951) -> (27961/27961): "EU_ATT_CLR: (0:1:0:0): 0xffffffff\n" <7> [366.588578] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3278:13: (27962/27951) -> (27961/27961): "EU_ATT_CLR: (0:1:0:1): 0xffffffff\n" <7> [366.588841] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3278:13: (27962/27951) -> (27961/27961): "EU_ATT_CLR: (0:2:0:0): 0xffffffff\n" <7> [366.589104] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3278:13: (27962/27951) -> (27961/27961): "EU_ATT_CLR: (0:2:0:1): 0xffffffff\n" <7> [366.589450] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3278:13: (27962/27951) -> (27961/27961): "EU_ATT_CLR: (0:3:0:0): 0xffffffff\n" <7> [366.589711] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3278:13: (27962/27951) -> (27961/27961): "EU_ATT_CLR: (0:3:0:1): 0xffffffff\n" <7> [366.589989] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3278:13: (27962/27951) -> (27961/27961): "EU_ATT_CLR: (1:0:0:0): 0xffffffff\n" <7> [366.590280] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3278:13: (27962/27951) -> (27961/27961): "EU_ATT_CLR: (1:0:0:1): 0xffffffff\n" <7> [366.590605] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3278:13: (27962/27951) -> (27961/27961): "EU_ATT_CLR: (1:1:0:0): 0xffffffff\n" <7> [366.590883] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3278:13: (27962/27951) -> (27961/27961): "EU_ATT_CLR: (1:1:0:1): 0xffffffff\n" <7> [366.591151] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3278:13: (27962/27951) -> (27961/27961): "EU_ATT_CLR: (1:2:0:0): 0xffffffff\n" <7> [366.591457] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3278:13: (27962/27951) -> (27961/27961): "EU_ATT_CLR: (1:2:0:1): 0xffffffff\n" <7> [366.591735] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:14: (27962/27951) -> (27961/27961): "eu_control: client_handle=1, cmd=2, flags=0x0, exec_queue_handle=1, bitmask_size=56 ret=0\n" <7> [366.592002] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:14: (27962/27951) -> (27961/27961): "ioctl cmd=EU_CONTROL ret=0\n" <7> [366.592292] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:14: (27962/27951) -> (27961/27961): "event read=0: type=5, flags=0x4, seqno=13" <7> [366.693371] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone RCU_DEBUG_1 = 0x00000280 <7> [366.693803] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine rcs0: runalone=active, context=active <7> [366.694162] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone engine rcs0 found <7> [366.694558] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine ccs0: runalone=inactive, context=inactive <7> [366.695108] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone RCU_DEBUG_1 = 0x00000280 <7> [366.695155] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:15: (27962/27951) -> (27961/27961): "event read=0: type=5, flags=0x4, seqno=15" <7> [366.695478] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine rcs0: runalone=active, context=active <7> [366.695657] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:15: (27962/27951) -> (27961/27961): "eu_control: client_handle=1, cmd=1, flags=0x0, exec_queue_handle=1, bitmask_size=56\n" <7> [366.695828] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone engine rcs0 found <7> [366.696076] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone RCU_DEBUG_1 = 0x00000280 <7> [366.696153] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine ccs0: runalone=inactive, context=inactive <7> [366.696468] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine rcs0: runalone=active, context=active <7> [366.696534] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone RCU_DEBUG_1 = 0x00000280 <7> [366.696810] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone engine rcs0 found <7> [366.696837] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine rcs0: runalone=active, context=active <7> [366.697140] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone engine rcs0 found <7> [366.697132] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine ccs0: runalone=inactive, context=inactive <7> [366.697477] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine ccs0: runalone=inactive, context=inactive <7> [366.697661] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:16: (27962/27951) -> (27961/27961): "eu_control: client_handle=1, cmd=1, flags=0x0, exec_queue_handle=1, bitmask_size=56 ret=0\n" <7> [366.698005] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:17: (27962/27951) -> (27961/27961): "ioctl cmd=EU_CONTROL ret=0\n" <7> [366.698402] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:17: (27962/27951) -> (27961/27961): "eu_control: client_handle=1, cmd=2, flags=0x0, exec_queue_handle=1, bitmask_size=56\n" <7> [366.698781] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone RCU_DEBUG_1 = 0x00000280 <7> [366.699120] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine rcs0: runalone=active, context=active <7> [366.699459] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone engine rcs0 found <7> [366.699756] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine ccs0: runalone=inactive, context=inactive <7> [366.700134] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3278:17: (27962/27951) -> (27961/27961): "EU_ATT_CLR: (0:0:0:0): 0xffffffff\n" <7> [366.700470] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3278:17: (27962/27951) -> (27961/27961): "EU_ATT_CLR: (0:0:0:1): 0xffffffff\n" <7> [366.700775] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:18: (27962/27951) -> (27961/27961): "eu_control: client_handle=1, cmd=2, flags=0x0, exec_queue_handle=1, bitmask_size=56 ret=0\n" <7> [366.701067] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:18: (27962/27951) -> (27961/27961): "ioctl cmd=EU_CONTROL ret=0\n" <7> [366.701410] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:18: (27962/27951) -> (27961/27961): "event read=0: type=5, flags=0x4, seqno=17" <7> [366.701702] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:20: (27962/27951) -> (27961/27961): "event read=0: type=4, flags=0x2, seqno=19" <7> [366.702054] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:20: (27962/27951) -> (27961/27961): "event read=0: type=3, flags=0x2, seqno=20" <7> [366.703211] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:21: (27962/27951) -> (27961/27961): "event read=0: type=9, flags=0x2, seqno=21" <7> [366.703675] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:22: (27962/27951) -> (27961/27961): "event read=0: type=9, flags=0x2, seqno=22" <7> [366.704026] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:23: (27962/27951) -> (27961/27961): "event read=0: type=2, flags=0x2, seqno=23" <6> [366.878716] [IGT] xe_eudebug_online: finished subtest DRM_XE_ENGINE_CLASS_RENDER0, FAIL <6> [366.880977] [IGT] xe_eudebug_online: finished subtest reset-with-attention, FAIL <7> [366.882947] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off <7> [366.883701] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00 <7> [366.901182] xe 0000:00:02.0: [drm:drm_client_dev_restore] fbdev: ret=0 <6> [366.902024] [IGT] xe_eudebug_online: exiting, ret=98 <6> [366.917642] Console: switching to colour frame buffer device 180x56 <7> [366.945914] xe 0000:00:02.0: [drm:xe_eudebug_detach [xe]] eudbg: 3278:23:disconnected (27951/27951) -> (27961/27961): "session 3278 detached with 0" <7> [366.946188] xe 0000:00:02.0: [drm:xe_eudebug_release [xe]] eudbg: 3278:23:disconnected (27951/27951) -> (27961/27961): "Session closed (0)" <7> [366.951335] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off <7> [366.951720] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6 <7> [366.952047] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02

Detail

Value

Duration

0.66 seconds

Igt-Version

IGT-Version: 2.3-g105262d67 (x86_64) (Linux: 6.17.13-lgci-xe-xkb-pr-main-259-472-bdfb5+ x86_64)

Out

Using IGT_SRANDOM=1770724081 for randomisation
Opened device: /dev/dri/card0
Using IGT_SRANDOM=1770724081 for randomisation
Starting subtest: reset-with-attention
Starting dynamic subtest: DRM_XE_ENGINE_CLASS_RENDER0
client running with pid 27953
client running with pid 27961
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/intel/xe_eudebug_online.c:1386 online_session_check()
  #2 ../tests/intel/xe_eudebug_online.c:1877 __igt_unique____real_main2671()
  #3 ../tests/intel/xe_eudebug_online.c:2671 main()
  #4 ../sysdeps/nptl/libc_start_call_main.h:74 __libc_start_call_main()
  #5 ../csu/libc-start.c:128 __libc_start_main@@GLIBC_2.34()
  #6 [_start+0x25]
Dynamic subtest DRM_XE_ENGINE_CLASS_RENDER0: FAIL (0.660s)
Subtest reset-with-attention: FAIL (0.662s)

Err

Starting subtest: reset-with-attention
Starting dynamic subtest: DRM_XE_ENGINE_CLASS_RENDER0
(xe_eudebug_online:27951) CRITICAL: Test assertion failure function online_session_check, file ../tests/intel/xe_eudebug_online.c:1381:
(xe_eudebug_online:27951) CRITICAL: Failed assertion: sum == data->thread_hit_count
(xe_eudebug_online:27951) CRITICAL: error: 1024 != 512
Dynamic subtest DRM_XE_ENGINE_CLASS_RENDER0 failed.
**** DEBUG ****
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: debugger: 27951 waiting for token 'client pid'
(xe_eudebug_online:27951) xe/xe_eudebug-INFO: client running with pid 27953
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: added trigger 0x5c68630576f0
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: added trigger 0x5c6863057720
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: debugger connected to 27953
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: 27951 signalling token 'client run' with value '27953'
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: debugger: 27951 waiting for token 'client fini'
(xe_eudebug_online:27951) [thread:27952] intel_allocator_simple-DEBUG: Using simple allocator
(xe_eudebug_online:27951) [thread:27954] xe/xe_eudebug-DEBUG: delivering ack for event: (10) vm_bind_ufence(8):create|ack: vm_bind_ref_seqno=7
(xe_eudebug_online:27951) [thread:27954] DEBUG: EVENT[11] eu-attention with reset; threads=448 client[1], exec_queue[1], lrc[1], bitmask_size[56]
(xe_eudebug_online:27951) [thread:27954] DEBUG: bitmask[0] = 0xffffffffffffffff
(xe_eudebug_online:27951) [thread:27954] DEBUG: bitmask[1] = 0xffffffffffffffff
(xe_eudebug_online:27951) [thread:27954] DEBUG: bitmask[2] = 0xffffffffffffffff
(xe_eudebug_online:27951) [thread:27954] DEBUG: bitmask[3] = 0xffffffffffffffff
(xe_eudebug_online:27951) [thread:27954] DEBUG: bitmask[4] = 0xffffffffffffffff
(xe_eudebug_online:27951) [thread:27954] DEBUG: bitmask[5] = 0xffffffffffffffff
(xe_eudebug_online:27951) [thread:27954] DEBUG: bitmask[6] = 0xffffffffffffffff
(xe_eudebug_online:27951) [thread:27952] intel_allocator_simple-DEBUG: <ial: 0x790314000c20, fd: 7> objects: 0, reserved_areas: 1
(xe_eudebug_online:27951) [thread:27952] intel_allocator_simple-DEBUG: <ial: 0x790314000c20, fd: 7> objects: 0, reserved_areas: 1
(xe_eudebug_online:27951) [thread:27954] DEBUG: EVENT[12] eu-attention with reset; threads=448 client[1], exec_queue[1], lrc[1], bitmask_size[56]
(xe_eudebug_online:27951) [thread:27954] DEBUG: bitmask[0] = 0xffffffffffffffff
(xe_eudebug_online:27951) [thread:27954] DEBUG: bitmask[1] = 0xffffffffffffffff
(xe_eudebug_online:27951) [thread:27954] DEBUG: bitmask[2] = 0xffffffffffffffff
(xe_eudebug_online:27951) [thread:27954] DEBUG: bitmask[3] = 0xffffffffffffffff
(xe_eudebug_online:27951) [thread:27954] DEBUG: bitmask[4] = 0xffffffffffffffff
(xe_eudebug_online:27951) [thread:27954] DEBUG: bitmask[5] = 0xffffffffffffffff
(xe_eudebug_online:27951) [thread:27954] DEBUG: bitmask[6] = 0xffffffffffffffff
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: event log 'debugger' (984 bytes):
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (1)       client(2):create: handle=1
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (2)     metadata(9):create: client_handle=1, metadata_handle=1, type=0, len=16
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (3)     metadata(9):create: client_handle=1, metadata_handle=2, type=1, len=16
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (4)           vm(3):create: client_handle=1, handle=1
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (5)   exec_queue(4):create: client_handle=1, vm_handle=1, exec_queue_handle=1, engine_class=0, exec_queue_width=1
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (6) exec_queue_placements(13):create: client_handle=1, vm_handle=1, exec_queue_handle=1, lrc_handle=1, num_placements=1, gt_id=0, mask=[rcs0 pad0] 
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (7)      vm_bind(6):state-change: client_handle=1, vm_handle=1, flags=0x1, num_binds=2
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (8)   vm_bind_op(7):create: vm_bind_ref_seqno=7, addr=000000001b000000, range=12288 num_extensions=0
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (9)   vm_bind_op(7):create: vm_bind_ref_seqno=7, addr=000000001a000000, range=8192 num_extensions=0
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (10) vm_bind_ufence(8):create|ack: vm_bind_ref_seqno=7
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (11)    attention(5):state-change: client_handle=1, exec_queue_handle=1, lrc_handle=1, bitmask_size=56
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (12)    attention(5):state-change: client_handle=1, exec_queue_handle=1, lrc_handle=1, bitmask_size=56
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (13)   exec_queue(4):destroy: client_handle=1, vm_handle=1, exec_queue_handle=1, engine_class=0, exec_queue_width=1
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (14)           vm(3):destroy: client_handle=1, handle=1
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (15)     metadata(9):destroy: client_handle=1, metadata_handle=1, type=0, len=16
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (16)     metadata(9):destroy: client_handle=1, metadata_handle=2, type=1, len=16
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (17)       client(2):destroy: handle=1
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: event log 'client' (552 bytes):
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (1)       client(2):create: handle=7
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (2)     metadata(9):create: client_handle=7, metadata_handle=1, type=0, len=16
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (3)     metadata(9):create: client_handle=7, metadata_handle=2, type=1, len=16
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (4)           vm(3):create: client_handle=7, handle=1
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (5)   exec_queue(4):create: client_handle=7, vm_handle=1, exec_queue_handle=1, engine_class=0, exec_queue_width=1
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (6) exec_queue_placements(13):create: client_handle=7, vm_handle=1, exec_queue_handle=1, lrc_handle=0, num_placements=1, gt_id=0, mask=[rcs0 pad0] 
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (7)   exec_queue(4):destroy: client_handle=7, vm_handle=1, exec_queue_handle=1, engine_class=0, exec_queue_width=1
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (8)           vm(3):destroy: client_handle=7, handle=1
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (9)     metadata(9):destroy: client_handle=7, metadata_handle=1, type=0, len=16
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (10)     metadata(9):destroy: client_handle=7, metadata_handle=2, type=1, len=16
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (11)       client(2):destroy: handle=7
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: 27951 signalling token 'client stop' with value '27953'
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: debugger: 27951 waiting for token 'client pid'
(xe_eudebug_online:27951) xe/xe_eudebug-INFO: client running with pid 27961
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: added trigger 0x5c6863058bf0
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: added trigger 0x5c6863058c80
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: debugger connected to 27961
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: 27951 signalling token 'client run' with value '27961'
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: debugger: 27951 waiting for token 'client fini'
(xe_eudebug_online:27951) [thread:27952] intel_allocator_simple-DEBUG: Using simple allocator
(xe_eudebug_online:27951) [thread:27962] xe/xe_eudebug-DEBUG: delivering ack for event: (10) vm_bind_ufence(8):create|ack: vm_bind_ref_seqno=7
(xe_eudebug_online:27951) [thread:27962] DEBUG: EU CONTROL[12]: stopped
(xe_eudebug_online:27951) [thread:27962] DEBUG: EU CONTROL[14]: resume
(xe_eudebug_online:27951) [thread:27962] DEBUG: EU CONTROL[16]: stopped
(xe_eudebug_online:27951) [thread:27952] intel_allocator_simple-DEBUG: <ial: 0x790314001140, fd: 7> objects: 0, reserved_areas: 1
(xe_eudebug_online:27951) [thread:27952] intel_allocator_simple-DEBUG: <ial: 0x790314001140, fd: 7> objects: 0, reserved_areas: 1
(xe_eudebug_online:27951) [thread:27962] DEBUG: EU CONTROL[18]: resume
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: event log 'debugger' (1208 bytes):
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (1)       client(2):create: handle=1
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (2)     metadata(9):create: client_handle=1, metadata_handle=1, type=0, len=16
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (3)     metadata(9):create: client_handle=1, metadata_handle=2, type=1, len=16
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (4)           vm(3):create: client_handle=1, handle=1
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (5)   exec_queue(4):create: client_handle=1, vm_handle=1, exec_queue_handle=1, engine_class=0, exec_queue_width=1
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (6) exec_queue_placements(13):create: client_handle=1, vm_handle=1, exec_queue_handle=1, lrc_handle=1, num_placements=1, gt_id=0, mask=[rcs0 pad0] 
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (7)      vm_bind(6):state-change: client_handle=1, vm_handle=1, flags=0x1, num_binds=2
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (8)   vm_bind_op(7):create: vm_bind_ref_seqno=7, addr=000000001b000000, range=12288 num_extensions=0
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (9)   vm_bind_op(7):create: vm_bind_ref_seqno=7, addr=000000001a000000, range=8192 num_extensions=0
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (10) vm_bind_ufence(8):create|ack: vm_bind_ref_seqno=7
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (11)    attention(5):state-change: client_handle=1, exec_queue_handle=1, lrc_handle=1, bitmask_size=56
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (13)    attention(5):state-change: client_handle=1, exec_queue_handle=1, lrc_handle=1, bitmask_size=56
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (15)    attention(5):state-change: client_handle=1, exec_queue_handle=1, lrc_handle=1, bitmask_size=56
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (17)    attention(5):state-change: client_handle=1, exec_queue_handle=1, lrc_handle=1, bitmask_size=56
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (19)   exec_queue(4):destroy: client_handle=1, vm_handle=1, exec_queue_handle=1, engine_class=0, exec_queue_width=1
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (20)           vm(3):destroy: client_handle=1, handle=1
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (21)     metadata(9):destroy: client_handle=1, metadata_handle=1, type=0, len=16
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (22)     metadata(9):destroy: client_handle=1, metadata_handle=2, type=1, len=16
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (23)       client(2):destroy: handle=1
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: event log 'client' (552 bytes):
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (1)       client(2):create: handle=7
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (2)     metadata(9):create: client_handle=7, metadata_handle=1, type=0, len=16
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (3)     metadata(9):create: client_handle=7, metadata_handle=2, type=1, len=16
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (4)           vm(3):create: client_handle=7, handle=1
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (5)   exec_queue(4):create: client_handle=7, vm_handle=1, exec_queue_handle=1, engine_class=0, exec_queue_width=1
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (6) exec_queue_placements(13):create: client_handle=7, vm_handle=1, exec_queue_handle=1, lrc_handle=0, num_placements=1, gt_id=0, mask=[rcs0 pad0] 
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (7)   exec_queue(4):destroy: client_handle=7, vm_handle=1, exec_queue_handle=1, engine_class=0, exec_queue_width=1
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (8)           vm(3):destroy: client_handle=7, handle=1
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (9)     metadata(9):destroy: client_handle=7, metadata_handle=1, type=0, len=16
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (10)     metadata(9):destroy: client_handle=7, metadata_handle=2, type=1, len=16
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: (11)       client(2):destroy: handle=7
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: client: 7 -> 1
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: comparing client 1 vs debugger 1
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: comparing client 2 vs debugger 2
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: comparing client 3 vs debugger 3
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: comparing client 4 vs debugger 4
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: comparing client 5 vs debugger 5
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: comparing client 6 vs debugger 6
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: comparing client 7 vs debugger 19
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: comparing client 8 vs debugger 20
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: comparing client 9 vs debugger 21
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: comparing client 10 vs debugger 22
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: comparing client 11 vs debugger 23
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: client: 1 -> 7
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: comparing debugger 1 vs client 1
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: comparing debugger 2 vs client 2
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: comparing debugger 3 vs client 3
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: comparing debugger 4 vs client 4
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: comparing debugger 5 vs client 5
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: comparing debugger 6 vs client 6
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: comparing debugger 19 vs client 7
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: comparing debugger 20 vs client 8
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: comparing debugger 21 vs client 9
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: comparing debugger 22 vs client 10
(xe_eudebug_online:27951) xe/xe_eudebug-DEBUG: comparing debugger 23 vs client 11
(xe_eudebug_online:27951) CRITICAL: Test assertion failure function online_session_check, file ../tests/intel/xe_eudebug_online.c:1381:
(xe_eudebug_online:27951) CRITICAL: Failed assertion: sum == data->thread_hit_count
(xe_eudebug_online:27951) CRITICAL: error: 1024 != 512
(xe_eudebug_online:27951) igt_core-INFO: Stack trace:
(xe_eudebug_online:27951) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(xe_eudebug_online:27951) igt_core-INFO:   #1 ../tests/intel/xe_eudebug_online.c:1386 online_session_check()
(xe_eudebug_online:27951) igt_core-INFO:   #2 ../tests/intel/xe_eudebug_online.c:1877 __igt_unique____real_main2671()
(xe_eudebug_online:27951) igt_core-INFO:   #3 ../tests/intel/xe_eudebug_online.c:2671 main()
(xe_eudebug_online:27951) igt_core-INFO:   #4 ../sysdeps/nptl/libc_start_call_main.h:74 __libc_start_call_main()
(xe_eudebug_online:27951) igt_core-INFO:   #5 ../csu/libc-start.c:128 __libc_start_main@@GLIBC_2.34()
(xe_eudebug_online:27951) igt_core-INFO:   #6 [_start+0x25]
****  END  ****
Dynamic subtest DRM_XE_ENGINE_CLASS_RENDER0: FAIL (0.660s)
Subtest reset-with-attention: FAIL (0.662s)

Dmesg

<6> [366.213619] Console: switching to colour dummy device 80x25

<6> [366.213871] [IGT] xe_eudebug_online: executing

<6> [366.218516] [IGT] xe_eudebug_online: starting subtest reset-with-attention

<6> [366.218796] [IGT] xe_eudebug_online: starting dynamic subtest DRM_XE_ENGINE_CLASS_RENDER0

<7> [366.222322] xe 0000:00:02.0: [drm:prelim_xe_eudebug_connect_ioctl [xe]] eudbg: 3277:0: (27951/27951) -> (27953/27953): "connected session 3277"

<7> [366.222518] xe 0000:00:02.0: [drm:discovery_work_fn [xe]] eudbg: 3277:0: (8399/8399) -> (27953/27953): "Discovery start for 3277\n"

<7> [366.222638] xe 0000:00:02.0: [drm:discovery_work_fn [xe]] eudbg: 3277:0: (8399/8399) -> (27953/27953): "Discovery end for 3277\n"

<7> [366.223410] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3277:1: (27954/27951) -> (27953/27953): "event read=0: type=2, flags=0x1, seqno=1"

<7> [366.224459] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3277:3: (27954/27951) -> (27953/27953): "event read=0: type=9, flags=0x1, seqno=2"

<7> [366.224598] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3277:3: (27954/27951) -> (27953/27953): "event read=0: type=9, flags=0x1, seqno=3"

<7> [366.225554] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3277:4: (27954/27951) -> (27953/27953): "event read=0: type=3, flags=0x1, seqno=4"

<7> [366.226426] xe 0000:00:02.0: [drm:xe_lrc_create [xe]] read CTX_CONTEXT_CONTROL: 0xffff0088

<7> [366.226507] xe 0000:00:02.0: [drm:xe_lrc_create [xe]] written CTX_CONTEXT_CONTROL: 0xffff0088

<7> [366.226633] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3277:6: (27954/27951) -> (27953/27953): "event read=0: type=4, flags=0x1, seqno=5"

<7> [366.226727] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3277:6: (27954/27951) -> (27953/27953): "event read=0: type=13, flags=0x1, seqno=6"

<7> [366.227883] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3277:9: (27954/27951) -> (27953/27953): "event read=0: type=6, flags=0x4, seqno=7"

<7> [366.227917] xe 0000:00:02.0: [drm:prelim_xe_eudebug_vm_bind_ufence [xe]] eudbg: 3277:10: (8399/8399) -> (27953/27953): "ACK: seqno=10: tracking started"

<7> [366.228013] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3277:10: (27954/27951) -> (27953/27953): "event read=0: type=7, flags=0x1, seqno=8"

<7> [366.228117] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3277:10: (27954/27951) -> (27953/27953): "event read=0: type=7, flags=0x1, seqno=9"

<7> [366.228216] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3277:10: (27954/27951) -> (27953/27953): "event read=0: type=8, flags=0x9, seqno=10"

<7> [366.228470] xe 0000:00:02.0: [drm:handle_ack [xe]] eudbg: 3277:10: (27954/27951) -> (27953/27953): "ACK: seqno=10: signalled by 10 (debugger) (held 554us)"

<7> [366.228581] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3277:10: (27954/27951) -> (27953/27953): "ioctl cmd=EVENT_ACK ret=0\n"

<7> [366.325522] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone RCU_DEBUG_1 = 0x00000280

<7> [366.325863] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine rcs0: runalone=active, context=active

<7> [366.326142] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone engine rcs0 found

<7> [366.326466] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine ccs0: runalone=inactive, context=inactive

<7> [366.326964] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone RCU_DEBUG_1 = 0x00000280

<7> [366.327116] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3277:11: (27954/27951) -> (27953/27953): "event read=0: type=5, flags=0x4, seqno=11"

<7> [366.327226] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine rcs0: runalone=active, context=active

<7> [366.327596] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone engine rcs0 found

<7> [366.327880] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine ccs0: runalone=inactive, context=inactive

<7> [366.328195] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone RCU_DEBUG_1 = 0x00000280

<7> [366.328506] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine rcs0: runalone=active, context=active

<7> [366.328803] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone engine rcs0 found

<7> [366.329092] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine ccs0: runalone=inactive, context=inactive

<6> [366.338456] xe 0000:00:02.0: [drm] Tile0: GT0: trying reset from force_reset_show [xe]

<6> [366.338756] xe 0000:00:02.0: [drm] Tile0: GT0: reset queued

<6> [366.338921] xe 0000:00:02.0: [drm] Tile0: GT0: reset started

<7> [366.338959] xe 0000:00:02.0: [drm:guc_ct_change_state [xe]] Tile0: GT0: GuC CT communication channel stopped

<6> [366.339347] xe 0000:00:02.0: [drm] exec queue reset detected

<7> [366.339457] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: Applying GT save-restore MMIOs

<7> [366.339710] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x4148] = 0x00000000

<7> [366.339957] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x8828] = 0x00800000

<7> [366.340166] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0xb104] = 0x08104440

<7> [366.340739] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0xb108] = 0x30200000

<7> [366.340786] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3277:14: (27954/27951) -> (27953/27953): "event read=0: type=5, flags=0x4, seqno=12"

<7> [366.341019] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0xb154] = 0x00020000

<7> [366.341314] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0xb158] = 0x0000007f

<7> [366.341589] xe 0000:00:02.0: [drm:xe_wopcm_init [xe]] WOPCM: 2048K

<7> [366.341943] xe 0000:00:02.0: [drm:xe_wopcm_init [xe]] GuC WOPCM is already locked [6144K, 832K)

<7> [366.342356] xe 0000:00:02.0: [drm:guc_ct_change_state [xe]] Tile0: GT0: GuC CT communication channel disabled

<7> [366.344086] xe 0000:00:02.0: [drm:xe_guc_ads_populate [xe]] Tile0: GT0: Updated ADS capture size 20480 (was 45056)

<6> [366.348159] xe 0000:00:02.0: [drm] Tile0: GT0: trying reset from force_reset_show [xe]

<7> [366.349134] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3277:17: (27954/27951) -> (27953/27953): "event read=0: type=4, flags=0x2, seqno=13"

<7> [366.349274] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3277:17: (27954/27951) -> (27953/27953): "event read=0: type=3, flags=0x2, seqno=14"

<7> [366.349388] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3277:17: (27954/27951) -> (27953/27953): "event read=0: type=9, flags=0x2, seqno=15"

<7> [366.349498] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3277:17: (27954/27951) -> (27953/27953): "event read=0: type=9, flags=0x2, seqno=16"

<7> [366.349605] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3277:17: (27954/27951) -> (27953/27953): "event read=0: type=2, flags=0x2, seqno=17"

<7> [366.355559] xe 0000:00:02.0: [drm:guc_wait_ucode [xe]] Tile0: GT0: GuC load: init took 10ms, freq = 1850MHz (req = 1850MHz), before = 1850MHz, status = 0x8002F034

<7> [366.355915] xe 0000:00:02.0: [drm:guc_ct_change_state [xe]] Tile0: GT0: GuC CT communication channel enabled

<7> [366.356954] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: flag:0x1

<7> [366.357062] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: mocs entries: 16

<7> [366.357140] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[0] 0x4000 0xc

<7> [366.357216] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[1] 0x4004 0x10c

<7> [366.357318] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[2] 0x4008 0x130

<7> [366.357389] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[3] 0x400c 0x13c

<7> [366.357457] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[4] 0x4010 0x100

<7> [366.357524] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[5] 0x4014 0x100

<7> [366.357591] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[6] 0x4018 0x100

<7> [366.357657] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[7] 0x401c 0x100

<7> [366.357721] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[8] 0x4020 0x100

<7> [366.357785] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[9] 0x4024 0x100

<7> [366.357850] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[10] 0x4028 0x100

<7> [366.357915] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[11] 0x402c 0x100

<7> [366.357980] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[12] 0x4030 0x100

<7> [366.358047] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[13] 0x4034 0x100

<7> [366.358114] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[14] 0x4038 0x100

<7> [366.358182] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[15] 0x403c 0x100

<7> [366.358259] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: Applying rcs0 save-restore MMIOs

<7> [366.358343] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x2050] = 0x10001000

<7> [366.358430] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x20c4] = 0x3f7e0306

<7> [366.358512] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x20d4] = 0xc080c080

<7> [366.358592] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x24d0] = 0x1000dafc

<7> [366.358666] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x24d4] = 0x1000db01

<7> [366.358739] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x24d8] = 0x0000db1c

<7> [366.358815] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0xe194] = 0x00400040

<7> [366.358901] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0xe400] = 0x00000095

<7> [366.358979] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0xe48c] = 0x02000200

<7> [366.359055] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0xe49c] = 0x40004000

<7> [366.359134] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0xe4c4] = 0x08400840

<7> [366.359211] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0xe4f0] = 0x00020002

<7> [366.359313] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0xe7c8] = 0x00002000

<7> [366.359406] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0xe7cc] = 0x00009000

<7> [366.359490] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: Applying bcs0 save-restore MMIOs

<7> [366.359568] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x22050] = 0x10001000

<7> [366.359645] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x220c4] = 0x3f7e0306

<7> [366.359731] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x220d4] = 0xc080c080

<7> [366.359815] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: Applying bcs8 save-restore MMIOs

<7> [366.359899] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x3ee050] = 0x10001000

<7> [366.359984] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x3ee0c4] = 0x3f7e0306

<7> [366.360065] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x3ee0d4] = 0xc080c080

<7> [366.360150] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: Applying ccs0 save-restore MMIOs

<7> [366.360233] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x1a050] = 0x10001000

<7> [366.360353] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x1a0c4] = 0x3f7e0306

<7> [366.360437] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x1a0d4] = 0xc080c080

<7> [366.360532] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x1a4d0] = 0x1000dafc

<7> [366.360627] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x1a4d4] = 0x1000db01

<7> [366.360710] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x1a4d8] = 0x0000db1c

<6> [366.360845] xe 0000:00:02.0: [drm] Tile0: GT0: reset done

<7> [366.450808] xe 0000:00:02.0: [drm:xe_eudebug_detach [xe]] eudbg: 3277:17:disconnected (27951/27951) -> (27953/27953): "session 3277 detached with 0"

<7> [366.451176] xe 0000:00:02.0: [drm:xe_eudebug_release [xe]] eudbg: 3277:17:disconnected (27951/27951) -> (27953/27953): "Session closed (0)"

<7> [366.478204] xe 0000:00:02.0: [drm:prelim_xe_eudebug_connect_ioctl [xe]] eudbg: 3278:0: (27951/27951) -> (27961/27961): "connected session 3278"

<7> [366.478677] xe 0000:00:02.0: [drm:discovery_work_fn [xe]] eudbg: 3278:0: (8400/8400) -> (27961/27961): "Discovery start for 3278\n"

<7> [366.479082] xe 0000:00:02.0: [drm:discovery_work_fn [xe]] eudbg: 3278:0: (8400/8400) -> (27961/27961): "Discovery end for 3278\n"

<7> [366.480166] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:1: (27962/27951) -> (27961/27961): "event read=0: type=2, flags=0x1, seqno=1"

<7> [366.481233] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:2: (27962/27951) -> (27961/27961): "event read=0: type=9, flags=0x1, seqno=2"

<7> [366.481698] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:3: (27962/27951) -> (27961/27961): "event read=0: type=9, flags=0x1, seqno=3"

<7> [366.483373] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:4: (27962/27951) -> (27961/27961): "event read=0: type=3, flags=0x1, seqno=4"

<7> [366.484888] xe 0000:00:02.0: [drm:xe_lrc_create [xe]] read CTX_CONTEXT_CONTROL: 0xffff0088

<7> [366.485229] xe 0000:00:02.0: [drm:xe_lrc_create [xe]] written CTX_CONTEXT_CONTROL: 0xffff0088

<7> [366.485758] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:6: (27962/27951) -> (27961/27961): "event read=0: type=4, flags=0x1, seqno=5"

<7> [366.486121] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:6: (27962/27951) -> (27961/27961): "event read=0: type=13, flags=0x1, seqno=6"

<7> [366.489334] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:9: (27962/27951) -> (27961/27961): "event read=0: type=6, flags=0x4, seqno=7"

<7> [366.489413] xe 0000:00:02.0: [drm:prelim_xe_eudebug_vm_bind_ufence [xe]] eudbg: 3278:10: (8400/8400) -> (27961/27961): "ACK: seqno=10: tracking started"

<7> [366.489682] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:10: (27962/27951) -> (27961/27961): "event read=0: type=7, flags=0x1, seqno=8"

<7> [366.490057] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:10: (27962/27951) -> (27961/27961): "event read=0: type=7, flags=0x1, seqno=9"

<7> [366.490436] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:10: (27962/27951) -> (27961/27961): "event read=0: type=8, flags=0x9, seqno=10"

<7> [366.490905] xe 0000:00:02.0: [drm:handle_ack [xe]] eudbg: 3278:10: (27962/27951) -> (27961/27961): "ACK: seqno=10: signalled by 10 (debugger) (held 1495us)"

<7> [366.491274] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:10: (27962/27951) -> (27961/27961): "ioctl cmd=EVENT_ACK ret=0\n"

<7> [366.581494] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone RCU_DEBUG_1 = 0x00000280

<7> [366.581827] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine rcs0: runalone=active, context=active

<7> [366.582102] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone engine rcs0 found

<7> [366.582418] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine ccs0: runalone=inactive, context=inactive

<7> [366.582916] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone RCU_DEBUG_1 = 0x00000280

<7> [366.583069] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:11: (27962/27951) -> (27961/27961): "event read=0: type=5, flags=0x4, seqno=11"

<7> [366.583195] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine rcs0: runalone=active, context=active

<7> [366.583520] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone engine rcs0 found

<7> [366.583504] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:11: (27962/27951) -> (27961/27961): "eu_control: client_handle=1, cmd=1, flags=0x0, exec_queue_handle=1, bitmask_size=56\n"

<7> [366.583794] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine ccs0: runalone=inactive, context=inactive

<7> [366.583901] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone RCU_DEBUG_1 = 0x00000280

<7> [366.584130] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone RCU_DEBUG_1 = 0x00000280

<7> [366.584212] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine rcs0: runalone=active, context=active

<7> [366.584441] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine rcs0: runalone=active, context=active

<7> [366.584615] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone engine rcs0 found

<7> [366.584738] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone engine rcs0 found

<7> [366.584911] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine ccs0: runalone=inactive, context=inactive

<7> [366.585008] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine ccs0: runalone=inactive, context=inactive

<7> [366.585400] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:13: (27962/27951) -> (27961/27961): "eu_control: client_handle=1, cmd=1, flags=0x0, exec_queue_handle=1, bitmask_size=56 ret=0\n"

<7> [366.585683] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:13: (27962/27951) -> (27961/27961): "ioctl cmd=EU_CONTROL ret=0\n"

<7> [366.586133] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:13: (27962/27951) -> (27961/27961): "eu_control: client_handle=1, cmd=2, flags=0x0, exec_queue_handle=1, bitmask_size=56\n"

<7> [366.586508] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone RCU_DEBUG_1 = 0x00000280

<7> [366.586786] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine rcs0: runalone=active, context=active

<7> [366.587089] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone engine rcs0 found

<7> [366.587390] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine ccs0: runalone=inactive, context=inactive

<7> [366.587700] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3278:13: (27962/27951) -> (27961/27961): "EU_ATT_CLR: (0:0:0:0): 0xffffffff\n"

<7> [366.588019] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3278:13: (27962/27951) -> (27961/27961): "EU_ATT_CLR: (0:0:0:1): 0xffffffff\n"

<7> [366.588306] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3278:13: (27962/27951) -> (27961/27961): "EU_ATT_CLR: (0:1:0:0): 0xffffffff\n"

<7> [366.588578] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3278:13: (27962/27951) -> (27961/27961): "EU_ATT_CLR: (0:1:0:1): 0xffffffff\n"

<7> [366.588841] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3278:13: (27962/27951) -> (27961/27961): "EU_ATT_CLR: (0:2:0:0): 0xffffffff\n"

<7> [366.589104] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3278:13: (27962/27951) -> (27961/27961): "EU_ATT_CLR: (0:2:0:1): 0xffffffff\n"

<7> [366.589450] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3278:13: (27962/27951) -> (27961/27961): "EU_ATT_CLR: (0:3:0:0): 0xffffffff\n"

<7> [366.589711] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3278:13: (27962/27951) -> (27961/27961): "EU_ATT_CLR: (0:3:0:1): 0xffffffff\n"

<7> [366.589989] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3278:13: (27962/27951) -> (27961/27961): "EU_ATT_CLR: (1:0:0:0): 0xffffffff\n"

<7> [366.590280] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3278:13: (27962/27951) -> (27961/27961): "EU_ATT_CLR: (1:0:0:1): 0xffffffff\n"

<7> [366.590605] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3278:13: (27962/27951) -> (27961/27961): "EU_ATT_CLR: (1:1:0:0): 0xffffffff\n"

<7> [366.590883] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3278:13: (27962/27951) -> (27961/27961): "EU_ATT_CLR: (1:1:0:1): 0xffffffff\n"

<7> [366.591151] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3278:13: (27962/27951) -> (27961/27961): "EU_ATT_CLR: (1:2:0:0): 0xffffffff\n"

<7> [366.591457] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3278:13: (27962/27951) -> (27961/27961): "EU_ATT_CLR: (1:2:0:1): 0xffffffff\n"

<7> [366.591735] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:14: (27962/27951) -> (27961/27961): "eu_control: client_handle=1, cmd=2, flags=0x0, exec_queue_handle=1, bitmask_size=56 ret=0\n"

<7> [366.592002] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:14: (27962/27951) -> (27961/27961): "ioctl cmd=EU_CONTROL ret=0\n"

<7> [366.592292] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:14: (27962/27951) -> (27961/27961): "event read=0: type=5, flags=0x4, seqno=13"

<7> [366.693371] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone RCU_DEBUG_1 = 0x00000280

<7> [366.693803] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine rcs0: runalone=active, context=active

<7> [366.694162] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone engine rcs0 found

<7> [366.694558] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine ccs0: runalone=inactive, context=inactive

<7> [366.695108] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone RCU_DEBUG_1 = 0x00000280

<7> [366.695155] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:15: (27962/27951) -> (27961/27961): "event read=0: type=5, flags=0x4, seqno=15"

<7> [366.695478] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine rcs0: runalone=active, context=active

<7> [366.695657] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:15: (27962/27951) -> (27961/27961): "eu_control: client_handle=1, cmd=1, flags=0x0, exec_queue_handle=1, bitmask_size=56\n"

<7> [366.695828] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone engine rcs0 found

<7> [366.696076] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone RCU_DEBUG_1 = 0x00000280

<7> [366.696153] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine ccs0: runalone=inactive, context=inactive

<7> [366.696468] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine rcs0: runalone=active, context=active

<7> [366.696534] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone RCU_DEBUG_1 = 0x00000280

<7> [366.696810] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone engine rcs0 found

<7> [366.696837] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine rcs0: runalone=active, context=active

<7> [366.697140] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone engine rcs0 found

<7> [366.697132] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine ccs0: runalone=inactive, context=inactive

<7> [366.697477] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine ccs0: runalone=inactive, context=inactive

<7> [366.697661] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:16: (27962/27951) -> (27961/27961): "eu_control: client_handle=1, cmd=1, flags=0x0, exec_queue_handle=1, bitmask_size=56 ret=0\n"

<7> [366.698005] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:17: (27962/27951) -> (27961/27961): "ioctl cmd=EU_CONTROL ret=0\n"

<7> [366.698402] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:17: (27962/27951) -> (27961/27961): "eu_control: client_handle=1, cmd=2, flags=0x0, exec_queue_handle=1, bitmask_size=56\n"

<7> [366.698781] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone RCU_DEBUG_1 = 0x00000280

<7> [366.699120] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine rcs0: runalone=active, context=active

<7> [366.699459] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone engine rcs0 found

<7> [366.699756] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine ccs0: runalone=inactive, context=inactive

<7> [366.700134] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3278:17: (27962/27951) -> (27961/27961): "EU_ATT_CLR: (0:0:0:0): 0xffffffff\n"

<7> [366.700470] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3278:17: (27962/27951) -> (27961/27961): "EU_ATT_CLR: (0:0:0:1): 0xffffffff\n"

<7> [366.700775] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:18: (27962/27951) -> (27961/27961): "eu_control: client_handle=1, cmd=2, flags=0x0, exec_queue_handle=1, bitmask_size=56 ret=0\n"

<7> [366.701067] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:18: (27962/27951) -> (27961/27961): "ioctl cmd=EU_CONTROL ret=0\n"

<7> [366.701410] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:18: (27962/27951) -> (27961/27961): "event read=0: type=5, flags=0x4, seqno=17"

<7> [366.701702] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:20: (27962/27951) -> (27961/27961): "event read=0: type=4, flags=0x2, seqno=19"

<7> [366.702054] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:20: (27962/27951) -> (27961/27961): "event read=0: type=3, flags=0x2, seqno=20"

<7> [366.703211] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:21: (27962/27951) -> (27961/27961): "event read=0: type=9, flags=0x2, seqno=21"

<7> [366.703675] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:22: (27962/27951) -> (27961/27961): "event read=0: type=9, flags=0x2, seqno=22"

<7> [366.704026] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3278:23: (27962/27951) -> (27961/27961): "event read=0: type=2, flags=0x2, seqno=23"

<6> [366.878716] [IGT] xe_eudebug_online: finished subtest DRM_XE_ENGINE_CLASS_RENDER0, FAIL

<6> [366.880977] [IGT] xe_eudebug_online: finished subtest reset-with-attention, FAIL

<7> [366.882947] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off

<7> [366.883701] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00

<7> [366.901182] xe 0000:00:02.0: [drm:drm_client_dev_restore] fbdev: ret=0

<6> [366.902024] [IGT] xe_eudebug_online: exiting, ret=98

<6> [366.917642] Console: switching to colour frame buffer device 180x56

<7> [366.945914] xe 0000:00:02.0: [drm:xe_eudebug_detach [xe]] eudbg: 3278:23:disconnected (27951/27951) -> (27961/27961): "session 3278 detached with 0"

<7> [366.946188] xe 0000:00:02.0: [drm:xe_eudebug_release [xe]] eudbg: 3278:23:disconnected (27951/27951) -> (27961/27961): "Session closed (0)"

<7> [366.951335] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off

<7> [366.951720] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6

<7> [366.952047] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02

Results for igt@xe_eudebug_online@reset-with-attention