igt@xe_eudebug_online@pagefault-read on bat-ptl-1@xkb-226-520362ff61a16e40d24e7bed21b21463994304b9 Test details

Detail	Value
Duration	60.15 seconds
Igt-Version	IGT-Version: 2.3-gc142aab3a (x86_64) (Linux: 6.17.13-lgci-xe-xkb-226-520362ff61a16e40d-debug+ x86_64)
Out	Using IGT_SRANDOM=1773745316 for randomisation Opened device: /dev/dri/card0 Using IGT_SRANDOM=1773745316 for randomisation Starting subtest: pagefault-read Starting dynamic subtest: DRM_XE_ENGINE_CLASS_RENDER0 client running with pid 27522 Stack trace: #0 ../lib/igt_core.c:2075 __igt_fail_assert() #1 [wait_from_client+0x8d] #2 ../lib/xe/xe_eudebug.c:1673 xe_eudebug_client_wait_done() #3 ../lib/xe/xe_eudebug.c:1767 xe_eudebug_session_run() #4 ../tests/intel/xe_eudebug_online.c:1824 test_pagefault_online() #5 ../tests/intel/xe_eudebug_online.c:2801 __igt_unique____real_main2678() #6 ../tests/intel/xe_eudebug_online.c:2678 main() #7 [__libc_init_first+0x8a] #8 [__libc_start_main+0x8b] #9 [_start+0x25] Dynamic subtest DRM_XE_ENGINE_CLASS_RENDER0: FAIL (60.146s) Subtest pagefault-read: FAIL (60.152s) Test requirement not met in function xe_eudebug_enable, file ../lib/xe/xe_eudebug.c:1977: Test requirement: !(ret) Last errno: 16, Device or resource busy
Err	Starting subtest: pagefault-read Starting dynamic subtest: DRM_XE_ENGINE_CLASS_RENDER0 (xe_eudebug_online:27520) xe/xe_eudebug-CRITICAL: Test assertion failure function wait_from_client, file ../lib/xe/xe_eudebug.c:401: (xe_eudebug_online:27520) xe/xe_eudebug-CRITICAL: Failed assertion: c->allow_dead_client Dynamic subtest DRM_XE_ENGINE_CLASS_RENDER0 failed. ** DEBUG (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: debugger: 27520 waiting for token 'client pid' (xe_eudebug_online:27520) xe/xe_eudebug-INFO: client running with pid 27522 (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: added trigger 0x5f7008c926f0 (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: added trigger 0x5f7008c92720 (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: added trigger 0x5f7008c927b0 (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: added trigger 0x5f7008c927e0 (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: added trigger 0x5f7008c92810 (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: added trigger 0x5f7008c92840 (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: added trigger 0x5f7008c92870 (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: added trigger 0x5f7008c928a0 (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: debugger connected to 27522 (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: 27520 signalling token 'client run' with value '27522' (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: debugger: 27520 waiting for token 'client fini' (xe_eudebug_online:27520) [thread:27521] intel_allocator_simple-DEBUG: Using simple allocator (xe_eudebug_online:27520) [thread:27523] xe/xe_eudebug-DEBUG: delivering ack for event: (10) vm_bind_ufence(8):create\|ack: vm_bind_ref_seqno=7 (xe_eudebug_online:27520) [thread:27523] DEBUG: EVENT[11] pagefault; threads[before=0, after=0, resolved=4, pagefault=4] client[1], exec_queue[1], lrc[1], bitmask_size[576], pagefault_address[0x12345678000] (xe_eudebug_online:27520) [thread:27523] DEBUG: === Attentions before === (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[0] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[1] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[2] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[3] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[4] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[5] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[6] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[7] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[8] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[9] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[10] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[11] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[12] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[13] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[14] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[15] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[16] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[17] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[18] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[19] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[20] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[21] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[22] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[23] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: === Attentions after === (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[0] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[1] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[2] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[3] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[4] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[5] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[6] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[7] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[8] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[9] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[10] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[11] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[12] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[13] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[14] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[15] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[16] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[17] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[18] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[19] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[20] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[21] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[22] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[23] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: === Attentions resolved === (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[0] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[1] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[2] = 0x0000000100000001 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[3] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[4] = 0x0000000100000001 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[5] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[6] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[7] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[8] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[9] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[10] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[11] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[12] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[13] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[14] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[15] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[16] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[17] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[18] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[19] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[20] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[21] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[22] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[23] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: EVENT[12] eu-attenttion; threads=4 client[1], exec_queue[1], lrc[1], bitmask_size[192] (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[0] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[1] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[2] = 0x0000000100000001 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[3] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[4] = 0x0000000100000001 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[5] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[6] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[7] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[8] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[9] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[10] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[11] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[12] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[13] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[14] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[15] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[16] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[17] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[18] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[19] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[20] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[21] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[22] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[23] = 0x0000000000000000 (xe_eudebug_online:27520) [thread:27523] DEBUG: EU CONTROL[13]: stopped (xe_eudebug_online:27520) [thread:27523] DEBUG: EU CONTROL[14]: resume (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout (xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: safe_pipe_read failed with error: -110 waiting for token 'client fini:(3)' (xe_eudebug_online:27520) xe/xe_eudebug-CRITICAL: Test assertion failure function wait_from_client, file ../lib/xe/xe_eudebug.c:401: (xe_eudebug_online:27520) xe/xe_eudebug-CRITICAL: Failed assertion: c->allow_dead_client (xe_eudebug_online:27520) igt_core-INFO: Stack trace: (xe_eudebug_online:27520) igt_core-INFO: #0 ../lib/igt_core.c:2075 __igt_fail_assert() (xe_eudebug_online:27520) igt_core-INFO: #1 [wait_from_client+0x8d] (xe_eudebug_online:27520) igt_core-INFO: #2 ../lib/xe/xe_eudebug.c:1673 xe_eudebug_client_wait_done() (xe_eudebug_online:27520) igt_core-INFO: #3 ../lib/xe/xe_eudebug.c:1767 xe_eudebug_session_run() (xe_eudebug_online:27520) igt_core-INFO: #4 ../tests/intel/xe_eudebug_online.c:1824 test_pagefault_online() (xe_eudebug_online:27520) igt_core-INFO: #5 ../tests/intel/xe_eudebug_online.c:2801 __igt_unique____real_main2678() (xe_eudebug_online:27520) igt_core-INFO: #6 ../tests/intel/xe_eudebug_online.c:2678 main() (xe_eudebug_online:27520) igt_core-INFO: #7 [__libc_init_first+0x8a] (xe_eudebug_online:27520) igt_core-INFO: #8 [__libc_start_main+0x8b] (xe_eudebug_online:27520) igt_core-INFO: #9 [_start+0x25] END ** Dynamic subtest DRM_XE_ENGINE_CLASS_RENDER0: FAIL (60.146s) Subtest pagefault-read: FAIL (60.152s)
Dmesg	<6> [257.107719] [IGT] xe_eudebug_online: executing <6> [257.116927] xe 0000:00:02.0: [drm] Tile0: GT0: trying reset from xe_eudebug_enable [xe] <6> [257.117372] xe 0000:00:02.0: [drm] Tile0: GT0: reset queued <6> [257.117531] xe 0000:00:02.0: [drm] Tile0: GT0: reset started <7> [257.117919] xe 0000:00:02.0: [drm:guc_ct_change_state [xe]] Tile0: GT0: GuC CT communication channel stopped <7> [257.118524] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: Applying GT save-restore MMIOs <7> [257.118913] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x4148] = 0x00000000 <7> [257.119287] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x8828] = 0x00800000 <7> [257.119785] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0xb104] = 0x08104440 <7> [257.120101] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0xb108] = 0x30200000 <7> [257.120415] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0xb158] = 0x0000007f <7> [257.120765] xe 0000:00:02.0: [drm:xe_wopcm_init [xe]] WOPCM: 2048K <7> [257.121130] xe 0000:00:02.0: [drm:xe_wopcm_init [xe]] GuC WOPCM is already locked [6144K, 832K) <7> [257.121525] xe 0000:00:02.0: [drm:guc_ct_change_state [xe]] Tile0: GT0: GuC CT communication channel disabled <7> [257.123565] xe 0000:00:02.0: [drm:xe_guc_ads_populate [xe]] Tile0: GT0: Updated ADS capture size 20480 (was 45056) <7> [257.135724] xe 0000:00:02.0: [drm:guc_wait_ucode [xe]] Tile0: GT0: GuC load: init took 10ms, freq = 2000MHz (req = 2000MHz), before = 2000MHz, status = 0x8002F034 <7> [257.136353] xe 0000:00:02.0: [drm:guc_ct_change_state [xe]] Tile0: GT0: GuC CT communication channel enabled <7> [257.138044] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: flag:0x1 <7> [257.138389] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: mocs entries: 16 <7> [257.138780] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[0] 0x4000 0xc <7> [257.139117] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[1] 0x4004 0x10c <7> [257.139408] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[2] 0x4008 0x130 <7> [257.139748] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[3] 0x400c 0x13c <7> [257.140071] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[4] 0x4010 0x100 <7> [257.140358] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[5] 0x4014 0x100 <7> [257.140674] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[6] 0x4018 0x100 <7> [257.140960] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[7] 0x401c 0x100 <7> [257.141243] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[8] 0x4020 0x100 <7> [257.141544] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[9] 0x4024 0x100 <7> [257.141825] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[10] 0x4028 0x100 <7> [257.142101] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[11] 0x402c 0x100 <7> [257.142373] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[12] 0x4030 0x100 <7> [257.142683] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[13] 0x4034 0x100 <7> [257.142960] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[14] 0x4038 0x100 <7> [257.143239] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT0: GLOB_MOCS[15] 0x403c 0x100 <7> [257.143708] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: Applying rcs0 save-restore MMIOs <7> [257.144106] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x2050] = 0x10001000 <7> [257.144418] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x20c4] = 0x3f7e0306 <7> [257.144764] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x20d4] = 0x00800080 <7> [257.145076] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x24d0] = 0x1000dafc <7> [257.145391] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x24d4] = 0x1000db01 <7> [257.145737] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x24d8] = 0x0000db1c <7> [257.146051] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0xe194] = 0x00400040 <7> [257.146370] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0xe400] = 0x00000095 <7> [257.146707] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0xe4c4] = 0x00400040 <7> [257.147008] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0xe4f0] = 0x00020002 <7> [257.147317] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0xe530] = 0x00000400 <7> [257.147699] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0xe5f4] = 0x10001000 <7> [257.148012] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: Applying bcs0 save-restore MMIOs <7> [257.148300] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0xe4c4] = 0x00400040 <7> [257.148628] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x22050] = 0x10001000 <7> [257.148986] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x220c4] = 0x3f7e0306 <7> [257.149312] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x220d4] = 0x00800080 <7> [257.149631] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: Applying bcs8 save-restore MMIOs <7> [257.149914] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0xe4c4] = 0x00400040 <7> [257.150218] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x3ee050] = 0x10001000 <7> [257.150523] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x3ee0c4] = 0x3f7e0306 <7> [257.150802] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x3ee0d4] = 0x00800080 <7> [257.151218] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: Applying ccs0 save-restore MMIOs <7> [257.151552] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0xe4c4] = 0x00400040 <7> [257.151846] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x1a050] = 0x10001000 <7> [257.152126] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x1a0c4] = 0x3f7e0306 <7> [257.152402] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x1a0d4] = 0x00800080 <7> [257.152725] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x1a4d0] = 0x1000dafc <7> [257.153001] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x1a4d4] = 0x1000db01 <7> [257.153273] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0x1a4d8] = 0x0000db1c <6> [257.153629] xe 0000:00:02.0: [drm] Tile0: GT0: reset done <6> [257.153811] xe 0000:00:02.0: [drm] Tile0: GT1: trying reset from xe_eudebug_enable [xe] <6> [257.154237] xe 0000:00:02.0: [drm] Tile0: GT1: reset queued <6> [257.154275] xe 0000:00:02.0: [drm] Tile0: GT1: reset started <7> [257.154276] xe 0000:00:02.0: [drm:xe_gt_sriov_pf_config_restart [xe]] PF: Tile0: GT0: pushed 0 skip 7 of 7 VFs configurations <7> [257.154468] xe 0000:00:02.0: [drm:guc_ct_change_state [xe]] Tile0: GT1: GuC CT communication channel stopped <7> [257.154768] xe 0000:00:02.0: [drm:pf_worker_restart_func [xe]] PF: Tile0: GT0: restart completed <7> [257.154966] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT1: Applying GT save-restore MMIOs <7> [257.155364] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT1: REG[0x4148] = 0x00000000 <7> [257.155743] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT1: REG[0x82a8] = 0x00800000 <7> [257.156085] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT1: REG[0xb604] = 0x08104440 <7> [257.156410] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT1: REG[0xb608] = 0x30200000 <7> [257.156750] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT1: REG[0xb658] = 0x0000007f <7> [257.157092] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT1: REG[0x1c3f10] = 0x00400000 <7> [257.157407] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT1: REG[0x1c3f1c] = 0x00000008 <7> [257.157844] xe 0000:00:02.0: [drm:xe_wopcm_init [xe]] WOPCM: 2048K <7> [257.158236] xe 0000:00:02.0: [drm:xe_wopcm_init [xe]] GuC WOPCM is already locked [5136K, 832K) <7> [257.158665] xe 0000:00:02.0: [drm:guc_ct_change_state [xe]] Tile0: GT1: GuC CT communication channel disabled <7> [257.160838] xe 0000:00:02.0: [drm:xe_guc_ads_populate [xe]] Tile0: GT1: Updated ADS capture size 20480 (was 45056) <7> [257.181831] xe 0000:00:02.0: [drm:guc_wait_ucode [xe]] Tile0: GT1: GuC load: init took 19ms, freq = 1200MHz (req = 1200MHz), before = 1200MHz, status = 0x8002F034 <7> [257.182610] xe 0000:00:02.0: [drm:guc_ct_change_state [xe]] Tile0: GT1: GuC CT communication channel enabled <7> [257.184061] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT1: flag:0x1 <7> [257.184470] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT1: mocs entries: 16 <7> [257.184835] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT1: GLOB_MOCS[0] 0x4000 0xc <7> [257.185191] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT1: GLOB_MOCS[1] 0x4004 0x10c <7> [257.185555] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT1: GLOB_MOCS[2] 0x4008 0x130 <7> [257.185903] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT1: GLOB_MOCS[3] 0x400c 0x13c <7> [257.186245] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT1: GLOB_MOCS[4] 0x4010 0x100 <7> [257.186592] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT1: GLOB_MOCS[5] 0x4014 0x100 <7> [257.186895] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT1: GLOB_MOCS[6] 0x4018 0x100 <7> [257.187196] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT1: GLOB_MOCS[7] 0x401c 0x100 <7> [257.187512] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT1: GLOB_MOCS[8] 0x4020 0x100 <7> [257.187816] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT1: GLOB_MOCS[9] 0x4024 0x100 <7> [257.188117] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT1: GLOB_MOCS[10] 0x4028 0x100 <7> [257.188418] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT1: GLOB_MOCS[11] 0x402c 0x100 <7> [257.188736] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT1: GLOB_MOCS[12] 0x4030 0x100 <7> [257.189038] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT1: GLOB_MOCS[13] 0x4034 0x100 <7> [257.189337] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT1: GLOB_MOCS[14] 0x4038 0x100 <7> [257.189649] xe 0000:00:02.0: [drm:xe_mocs_init [xe]] Tile0: GT1: GLOB_MOCS[15] 0x403c 0x100 <7> [257.189953] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT1: Applying vcs0 save-restore MMIOs <7> [257.190297] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT1: REG[0x1c0050] = 0x10001000 <7> [257.190658] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT1: Applying vecs0 save-restore MMIOs <7> [257.190987] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT1: REG[0x1c8050] = 0x10001000 <6> [257.191354] xe 0000:00:02.0: [drm] Tile0: GT1: reset done <7> [257.191936] xe 0000:00:02.0: [drm:xe_gt_sriov_pf_config_restart [xe]] PF: Tile0: GT1: pushed 0 skip 7 of 7 VFs configurations <6> [257.192189] [IGT] xe_eudebug_online: starting subtest pagefault-read <7> [257.192467] xe 0000:00:02.0: [drm:pf_worker_restart_func [xe]] PF: Tile0: GT1: restart completed <6> [257.192993] [IGT] xe_eudebug_online: starting dynamic subtest DRM_XE_ENGINE_CLASS_RENDER0 <7> [257.206843] xe 0000:00:02.0: [drm:prelim_xe_eudebug_connect_ioctl [xe]] eudbg: 3274:0: (27520/27520) -> (27522/27522): "connected session 3274" <7> [257.207297] xe 0000:00:02.0: [drm:discovery_work_fn [xe]] eudbg: 3274:0: (9075/9075) -> (27522/27522): "Discovery start for 3274\n" <7> [257.207848] xe 0000:00:02.0: [drm:discovery_work_fn [xe]] eudbg: 3274:0: (9075/9075) -> (27522/27522): "Discovery end for 3274\n" <7> [257.209405] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:1: (27523/27520) -> (27522/27522): "event read=0: type=2, flags=0x1, seqno=1" <7> [257.210908] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:3: (27523/27520) -> (27522/27522): "event read=0: type=9, flags=0x1, seqno=2" <7> [257.211661] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:3: (27523/27520) -> (27522/27522): "read metadata: client_handle=1, metadata_handle=1, flags=0x0" <7> [257.212031] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:3: (27523/27520) -> (27522/27522): "ioctl cmd=READ_METADATA ret=0\n" <7> [257.212372] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:3: (27523/27520) -> (27522/27522): "event read=0: type=9, flags=0x1, seqno=3" <7> [257.212722] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:3: (27523/27520) -> (27522/27522): "read metadata: client_handle=1, metadata_handle=2, flags=0x0" <7> [257.213042] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:3: (27523/27520) -> (27522/27522): "ioctl cmd=READ_METADATA ret=0\n" <7> [257.213681] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:4: (27523/27520) -> (27522/27522): "event read=0: type=3, flags=0x1, seqno=4" <7> [257.214198] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:4: (27523/27520) -> (27522/27522): "vm_open: client_handle=1, handle=1, flags=0x0, fd=10" <7> [257.214586] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:4: (27523/27520) -> (27522/27522): "ioctl cmd=VM_OPEN ret=10\n" <7> [257.215315] xe 0000:00:02.0: [drm:xe_lrc_create [xe]] read CTX_CONTEXT_CONTROL: 0xffff0088 <7> [257.215812] xe 0000:00:02.0: [drm:xe_lrc_create [xe]] written CTX_CONTEXT_CONTROL: 0xffff0088 <7> [257.219924] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:6: (27523/27520) -> (27522/27522): "event read=0: type=4, flags=0x1, seqno=5" <7> [257.220295] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:6: (27523/27520) -> (27522/27522): "event read=0: type=13, flags=0x1, seqno=6" <7> [257.222515] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:9: (27523/27520) -> (27522/27522): "event read=0: type=6, flags=0x4, seqno=7" <7> [257.222961] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:9: (27523/27520) -> (27522/27522): "event read=0: type=7, flags=0x1, seqno=8" <7> [257.223340] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:9: (27523/27520) -> (27522/27522): "event read=0: type=7, flags=0x1, seqno=9" <7> [257.223432] xe 0000:00:02.0: [drm:prelim_xe_eudebug_vm_bind_ufence [xe]] eudbg: 3274:10: (9075/9075) -> (27522/27522): "ACK: seqno=10: tracking started" <7> [257.223872] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:10: (27523/27520) -> (27522/27522): "event read=0: type=8, flags=0x9, seqno=10" <7> [257.224376] xe 0000:00:02.0: [drm:handle_ack [xe]] eudbg: 3274:10: (27523/27520) -> (27522/27522): "ACK: seqno=10: signalled by 10 (debugger) (held 960us)" <7> [257.224800] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:10: (27523/27520) -> (27522/27522): "ioctl cmd=EVENT_ACK ret=0\n" <7> [257.226915] xe 0000:00:02.0: [drm:pf_queue_work_func [xe]] Tile0: GT0: ASID: 0 VFID: 0 PDATA: 0x0000 Faulted Address: 0x0000000000000000 FaultType: 0 AccessType: 0 FaultLevel: 0 EngineClass: 0 rcs EngineInstance: 0 <7> [257.227263] xe 0000:00:02.0: [drm:pf_queue_work_func [xe]] Tile0: GT0: Fault response: Unsuccessful -EINVAL <7> [257.227263] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone RCU_DEBUG_1 = 0x00000140 <7> [257.227560] xe 0000:00:02.0: [drm:pf_queue_work_func [xe]] Tile0: GT0: ASID: 0 VFID: 0 PDATA: 0x0000 Faulted Address: 0x0000000000000000 FaultType: 0 AccessType: 0 FaultLevel: 0 EngineClass: 0 rcs EngineInstance: 0 <7> [257.227688] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine rcs0: runalone=active, context=active <7> [257.227841] xe 0000:00:02.0: [drm:pf_queue_work_func [xe]] Tile0: GT0: Fault response: Unsuccessful -EINVAL <7> [257.228086] xe 0000:00:02.0: [drm:pf_queue_work_func [xe]] Tile0: GT0: ASID: 0 VFID: 0 PDATA: 0x0000 Faulted Address: 0x0000000000000000 FaultType: 0 AccessType: 0 FaultLevel: 0 EngineClass: 0 rcs EngineInstance: 0 <7> [257.228050] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone engine rcs0 found <7> [257.228313] xe 0000:00:02.0: [drm:pf_queue_work_func [xe]] Tile0: GT0: Fault response: Unsuccessful -EINVAL <7> [257.228400] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine ccs0: runalone=inactive, context=inactive <7> [257.228559] xe 0000:00:02.0: [drm:pf_queue_work_func [xe]] Tile0: GT0: ASID: 0 VFID: 0 PDATA: 0x0000 Faulted Address: 0x0000000000000000 FaultType: 0 AccessType: 0 FaultLevel: 0 EngineClass: 0 rcs EngineInstance: 0 <7> [257.228771] xe 0000:00:02.0: [drm:pf_queue_work_func [xe]] Tile0: GT0: Fault response: Unsuccessful -EINVAL <7> [257.431239] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone RCU_DEBUG_1 = 0x00000140 <7> [257.431343] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine rcs0: runalone=active, context=active <7> [257.431453] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone engine rcs0 found <7> [257.431561] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine ccs0: runalone=inactive, context=inactive <7> [257.431785] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:11: (27523/27520) -> (27522/27522): "event read=0: type=11, flags=0x4, seqno=11" <7> [257.432534] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone RCU_DEBUG_1 = 0x00000140 <7> [257.432632] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine rcs0: runalone=active, context=active <7> [257.432727] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone engine rcs0 found <7> [257.432821] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine ccs0: runalone=inactive, context=inactive <7> [257.432951] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone RCU_DEBUG_1 = 0x00000140 <7> [257.433044] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine rcs0: runalone=active, context=active <7> [257.433137] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone engine rcs0 found <7> [257.433229] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine ccs0: runalone=inactive, context=inactive <7> [257.433646] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:12: (27523/27520) -> (27522/27522): "event read=0: type=5, flags=0x4, seqno=12" <7> [257.433807] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:12: (27523/27520) -> (27522/27522): "eu_control: client_handle=1, cmd=1, flags=0x0, exec_queue_handle=1, bitmask_size=192\n" <7> [257.433946] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone RCU_DEBUG_1 = 0x00000140 <7> [257.434055] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine rcs0: runalone=active, context=active <7> [257.434161] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone engine rcs0 found <7> [257.434267] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine ccs0: runalone=inactive, context=inactive <7> [257.434690] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:13: (27523/27520) -> (27522/27522): "eu_control: client_handle=1, cmd=1, flags=0x0, exec_queue_handle=1, bitmask_size=192 ret=0\n" <7> [257.434797] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:13: (27523/27520) -> (27522/27522): "ioctl cmd=EU_CONTROL ret=0\n" <7> [257.434954] xe 0000:00:02.0: [drm:xe_eudebug_vm_fsync [xe]] eudbg: 3274:13: (27523/27520) -> (27522/27522): "vm_fsync: client_handle=1, vm_handle=1, flags=0x0, start=0, end=9223372036854775807 datasync=0\n" <7> [257.435109] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:13: (27523/27520) -> (27522/27522): "eu_control: client_handle=1, cmd=2, flags=0x0, exec_queue_handle=1, bitmask_size=192\n" <7> [257.435229] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone RCU_DEBUG_1 = 0x10000140 <7> [257.435332] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine rcs0: runalone=active, context=active <7> [257.435442] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: runalone engine rcs0 found <7> [257.435545] xe 0000:00:02.0: [drm:get_runalone_active_hw_engine [xe]] eudbg: engine ccs0: runalone=inactive, context=inactive <7> [257.435679] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3274:13: (27523/27520) -> (27522/27522): "EU_ATT_CLR: (0:1:0:0): 0x00000001\n" <7> [257.435788] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3274:13: (27523/27520) -> (27522/27522): "EU_ATT_CLR: (0:1:0:1): 0x00000001\n" <7> [257.435896] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3274:13: (27523/27520) -> (27522/27522): "EU_ATT_CLR: (0:2:0:0): 0x00000001\n" <7> [257.436004] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3274:13: (27523/27520) -> (27522/27522): "EU_ATT_CLR: (0:2:0:1): 0x00000001\n" <7> [257.436113] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3274:13: (27523/27520) -> (27522/27522): "EU_ATT_CLR: (1:3:0:0): 0x00000001\n" <7> [257.436221] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3274:13: (27523/27520) -> (27522/27522): "EU_ATT_CLR: (1:4:0:0): 0x00000001\n" <7> [257.436329] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3274:13: (27523/27520) -> (27522/27522): "EU_ATT_CLR: (1:5:0:0): 0x00000001\n" <7> [257.436445] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:14: (27523/27520) -> (27522/27522): "eu_control: client_handle=1, cmd=2, flags=0x0, exec_queue_handle=1, bitmask_size=192 ret=0\n" <7> [257.436547] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:14: (27523/27520) -> (27522/27522): "ioctl cmd=EU_CONTROL ret=0\n" <6> [317.339513] [IGT] xe_eudebug_online: finished subtest DRM_XE_ENGINE_CLASS_RENDER0, FAIL <7> [317.342312] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:15: (27523/27520) -> (27522/27522): "event read=0: type=2, flags=0x2, seqno=15" <6> [317.343692] xe 0000:00:02.0: [drm] Tile0: GT0: Engine reset: engine_class=rcs, logical_mask: 0x1, guc_id=2 <6> [317.345084] [IGT] xe_eudebug_online: finished subtest pagefault-read, FAIL <6> [317.345580] [IGT] xe_eudebug_online: exiting, ret=98 <7> [317.350553] xe 0000:00:02.0: [drm:xe_eudebug_vm_release [xe]] eudbg: 3274:15: (27523/27520) -> (27522/27522): "vm_release: client_handle=1, vm_handle=1, flags=0x0" <7> [317.350881] xe 0000:00:02.0: [drm:xe_eudebug_detach [xe]] eudbg: 3274:15:disconnected (27523/27520) -> (27522/27522): "session 3274 detached with 0" <7> [317.351048] xe 0000:00:02.0: [drm:xe_eudebug_release [xe]] eudbg: 3274:15:disconnected (27523/27520) -> (27522/27522): "Session closed (0)" <7> [317.351535] xe 0000:00:02.0: [drm:drm_client_dev_restore] fbdev: ret=0

