igt@xe_exec_sip@sanity-after-timeout on bat-ptl-vm@xkb-pr-main-294-513-1dc5776231bec770a519d749272744f15fc6f71d Test details

Result: Fail

Detail	Value
Duration	26.75 seconds
Igt-Version	IGT-Version: 2.3-gc142aab3a (x86_64) (Linux: 7.0.0-rc1-lgci-xe-xkb-pr-main-294-513-1dc57-debug+ x86_64)
Out	Using IGT_SRANDOM=1773676114 for randomisation Opened device: /dev/dri/card0 Starting subtest: sanity-after-timeout Starting dynamic subtest: DRM_XE_ENGINE_CLASS_RENDER0 Stack trace: #0 ../lib/igt_core.c:2075 __igt_fail_assert() #1 [test_sip.constprop.0+0x665] #2 ../tests/intel/xe_exec_sip.c:324 __igt_unique____real_main312() #3 ../tests/intel/xe_exec_sip.c:312 main() #4 [__libc_init_first+0x8a] #5 [__libc_start_main+0x8b] #6 [_start+0x25] Dynamic subtest DRM_XE_ENGINE_CLASS_RENDER0: FAIL (15.446s) Starting dynamic subtest: DRM_XE_ENGINE_CLASS_COMPUTE0 Dynamic subtest DRM_XE_ENGINE_CLASS_COMPUTE0: SUCCESS (11.290s) Subtest sanity-after-timeout: FAIL (26.746s)
Err	Starting subtest: sanity-after-timeout Starting dynamic subtest: DRM_XE_ENGINE_CLASS_RENDER0 (xe_exec_sip:7623) CRITICAL: Test assertion failure function test_sip, file ../tests/intel/xe_exec_sip.c:291: (xe_exec_sip:7623) CRITICAL: Failed assertion: igt_nsec_elapsed(&ts) < timeout (xe_exec_sip:7623) CRITICAL: error: 0x393e35688 >= 0x306dc4200 Dynamic subtest DRM_XE_ENGINE_CLASS_RENDER0 failed. ** DEBUG (xe_exec_sip:7623) DEBUG: Using DRM_XE_ENGINE_CLASS_RENDER (xe_exec_sip:7623) drmtest-DEBUG: Opened previously opened device: /dev/dri/card0 (xe_exec_sip:7623) drmtest-DEBUG: Test requirement passed: !(fd<0) (xe_exec_sip:7623) intel_allocator_simple-DEBUG: Using simple allocator (xe_exec_sip:7623) intel_bufops-DEBUG: generation: 30, supported tiles: 0x3f, driver: xe (xe_exec_sip:7623) gpgpu_shader-DEBUG: Test requirement passed: shdr->gen_ver >= SUPPORTED_GEN_VER (xe_exec_sip:7623) gpgpu_shader-DEBUG: Test requirement passed: shdr->gen_ver >= SUPPORTED_GEN_VER (xe_exec_sip:7623) gpgpu_shader-DEBUG: Test requirement passed: shdr->gen_ver >= SUPPORTED_GEN_VER (xe_exec_sip:7623) gpgpu_shader-DEBUG: Test requirement passed: shdr->gen_ver >= SUPPORTED_GEN_VER (xe_exec_sip:7623) gpgpu_shader-DEBUG: Test requirement passed: shdr->gen_ver >= SUPPORTED_GEN_VER (xe_exec_sip:7623) intel_allocator_simple-DEBUG: Using simple allocator (xe_exec_sip:7623) intel_allocator_simple-DEBUG: <ial: 0x5e3cb19b3590, fd: 6> objects: 1, reserved_areas: 0 (xe_exec_sip:7623) intel_allocator_simple-DEBUG: <ial: 0x5e3cb19b3590, fd: 6> objects: 1, reserved_areas: 0 (xe_exec_sip:7623) intel_batchbuffer-DEBUG: bind_ops: MAP (xe_exec_sip:7623) intel_batchbuffer-DEBUG: [0]: handle: 1, offset: ffffffffe000, size: 2000 pat_index: 2 (xe_exec_sip:7623) intel_batchbuffer-DEBUG: [1]: handle: 2, offset: 0, size: 10000 pat_index: 2 (xe_exec_sip:7623) CRITICAL: Test assertion failure function test_sip, file ../tests/intel/xe_exec_sip.c:291: (xe_exec_sip:7623) CRITICAL: Failed assertion: igt_nsec_elapsed(&ts) < timeout (xe_exec_sip:7623) CRITICAL: error: 0x393e35688 >= 0x306dc4200 (xe_exec_sip:7623) igt_core-INFO: Stack trace: (xe_exec_sip:7623) igt_core-INFO: #0 ../lib/igt_core.c:2075 __igt_fail_assert() (xe_exec_sip:7623) igt_core-INFO: #1 [test_sip.constprop.0+0x665] (xe_exec_sip:7623) igt_core-INFO: #2 ../tests/intel/xe_exec_sip.c:324 __igt_unique____real_main312() (xe_exec_sip:7623) igt_core-INFO: #3 ../tests/intel/xe_exec_sip.c:312 main() (xe_exec_sip:7623) igt_core-INFO: #4 [__libc_init_first+0x8a] (xe_exec_sip:7623) igt_core-INFO: #5 [__libc_start_main+0x8b] (xe_exec_sip:7623) igt_core-INFO: #6 [_start+0x25] END ** Dynamic subtest DRM_XE_ENGINE_CLASS_RENDER0: FAIL (15.446s) Starting dynamic subtest: DRM_XE_ENGINE_CLASS_COMPUTE0 Dynamic subtest DRM_XE_ENGINE_CLASS_COMPUTE0: SUCCESS (11.290s) Subtest sanity-after-timeout: FAIL (26.746s)
Dmesg	<6> [62.279128] [IGT] xe_exec_sip: executing <6> [62.292007] [IGT] xe_exec_sip: starting subtest sanity-after-timeout <6> [62.292685] [IGT] xe_exec_sip: starting dynamic subtest DRM_XE_ENGINE_CLASS_RENDER0 <7> [67.353530] xe 0000:00:03.0: [drm:guc_exec_queue_timedout_job [xe]] Tile0: GT0: Check job timeout: seqno=4294967169, lrc_seqno=4294967169, guc_id=4, running_time_ms=0, timeout_ms=5000, diff=0x00000001 <5> [72.473549] xe 0000:00:03.0: [drm] Tile0: GT0: Check job timeout: seqno=4294967169, lrc_seqno=4294967169, guc_id=4, timestamp stuck <6> [73.133272] xe 0000:00:03.0: [drm] Tile0: GT0: Engine reset: engine_class=rcs, logical_mask: 0x1, guc_id=4, state=0x209 <5> [77.592929] xe 0000:00:03.0: [drm] Tile0: GT0: Timedout job: seqno=4294967169, lrc_seqno=4294967169, guc_id=4, flags=0x0 in xe_exec_sip [7623] <6> [77.632326] xe 0000:00:03.0: [drm] Xe device coredump has been created <6> [77.636750] xe 0000:00:03.0: [drm] Check your /sys/class/drm/card0/device/devcoredump/data <6> [77.740201] [IGT] xe_exec_sip: finished subtest DRM_XE_ENGINE_CLASS_RENDER0, FAIL <6> [77.744407] [IGT] xe_exec_sip: starting dynamic subtest DRM_XE_ENGINE_CLASS_COMPUTE0 <7> [83.224927] xe 0000:00:03.0: [drm:guc_exec_queue_timedout_job [xe]] Tile0: GT0: Check job timeout: seqno=4294967169, lrc_seqno=4294967169, guc_id=6, running_time_ms=0, timeout_ms=5000, diff=0x00000000 <5> [88.345018] xe 0000:00:03.0: [drm] Tile0: GT0: Check job timeout: seqno=4294967169, lrc_seqno=4294967169, guc_id=6, timestamp stuck <6> [88.994214] xe 0000:00:03.0: [drm] Tile0: GT0: Engine reset: engine_class=ccs, logical_mask: 0x1, guc_id=6, state=0x209 <5> [89.002106] xe 0000:00:03.0: [drm] Tile0: GT0: Timedout job: seqno=4294967169, lrc_seqno=4294967169, guc_id=6, flags=0x0 in xe_exec_sip [7623] <7> [89.015929] xe 0000:00:03.0: [drm:xe_devcoredump [xe]] Multiple hangs are occurring, but only the first snapshot was taken <6> [89.037604] [IGT] xe_exec_sip: finished subtest DRM_XE_ENGINE_CLASS_COMPUTE0, SUCCESS <6> [89.038934] [IGT] xe_exec_sip: finished subtest sanity-after-timeout, FAIL <6> [89.040606] [IGT] xe_exec_sip: exiting, ret=98

