Results for igt@perf_pmu@most-busy-idle-check-all

Machine description: shard-dg2-3

Result: Fail

git-log-oneline i915_display_info18 igt_runner18 runtimes18 results18.json results18-i915-load.json i915_display_info_post_exec18 boot18 dmesg18

DetailValue
Duration 4.61 seconds
Hostname 
shard-dg2-3
Igt-Version 
IGT-Version: 2.0-g49d989682 (x86_64) (Linux: 6.14.0-rc7-CI_DRM_16299-gb15a87713d14+ x86_64)
Out 
Using IGT_SRANDOM=1742570057 for randomisation
Opened device: /dev/dri/card0
Starting subtest: most-busy-idle-check-all
Starting dynamic subtest: rcs0
0=0
1=500551041
2=214645348073
3=214633170677
4=214622790989
5=214598047135
6=213541941041
Stack trace:
  #0 ../lib/igt_core.c:2055 __igt_fail_assert()
  #1 ../tests/intel/perf_pmu.c:630 most_busy_check_all()
  #2 ../tests/intel/perf_pmu.c:2382 __igt_unique____real_main2285()
  #3 ../tests/intel/perf_pmu.c:2285 main()
  #4 [__libc_init_first+0x8a]
  #5 [__libc_start_main+0x8b]
  #6 [_start+0x25]
Dynamic subtest rcs0: FAIL (0.673s)
Starting dynamic subtest: bcs0
0=500456875
1=0
2=500388698
3=500315364
4=500314323
5=500306354
6=500363438
Dynamic subtest bcs0: SUCCESS (0.667s)
Starting dynamic subtest: vcs0
0=500292447
1=500297187
2=0
3=500185625
4=500162708
5=500157448
6=500186457
Dynamic subtest vcs0: SUCCESS (0.651s)
Starting dynamic subtest: vcs1
0=500278957
1=500282500
2=500184947
3=0
4=500170520
5=500165833
6=500223698
Dynamic subtest vcs1: SUCCESS (0.648s)
Starting dynamic subtest: vecs0
0=500514791
1=500519010
2=500421093
3=500417864
4=0
5=500405988
6=500461615
Dynamic subtest vecs0: SUCCESS (0.652s)
Starting dynamic subtest: vecs1
0=500516562
1=500526145
2=500445364
3=500406093
4=500430885
5=0
6=500467239
Dynamic subtest vecs1: SUCCESS (0.652s)
Starting dynamic subtest: ccs0
0=500243125
1=500249947
2=500133853
3=500131042
4=500151094
5=500124114
6=0
Dynamic subtest ccs0: SUCCESS (0.651s)
Subtest most-busy-idle-check-all: FAIL (4.607s)
Opened device: /dev/dri/renderD128
Err 
Starting subtest: most-busy-idle-check-all
Starting dynamic subtest: rcs0
(perf_pmu:2758) CRITICAL: Test assertion failure function most_busy_check_all, file ../tests/intel/perf_pmu.c:627:
(perf_pmu:2758) CRITICAL: Failed assertion: (double)(val[i]) <= (1.0 + (tolerance)) * (double)(slept) && (double)(val[i]) >= (1.0 - (tolerance)) * (double)(slept)
(perf_pmu:2758) CRITICAL: 'val[i]' != 'slept' (214645348073.000000 not within +5.0%/-5.0% tolerance of 500145000.000000)

Dynamic subtest rcs0 failed.
**** DEBUG ****
(perf_pmu:2758) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2758) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2758) DEBUG: Test requirement passed: spin
(perf_pmu:2758) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
(perf_pmu:2758) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
(perf_pmu:2758) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
(perf_pmu:2758) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
(perf_pmu:2758) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
(perf_pmu:2758) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
(perf_pmu:2758) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
(perf_pmu:2758) INFO: 0=0
1=500551041
2=214645348073
3=214633170677
4=214622790989
5=214598047135
6=213541941041
(perf_pmu:2758) CRITICAL: Test assertion failure function most_busy_check_all, file ../tests/intel/perf_pmu.c:627:
(perf_pmu:2758) CRITICAL: Failed assertion: (double)(val[i]) <= (1.0 + (tolerance)) * (double)(slept) && (double)(val[i]) >= (1.0 - (tolerance)) * (double)(slept)
(perf_pmu:2758) CRITICAL: 'val[i]' != 'slept' (214645348073.000000 not within +5.0%/-5.0% tolerance of 500145000.000000)