Detail

Value

Duration

60.15 seconds

Igt-Version

IGT-Version: 2.3-gc142aab3a (x86_64) (Linux: 6.17.13-lgci-xe-xkb-226-520362ff61a16e40d-debug+ x86_64)

Out

Using IGT_SRANDOM=1773745316 for randomisation
Opened device: /dev/dri/card0
Using IGT_SRANDOM=1773745316 for randomisation
Starting subtest: pagefault-read
Starting dynamic subtest: DRM_XE_ENGINE_CLASS_RENDER0
client running with pid 27522
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 [wait_from_client+0x8d]
  #2 ../lib/xe/xe_eudebug.c:1673 xe_eudebug_client_wait_done()
  #3 ../lib/xe/xe_eudebug.c:1767 xe_eudebug_session_run()
  #4 ../tests/intel/xe_eudebug_online.c:1824 test_pagefault_online()
  #5 ../tests/intel/xe_eudebug_online.c:2801 __igt_unique____real_main2678()
  #6 ../tests/intel/xe_eudebug_online.c:2678 main()
  #7 [__libc_init_first+0x8a]
  #8 [__libc_start_main+0x8b]
  #9 [_start+0x25]
Dynamic subtest DRM_XE_ENGINE_CLASS_RENDER0: FAIL (60.146s)
Subtest pagefault-read: FAIL (60.152s)
Test requirement not met in function xe_eudebug_enable, file ../lib/xe/xe_eudebug.c:1977:
Test requirement: !(ret)
Last errno: 16, Device or resource busy