Detail

Value

Duration

26.75 seconds

Igt-Version

IGT-Version: 2.3-gc142aab3a (x86_64) (Linux: 7.0.0-rc1-lgci-xe-xkb-pr-main-294-513-1dc57-debug+ x86_64)

Out

Using IGT_SRANDOM=1773676114 for randomisation
Opened device: /dev/dri/card0
Starting subtest: sanity-after-timeout
Starting dynamic subtest: DRM_XE_ENGINE_CLASS_RENDER0
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 [test_sip.constprop.0+0x665]
  #2 ../tests/intel/xe_exec_sip.c:324 __igt_unique____real_main312()
  #3 ../tests/intel/xe_exec_sip.c:312 main()
  #4 [__libc_init_first+0x8a]
  #5 [__libc_start_main+0x8b]
  #6 [_start+0x25]
Dynamic subtest DRM_XE_ENGINE_CLASS_RENDER0: FAIL (15.446s)
Starting dynamic subtest: DRM_XE_ENGINE_CLASS_COMPUTE0
Dynamic subtest DRM_XE_ENGINE_CLASS_COMPUTE0: SUCCESS (11.290s)
Subtest sanity-after-timeout: FAIL (26.746s)

Err

Starting subtest: sanity-after-timeout
Starting dynamic subtest: DRM_XE_ENGINE_CLASS_RENDER0
(xe_exec_sip:7623) CRITICAL: Test assertion failure function test_sip, file ../tests/intel/xe_exec_sip.c:291:
(xe_exec_sip:7623) CRITICAL: Failed assertion: igt_nsec_elapsed(&ts) < timeout
(xe_exec_sip:7623) CRITICAL: error: 0x393e35688 >= 0x306dc4200
Dynamic subtest DRM_XE_ENGINE_CLASS_RENDER0 failed.
**** DEBUG ****
(xe_exec_sip:7623) DEBUG: Using DRM_XE_ENGINE_CLASS_RENDER
(xe_exec_sip:7623) drmtest-DEBUG: Opened previously opened device: /dev/dri/card0
(xe_exec_sip:7623) drmtest-DEBUG: Test requirement passed: !(fd<0)
(xe_exec_sip:7623) intel_allocator_simple-DEBUG: Using simple allocator
(xe_exec_sip:7623) intel_bufops-DEBUG: generation: 30, supported tiles: 0x3f, driver: xe
(xe_exec_sip:7623) gpgpu_shader-DEBUG: Test requirement passed: shdr->gen_ver >= SUPPORTED_GEN_VER
(xe_exec_sip:7623) gpgpu_shader-DEBUG: Test requirement passed: shdr->gen_ver >= SUPPORTED_GEN_VER
(xe_exec_sip:7623) gpgpu_shader-DEBUG: Test requirement passed: shdr->gen_ver >= SUPPORTED_GEN_VER
(xe_exec_sip:7623) gpgpu_shader-DEBUG: Test requirement passed: shdr->gen_ver >= SUPPORTED_GEN_VER
(xe_exec_sip:7623) gpgpu_shader-DEBUG: Test requirement passed: shdr->gen_ver >= SUPPORTED_GEN_VER
(xe_exec_sip:7623) intel_allocator_simple-DEBUG: Using simple allocator
(xe_exec_sip:7623) intel_allocator_simple-DEBUG: <ial: 0x5e3cb19b3590, fd: 6> objects: 1, reserved_areas: 0
(xe_exec_sip:7623) intel_allocator_simple-DEBUG: <ial: 0x5e3cb19b3590, fd: 6> objects: 1, reserved_areas: 0
(xe_exec_sip:7623) intel_batchbuffer-DEBUG: bind_ops: MAP
(xe_exec_sip:7623) intel_batchbuffer-DEBUG:   [0]: handle: 1, offset: ffffffffe000, size: 2000 pat_index: 2
(xe_exec_sip:7623) intel_batchbuffer-DEBUG:   [1]: handle: 2, offset: 0, size: 10000 pat_index: 2
(xe_exec_sip:7623) CRITICAL: Test assertion failure function test_sip, file ../tests/intel/xe_exec_sip.c:291:
(xe_exec_sip:7623) CRITICAL: Failed assertion: igt_nsec_elapsed(&ts) < timeout
(xe_exec_sip:7623) CRITICAL: error: 0x393e35688 >= 0x306dc4200
(xe_exec_sip:7623) igt_core-INFO: Stack trace:
(xe_exec_sip:7623) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(xe_exec_sip:7623) igt_core-INFO:   #1 [test_sip.constprop.0+0x665]
(xe_exec_sip:7623) igt_core-INFO:   #2 ../tests/intel/xe_exec_sip.c:324 __igt_unique____real_main312()
(xe_exec_sip:7623) igt_core-INFO:   #3 ../tests/intel/xe_exec_sip.c:312 main()
(xe_exec_sip:7623) igt_core-INFO:   #4 [__libc_init_first+0x8a]
(xe_exec_sip:7623) igt_core-INFO:   #5 [__libc_start_main+0x8b]
(xe_exec_sip:7623) igt_core-INFO:   #6 [_start+0x25]
****  END  ****
Dynamic subtest DRM_XE_ENGINE_CLASS_RENDER0: FAIL (15.446s)
Starting dynamic subtest: DRM_XE_ENGINE_CLASS_COMPUTE0
Dynamic subtest DRM_XE_ENGINE_CLASS_COMPUTE0: SUCCESS (11.290s)
Subtest sanity-after-timeout: FAIL (26.746s)

