Results for igt@sysfs_heartbeat_interval@precise@vecs0

Result: Fail

integration-manifest git-log-oneline i915_display_info8 igt_runner8 runtimes8 results8.json results8-i915-load.json i915_display_info_post_exec8 boot8 dmesg8

DetailValue
Duration 0.80 seconds
Hostname 
shard-rkl-4
Igt-Version 
IGT-Version: 2.1-g389669153 (x86_64) (Linux: 6.17.0-rc1-CI_DRM_16998-gccbc444c19d0+ x86_64)
Out 
Using IGT_SRANDOM=1755185085 for randomisation
Opened device: /dev/dri/card0
Starting subtest: precise
Starting dynamic subtest: vecs0
heartbeat_interval_ms:1, elapsed=11.971ms[11]
heartbeat_interval_ms:50, elapsed=108.150ms[108]
heartbeat_interval_ms:100, elapsed=479.897ms[479]
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/intel/sysfs_heartbeat_interval.c:241 test_precise()
  #2 ../lib/i915/gem_engine_topology.c:714 dyn_sysfs_engines()
  #3 ../tests/intel/sysfs_heartbeat_interval.c:525 __igt_unique____real_main490()
  #4 ../tests/intel/sysfs_heartbeat_interval.c:490 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest vecs0: FAIL (0.796s)
