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

Machine description: shard-dg2-6

Result: Fail

git-log-oneline i915_display_info2 igt_runner2 runtimes2 results2.json results2-i915-load.json i915_display_info_post_exec2 boot2 dmesg2

DetailValue
Duration 3.86 seconds
Hostname 
shard-dg2-6
Igt-Version 
IGT-Version: 1.30-g0a4beb5fb (x86_64) (Linux: 6.14.0-rc5-CI_DRM_16231-g3ccfd8016ad8+ x86_64)
Out 
Using IGT_SRANDOM=1741242066 for randomisation
Opened device: /dev/dri/card0
Starting subtest: most-busy-check-all
Starting dynamic subtest: rcs0
0=0
1=500404062
2=500424688
3=500443958
4=500467657
5=500486719
6=500506094
Dynamic subtest rcs0: SUCCESS (0.577s)
Starting dynamic subtest: bcs0
0=500296980
1=0
2=233379844
3=396999792
4=305688958
5=315843228
6=196976459
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:2379 __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 bcs0: FAIL (0.530s)
Starting dynamic subtest: vcs0
0=500184219
1=500184948
2=0
3=500185000
4=500185364
5=500186042
6=500185781
Dynamic subtest vcs0: SUCCESS (0.545s)
Starting dynamic subtest: vcs1
0=500488073
1=500524167
2=500552135
3=0
4=500605312
5=500631302
6=500658646
Dynamic subtest vcs1: SUCCESS (0.552s)
Starting dynamic subtest: vecs0
0=500468646
1=500503282
2=500531094
3=500557500
4=0
5=500611666
6=500637969
Dynamic subtest vecs0: SUCCESS (0.548s)
Starting dynamic subtest: vecs1
0=500545937
1=500580782
2=500609167
3=500635573
4=500661667
5=0
6=500714584
Dynamic subtest vecs1: SUCCESS (0.549s)
Starting dynamic subtest: ccs0
0=500544948
1=500578542
2=500606458
3=500632656
4=500659271
5=500685989
6=0
Dynamic subtest ccs0: SUCCESS (0.550s)
Subtest most-busy-check-all: FAIL (3.862s)
Opened device: /dev/dri/renderD128
Err 
Starting subtest: most-busy-check-all
Starting dynamic subtest: rcs0
Dynamic subtest rcs0: SUCCESS (0.577s)
Starting dynamic subtest: bcs0
(perf_pmu:10267) CRITICAL: Test assertion failure function most_busy_check_all, file ../tests/intel/perf_pmu.c:627:
(perf_pmu:10267) CRITICAL: Failed assertion: (double)(val[i]) <= (1.0 + (tolerance)) * (double)(slept) && (double)(val[i]) >= (1.0 - (tolerance)) * (double)(slept)
(perf_pmu:10267) CRITICAL: 'val[i]' != 'slept' (233379844.000000 not within +5.0%/-5.0% tolerance of 500055000.000000)

Dynamic subtest bcs0 failed.
**** DEBUG ****
(perf_pmu:10267) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:10267) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:10267) DEBUG: Test requirement passed: spin
(perf_pmu:10267) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
(perf_pmu:10267) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
(perf_pmu:10267) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
(perf_pmu:10267) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
(perf_pmu:10267) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
(perf_pmu:10267) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
(perf_pmu:10267) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
(perf_pmu:10267) INFO: 0=500296980
1=0
2=233379844
3=396999792
4=305688958
5=315843228
6=196976459
(perf_pmu:10267) CRITICAL: Test assertion failure function most_busy_check_all, file ../tests/intel/perf_pmu.c:627:
(perf_pmu:10267) CRITICAL: Failed assertion: (double)(val[i]) <= (1.0 + (tolerance)) * (double)(slept) && (double)(val[i]) >= (1.0 - (tolerance)) * (double)(slept)
(perf_pmu:10267) CRITICAL: 'val[i]' != 'slept' (233379844.000000 not within +5.0%/-5.0% tolerance of 500055000.000000)