Dmesg

<6> [62.279128] [IGT] xe_exec_sip: executing

<6> [62.292007] [IGT] xe_exec_sip: starting subtest sanity-after-timeout

<6> [62.292685] [IGT] xe_exec_sip: starting dynamic subtest DRM_XE_ENGINE_CLASS_RENDER0

<7> [67.353530] xe 0000:00:03.0: [drm:guc_exec_queue_timedout_job [xe]] Tile0: GT0: Check job timeout: seqno=4294967169, lrc_seqno=4294967169, guc_id=4, running_time_ms=0, timeout_ms=5000, diff=0x00000001

<5> [72.473549] xe 0000:00:03.0: [drm] Tile0: GT0: Check job timeout: seqno=4294967169, lrc_seqno=4294967169, guc_id=4, timestamp stuck

<6> [73.133272] xe 0000:00:03.0: [drm] Tile0: GT0: Engine reset: engine_class=rcs, logical_mask: 0x1, guc_id=4, state=0x209

<5> [77.592929] xe 0000:00:03.0: [drm] Tile0: GT0: Timedout job: seqno=4294967169, lrc_seqno=4294967169, guc_id=4, flags=0x0 in xe_exec_sip [7623]

<6> [77.632326] xe 0000:00:03.0: [drm] Xe device coredump has been created