Err 
Starting subtest: precise
Starting dynamic subtest: vecs0
(sysfs_heartbeat_interval:2177) CRITICAL: Test assertion failure function test_precise, file ../tests/intel/sysfs_heartbeat_interval.c:259:
(sysfs_heartbeat_interval:2177) CRITICAL: Failed assertion: elapsed / 1000 / 1000 < 3 * delays[i] + 150
(sysfs_heartbeat_interval:2177) CRITICAL: Heartbeat interval (and CPR) exceeded request!
Dynamic subtest vecs0 failed.
**** DEBUG ****
(sysfs_heartbeat_interval:2177) i915/gem_engine_topology-DEBUG: Test requirement passed: fstatat(engine, file, &st, 0) == 0
(sysfs_heartbeat_interval:2177) DEBUG: set preempt_timeout_ms:1
(sysfs_heartbeat_interval:2177) DEBUG: Test requirement passed: igt_sysfs_printf(engine, attr, "%u", value) > 0
(sysfs_heartbeat_interval:2177) DEBUG: Initial heartbeat_interval_ms:2500
(sysfs_heartbeat_interval:2177) DEBUG: set heartbeat_interval_ms:1
(sysfs_heartbeat_interval:2177) DEBUG: Test requirement passed: igt_sysfs_printf(engine, attr, "%u", value) > 0
(sysfs_heartbeat_interval:2177) intel_allocator_reloc-DEBUG: Using reloc allocator
(sysfs_heartbeat_interval:2177) igt_dummyload-DEBUG: Test requirement passed: gem_class_can_store_dword(fd, class)
(sysfs_heartbeat_interval:2177) igt_dummyload-DEBUG: Test requirement passed: nengine
(sysfs_heartbeat_interval:2177) intel_allocator_reloc-DEBUG: Using reloc allocator
(sysfs_heartbeat_interval:2177) igt_dummyload-DEBUG: Test requirement passed: gem_class_can_store_dword(fd, class)
(sysfs_heartbeat_interval:2177) igt_dummyload-DEBUG: Test requirement passed: nengine
(sysfs_heartbeat_interval:2177) INFO: heartbeat_interval_ms:1, elapsed=11.971ms[11]
(sysfs_heartbeat_interval:2177) DEBUG: set heartbeat_interval_ms:50
(sysfs_heartbeat_interval:2177) DEBUG: Test requirement passed: igt_sysfs_printf(engine, attr, "%u", value) > 0
(sysfs_heartbeat_interval:2177) intel_allocator_reloc-DEBUG: Using reloc allocator
(sysfs_heartbeat_interval:2177) igt_dummyload-DEBUG: Test requirement passed: gem_class_can_store_dword(fd, class)
(sysfs_heartbeat_interval:2177) igt_dummyload-DEBUG: Test requirement passed: nengine
(sysfs_heartbeat_interval:2177) intel_allocator_reloc-DEBUG: Using reloc allocator
(sysfs_heartbeat_interval:2177) igt_dummyload-DEBUG: Test requirement passed: gem_class_can_store_dword(fd, class)
(sysfs_heartbeat_interval:2177) igt_dummyload-DEBUG: Test requirement passed: nengine
(sysfs_heartbeat_interval:2177) INFO: heartbeat_interval_ms:50, elapsed=108.150ms[108]
(sysfs_heartbeat_interval:2177) DEBUG: set heartbeat_interval_ms:100
(sysfs_heartbeat_interval:2177) DEBUG: Test requirement passed: igt_sysfs_printf(engine, attr, "%u", value) > 0
(sysfs_heartbeat_interval:2177) intel_allocator_reloc-DEBUG: Using reloc allocator
(sysfs_heartbeat_interval:2177) igt_dummyload-DEBUG: Test requirement passed: gem_class_can_store_dword(fd, class)
(sysfs_heartbeat_interval:2177) igt_dummyload-DEBUG: Test requirement passed: nengine
(sysfs_heartbeat_interval:2177) intel_allocator_reloc-DEBUG: Using reloc allocator
(sysfs_heartbeat_interval:2177) igt_dummyload-DEBUG: Test requirement passed: gem_class_can_store_dword(fd, class)
(sysfs_heartbeat_interval:2177) igt_dummyload-DEBUG: Test requirement passed: nengine
(sysfs_heartbeat_interval:2177) INFO: heartbeat_interval_ms:100, elapsed=479.897ms[479]
(sysfs_heartbeat_interval:2177) CRITICAL: Test assertion failure function test_precise, file ../tests/intel/sysfs_heartbeat_interval.c:259:
(sysfs_heartbeat_interval:2177) CRITICAL: Failed assertion: elapsed / 1000 / 1000 < 3 * delays[i] + 150
(sysfs_heartbeat_interval:2177) CRITICAL: Heartbeat interval (and CPR) exceeded request!
(sysfs_heartbeat_interval:2177) igt_core-INFO: Stack trace:
(sysfs_heartbeat_interval:2177) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(sysfs_heartbeat_interval:2177) igt_core-INFO:   #1 ../tests/intel/sysfs_heartbeat_interval.c:241 test_precise()
(sysfs_heartbeat_interval:2177) igt_core-INFO:   #2 ../lib/i915/gem_engine_topology.c:714 dyn_sysfs_engines()
(sysfs_heartbeat_interval:2177) igt_core-INFO:   #3 ../tests/intel/sysfs_heartbeat_interval.c:525 __igt_unique____real_main490()
(sysfs_heartbeat_interval:2177) igt_core-INFO:   #4 ../tests/intel/sysfs_heartbeat_interval.c:490 main()
(sysfs_heartbeat_interval:2177) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(sysfs_heartbeat_interval:2177) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(sysfs_heartbeat_interval:2177) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest vecs0: FAIL (0.796s)
Dmesg
<6> [252.731389] Console: switching to colour dummy device 80x25
<6> [252.731556] [IGT] sysfs_heartbeat_interval: executing
<7> [252.733809] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [252.734367] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [252.734576] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [252.735022] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<6> [252.738636] [IGT] sysfs_heartbeat_interval: starting subtest precise
<6> [252.739284] [IGT] sysfs_heartbeat_interval: starting dynamic subtest vecs0
<7> [252.739480] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<5> [252.740046] i915 0000:00:02.0: [drm] vecs0 heartbeat interval adjusted to a non-default value which may cause engine resets to target innocent contexts!
<7> [252.740638] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508
<7> [252.743481] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508
<5> [252.755243] i915 0000:00:02.0: [drm] Resetting vecs0 for preemption time out
<6> [252.756573] i915 0000:00:02.0: [drm] GPU HANG: ecode 12:8:277ffefe, in sysfs_heartbeat [2177]
<6> [252.756577] i915 0000:00:02.0: [drm] GPU error state saved to /sys/class/drm/card0/error
<7> [252.757278] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [252.811322] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508
<7> [252.812849] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508
<5> [252.921037] i915 0000:00:02.0: [drm] Resetting vecs0 for preemption time out
<6> [252.921814] i915 0000:00:02.0: [drm] GPU HANG: ecode 12:8:24dffffd, in sysfs_heartbeat [2177]
<7> [252.922145] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [252.967932] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508
<7> [252.973635] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508
<5> [253.454042] i915 0000:00:02.0: [drm] Resetting vecs0 for preemption time out
<6> [253.454893] i915 0000:00:02.0: [drm] GPU HANG: ecode 12:8:277ffefe, in sysfs_heartbeat [2177]
<7> [253.455260] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<6> [253.536002] [IGT] sysfs_heartbeat_interval: finished subtest vecs0, FAIL
Created at 2025-08-14 16:24:17