Err

Starting subtest: pagefault-read
Starting dynamic subtest: DRM_XE_ENGINE_CLASS_RENDER0
(xe_eudebug_online:27520) xe/xe_eudebug-CRITICAL: Test assertion failure function wait_from_client, file ../lib/xe/xe_eudebug.c:401:
(xe_eudebug_online:27520) xe/xe_eudebug-CRITICAL: Failed assertion: c->allow_dead_client
Dynamic subtest DRM_XE_ENGINE_CLASS_RENDER0 failed.
**** DEBUG ****
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: debugger: 27520 waiting for token 'client pid'
(xe_eudebug_online:27520) xe/xe_eudebug-INFO: client running with pid 27522
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: added trigger 0x5f7008c926f0
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: added trigger 0x5f7008c92720
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: added trigger 0x5f7008c927b0
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: added trigger 0x5f7008c927e0
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: added trigger 0x5f7008c92810
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: added trigger 0x5f7008c92840
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: added trigger 0x5f7008c92870
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: added trigger 0x5f7008c928a0
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: debugger connected to 27522
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: 27520 signalling token 'client run' with value '27522'
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: debugger: 27520 waiting for token 'client fini'
(xe_eudebug_online:27520) [thread:27521] intel_allocator_simple-DEBUG: Using simple allocator
(xe_eudebug_online:27520) [thread:27523] xe/xe_eudebug-DEBUG: delivering ack for event: (10) vm_bind_ufence(8):create|ack: vm_bind_ref_seqno=7
(xe_eudebug_online:27520) [thread:27523] DEBUG: EVENT[11] pagefault; threads[before=0, after=0, resolved=4, pagefault=4] client[1], exec_queue[1], lrc[1], bitmask_size[576], pagefault_address[0x12345678000]
(xe_eudebug_online:27520) [thread:27523] DEBUG: === Attentions before ===
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[0] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[1] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[2] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[3] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[4] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[5] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[6] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[7] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[8] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[9] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[10] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[11] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[12] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[13] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[14] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[15] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[16] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[17] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[18] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[19] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[20] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[21] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[22] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[23] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: === Attentions after ===
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[0] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[1] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[2] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[3] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[4] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[5] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[6] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[7] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[8] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[9] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[10] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[11] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[12] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[13] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[14] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[15] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[16] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[17] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[18] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[19] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[20] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[21] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[22] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[23] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: === Attentions resolved ===
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[0] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[1] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[2] = 0x0000000100000001
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[3] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[4] = 0x0000000100000001
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[5] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[6] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[7] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[8] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[9] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[10] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[11] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[12] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[13] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[14] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[15] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[16] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[17] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[18] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[19] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[20] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[21] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[22] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[23] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: EVENT[12] eu-attenttion; threads=4 client[1], exec_queue[1], lrc[1], bitmask_size[192]
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[0] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[1] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[2] = 0x0000000100000001
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[3] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[4] = 0x0000000100000001
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[5] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[6] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[7] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[8] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[9] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[10] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[11] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[12] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[13] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[14] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[15] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[16] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[17] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[18] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[19] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[20] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[21] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[22] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: bitmask[23] = 0x0000000000000000
(xe_eudebug_online:27520) [thread:27523] DEBUG: EU CONTROL[13]: stopped
(xe_eudebug_online:27520) [thread:27523] DEBUG: EU CONTROL[14]: resume
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: poll: timeout
(xe_eudebug_online:27520) xe/xe_eudebug-DEBUG: safe_pipe_read failed with error: -110 waiting for token 'client fini:(3)'
(xe_eudebug_online:27520) xe/xe_eudebug-CRITICAL: Test assertion failure function wait_from_client, file ../lib/xe/xe_eudebug.c:401:
(xe_eudebug_online:27520) xe/xe_eudebug-CRITICAL: Failed assertion: c->allow_dead_client
(xe_eudebug_online:27520) igt_core-INFO: Stack trace:
(xe_eudebug_online:27520) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(xe_eudebug_online:27520) igt_core-INFO:   #1 [wait_from_client+0x8d]
(xe_eudebug_online:27520) igt_core-INFO:   #2 ../lib/xe/xe_eudebug.c:1673 xe_eudebug_client_wait_done()
(xe_eudebug_online:27520) igt_core-INFO:   #3 ../lib/xe/xe_eudebug.c:1767 xe_eudebug_session_run()
(xe_eudebug_online:27520) igt_core-INFO:   #4 ../tests/intel/xe_eudebug_online.c:1824 test_pagefault_online()
(xe_eudebug_online:27520) igt_core-INFO:   #5 ../tests/intel/xe_eudebug_online.c:2801 __igt_unique____real_main2678()
(xe_eudebug_online:27520) igt_core-INFO:   #6 ../tests/intel/xe_eudebug_online.c:2678 main()
(xe_eudebug_online:27520) igt_core-INFO:   #7 [__libc_init_first+0x8a]
(xe_eudebug_online:27520) igt_core-INFO:   #8 [__libc_start_main+0x8b]
(xe_eudebug_online:27520) igt_core-INFO:   #9 [_start+0x25]
****  END  ****
Dynamic subtest DRM_XE_ENGINE_CLASS_RENDER0: FAIL (60.146s)
Subtest pagefault-read: FAIL (60.152s)

