Results for igt@perf_pmu@busy-double-start

Result: Fail

git-log-oneline i915_display_info16 igt_runner16 runtimes16 results16.json results16-i915-load.json guc_logs16.tar i915_display_info_post_exec16 boot16 dmesg16

DetailValue
Duration 22.96 seconds
Hostname 
shard-mtlp-2
Igt-Version 
IGT-Version: 2.4-g22222b7d9 (x86_64) (Linux: 7.1.0-rc3-CI_DRM_18489-g121c16f9d8c5+ x86_64)
Out 
Using IGT_SRANDOM=1778807641 for randomisation
Opened device: /dev/dri/card0
Starting subtest: busy-double-start
Starting dynamic subtest: rcs0
busy=475067864 idle=0
Dynamic subtest rcs0: SUCCESS (3.592s)
Starting dynamic subtest: bcs0
busy=475090833 idle=0
Stack trace:
  #0 ../lib/igt_core.c:2074 __igt_fail_assert()
  #1 ../tests/intel/perf_pmu.c:481 busy_double_start()
  #2 ../tests/intel/perf_pmu.c:2434 __igt_unique____real_main2286()
  #3 ../tests/intel/perf_pmu.c:2286 main()
  #4 [__libc_init_first+0x8a]
  #5 [__libc_start_main+0x8b]
  #6 [_start+0x25]
Dynamic subtest bcs0: FAIL (3.575s)
Starting dynamic subtest: vcs0
busy=477333958 idle=0
Dynamic subtest vcs0: SUCCESS (4.203s)
Starting dynamic subtest: vcs1
busy=476749271 idle=0
Dynamic subtest vcs1: SUCCESS (4.048s)
Starting dynamic subtest: vecs0
busy=475490937 idle=0
Dynamic subtest vecs0: SUCCESS (3.966s)
Starting dynamic subtest: ccs0
busy=473959376 idle=0
Stack trace:
  #0 ../lib/igt_core.c:2074 __igt_fail_assert()
  #1 ../tests/intel/perf_pmu.c:481 busy_double_start()
  #2 ../tests/intel/perf_pmu.c:2434 __igt_unique____real_main2286()
  #3 ../tests/intel/perf_pmu.c:2286 main()
  #4 [__libc_init_first+0x8a]
  #5 [__libc_start_main+0x8b]
  #6 [_start+0x25]
Dynamic subtest ccs0: FAIL (3.568s)
Subtest busy-double-start: FAIL (22.963s)
Opened device: /dev/dri/renderD128
Err 
Starting subtest: busy-double-start
Starting dynamic subtest: rcs0
Dynamic subtest rcs0: SUCCESS (3.592s)
Starting dynamic subtest: bcs0
[175.788487] (perf_pmu:2704) CRITICAL: Test assertion failure function busy_double_start, file ../tests/intel/perf_pmu.c:477:
[175.788525] (perf_pmu:2704) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance)) * (double)(ts[1] - ts[0]) && (double)(val) >= (1.0 - (tolerance)) * (double)(ts[1] - ts[0])
[175.788577] (perf_pmu:2704) CRITICAL: 'val' != 'ts[1] - ts[0]' (475090833.000000 not within +5.0%/-5.0% tolerance of 500183010.000000)

Dynamic subtest bcs0 failed.
**** DEBUG ****
[172.235054] (perf_pmu:2704) intel_allocator_reloc-DEBUG: Using reloc allocator
[172.236125] (perf_pmu:2704) intel_allocator_reloc-DEBUG: Using reloc allocator
[174.235235] (perf_pmu:2704) igt_dummyload-DEBUG: Test requirement passed: nengine
[174.742636] (perf_pmu:2704) igt_dummyload-DEBUG: Test requirement passed: nengine
[174.749657] (perf_pmu:2704) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
[175.249984] (perf_pmu:2704) DEBUG: slept=500139000 perf=500183010
[175.787713] (perf_pmu:2704) INFO: busy=475090833 idle=0
[175.788487] (perf_pmu:2704) CRITICAL: Test assertion failure function busy_double_start, file ../tests/intel/perf_pmu.c:477:
[175.788525] (perf_pmu:2704) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance)) * (double)(ts[1] - ts[0]) && (double)(val) >= (1.0 - (tolerance)) * (double)(ts[1] - ts[0])
[175.788577] (perf_pmu:2704) CRITICAL: 'val' != 'ts[1] - ts[0]' (475090833.000000 not within +5.0%/-5.0% tolerance of 500183010.000000)

[175.794191] (perf_pmu:2704) igt_core-INFO: Stack trace:
[175.799003] (perf_pmu:2704) igt_core-INFO:   #0 ../lib/igt_core.c:2074 __igt_fail_assert()
[175.799895] (perf_pmu:2704) igt_core-INFO:   #1 ../tests/intel/perf_pmu.c:481 busy_double_start()
[175.799934] (perf_pmu:2704) igt_core-INFO:   #2 ../tests/intel/perf_pmu.c:2434 __igt_unique____real_main2286()
[175.799954] (perf_pmu:2704) igt_core-INFO:   #3 ../tests/intel/perf_pmu.c:2286 main()
[175.807959] (perf_pmu:2704) igt_core-INFO:   #4 [__libc_init_first+0x8a]
[175.808511] (perf_pmu:2704) igt_core-INFO:   #5 [__libc_start_main+0x8b]
[175.808664] (perf_pmu:2704) igt_core-INFO:   #6 [_start+0x25]
****  END  ****
Dynamic subtest bcs0: FAIL (3.575s)
Starting dynamic subtest: vcs0
Dynamic subtest vcs0: SUCCESS (4.203s)
Starting dynamic subtest: vcs1
Dynamic subtest vcs1: SUCCESS (4.048s)
Starting dynamic subtest: vecs0
Dynamic subtest vecs0: SUCCESS (3.966s)
Starting dynamic subtest: ccs0
[191.591453] (perf_pmu:2704) CRITICAL: Test assertion failure function busy_double_start, file ../tests/intel/perf_pmu.c:477:
[191.591524] (perf_pmu:2704) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance)) * (double)(ts[1] - ts[0]) && (double)(val) >= (1.0 - (tolerance)) * (double)(ts[1] - ts[0])
[191.591583] (perf_pmu:2704) CRITICAL: 'val' != 'ts[1] - ts[0]' (473959376.000000 not within +5.0%/-5.0% tolerance of 500421333.000000)