<6> [77.636750] xe 0000:00:03.0: [drm] Check your /sys/class/drm/card0/device/devcoredump/data

<6> [77.740201] [IGT] xe_exec_sip: finished subtest DRM_XE_ENGINE_CLASS_RENDER0, FAIL

<6> [77.744407] [IGT] xe_exec_sip: starting dynamic subtest DRM_XE_ENGINE_CLASS_COMPUTE0

<7> [83.224927] xe 0000:00:03.0: [drm:guc_exec_queue_timedout_job [xe]] Tile0: GT0: Check job timeout: seqno=4294967169, lrc_seqno=4294967169, guc_id=6, running_time_ms=0, timeout_ms=5000, diff=0x00000000

<5> [88.345018] xe 0000:00:03.0: [drm] Tile0: GT0: Check job timeout: seqno=4294967169, lrc_seqno=4294967169, guc_id=6, timestamp stuck

<6> [88.994214] xe 0000:00:03.0: [drm] Tile0: GT0: Engine reset: engine_class=ccs, logical_mask: 0x1, guc_id=6, state=0x209

<5> [89.002106] xe 0000:00:03.0: [drm] Tile0: GT0: Timedout job: seqno=4294967169, lrc_seqno=4294967169, guc_id=6, flags=0x0 in xe_exec_sip [7623]

<7> [89.015929] xe 0000:00:03.0: [drm:xe_devcoredump [xe]] Multiple hangs are occurring, but only the first snapshot was taken

<6> [89.037604] [IGT] xe_exec_sip: finished subtest DRM_XE_ENGINE_CLASS_COMPUTE0, SUCCESS

<6> [89.038934] [IGT] xe_exec_sip: finished subtest sanity-after-timeout, FAIL

<6> [89.040606] [IGT] xe_exec_sip: exiting, ret=98

Results for igt@xe_exec_sip@sanity-after-timeout