Dmesg

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

<7> [257.147317] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT0: REG[0xe530] = 0x00000400

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

<6> [257.153811] xe 0000:00:02.0: [drm] Tile0: GT1: trying reset from xe_eudebug_enable [xe]

<6> [257.154237] xe 0000:00:02.0: [drm] Tile0: GT1: reset queued

<6> [257.154275] xe 0000:00:02.0: [drm] Tile0: GT1: reset started

<7> [257.154276] xe 0000:00:02.0: [drm:xe_gt_sriov_pf_config_restart [xe]] PF: Tile0: GT0: pushed 0 skip 7 of 7 VFs configurations

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

<7> [257.154768] xe 0000:00:02.0: [drm:pf_worker_restart_func [xe]] PF: Tile0: GT0: restart completed

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

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

<7> [257.155743] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT1: REG[0x82a8] = 0x00800000

<7> [257.156085] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT1: REG[0xb604] = 0x08104440

<7> [257.156410] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT1: REG[0xb608] = 0x30200000

<7> [257.156750] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT1: REG[0xb658] = 0x0000007f

<7> [257.157092] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT1: REG[0x1c3f10] = 0x00400000

<7> [257.157407] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT1: REG[0x1c3f1c] = 0x00000008

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

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

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

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

<7> [257.181831] xe 0000:00:02.0: [drm:guc_wait_ucode [xe]] Tile0: GT1: GuC load: init took 19ms, freq = 1200MHz (req = 1200MHz), before = 1200MHz, status = 0x8002F034

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