(perf_pmu:10267) igt_core-INFO: Stack trace:
(perf_pmu:10267) igt_core-INFO:   #0 ../lib/igt_core.c:2055 __igt_fail_assert()
(perf_pmu:10267) igt_core-INFO:   #1 ../tests/intel/perf_pmu.c:630 most_busy_check_all()
(perf_pmu:10267) igt_core-INFO:   #2 ../tests/intel/perf_pmu.c:2379 __igt_unique____real_main2285()
(perf_pmu:10267) igt_core-INFO:   #3 ../tests/intel/perf_pmu.c:2285 main()
(perf_pmu:10267) igt_core-INFO:   #4 [__libc_init_first+0x8a]
(perf_pmu:10267) igt_core-INFO:   #5 [__libc_start_main+0x8b]
(perf_pmu:10267) igt_core-INFO:   #6 [_start+0x25]
****  END  ****
Dynamic subtest bcs0: FAIL (0.530s)
Starting dynamic subtest: vcs0
Dynamic subtest vcs0: SUCCESS (0.545s)
Starting dynamic subtest: vcs1
Dynamic subtest vcs1: SUCCESS (0.552s)
Starting dynamic subtest: vecs0
Dynamic subtest vecs0: SUCCESS (0.548s)
Starting dynamic subtest: vecs1
Dynamic subtest vecs1: SUCCESS (0.549s)
Starting dynamic subtest: ccs0
Dynamic subtest ccs0: SUCCESS (0.550s)
Subtest most-busy-check-all: FAIL (3.862s)
Dmesg
<6> [263.758998] Console: switching to colour dummy device 80x25
<6> [263.759314] [IGT] perf_pmu: executing
<7> [263.774539] i915 0000:03:00.0: [drm:i915_gem_open [i915]]
<7> [263.776167] i915 0000:03:00.0: [drm:i915_gem_open [i915]]
<6> [263.782815] [IGT] perf_pmu: starting subtest most-busy-check-all
<6> [263.783128] [IGT] perf_pmu: starting dynamic subtest rcs0
<7> [263.783508] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [264.187574] i915 0000:03:00.0: [drm:drm_dp_dpcd_access [drm_display_helper]] AUX A/DDI A/PHY A: Too many retries, giving up. First error: -6
<7> [264.317994] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [264.347574] i915 0000:03:00.0: [drm:drm_dp_dpcd_access [drm_display_helper]] AUX B/DDI B/PHY B: Too many retries, giving up. First error: -6
<6> [264.361354] [IGT] perf_pmu: finished subtest rcs0, SUCCESS
<6> [264.362769] [IGT] perf_pmu: starting dynamic subtest bcs0
<7> [264.363203] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [264.507602] i915 0000:03:00.0: [drm:drm_dp_dpcd_access [drm_display_helper]] AUX C/DDI C/PHY C: Too many retries, giving up. First error: -6
<7> [264.508185] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling AUX_D
<7> [264.537518] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [264.566518] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [264.595517] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [264.624516] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [264.653516] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [264.682515] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [264.711515] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [264.740514] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [264.769513] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [264.798513] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [264.827513] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [264.856512] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [264.885513] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<6> [264.893167] [IGT] perf_pmu: finished subtest bcs0, FAIL
<6> [264.893508] [IGT] perf_pmu: starting dynamic subtest vcs0
<7> [264.893608] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [264.913514] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [264.941510] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [264.970509] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [264.999509] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [265.028509] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [265.057508] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [265.086508] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [265.112507] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [265.141507] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [265.170506] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [265.199506] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [265.228506] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [265.257505] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [265.286504] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [265.315503] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [265.344503] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [265.373502] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [265.398217] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [265.402503] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [265.431502] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [265.431683] i915 0000:03:00.0: [drm:drm_dp_dpcd_access [drm_display_helper]] AUX D/DDI D/PHY D: Too many retries, giving up. First error: -110
<6> [265.438929] [IGT] perf_pmu: finished subtest vcs0, SUCCESS
<6> [265.439252] [IGT] perf_pmu: starting dynamic subtest vcs1
<7> [265.439379] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [265.534628] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling AUX_D
<7> [265.950319] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<6> [265.991430] [IGT] perf_pmu: finished subtest vcs1, SUCCESS
<6> [265.991870] [IGT] perf_pmu: starting dynamic subtest vecs0
<7> [265.991994] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [266.499274] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<6> [266.541350] [IGT] perf_pmu: finished subtest vecs0, SUCCESS
<6> [266.542881] [IGT] perf_pmu: starting dynamic subtest vecs1
<7> [266.543420] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [267.052684] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<6> [267.092435] [IGT] perf_pmu: finished subtest vecs1, SUCCESS
<6> [267.092777] [IGT] perf_pmu: starting dynamic subtest ccs0
<7> [267.092898] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [267.603342] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<6> [267.644428] [IGT] perf_pmu: finished subtest ccs0, SUCCESS
<6> [267.646020] [IGT] perf_pmu: finished subtest most-busy-check-all, FAIL
<7> [267.660411] i915 0000:03:00.0: [drm:i915_gem_open [i915]]
<7> [267.661665] i915 0000:03:00.0: [drm:i915_gem_open [i915]]
<7> [267.663606] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [267.699837] i915 0000:03:00.0: [drm:drm_client_dev_restore] fbdev: ret=0
<6> [267.700233] [IGT] perf_pmu: exiting, ret=98
<6> [267.716826] Console: switching to colour frame buffer device 240x67
Created at 2025-03-06 07:35:34