Dynamic subtest ccs0 failed.
**** DEBUG ****
[188.034285] (perf_pmu:2704) intel_allocator_reloc-DEBUG: Using reloc allocator
[188.035165] (perf_pmu:2704) intel_allocator_reloc-DEBUG: Using reloc allocator
[190.035558] (perf_pmu:2704) igt_dummyload-DEBUG: Test requirement passed: nengine
[190.544043] (perf_pmu:2704) igt_dummyload-DEBUG: Test requirement passed: nengine
[190.551142] (perf_pmu:2704) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
[191.051691] (perf_pmu:2704) DEBUG: slept=500377000 perf=500421333
[191.589860] (perf_pmu:2704) INFO: busy=473959376 idle=0
[191.591453] (perf_pmu:2704) CRITICAL: Test assertion failure function busy_double_start, file ../tests/intel/perf_pmu.c:477:
[191.591524] (perf_pmu:2704) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance)) * (double)(ts[1] - ts[0]) && (double)(val) >= (1.0 - (tolerance)) * (double)(ts[1] - ts[0])
[191.591583] (perf_pmu:2704) CRITICAL: 'val' != 'ts[1] - ts[0]' (473959376.000000 not within +5.0%/-5.0% tolerance of 500421333.000000)

[191.595740] (perf_pmu:2704) igt_core-INFO: Stack trace:
[191.597973] (perf_pmu:2704) igt_core-INFO:   #0 ../lib/igt_core.c:2074 __igt_fail_assert()
[191.598961] (perf_pmu:2704) igt_core-INFO:   #1 ../tests/intel/perf_pmu.c:481 busy_double_start()
[191.598977] (perf_pmu:2704) igt_core-INFO:   #2 ../tests/intel/perf_pmu.c:2434 __igt_unique____real_main2286()
[191.598989] (perf_pmu:2704) igt_core-INFO:   #3 ../tests/intel/perf_pmu.c:2286 main()
[191.600350] (perf_pmu:2704) igt_core-INFO:   #4 [__libc_init_first+0x8a]
[191.601045] (perf_pmu:2704) igt_core-INFO:   #5 [__libc_start_main+0x8b]
[191.601213] (perf_pmu:2704) igt_core-INFO:   #6 [_start+0x25]
****  END  ****
Dynamic subtest ccs0: FAIL (3.568s)
Subtest busy-double-start: FAIL (22.963s)
Dmesg
<6> [201.019791] Console: switching to colour dummy device 80x25
<6> [201.020170] [IGT] perf_pmu: executing
<7> [201.023642] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [201.024779] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<6> [201.029217] [IGT] perf_pmu: starting subtest busy-double-start
<6> [201.029633] [IGT] perf_pmu: starting dynamic subtest rcs0
<7> [201.029952] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [201.033860] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [201.809773] i915 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 turning VDD off
<7> [201.810761] i915 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x00000067
<7> [201.811702] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [201.812335] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [201.812782] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [204.049509] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [204.587843] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<6> [204.622616] [IGT] perf_pmu: finished subtest rcs0, SUCCESS
<6> [204.623897] [IGT] perf_pmu: starting dynamic subtest bcs0
<7> [204.624311] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [204.625389] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [207.640251] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<6> [208.199440] [IGT] perf_pmu: finished subtest bcs0, FAIL
<6> [208.199984] [IGT] perf_pmu: starting dynamic subtest vcs0
<7> [208.200435] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [208.200871] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [211.214711] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [212.389459] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<6> [212.403968] [IGT] perf_pmu: finished subtest vcs0, SUCCESS
<6> [212.405231] [IGT] perf_pmu: starting dynamic subtest vcs1
<7> [212.405644] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [212.406475] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [215.422206] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [216.420810] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<6> [216.453848] [IGT] perf_pmu: finished subtest vcs1, SUCCESS
<6> [216.454789] [IGT] perf_pmu: starting dynamic subtest vecs0
<7> [216.455205] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [216.455768] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [219.473082] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [220.388440] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<6> [220.421378] [IGT] perf_pmu: finished subtest vecs0, SUCCESS
<6> [220.422929] [IGT] perf_pmu: starting dynamic subtest ccs0
<7> [220.423516] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [220.424576] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [223.441895] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<6> [223.991865] [IGT] perf_pmu: finished subtest ccs0, FAIL
<6> [223.992512] [IGT] perf_pmu: finished subtest busy-double-start, FAIL
<7> [223.992799] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [223.993775] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [223.995745] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [224.013108] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [224.013896] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [224.025641] i915 0000:00:02.0: [drm:drm_client_dev_restore] fbdev: ret=0
<6> [224.026056] [IGT] perf_pmu: exiting, ret=98
<6> [224.036767] Console: switching to colour frame buffer device 180x56
<7> [224.065299] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [224.065501] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [224.065662] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
Created at 2026-05-15 09:13:43