<7> [257.189953] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT1: Applying vcs0 save-restore MMIOs

<7> [257.190297] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT1: REG[0x1c0050] = 0x10001000

<7> [257.190658] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT1: Applying vecs0 save-restore MMIOs

<7> [257.190987] xe 0000:00:02.0: [drm:xe_reg_sr_apply_mmio [xe]] Tile0: GT1: REG[0x1c8050] = 0x10001000

<6> [257.191354] xe 0000:00:02.0: [drm] Tile0: GT1: reset done

<7> [257.191936] xe 0000:00:02.0: [drm:xe_gt_sriov_pf_config_restart [xe]] PF: Tile0: GT1: pushed 0 skip 7 of 7 VFs configurations

<6> [257.192189] [IGT] xe_eudebug_online: starting subtest pagefault-read

<7> [257.192467] xe 0000:00:02.0: [drm:pf_worker_restart_func [xe]] PF: Tile0: GT1: restart completed

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

<7> [257.206843] xe 0000:00:02.0: [drm:prelim_xe_eudebug_connect_ioctl [xe]] eudbg: 3274:0: (27520/27520) -> (27522/27522): "connected session 3274"

<7> [257.207297] xe 0000:00:02.0: [drm:discovery_work_fn [xe]] eudbg: 3274:0: (9075/9075) -> (27522/27522): "Discovery start for 3274\n"

