igt@xe_exec_sip@sanity-after-timeout on bat-ptl-vm@xkb-pr-main-294-516-ffd82deccebe25ac947b5304ac5aedc717c4dfd7 Test details

Result: Fail

Detail	Value
Duration	26.94 seconds
Igt-Version	IGT-Version: 2.3-gc142aab3a (x86_64) (Linux: 7.0.0-rc1-lgci-xe-xkb-pr-main-294-516-ffd82-debug+ x86_64)
Out	Using IGT_SRANDOM=1773742422 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.631s) Starting dynamic subtest: DRM_XE_ENGINE_CLASS_COMPUTE0 Dynamic subtest DRM_XE_ENGINE_CLASS_COMPUTE0: SUCCESS (11.298s) Subtest sanity-after-timeout: FAIL (26.941s)
Err	Starting subtest: sanity-after-timeout Starting dynamic subtest: DRM_XE_ENGINE_CLASS_RENDER0 (xe_exec_sip:7628) CRITICAL: Test assertion failure function test_sip, file ../tests/intel/xe_exec_sip.c:291: (xe_exec_sip:7628) CRITICAL: Failed assertion: igt_nsec_elapsed(&ts) < timeout (xe_exec_sip:7628) CRITICAL: error: 0x39e3f3773 >= 0x306dc4200 Dynamic subtest DRM_XE_ENGINE_CLASS_RENDER0 failed. ** DEBUG (xe_exec_sip:7628) DEBUG: Using DRM_XE_ENGINE_CLASS_RENDER (xe_exec_sip:7628) drmtest-DEBUG: Opened previously opened device: /dev/dri/card0 (xe_exec_sip:7628) drmtest-DEBUG: Test requirement passed: !(fd<0) (xe_exec_sip:7628) intel_allocator_simple-DEBUG: Using simple allocator (xe_exec_sip:7628) intel_bufops-DEBUG: generation: 30, supported tiles: 0x3f, driver: xe (xe_exec_sip:7628) gpgpu_shader-DEBUG: Test requirement passed: shdr->gen_ver >= SUPPORTED_GEN_VER (xe_exec_sip:7628) gpgpu_shader-DEBUG: Test requirement passed: shdr->gen_ver >= SUPPORTED_GEN_VER (xe_exec_sip:7628) gpgpu_shader-DEBUG: Test requirement passed: shdr->gen_ver >= SUPPORTED_GEN_VER (xe_exec_sip:7628) gpgpu_shader-DEBUG: Test requirement passed: shdr->gen_ver >= SUPPORTED_GEN_VER (xe_exec_sip:7628) gpgpu_shader-DEBUG: Test requirement passed: shdr->gen_ver >= SUPPORTED_GEN_VER (xe_exec_sip:7628) intel_allocator_simple-DEBUG: Using simple allocator (xe_exec_sip:7628) intel_allocator_simple-DEBUG: <ial: 0x55f22c4fe590, fd: 6> objects: 1, reserved_areas: 0 (xe_exec_sip:7628) intel_allocator_simple-DEBUG: <ial: 0x55f22c4fe590, fd: 6> objects: 1, reserved_areas: 0 (xe_exec_sip:7628) intel_batchbuffer-DEBUG: bind_ops: MAP (xe_exec_sip:7628) intel_batchbuffer-DEBUG: [0]: handle: 1, offset: ffffffffe000, size: 2000 pat_index: 2 (xe_exec_sip:7628) intel_batchbuffer-DEBUG: [1]: handle: 2, offset: 0, size: 10000 pat_index: 2 (xe_exec_sip:7628) CRITICAL: Test assertion failure function test_sip, file ../tests/intel/xe_exec_sip.c:291: (xe_exec_sip:7628) CRITICAL: Failed assertion: igt_nsec_elapsed(&ts) < timeout (xe_exec_sip:7628) CRITICAL: error: 0x39e3f3773 >= 0x306dc4200 (xe_exec_sip:7628) igt_core-INFO: Stack trace: (xe_exec_sip:7628) igt_core-INFO: #0 ../lib/igt_core.c:2075 __igt_fail_assert() (xe_exec_sip:7628) igt_core-INFO: #1 [test_sip.constprop.0+0x665] (xe_exec_sip:7628) igt_core-INFO: #2 ../tests/intel/xe_exec_sip.c:324 __igt_unique____real_main312() (xe_exec_sip:7628) igt_core-INFO: #3 ../tests/intel/xe_exec_sip.c:312 main() (xe_exec_sip:7628) igt_core-INFO: #4 [__libc_init_first+0x8a] (xe_exec_sip:7628) igt_core-INFO: #5 [__libc_start_main+0x8b] (xe_exec_sip:7628) igt_core-INFO: #6 [_start+0x25] END ** Dynamic subtest DRM_XE_ENGINE_CLASS_RENDER0: FAIL (15.631s) Starting dynamic subtest: DRM_XE_ENGINE_CLASS_COMPUTE0 Dynamic subtest DRM_XE_ENGINE_CLASS_COMPUTE0: SUCCESS (11.298s) Subtest sanity-after-timeout: FAIL (26.941s)
Dmesg	<6> [62.496718] [IGT] xe_exec_sip: executing <6> [62.511447] [IGT] xe_exec_sip: starting subtest sanity-after-timeout <6> [62.512165] [IGT] xe_exec_sip: starting dynamic subtest DRM_XE_ENGINE_CLASS_RENDER0 <7> [67.772007] 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=0x00000000 <5> [72.890617] xe 0000:00:03.0: [drm] Tile0: GT0: Check job timeout: seqno=4294967169, lrc_seqno=4294967169, guc_id=4, timestamp stuck <6> [73.544277] xe 0000:00:03.0: [drm] Tile0: GT0: Engine reset: engine_class=rcs, logical_mask: 0x1, guc_id=4, state=0x209 <5> [78.010774] xe 0000:00:03.0: [drm] Tile0: GT0: Timedout job: seqno=4294967169, lrc_seqno=4294967169, guc_id=4, flags=0x0 in xe_exec_sip [7628] <6> [78.033388] xe 0000:00:03.0: [drm] Xe device coredump has been created <6> [78.036303] xe 0000:00:03.0: [drm] Check your /sys/class/drm/card0/device/devcoredump/data <6> [78.145211] [IGT] xe_exec_sip: finished subtest DRM_XE_ENGINE_CLASS_RENDER0, FAIL <6> [78.149269] [IGT] xe_exec_sip: starting dynamic subtest DRM_XE_ENGINE_CLASS_COMPUTE0 <7> [83.642440] 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.762505] xe 0000:00:03.0: [drm] Tile0: GT0: Check job timeout: seqno=4294967169, lrc_seqno=4294967169, guc_id=6, timestamp stuck <6> [89.411414] xe 0000:00:03.0: [drm] Tile0: GT0: Engine reset: engine_class=ccs, logical_mask: 0x1, guc_id=6, state=0x209 <5> [89.417588] xe 0000:00:03.0: [drm] Tile0: GT0: Timedout job: seqno=4294967169, lrc_seqno=4294967169, guc_id=6, flags=0x0 in xe_exec_sip [7628] <7> [89.429314] xe 0000:00:03.0: [drm:xe_devcoredump [xe]] Multiple hangs are occurring, but only the first snapshot was taken <6> [89.451598] [IGT] xe_exec_sip: finished subtest DRM_XE_ENGINE_CLASS_COMPUTE0, SUCCESS <6> [89.453495] [IGT] xe_exec_sip: finished subtest sanity-after-timeout, FAIL <6> [89.454789] [IGT] xe_exec_sip: exiting, ret=98

Detail

Value

Duration

26.94 seconds

Igt-Version

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

Out

Using IGT_SRANDOM=1773742422 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.631s)
Starting dynamic subtest: DRM_XE_ENGINE_CLASS_COMPUTE0
Dynamic subtest DRM_XE_ENGINE_CLASS_COMPUTE0: SUCCESS (11.298s)
Subtest sanity-after-timeout: FAIL (26.941s)

Err

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

Dmesg

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

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

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

<7> [67.772007] 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=0x00000000

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

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

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

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

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

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

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

<7> [83.642440] 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.762505] xe 0000:00:03.0: [drm] Tile0: GT0: Check job timeout: seqno=4294967169, lrc_seqno=4294967169, guc_id=6, timestamp stuck

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

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

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

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

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

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

Results for igt@xe_exec_sip@sanity-after-timeout