(perf_pmu:2758) igt_core-INFO: Stack trace:
(perf_pmu:2758) igt_core-INFO:   #0 ../lib/igt_core.c:2055 __igt_fail_assert()
(perf_pmu:2758) igt_core-INFO:   #1 ../tests/intel/perf_pmu.c:630 most_busy_check_all()
(perf_pmu:2758) igt_core-INFO:   #2 ../tests/intel/perf_pmu.c:2382 __igt_unique____real_main2285()
(perf_pmu:2758) igt_core-INFO:   #3 ../tests/intel/perf_pmu.c:2285 main()
(perf_pmu:2758) igt_core-INFO:   #4 [__libc_init_first+0x8a]
(perf_pmu:2758) igt_core-INFO:   #5 [__libc_start_main+0x8b]
(perf_pmu:2758) igt_core-INFO:   #6 [_start+0x25]
****  END  ****
Dynamic subtest rcs0: FAIL (0.673s)
Starting dynamic subtest: bcs0
Dynamic subtest bcs0: SUCCESS (0.667s)
Starting dynamic subtest: vcs0
Dynamic subtest vcs0: SUCCESS (0.651s)
Starting dynamic subtest: vcs1
Dynamic subtest vcs1: SUCCESS (0.648s)
Starting dynamic subtest: vecs0
Dynamic subtest vecs0: SUCCESS (0.652s)
Starting dynamic subtest: vecs1
Dynamic subtest vecs1: SUCCESS (0.652s)
Starting dynamic subtest: ccs0
Dynamic subtest ccs0: SUCCESS (0.651s)
Subtest most-busy-idle-check-all: FAIL (4.607s)
Dmesg
<6> [433.919762] Console: switching to colour dummy device 80x25
<6> [433.920259] [IGT] perf_pmu: executing
<7> [433.936393] i915 0000:03:00.0: [drm:i915_gem_open [i915]]
<7> [433.937790] i915 0000:03:00.0: [drm:i915_gem_open [i915]]
<6> [433.945343] [IGT] perf_pmu: starting subtest most-busy-idle-check-all
<6> [433.945815] [IGT] perf_pmu: starting dynamic subtest rcs0
<7> [433.946226] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<6> [434.619752] [IGT] perf_pmu: finished subtest rcs0, FAIL
<6> [434.620892] [IGT] perf_pmu: starting dynamic subtest bcs0
<7> [434.621141] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [435.233317] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<6> [435.288203] [IGT] perf_pmu: finished subtest bcs0, SUCCESS
<6> [435.288904] [IGT] perf_pmu: starting dynamic subtest vcs0
<7> [435.289329] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [435.901266] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<6> [435.940635] [IGT] perf_pmu: finished subtest vcs0, SUCCESS
<6> [435.941103] [IGT] perf_pmu: starting dynamic subtest vcs1
<7> [435.941453] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [436.550099] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<6> [436.589892] [IGT] perf_pmu: finished subtest vcs1, SUCCESS
<6> [436.591087] [IGT] perf_pmu: starting dynamic subtest vecs0
<7> [436.591620] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [437.204749] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<6> [437.244645] [IGT] perf_pmu: finished subtest vecs0, SUCCESS
<6> [437.245912] [IGT] perf_pmu: starting dynamic subtest vecs1
<7> [437.246490] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [437.858048] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<6> [437.898891] [IGT] perf_pmu: finished subtest vecs1, SUCCESS
<6> [437.900168] [IGT] perf_pmu: starting dynamic subtest ccs0
<7> [437.900729] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [438.512652] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<6> [438.552623] [IGT] perf_pmu: finished subtest ccs0, SUCCESS
<6> [438.554045] [IGT] perf_pmu: finished subtest most-busy-idle-check-all, FAIL
<7> [438.569143] i915 0000:03:00.0: [drm:i915_gem_open [i915]]
<7> [438.570347] i915 0000:03:00.0: [drm:i915_gem_open [i915]]
<7> [438.572513] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [438.614791] i915 0000:03:00.0: [drm:drm_client_dev_restore] fbdev: ret=0
<6> [438.615533] [IGT] perf_pmu: exiting, ret=98
<6> [438.631960] Console: switching to colour frame buffer device 240x67
Created at 2025-03-21 16:14:52