<7> [257.207848] xe 0000:00:02.0: [drm:discovery_work_fn [xe]] eudbg: 3274:0: (9075/9075) -> (27522/27522): "Discovery end for 3274\n"

<7> [257.209405] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:1: (27523/27520) -> (27522/27522): "event read=0: type=2, flags=0x1, seqno=1"

<7> [257.210908] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:3: (27523/27520) -> (27522/27522): "event read=0: type=9, flags=0x1, seqno=2"

<7> [257.211661] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:3: (27523/27520) -> (27522/27522): "read metadata: client_handle=1, metadata_handle=1, flags=0x0"

<7> [257.212031] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:3: (27523/27520) -> (27522/27522): "ioctl cmd=READ_METADATA ret=0\n"

<7> [257.212372] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:3: (27523/27520) -> (27522/27522): "event read=0: type=9, flags=0x1, seqno=3"

<7> [257.212722] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:3: (27523/27520) -> (27522/27522): "read metadata: client_handle=1, metadata_handle=2, flags=0x0"

<7> [257.213042] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:3: (27523/27520) -> (27522/27522): "ioctl cmd=READ_METADATA ret=0\n"

<7> [257.213681] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:4: (27523/27520) -> (27522/27522): "event read=0: type=3, flags=0x1, seqno=4"

