igt@xe_eudebug_online@interrupt-all on bat-lnl-2@xkb-pr-main-261-468-c6fbff4eb06bc4f75f9e7a9720093e24a758a231 Test details

Detail	Value
Duration	0.14 seconds
Igt-Version	IGT-Version: 2.3-g105262d67 (x86_64) (Linux: 6.17.13-lgci-xe-xkb-pr-main-261-468-c6fbf+ x86_64)
Out	Using IGT_SRANDOM=1770173146 for randomisation Opened device: /dev/dri/card0 Using IGT_SRANDOM=1770173146 for randomisation Starting subtest: interrupt-all Starting dynamic subtest: DRM_XE_ENGINE_CLASS_RENDER0 client running with pid 27285 [thread:27286] Stack trace: [thread:27286] #0 ../lib/igt_core.c:2075 __igt_fail_assert() [thread:27286] #1 ../tests/intel/xe_eudebug_online.c:728 eu_attention_resume_trigger() [thread:27286] #2 ../lib/xe/xe_eudebug.c:1131 debugger_worker_loop() [thread:27286] #3 [pthread_condattr_setpshared+0x684] [thread:27286] #4 [__clone+0x24c] Stack trace: #0 ../lib/igt_core.c:2075 __igt_fail_assert() #1 ../lib/xe/xe_eudebug.c:835 compare_client() #2 ../lib/xe/xe_eudebug.c:1074 xe_eudebug_event_log_compare() #3 ../lib/xe/xe_eudebug.c:1781 xe_eudebug_session_check() #4 ../tests/intel/xe_eudebug_online.c:1352 online_session_check() #5 ../tests/intel/xe_eudebug_online.c:1958 test_interrupt_all() #6 ../tests/intel/xe_eudebug_online.c:2715 __igt_unique____real_main2671() #7 ../tests/intel/xe_eudebug_online.c:2671 main() #8 [__libc_init_first+0x8a] #9 [__libc_start_main+0x8b] #10 [_start+0x25] Dynamic subtest DRM_XE_ENGINE_CLASS_RENDER0: FAIL (0.139s) Subtest interrupt-all: FAIL (0.143s)
Err	Starting subtest: interrupt-all Starting dynamic subtest: DRM_XE_ENGINE_CLASS_RENDER0 (xe_eudebug_online:27283) [thread:27286] CRITICAL: Test assertion failure function eu_ctl, file ../tests/intel/xe_eudebug_online.c:395: (xe_eudebug_online:27283) [thread:27286] CRITICAL: Failed assertion: __eu_ctl(debugfd, client, exec_queue, lrc, bitmask, bitmask_size, cmd, &seqno) == 0 (xe_eudebug_online:27283) [thread:27286] CRITICAL: Last errno: 2, No such file or directory (xe_eudebug_online:27283) [thread:27286] CRITICAL: error: -2 != 0 (xe_eudebug_online:27283) xe/xe_eudebug-CRITICAL: Test assertion failure function compare_client, file ../lib/xe/xe_eudebug.c:835: (xe_eudebug_online:27283) xe/xe_eudebug-CRITICAL: Failed assertion: evptr2 (xe_eudebug_online:27283) xe/xe_eudebug-CRITICAL: client (7): no matching event type 4 found for client 7 Dynamic subtest DRM_XE_ENGINE_CLASS_RENDER0 failed. ** DEBUG (xe_eudebug_online:27283) DEBUG: Test requirement passed: !(flags & FAULTABLE_VM) \|\| !xe_supports_faults(fd) (xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: debugger: 27283 waiting for token 'client pid' (xe_eudebug_online:27283) xe/xe_eudebug-INFO: client running with pid 27285 (xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: added trigger 0x61ba502ee6e0 (xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: added trigger 0x61ba502ee710 (xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: added trigger 0x61ba502ee740 (xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: added trigger 0x61ba502ee770 (xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: added trigger 0x61ba502ee7a0 (xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: added trigger 0x61ba502ee7d0 (xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: added trigger 0x61ba502ee800 (xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: debugger connected to 27285 (xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: 27283 signalling token 'client run' with value '27285' (xe_eudebug_online:27283) [thread:27284] intel_allocator_simple-DEBUG: Using simple allocator (xe_eudebug_online:27283) [thread:27286] xe/xe_eudebug-DEBUG: delivering ack for event: (10) vm_bind_ufence(8):create\|ack: vm_bind_ref_seqno=7 (xe_eudebug_online:27283) DEBUG: EU CONTROL[11]: interrupt all (xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: debugger: 27283 waiting for token 'client fini' (xe_eudebug_online:27283) [thread:27286] DEBUG: EVENT[12] eu-attenttion; threads=448 client[1], exec_queue[1], lrc[1], bitmask_size[56] (xe_eudebug_online:27283) [thread:27286] DEBUG: bitmask[0] = 0xffffffffffffffff (xe_eudebug_online:27283) [thread:27286] DEBUG: bitmask[1] = 0xffffffffffffffff (xe_eudebug_online:27283) [thread:27286] DEBUG: bitmask[2] = 0xffffffffffffffff (xe_eudebug_online:27283) [thread:27286] DEBUG: bitmask[3] = 0xffffffffffffffff (xe_eudebug_online:27283) [thread:27286] DEBUG: bitmask[4] = 0xffffffffffffffff (xe_eudebug_online:27283) [thread:27286] DEBUG: bitmask[5] = 0xffffffffffffffff (xe_eudebug_online:27283) [thread:27286] DEBUG: bitmask[6] = 0xffffffffffffffff (xe_eudebug_online:27283) [thread:27286] DEBUG: EU CONTROL[13]: stopped (xe_eudebug_online:27283) [thread:27286] DEBUG: EU CONTROL[14]: resume (xe_eudebug_online:27283) [thread:27286] DEBUG: EVENT[15] eu-attenttion; threads=0 client[1], exec_queue[1], lrc[1], bitmask_size[56] (xe_eudebug_online:27283) [thread:27286] DEBUG: bitmask[0] = 0x0000000000000000 (xe_eudebug_online:27283) [thread:27286] DEBUG: bitmask[1] = 0x0000000000000000 (xe_eudebug_online:27283) [thread:27286] DEBUG: bitmask[2] = 0x0000000000000000 (xe_eudebug_online:27283) [thread:27286] DEBUG: bitmask[3] = 0x0000000000000000 (xe_eudebug_online:27283) [thread:27286] DEBUG: bitmask[4] = 0x0000000000000000 (xe_eudebug_online:27283) [thread:27286] DEBUG: bitmask[5] = 0x0000000000000000 (xe_eudebug_online:27283) [thread:27286] DEBUG: bitmask[6] = 0x0000000000000000 (xe_eudebug_online:27283) [thread:27284] intel_allocator_simple-DEBUG: <ial: 0x736ce0000c20, fd: 7> objects: 0, reserved_areas: 1 (xe_eudebug_online:27283) [thread:27284] intel_allocator_simple-DEBUG: <ial: 0x736ce0000c20, fd: 7> objects: 0, reserved_areas: 1 (xe_eudebug_online:27283) [thread:27286] CRITICAL: Test assertion failure function eu_ctl, file ../tests/intel/xe_eudebug_online.c:395: (xe_eudebug_online:27283) [thread:27286] CRITICAL: Failed assertion: __eu_ctl(debugfd, client, exec_queue, lrc, bitmask, bitmask_size, cmd, &seqno) == 0 (xe_eudebug_online:27283) [thread:27286] CRITICAL: Last errno: 2, No such file or directory (xe_eudebug_online:27283) [thread:27286] CRITICAL: error: -2 != 0 (xe_eudebug_online:27283) [thread:27286] igt_core-INFO: Stack trace: (xe_eudebug_online:27283) [thread:27286] igt_core-INFO: #0 ../lib/igt_core.c:2075 __igt_fail_assert() (xe_eudebug_online:27283) [thread:27286] igt_core-INFO: #1 ../tests/intel/xe_eudebug_online.c:728 eu_attention_resume_trigger() (xe_eudebug_online:27283) [thread:27286] igt_core-INFO: #2 ../lib/xe/xe_eudebug.c:1131 debugger_worker_loop() (xe_eudebug_online:27283) [thread:27286] igt_core-INFO: #3 [pthread_condattr_setpshared+0x684] (xe_eudebug_online:27283) [thread:27286] igt_core-INFO: #4 [__clone+0x24c] (xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: event log 'debugger' (736 bytes): (xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: (1) client(2):create: handle=1 (xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: (2) metadata(9):create: client_handle=1, metadata_handle=1, type=0, len=16 (xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: (3) metadata(9):create: client_handle=1, metadata_handle=2, type=1, len=16 (xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: (4) vm(3):create: client_handle=1, handle=1 (xe_eudebug_online:27283) 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:27283) 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:27283) xe/xe_eudebug-DEBUG: (7) vm_bind(6):state-change: client_handle=1, vm_handle=1, flags=0x1, num_binds=2 (xe_eudebug_online:27283) 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:27283) xe/xe_eudebug-DEBUG: (9) vm_bind_op(7):create: vm_bind_ref_seqno=7, addr=000000001a000000, range=12288 num_extensions=0 (xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: (10) vm_bind_ufence(8):create\|ack: vm_bind_ref_seqno=7 (xe_eudebug_online:27283) 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:27283) 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:27283) xe/xe_eudebug-DEBUG: event log 'client' (552 bytes): (xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: (1) client(2):create: handle=7 (xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: (2) metadata(9):create: client_handle=7, metadata_handle=1, type=0, len=16 (xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: (3) metadata(9):create: client_handle=7, metadata_handle=2, type=1, len=16 (xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: (4) vm(3):create: client_handle=7, handle=1 (xe_eudebug_online:27283) 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:27283) 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:27283) 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:27283) xe/xe_eudebug-DEBUG: (8) vm(3):destroy: client_handle=7, handle=1 (xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: (9) metadata(9):destroy: client_handle=7, metadata_handle=1, type=0, len=16 (xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: (10) metadata(9):destroy: client_handle=7, metadata_handle=2, type=1, len=16 (xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: (11) client(2):destroy: handle=7 (xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: client: 7 -> 1 (xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: comparing client 1 vs debugger 1 (xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: comparing client 2 vs debugger 2 (xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: comparing client 3 vs debugger 3 (xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: comparing client 4 vs debugger 4 (xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: comparing client 5 vs debugger 5 (xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: comparing client 6 vs debugger 6 (xe_eudebug_online:27283) xe/xe_eudebug-CRITICAL: Test assertion failure function compare_client, file ../lib/xe/xe_eudebug.c:835: (xe_eudebug_online:27283) xe/xe_eudebug-CRITICAL: Failed assertion: evptr2 (xe_eudebug_online:27283) xe/xe_eudebug-CRITICAL: client (7): no matching event type 4 found for client 7 (xe_eudebug_online:27283) igt_core-INFO: Stack trace: (xe_eudebug_online:27283) igt_core-INFO: #0 ../lib/igt_core.c:2075 __igt_fail_assert() (xe_eudebug_online:27283) igt_core-INFO: #1 ../lib/xe/xe_eudebug.c:835 compare_client() (xe_eudebug_online:27283) igt_core-INFO: #2 ../lib/xe/xe_eudebug.c:1074 xe_eudebug_event_log_compare() (xe_eudebug_online:27283) igt_core-INFO: #3 ../lib/xe/xe_eudebug.c:1781 xe_eudebug_session_check() (xe_eudebug_online:27283) igt_core-INFO: #4 ../tests/intel/xe_eudebug_online.c:1352 online_session_check() (xe_eudebug_online:27283) igt_core-INFO: #5 ../tests/intel/xe_eudebug_online.c:1958 test_interrupt_all() (xe_eudebug_online:27283) igt_core-INFO: #6 ../tests/intel/xe_eudebug_online.c:2715 __igt_unique____real_main2671() (xe_eudebug_online:27283) igt_core-INFO: #7 ../tests/intel/xe_eudebug_online.c:2671 main() (xe_eudebug_online:27283) igt_core-INFO: #8 [__libc_init_first+0x8a] (xe_eudebug_online:27283) igt_core-INFO: #9 [__libc_start_main+0x8b] (xe_eudebug_online:27283) igt_core-INFO: #10 [_start+0x25] END ** Dynamic subtest DRM_XE_ENGINE_CLASS_RENDER0: FAIL (0.139s) (xe_eudebug_online:27283) igt_thread-CRITICAL: Failure in a thread! Subtest interrupt-all: FAIL (0.143s)
Dmesg	<6> [211.460751] [IGT] xe_eudebug_online: executing <6> [211.464596] [IGT] xe_eudebug_online: starting subtest interrupt-all <6> [211.464811] [IGT] xe_eudebug_online: starting dynamic subtest DRM_XE_ENGINE_CLASS_RENDER0 <7> [211.469815] xe 0000:00:02.0: [drm:prelim_xe_eudebug_connect_ioctl [xe]] eudbg: 3271:0: (27283/27283) -> (27285/27285): "connected session 3271" <7> [211.469937] xe 0000:00:02.0: [drm:discovery_work_fn [xe]] eudbg: 3271:0: (8859/8859) -> (27285/27285): "Discovery start for 3271\n" <7> [211.470040] xe 0000:00:02.0: [drm:discovery_work_fn [xe]] eudbg: 3271:0: (8859/8859) -> (27285/27285): "Discovery end for 3271\n" <7> [211.470727] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:1: (27286/27283) -> (27285/27285): "event read=0: type=2, flags=0x1, seqno=1" <7> [211.471073] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:2: (27286/27283) -> (27285/27285): "event read=0: type=9, flags=0x1, seqno=2" <7> [211.471275] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:3: (27286/27283) -> (27285/27285): "read metadata: client_handle=1, metadata_handle=1, flags=0x0" <7> [211.471385] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:3: (27286/27283) -> (27285/27285): "ioctl cmd=READ_METADATA ret=0\n" <7> [211.471480] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:3: (27286/27283) -> (27285/27285): "event read=0: type=9, flags=0x1, seqno=3" <7> [211.471575] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:3: (27286/27283) -> (27285/27285): "read metadata: client_handle=1, metadata_handle=2, flags=0x0" <7> [211.471665] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:3: (27286/27283) -> (27285/27285): "ioctl cmd=READ_METADATA ret=0\n" <7> [211.472287] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:4: (27286/27283) -> (27285/27285): "event read=0: type=3, flags=0x1, seqno=4" <7> [211.472416] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:4: (27286/27283) -> (27285/27285): "vm_open: client_handle=1, handle=1, flags=0x0, fd=10" <7> [211.472514] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:4: (27286/27283) -> (27285/27285): "ioctl cmd=VM_OPEN ret=10\n" <7> [211.473271] xe 0000:00:02.0: [drm:xe_lrc_create [xe]] read CTX_CONTEXT_CONTROL: 0xffff0088 <7> [211.473380] xe 0000:00:02.0: [drm:xe_lrc_create [xe]] written CTX_CONTEXT_CONTROL: 0xffff0088 <7> [211.473577] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:6: (27286/27283) -> (27285/27285): "event read=0: type=4, flags=0x1, seqno=5" <7> [211.473658] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:6: (27286/27283) -> (27285/27285): "event read=0: type=13, flags=0x1, seqno=6" <7> [211.474980] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:9: (27286/27283) -> (27285/27285): "event read=0: type=6, flags=0x4, seqno=7" <7> [211.475005] xe 0000:00:02.0: [drm:prelim_xe_eudebug_vm_bind_ufence [xe]] eudbg: 3271:10: (8859/8859) -> (27285/27285): "ACK: seqno=10: tracking started" <7> [211.475065] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:10: (27286/27283) -> (27285/27285): "event read=0: type=7, flags=0x1, seqno=8" <7> [211.475138] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:10: (27286/27283) -> (27285/27285): "event read=0: type=7, flags=0x1, seqno=9" <7> [211.475226] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:10: (27286/27283) -> (27285/27285): "event read=0: type=8, flags=0x9, seqno=10" <7> [211.475331] xe 0000:00:02.0: [drm:handle_ack [xe]] eudbg: 3271:10: (27286/27283) -> (27285/27285): "ACK: seqno=10: signalled by 10 (debugger) (held 326us)" <7> [211.475420] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:10: (27286/27283) -> (27285/27285): "ioctl cmd=EVENT_ACK ret=0\n" <7> [211.475872] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:10: (27283/27283) -> (27285/27285): "eu_control: client_handle=1, cmd=0, flags=0x0, exec_queue_handle=1, bitmask_size=0\n" <7> [211.476024] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone RCU_DEBUG_1 = 0x00000280 <7> [211.476126] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine rcs0: runalone=active, context=active <7> [211.476235] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone engine rcs0 found <7> [211.476327] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine ccs0: runalone=inactive, context=inactive <7> [211.477195] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:11: (27283/27283) -> (27285/27285): "eu_control: client_handle=1, cmd=0, flags=0x0, exec_queue_handle=1, bitmask_size=0 ret=0\n" <7> [211.477305] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:11: (27283/27283) -> (27285/27285): "ioctl cmd=EU_CONTROL ret=0\n" <7> [211.574300] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone RCU_DEBUG_1 = 0x00000280 <7> [211.574949] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine rcs0: runalone=active, context=active <7> [211.575561] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone engine rcs0 found <7> [211.576092] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine ccs0: runalone=inactive, context=inactive <7> [211.576916] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:12: (27286/27283) -> (27285/27285): "event read=0: type=5, flags=0x4, seqno=12" <7> [211.576899] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone RCU_DEBUG_1 = 0x00000280 <7> [211.577370] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:12: (27286/27283) -> (27285/27285): "eu_control: client_handle=1, cmd=1, flags=0x0, exec_queue_handle=1, bitmask_size=56\n" <7> [211.577443] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine rcs0: runalone=active, context=active <7> [211.577646] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone RCU_DEBUG_1 = 0x00000280 <7> [211.577861] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine rcs0: runalone=active, context=active <7> [211.578078] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone engine rcs0 found <7> [211.577954] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone engine rcs0 found <7> [211.578330] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine ccs0: runalone=inactive, context=inactive <7> [211.578485] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine ccs0: runalone=inactive, context=inactive <7> [211.578693] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:13: (27286/27283) -> (27285/27285): "eu_control: client_handle=1, cmd=1, flags=0x0, exec_queue_handle=1, bitmask_size=56 ret=0\n" <7> [211.578907] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:13: (27286/27283) -> (27285/27285): "ioctl cmd=EU_CONTROL ret=0\n" <7> [211.579039] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone RCU_DEBUG_1 = 0x00000280 <7> [211.579258] xe 0000:00:02.0: [drm:xe_eudebug_vm_fsync [xe]] eudbg: 3271:13: (27286/27283) -> (27285/27285): "vm_fsync: client_handle=1, vm_handle=1, flags=0x0, start=0, end=9223372036854775807 datasync=0\n" <7> [211.579560] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:13: (27286/27283) -> (27285/27285): "eu_control: client_handle=1, cmd=2, flags=0x0, exec_queue_handle=1, bitmask_size=56\n" <7> [211.579498] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine rcs0: runalone=active, context=active <7> [211.579770] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone RCU_DEBUG_1 = 0x00000280 <7> [211.580004] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine rcs0: runalone=active, context=active <7> [211.579931] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone engine rcs0 found <7> [211.580216] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone engine rcs0 found <7> [211.580412] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine ccs0: runalone=inactive, context=inactive <7> [211.580379] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine ccs0: runalone=inactive, context=inactive <7> [211.580638] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3271:13: (27286/27283) -> (27285/27285): "EU_ATT_CLR: (0:0:0:0): 0xffffffff\n" <7> [211.580837] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3271:13: (27286/27283) -> (27285/27285): "EU_ATT_CLR: (0:0:0:1): 0xffffffff\n" <7> [211.581027] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3271:13: (27286/27283) -> (27285/27285): "EU_ATT_CLR: (0:1:0:0): 0xffffffff\n" <7> [211.581266] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3271:13: (27286/27283) -> (27285/27285): "EU_ATT_CLR: (0:1:0:1): 0xffffffff\n" <7> [211.581458] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3271:13: (27286/27283) -> (27285/27285): "EU_ATT_CLR: (0:2:0:0): 0xffffffff\n" <7> [211.581664] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3271:13: (27286/27283) -> (27285/27285): "EU_ATT_CLR: (0:2:0:1): 0xffffffff\n" <7> [211.581843] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3271:13: (27286/27283) -> (27285/27285): "EU_ATT_CLR: (0:3:0:0): 0xffffffff\n" <7> [211.582024] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3271:13: (27286/27283) -> (27285/27285): "EU_ATT_CLR: (0:3:0:1): 0xffffffff\n" <7> [211.582244] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3271:13: (27286/27283) -> (27285/27285): "EU_ATT_CLR: (1:0:0:0): 0xffffffff\n" <7> [211.582446] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3271:13: (27286/27283) -> (27285/27285): "EU_ATT_CLR: (1:0:0:1): 0xffffffff\n" <7> [211.582701] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3271:13: (27286/27283) -> (27285/27285): "EU_ATT_CLR: (1:1:0:0): 0xffffffff\n" <7> [211.582913] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3271:13: (27286/27283) -> (27285/27285): "EU_ATT_CLR: (1:1:0:1): 0xffffffff\n" <7> [211.583133] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3271:13: (27286/27283) -> (27285/27285): "EU_ATT_CLR: (1:2:0:0): 0xffffffff\n" <7> [211.583385] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3271:13: (27286/27283) -> (27285/27285): "EU_ATT_CLR: (1:2:0:1): 0xffffffff\n" <7> [211.583609] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:15: (27286/27283) -> (27285/27285): "eu_control: client_handle=1, cmd=2, flags=0x0, exec_queue_handle=1, bitmask_size=56 ret=0\n" <7> [211.583863] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:15: (27286/27283) -> (27285/27285): "ioctl cmd=EU_CONTROL ret=0\n" <7> [211.584104] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:15: (27286/27283) -> (27285/27285): "event read=0: type=5, flags=0x4, seqno=15" <7> [211.584353] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:15: (27286/27283) -> (27285/27285): "eu_control: client_handle=1, cmd=1, flags=0x0, exec_queue_handle=1, bitmask_size=56\n" <7> [211.584735] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone RCU_DEBUG_1 = 0x00000000 <7> [211.584927] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine rcs0: runalone=inactive, context=inactive <7> [211.585121] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine ccs0: runalone=inactive, context=inactive <7> [211.585358] xe 0000:00:02.0: [drm:runalone_active_queue_get [xe]] Runalone engine not found! <7> [211.585566] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:17: (27286/27283) -> (27285/27285): "eu_control: client_handle=1, cmd=1, flags=0x0, exec_queue_handle=1, bitmask_size=56 ret=-2\n" <7> [211.585768] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:17: (27286/27283) -> (27285/27285): "ioctl cmd=EU_CONTROL ret=-2\n" <6> [211.604350] [IGT] xe_eudebug_online: finished subtest DRM_XE_ENGINE_CLASS_RENDER0, FAIL <6> [211.607461] [IGT] xe_eudebug_online: finished subtest interrupt-all, FAIL <7> [211.609111] xe 0000:00:02.0: [drm:drm_client_dev_restore] fbdev: ret=0 <6> [211.609168] [IGT] xe_eudebug_online: exiting, ret=98 <7> [211.613150] xe 0000:00:02.0: [drm:xe_eudebug_vm_release [xe]] eudbg: 3271:20: (27283/27283) -> (27285/27285): "vm_release: client_handle=1, vm_handle=1, flags=0x0" <7> [211.613418] xe 0000:00:02.0: [drm:xe_eudebug_detach [xe]] eudbg: 3271:20:disconnected (27283/27283) -> (27285/27285): "session 3271 detached with 0" <7> [211.613556] xe 0000:00:02.0: [drm:xe_eudebug_release [xe]] eudbg: 3271:20:disconnected (27283/27283) -> (27285/27285): "Session closed (0)"

Detail

Value

Duration

0.14 seconds

Igt-Version

IGT-Version: 2.3-g105262d67 (x86_64) (Linux: 6.17.13-lgci-xe-xkb-pr-main-261-468-c6fbf+ x86_64)

Out

Using IGT_SRANDOM=1770173146 for randomisation
Opened device: /dev/dri/card0
Using IGT_SRANDOM=1770173146 for randomisation
Starting subtest: interrupt-all
Starting dynamic subtest: DRM_XE_ENGINE_CLASS_RENDER0
client running with pid 27285
[thread:27286] Stack trace:
[thread:27286]   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
[thread:27286]   #1 ../tests/intel/xe_eudebug_online.c:728 eu_attention_resume_trigger()
[thread:27286]   #2 ../lib/xe/xe_eudebug.c:1131 debugger_worker_loop()
[thread:27286]   #3 [pthread_condattr_setpshared+0x684]
[thread:27286]   #4 [__clone+0x24c]
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../lib/xe/xe_eudebug.c:835 compare_client()
  #2 ../lib/xe/xe_eudebug.c:1074 xe_eudebug_event_log_compare()
  #3 ../lib/xe/xe_eudebug.c:1781 xe_eudebug_session_check()
  #4 ../tests/intel/xe_eudebug_online.c:1352 online_session_check()
  #5 ../tests/intel/xe_eudebug_online.c:1958 test_interrupt_all()
  #6 ../tests/intel/xe_eudebug_online.c:2715 __igt_unique____real_main2671()
  #7 ../tests/intel/xe_eudebug_online.c:2671 main()
  #8 [__libc_init_first+0x8a]
  #9 [__libc_start_main+0x8b]
  #10 [_start+0x25]
Dynamic subtest DRM_XE_ENGINE_CLASS_RENDER0: FAIL (0.139s)
Subtest interrupt-all: FAIL (0.143s)

Err

Starting subtest: interrupt-all
Starting dynamic subtest: DRM_XE_ENGINE_CLASS_RENDER0
(xe_eudebug_online:27283) [thread:27286] CRITICAL: Test assertion failure function eu_ctl, file ../tests/intel/xe_eudebug_online.c:395:
(xe_eudebug_online:27283) [thread:27286] CRITICAL: Failed assertion: __eu_ctl(debugfd, client, exec_queue, lrc, bitmask, bitmask_size, cmd, &seqno) == 0
(xe_eudebug_online:27283) [thread:27286] CRITICAL: Last errno: 2, No such file or directory
(xe_eudebug_online:27283) [thread:27286] CRITICAL: error: -2 != 0
(xe_eudebug_online:27283) xe/xe_eudebug-CRITICAL: Test assertion failure function compare_client, file ../lib/xe/xe_eudebug.c:835:
(xe_eudebug_online:27283) xe/xe_eudebug-CRITICAL: Failed assertion: evptr2
(xe_eudebug_online:27283) xe/xe_eudebug-CRITICAL: client (7): no matching event type 4 found for client 7
Dynamic subtest DRM_XE_ENGINE_CLASS_RENDER0 failed.
**** DEBUG ****
(xe_eudebug_online:27283) DEBUG: Test requirement passed: !(flags & FAULTABLE_VM) || !xe_supports_faults(fd)
(xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: debugger: 27283 waiting for token 'client pid'
(xe_eudebug_online:27283) xe/xe_eudebug-INFO: client running with pid 27285
(xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: added trigger 0x61ba502ee6e0
(xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: added trigger 0x61ba502ee710
(xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: added trigger 0x61ba502ee740
(xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: added trigger 0x61ba502ee770
(xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: added trigger 0x61ba502ee7a0
(xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: added trigger 0x61ba502ee7d0
(xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: added trigger 0x61ba502ee800
(xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: debugger connected to 27285
(xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: 27283 signalling token 'client run' with value '27285'
(xe_eudebug_online:27283) [thread:27284] intel_allocator_simple-DEBUG: Using simple allocator
(xe_eudebug_online:27283) [thread:27286] xe/xe_eudebug-DEBUG: delivering ack for event: (10) vm_bind_ufence(8):create|ack: vm_bind_ref_seqno=7
(xe_eudebug_online:27283) DEBUG: EU CONTROL[11]: interrupt all
(xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: debugger: 27283 waiting for token 'client fini'
(xe_eudebug_online:27283) [thread:27286] DEBUG: EVENT[12] eu-attenttion; threads=448 client[1], exec_queue[1], lrc[1], bitmask_size[56]
(xe_eudebug_online:27283) [thread:27286] DEBUG: bitmask[0] = 0xffffffffffffffff
(xe_eudebug_online:27283) [thread:27286] DEBUG: bitmask[1] = 0xffffffffffffffff
(xe_eudebug_online:27283) [thread:27286] DEBUG: bitmask[2] = 0xffffffffffffffff
(xe_eudebug_online:27283) [thread:27286] DEBUG: bitmask[3] = 0xffffffffffffffff
(xe_eudebug_online:27283) [thread:27286] DEBUG: bitmask[4] = 0xffffffffffffffff
(xe_eudebug_online:27283) [thread:27286] DEBUG: bitmask[5] = 0xffffffffffffffff
(xe_eudebug_online:27283) [thread:27286] DEBUG: bitmask[6] = 0xffffffffffffffff
(xe_eudebug_online:27283) [thread:27286] DEBUG: EU CONTROL[13]: stopped
(xe_eudebug_online:27283) [thread:27286] DEBUG: EU CONTROL[14]: resume
(xe_eudebug_online:27283) [thread:27286] DEBUG: EVENT[15] eu-attenttion; threads=0 client[1], exec_queue[1], lrc[1], bitmask_size[56]
(xe_eudebug_online:27283) [thread:27286] DEBUG: bitmask[0] = 0x0000000000000000
(xe_eudebug_online:27283) [thread:27286] DEBUG: bitmask[1] = 0x0000000000000000
(xe_eudebug_online:27283) [thread:27286] DEBUG: bitmask[2] = 0x0000000000000000
(xe_eudebug_online:27283) [thread:27286] DEBUG: bitmask[3] = 0x0000000000000000
(xe_eudebug_online:27283) [thread:27286] DEBUG: bitmask[4] = 0x0000000000000000
(xe_eudebug_online:27283) [thread:27286] DEBUG: bitmask[5] = 0x0000000000000000
(xe_eudebug_online:27283) [thread:27286] DEBUG: bitmask[6] = 0x0000000000000000
(xe_eudebug_online:27283) [thread:27284] intel_allocator_simple-DEBUG: <ial: 0x736ce0000c20, fd: 7> objects: 0, reserved_areas: 1
(xe_eudebug_online:27283) [thread:27284] intel_allocator_simple-DEBUG: <ial: 0x736ce0000c20, fd: 7> objects: 0, reserved_areas: 1
(xe_eudebug_online:27283) [thread:27286] CRITICAL: Test assertion failure function eu_ctl, file ../tests/intel/xe_eudebug_online.c:395:
(xe_eudebug_online:27283) [thread:27286] CRITICAL: Failed assertion: __eu_ctl(debugfd, client, exec_queue, lrc, bitmask, bitmask_size, cmd, &seqno) == 0
(xe_eudebug_online:27283) [thread:27286] CRITICAL: Last errno: 2, No such file or directory
(xe_eudebug_online:27283) [thread:27286] CRITICAL: error: -2 != 0
(xe_eudebug_online:27283) [thread:27286] igt_core-INFO: Stack trace:
(xe_eudebug_online:27283) [thread:27286] igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(xe_eudebug_online:27283) [thread:27286] igt_core-INFO:   #1 ../tests/intel/xe_eudebug_online.c:728 eu_attention_resume_trigger()
(xe_eudebug_online:27283) [thread:27286] igt_core-INFO:   #2 ../lib/xe/xe_eudebug.c:1131 debugger_worker_loop()
(xe_eudebug_online:27283) [thread:27286] igt_core-INFO:   #3 [pthread_condattr_setpshared+0x684]
(xe_eudebug_online:27283) [thread:27286] igt_core-INFO:   #4 [__clone+0x24c]
(xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: event log 'debugger' (736 bytes):
(xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: (1)       client(2):create: handle=1
(xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: (2)     metadata(9):create: client_handle=1, metadata_handle=1, type=0, len=16
(xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: (3)     metadata(9):create: client_handle=1, metadata_handle=2, type=1, len=16
(xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: (4)           vm(3):create: client_handle=1, handle=1
(xe_eudebug_online:27283) 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:27283) 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:27283) xe/xe_eudebug-DEBUG: (7)      vm_bind(6):state-change: client_handle=1, vm_handle=1, flags=0x1, num_binds=2
(xe_eudebug_online:27283) 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:27283) xe/xe_eudebug-DEBUG: (9)   vm_bind_op(7):create: vm_bind_ref_seqno=7, addr=000000001a000000, range=12288 num_extensions=0
(xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: (10) vm_bind_ufence(8):create|ack: vm_bind_ref_seqno=7
(xe_eudebug_online:27283) 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:27283) 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:27283) xe/xe_eudebug-DEBUG: event log 'client' (552 bytes):
(xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: (1)       client(2):create: handle=7
(xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: (2)     metadata(9):create: client_handle=7, metadata_handle=1, type=0, len=16
(xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: (3)     metadata(9):create: client_handle=7, metadata_handle=2, type=1, len=16
(xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: (4)           vm(3):create: client_handle=7, handle=1
(xe_eudebug_online:27283) 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:27283) 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:27283) 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:27283) xe/xe_eudebug-DEBUG: (8)           vm(3):destroy: client_handle=7, handle=1
(xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: (9)     metadata(9):destroy: client_handle=7, metadata_handle=1, type=0, len=16
(xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: (10)     metadata(9):destroy: client_handle=7, metadata_handle=2, type=1, len=16
(xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: (11)       client(2):destroy: handle=7
(xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: client: 7 -> 1
(xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: comparing client 1 vs debugger 1
(xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: comparing client 2 vs debugger 2
(xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: comparing client 3 vs debugger 3
(xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: comparing client 4 vs debugger 4
(xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: comparing client 5 vs debugger 5
(xe_eudebug_online:27283) xe/xe_eudebug-DEBUG: comparing client 6 vs debugger 6
(xe_eudebug_online:27283) xe/xe_eudebug-CRITICAL: Test assertion failure function compare_client, file ../lib/xe/xe_eudebug.c:835:
(xe_eudebug_online:27283) xe/xe_eudebug-CRITICAL: Failed assertion: evptr2
(xe_eudebug_online:27283) xe/xe_eudebug-CRITICAL: client (7): no matching event type 4 found for client 7
(xe_eudebug_online:27283) igt_core-INFO: Stack trace:
(xe_eudebug_online:27283) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(xe_eudebug_online:27283) igt_core-INFO:   #1 ../lib/xe/xe_eudebug.c:835 compare_client()
(xe_eudebug_online:27283) igt_core-INFO:   #2 ../lib/xe/xe_eudebug.c:1074 xe_eudebug_event_log_compare()
(xe_eudebug_online:27283) igt_core-INFO:   #3 ../lib/xe/xe_eudebug.c:1781 xe_eudebug_session_check()
(xe_eudebug_online:27283) igt_core-INFO:   #4 ../tests/intel/xe_eudebug_online.c:1352 online_session_check()
(xe_eudebug_online:27283) igt_core-INFO:   #5 ../tests/intel/xe_eudebug_online.c:1958 test_interrupt_all()
(xe_eudebug_online:27283) igt_core-INFO:   #6 ../tests/intel/xe_eudebug_online.c:2715 __igt_unique____real_main2671()
(xe_eudebug_online:27283) igt_core-INFO:   #7 ../tests/intel/xe_eudebug_online.c:2671 main()
(xe_eudebug_online:27283) igt_core-INFO:   #8 [__libc_init_first+0x8a]
(xe_eudebug_online:27283) igt_core-INFO:   #9 [__libc_start_main+0x8b]
(xe_eudebug_online:27283) igt_core-INFO:   #10 [_start+0x25]
****  END  ****
Dynamic subtest DRM_XE_ENGINE_CLASS_RENDER0: FAIL (0.139s)
(xe_eudebug_online:27283) igt_thread-CRITICAL: Failure in a thread!
Subtest interrupt-all: FAIL (0.143s)

Dmesg

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

<6> [211.464596] [IGT] xe_eudebug_online: starting subtest interrupt-all

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

<7> [211.469815] xe 0000:00:02.0: [drm:prelim_xe_eudebug_connect_ioctl [xe]] eudbg: 3271:0: (27283/27283) -> (27285/27285): "connected session 3271"

<7> [211.469937] xe 0000:00:02.0: [drm:discovery_work_fn [xe]] eudbg: 3271:0: (8859/8859) -> (27285/27285): "Discovery start for 3271\n"

<7> [211.470040] xe 0000:00:02.0: [drm:discovery_work_fn [xe]] eudbg: 3271:0: (8859/8859) -> (27285/27285): "Discovery end for 3271\n"

<7> [211.470727] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:1: (27286/27283) -> (27285/27285): "event read=0: type=2, flags=0x1, seqno=1"

<7> [211.471073] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:2: (27286/27283) -> (27285/27285): "event read=0: type=9, flags=0x1, seqno=2"

<7> [211.471275] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:3: (27286/27283) -> (27285/27285): "read metadata: client_handle=1, metadata_handle=1, flags=0x0"

<7> [211.471385] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:3: (27286/27283) -> (27285/27285): "ioctl cmd=READ_METADATA ret=0\n"

<7> [211.471480] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:3: (27286/27283) -> (27285/27285): "event read=0: type=9, flags=0x1, seqno=3"

<7> [211.471575] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:3: (27286/27283) -> (27285/27285): "read metadata: client_handle=1, metadata_handle=2, flags=0x0"

<7> [211.471665] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:3: (27286/27283) -> (27285/27285): "ioctl cmd=READ_METADATA ret=0\n"

<7> [211.472287] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:4: (27286/27283) -> (27285/27285): "event read=0: type=3, flags=0x1, seqno=4"

<7> [211.472416] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:4: (27286/27283) -> (27285/27285): "vm_open: client_handle=1, handle=1, flags=0x0, fd=10"

<7> [211.472514] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:4: (27286/27283) -> (27285/27285): "ioctl cmd=VM_OPEN ret=10\n"

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

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

<7> [211.473577] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:6: (27286/27283) -> (27285/27285): "event read=0: type=4, flags=0x1, seqno=5"

<7> [211.473658] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:6: (27286/27283) -> (27285/27285): "event read=0: type=13, flags=0x1, seqno=6"

<7> [211.474980] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:9: (27286/27283) -> (27285/27285): "event read=0: type=6, flags=0x4, seqno=7"

<7> [211.475005] xe 0000:00:02.0: [drm:prelim_xe_eudebug_vm_bind_ufence [xe]] eudbg: 3271:10: (8859/8859) -> (27285/27285): "ACK: seqno=10: tracking started"

<7> [211.475065] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:10: (27286/27283) -> (27285/27285): "event read=0: type=7, flags=0x1, seqno=8"

<7> [211.475138] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:10: (27286/27283) -> (27285/27285): "event read=0: type=7, flags=0x1, seqno=9"

<7> [211.475226] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:10: (27286/27283) -> (27285/27285): "event read=0: type=8, flags=0x9, seqno=10"

<7> [211.475331] xe 0000:00:02.0: [drm:handle_ack [xe]] eudbg: 3271:10: (27286/27283) -> (27285/27285): "ACK: seqno=10: signalled by 10 (debugger) (held 326us)"

<7> [211.475420] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:10: (27286/27283) -> (27285/27285): "ioctl cmd=EVENT_ACK ret=0\n"

<7> [211.475872] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:10: (27283/27283) -> (27285/27285): "eu_control: client_handle=1, cmd=0, flags=0x0, exec_queue_handle=1, bitmask_size=0\n"

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

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

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

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

<7> [211.477195] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:11: (27283/27283) -> (27285/27285): "eu_control: client_handle=1, cmd=0, flags=0x0, exec_queue_handle=1, bitmask_size=0 ret=0\n"

<7> [211.477305] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:11: (27283/27283) -> (27285/27285): "ioctl cmd=EU_CONTROL ret=0\n"

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

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

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

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

<7> [211.576916] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:12: (27286/27283) -> (27285/27285): "event read=0: type=5, flags=0x4, seqno=12"

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

<7> [211.577370] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:12: (27286/27283) -> (27285/27285): "eu_control: client_handle=1, cmd=1, flags=0x0, exec_queue_handle=1, bitmask_size=56\n"

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

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

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

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

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

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

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

<7> [211.578693] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:13: (27286/27283) -> (27285/27285): "eu_control: client_handle=1, cmd=1, flags=0x0, exec_queue_handle=1, bitmask_size=56 ret=0\n"

<7> [211.578907] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:13: (27286/27283) -> (27285/27285): "ioctl cmd=EU_CONTROL ret=0\n"

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

<7> [211.579258] xe 0000:00:02.0: [drm:xe_eudebug_vm_fsync [xe]] eudbg: 3271:13: (27286/27283) -> (27285/27285): "vm_fsync: client_handle=1, vm_handle=1, flags=0x0, start=0, end=9223372036854775807 datasync=0\n"

<7> [211.579560] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:13: (27286/27283) -> (27285/27285): "eu_control: client_handle=1, cmd=2, flags=0x0, exec_queue_handle=1, bitmask_size=56\n"

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

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

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

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

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

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

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

<7> [211.580638] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3271:13: (27286/27283) -> (27285/27285): "EU_ATT_CLR: (0:0:0:0): 0xffffffff\n"

<7> [211.580837] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3271:13: (27286/27283) -> (27285/27285): "EU_ATT_CLR: (0:0:0:1): 0xffffffff\n"

<7> [211.581027] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3271:13: (27286/27283) -> (27285/27285): "EU_ATT_CLR: (0:1:0:0): 0xffffffff\n"

<7> [211.581266] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3271:13: (27286/27283) -> (27285/27285): "EU_ATT_CLR: (0:1:0:1): 0xffffffff\n"

<7> [211.581458] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3271:13: (27286/27283) -> (27285/27285): "EU_ATT_CLR: (0:2:0:0): 0xffffffff\n"

<7> [211.581664] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3271:13: (27286/27283) -> (27285/27285): "EU_ATT_CLR: (0:2:0:1): 0xffffffff\n"

<7> [211.581843] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3271:13: (27286/27283) -> (27285/27285): "EU_ATT_CLR: (0:3:0:0): 0xffffffff\n"

<7> [211.582024] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3271:13: (27286/27283) -> (27285/27285): "EU_ATT_CLR: (0:3:0:1): 0xffffffff\n"

<7> [211.582244] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3271:13: (27286/27283) -> (27285/27285): "EU_ATT_CLR: (1:0:0:0): 0xffffffff\n"

<7> [211.582446] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3271:13: (27286/27283) -> (27285/27285): "EU_ATT_CLR: (1:0:0:1): 0xffffffff\n"

<7> [211.582701] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3271:13: (27286/27283) -> (27285/27285): "EU_ATT_CLR: (1:1:0:0): 0xffffffff\n"

<7> [211.582913] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3271:13: (27286/27283) -> (27285/27285): "EU_ATT_CLR: (1:1:0:1): 0xffffffff\n"

<7> [211.583133] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3271:13: (27286/27283) -> (27285/27285): "EU_ATT_CLR: (1:2:0:0): 0xffffffff\n"

<7> [211.583385] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3271:13: (27286/27283) -> (27285/27285): "EU_ATT_CLR: (1:2:0:1): 0xffffffff\n"

<7> [211.583609] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:15: (27286/27283) -> (27285/27285): "eu_control: client_handle=1, cmd=2, flags=0x0, exec_queue_handle=1, bitmask_size=56 ret=0\n"

<7> [211.583863] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:15: (27286/27283) -> (27285/27285): "ioctl cmd=EU_CONTROL ret=0\n"

<7> [211.584104] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:15: (27286/27283) -> (27285/27285): "event read=0: type=5, flags=0x4, seqno=15"

<7> [211.584353] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:15: (27286/27283) -> (27285/27285): "eu_control: client_handle=1, cmd=1, flags=0x0, exec_queue_handle=1, bitmask_size=56\n"

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

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

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

<7> [211.585358] xe 0000:00:02.0: [drm:runalone_active_queue_get [xe]] Runalone engine not found!

<7> [211.585566] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:17: (27286/27283) -> (27285/27285): "eu_control: client_handle=1, cmd=1, flags=0x0, exec_queue_handle=1, bitmask_size=56 ret=-2\n"

<7> [211.585768] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3271:17: (27286/27283) -> (27285/27285): "ioctl cmd=EU_CONTROL ret=-2\n"

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

<6> [211.607461] [IGT] xe_eudebug_online: finished subtest interrupt-all, FAIL

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

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

<7> [211.613150] xe 0000:00:02.0: [drm:xe_eudebug_vm_release [xe]] eudbg: 3271:20: (27283/27283) -> (27285/27285): "vm_release: client_handle=1, vm_handle=1, flags=0x0"

<7> [211.613418] xe 0000:00:02.0: [drm:xe_eudebug_detach [xe]] eudbg: 3271:20:disconnected (27283/27283) -> (27285/27285): "session 3271 detached with 0"

<7> [211.613556] xe 0000:00:02.0: [drm:xe_eudebug_release [xe]] eudbg: 3271:20:disconnected (27283/27283) -> (27285/27285): "Session closed (0)"

Results for igt@xe_eudebug_online@interrupt-all