<7> [257.214198] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:4: (27523/27520) -> (27522/27522): "vm_open: client_handle=1, handle=1, flags=0x0, fd=10"

<7> [257.214586] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:4: (27523/27520) -> (27522/27522): "ioctl cmd=VM_OPEN ret=10\n"

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

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

<7> [257.219924] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:6: (27523/27520) -> (27522/27522): "event read=0: type=4, flags=0x1, seqno=5"

<7> [257.220295] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:6: (27523/27520) -> (27522/27522): "event read=0: type=13, flags=0x1, seqno=6"

<7> [257.222515] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:9: (27523/27520) -> (27522/27522): "event read=0: type=6, flags=0x4, seqno=7"

<7> [257.222961] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:9: (27523/27520) -> (27522/27522): "event read=0: type=7, flags=0x1, seqno=8"

<7> [257.223340] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:9: (27523/27520) -> (27522/27522): "event read=0: type=7, flags=0x1, seqno=9"

<7> [257.223432] xe 0000:00:02.0: [drm:prelim_xe_eudebug_vm_bind_ufence [xe]] eudbg: 3274:10: (9075/9075) -> (27522/27522): "ACK: seqno=10: tracking started"

<7> [257.223872] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:10: (27523/27520) -> (27522/27522): "event read=0: type=8, flags=0x9, seqno=10"

<7> [257.224376] xe 0000:00:02.0: [drm:handle_ack [xe]] eudbg: 3274:10: (27523/27520) -> (27522/27522): "ACK: seqno=10: signalled by 10 (debugger) (held 960us)"

<7> [257.224800] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:10: (27523/27520) -> (27522/27522): "ioctl cmd=EVENT_ACK ret=0\n"

<7> [257.226915] xe 0000:00:02.0: [drm:pf_queue_work_func [xe]] Tile0: GT0:

ASID: 0

VFID: 0

PDATA: 0x0000

Faulted Address: 0x0000000000000000

FaultType: 0

AccessType: 0

FaultLevel: 0

EngineClass: 0 rcs

EngineInstance: 0

<7> [257.227263] xe 0000:00:02.0: [drm:pf_queue_work_func [xe]] Tile0: GT0: Fault response: Unsuccessful -EINVAL

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

<7> [257.227560] xe 0000:00:02.0: [drm:pf_queue_work_func [xe]] Tile0: GT0:

ASID: 0

VFID: 0

PDATA: 0x0000

Faulted Address: 0x0000000000000000

FaultType: 0

AccessType: 0

FaultLevel: 0

EngineClass: 0 rcs

EngineInstance: 0

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

<7> [257.227841] xe 0000:00:02.0: [drm:pf_queue_work_func [xe]] Tile0: GT0: Fault response: Unsuccessful -EINVAL

<7> [257.228086] xe 0000:00:02.0: [drm:pf_queue_work_func [xe]] Tile0: GT0:

ASID: 0

VFID: 0

PDATA: 0x0000

Faulted Address: 0x0000000000000000

FaultType: 0

AccessType: 0

FaultLevel: 0

EngineClass: 0 rcs

EngineInstance: 0

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

<7> [257.228313] xe 0000:00:02.0: [drm:pf_queue_work_func [xe]] Tile0: GT0: Fault response: Unsuccessful -EINVAL

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

<7> [257.228559] xe 0000:00:02.0: [drm:pf_queue_work_func [xe]] Tile0: GT0:

ASID: 0

VFID: 0

PDATA: 0x0000

Faulted Address: 0x0000000000000000

FaultType: 0

AccessType: 0

FaultLevel: 0

EngineClass: 0 rcs

EngineInstance: 0

<7> [257.228771] xe 0000:00:02.0: [drm:pf_queue_work_func [xe]] Tile0: GT0: Fault response: Unsuccessful -EINVAL

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

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

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

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

<7> [257.431785] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:11: (27523/27520) -> (27522/27522): "event read=0: type=11, flags=0x4, seqno=11"

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

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

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

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

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

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

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

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

<7> [257.433646] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:12: (27523/27520) -> (27522/27522): "event read=0: type=5, flags=0x4, seqno=12"

<7> [257.433807] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:12: (27523/27520) -> (27522/27522): "eu_control: client_handle=1, cmd=1, flags=0x0, exec_queue_handle=1, bitmask_size=192\n"

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

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

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

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

<7> [257.434690] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:13: (27523/27520) -> (27522/27522): "eu_control: client_handle=1, cmd=1, flags=0x0, exec_queue_handle=1, bitmask_size=192 ret=0\n"

<7> [257.434797] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:13: (27523/27520) -> (27522/27522): "ioctl cmd=EU_CONTROL ret=0\n"

<7> [257.434954] xe 0000:00:02.0: [drm:xe_eudebug_vm_fsync [xe]] eudbg: 3274:13: (27523/27520) -> (27522/27522): "vm_fsync: client_handle=1, vm_handle=1, flags=0x0, start=0, end=9223372036854775807 datasync=0\n"

<7> [257.435109] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:13: (27523/27520) -> (27522/27522): "eu_control: client_handle=1, cmd=2, flags=0x0, exec_queue_handle=1, bitmask_size=192\n"

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

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

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

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

<7> [257.435679] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3274:13: (27523/27520) -> (27522/27522): "EU_ATT_CLR: (0:1:0:0): 0x00000001\n"

<7> [257.435788] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3274:13: (27523/27520) -> (27522/27522): "EU_ATT_CLR: (0:1:0:1): 0x00000001\n"

<7> [257.435896] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3274:13: (27523/27520) -> (27522/27522): "EU_ATT_CLR: (0:2:0:0): 0x00000001\n"

<7> [257.436004] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3274:13: (27523/27520) -> (27522/27522): "EU_ATT_CLR: (0:2:0:1): 0x00000001\n"

<7> [257.436113] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3274:13: (27523/27520) -> (27522/27522): "EU_ATT_CLR: (1:3:0:0): 0x00000001\n"

<7> [257.436221] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3274:13: (27523/27520) -> (27522/27522): "EU_ATT_CLR: (1:4:0:0): 0x00000001\n"

<7> [257.436329] xe 0000:00:02.0: [drm:clear_attn_mcr [xe]] eudbg: 3274:13: (27523/27520) -> (27522/27522): "EU_ATT_CLR: (1:5:0:0): 0x00000001\n"

<7> [257.436445] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:14: (27523/27520) -> (27522/27522): "eu_control: client_handle=1, cmd=2, flags=0x0, exec_queue_handle=1, bitmask_size=192 ret=0\n"

<7> [257.436547] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:14: (27523/27520) -> (27522/27522): "ioctl cmd=EU_CONTROL ret=0\n"

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

<7> [317.342312] xe 0000:00:02.0: [drm:xe_eudebug_ioctl [xe]] eudbg: 3274:15: (27523/27520) -> (27522/27522): "event read=0: type=2, flags=0x2, seqno=15"

<6> [317.343692] xe 0000:00:02.0: [drm] Tile0: GT0: Engine reset: engine_class=rcs, logical_mask: 0x1, guc_id=2

<6> [317.345084] [IGT] xe_eudebug_online: finished subtest pagefault-read, FAIL

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

<7> [317.350553] xe 0000:00:02.0: [drm:xe_eudebug_vm_release [xe]] eudbg: 3274:15: (27523/27520) -> (27522/27522): "vm_release: client_handle=1, vm_handle=1, flags=0x0"

<7> [317.350881] xe 0000:00:02.0: [drm:xe_eudebug_detach [xe]] eudbg: 3274:15:disconnected (27523/27520) -> (27522/27522): "session 3274 detached with 0"

<7> [317.351048] xe 0000:00:02.0: [drm:xe_eudebug_release [xe]] eudbg: 3274:15:disconnected (27523/27520) -> (27522/27522): "Session closed (0)"

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

Results for igt@xe_eudebug_online@pagefault-read