Results for igt@gem_exec_fair@basic-pace

Machine description: shard-rkl-1

Result: Fail

integration-manifest git-log-oneline i915_display_info0 igt_runner0 run0 runtimes0 results0.json i915_display_info_post_exec0 boot0 dmesg0

DetailValue
Duration 18.65 seconds
Hostname 
shard-rkl-1
Igt-Version 
IGT-Version: 1.29-NO-GIT (x86_64) (Linux: 6.12.0-rc5-CI_DRM_15608-gb97473387ce1+ x86_64)
Out 
Using IGT_SRANDOM=1730290318 for randomisation
Opened device: /dev/dri/card0
Running on rocketlake
Using Execlists submission
Has kernel scheduler
 - With priority sorting
 - With preemption enabled
 - With HW semaphores enabled
 - With engine busy statistics
 - With timeslicing enabled
CS timestamp frequency: 12000000
Starting subtest: basic-pace
Starting dynamic subtest: rcs0
Interval 16.82ms, range 0.00ms
  3 clients, range: [17.7, 17.7], iqr: [17.7, 17.7], median: 17.7 [0.1, 2.0], mean: 17.7 ± 0.00 ms, cpu: 12%
  7 clients, range: [14.5, 14.5], iqr: [14.5, 14.5], median: 14.5 [9.9, 11.9], mean: 14.5 ± 0.00 ms, cpu: 28%
 15 clients, range: [12.9, 28.7], iqr: [13.3, 25.7], median: 19.6 [9.9, 19.9], mean: 20.1 ± 5.85 ms, cpu: 60%
Dynamic subtest rcs0: SUCCESS (5.514s)
Starting dynamic subtest: bcs0
Interval 16.79ms, range 0.00ms
  3 clients, range: [17.6, 17.6], iqr: [17.6, 17.6], median: 17.6 [0.7, 2.0], mean: 17.6 ± 0.01 ms, cpu: 13%
  7 clients, range: [14.4, 18.4], iqr: [14.5, 16.4], median: 15.4 [8.8, 10.0], mean: 15.6 ± 1.34 ms, cpu: 29%
 15 clients, range: [4.7, 25.9], iqr: [16.9, 25.2], median: 18.8 [18.0, 26.2], mean: 19.1 ± 6.57 ms, cpu: 61%
Stack trace:
  #0 ../lib/igt_core.c:2051 __igt_fail_assert()
  #1 ../tests/intel/gem_exec_fair.c:963 fairness()
  #2 ../tests/intel/gem_exec_fair.c:1423 __igt_unique____real_main1292()
  #3 ../tests/intel/gem_exec_fair.c:1292 main()
  #4 [__libc_init_first+0x8a]
  #5 [__libc_start_main+0x8b]
  #6 [_start+0x25]
Dynamic subtest bcs0: FAIL (4.384s)
Starting dynamic subtest: vcs0
Interval 16.79ms, range 0.00ms
  3 clients, range: [17.6, 17.6], iqr: [17.6, 17.6], median: 17.6 [1.9, 2.0], mean: 17.6 ± 0.01 ms, cpu: 13%
  7 clients, range: [14.4, 18.4], iqr: [14.5, 18.4], median: 14.5 [8.9, 10.0], mean: 15.6 ± 1.76 ms, cpu: 28%
 15 clients, range: [12.4, 25.2], iqr: [15.0, 24.2], median: 23.0 [12.3, 28.0], mean: 20.2 ± 4.59 ms, cpu: 58%
Stack trace:
  #0 ../lib/igt_core.c:2051 __igt_fail_assert()
  #1 ../tests/intel/gem_exec_fair.c:963 fairness()
  #2 ../tests/intel/gem_exec_fair.c:1423 __igt_unique____real_main1292()
  #3 ../tests/intel/gem_exec_fair.c:1292 main()
  #4 [__libc_init_first+0x8a]
  #5 [__libc_start_main+0x8b]
  #6 [_start+0x25]
Dynamic subtest vcs0: FAIL (4.378s)
Starting dynamic subtest: vecs0
Interval 16.79ms, range 0.00ms
  3 clients, range: [17.6, 17.6], iqr: [17.6, 17.6], median: 17.6 [1.9, 2.0], mean: 17.6 ± 0.00 ms, cpu: 12%
  7 clients, range: [14.4, 18.6], iqr: [14.4, 14.5], median: 14.5 [10.0, 10.0], mean: 15.0 ± 1.44 ms, cpu: 28%
 15 clients, range: [12.0, 26.0], iqr: [18.0, 25.3], median: 22.1 [16.4, 27.0], mean: 21.2 ± 4.46 ms, cpu: 61%
Stack trace:
  #0 ../lib/igt_core.c:2051 __igt_fail_assert()
  #1 ../tests/intel/gem_exec_fair.c:963 fairness()
  #2 ../tests/intel/gem_exec_fair.c:1423 __igt_unique____real_main1292()
  #3 ../tests/intel/gem_exec_fair.c:1292 main()
  #4 [__libc_init_first+0x8a]
  #5 [__libc_start_main+0x8b]
  #6 [_start+0x25]
Dynamic subtest vecs0: FAIL (4.375s)
Subtest basic-pace: FAIL (18.652s)
Err 
Starting subtest: basic-pace
Starting dynamic subtest: rcs0
Dynamic subtest rcs0: SUCCESS (5.514s)
Starting dynamic subtest: bcs0
(gem_exec_fair:2630) CRITICAL: Test assertion failure function fairness, file ../tests/intel/gem_exec_fair.c:958:
(gem_exec_fair:2630) CRITICAL: Failed assertion: iqr[nchild / 2] < result[nchild / 2]
(gem_exec_fair:2630) CRITICAL: Child frame IQR 25.99ms exceeded median threshold 18.82ms
Dynamic subtest bcs0 failed.
**** DEBUG ****
(gem_exec_fair:2630) DEBUG: Test requirement passed: has_ctx_timestamp(i915, cfg, e)
(gem_exec_fair:2630) DEBUG: Test requirement passed: gem_class_has_mutable_submission(i915, e->class)
(gem_exec_fair:2630) DEBUG: CPU usage: 4%
(gem_exec_fair:2630) INFO: Interval 16.79ms, range 0.00ms
(gem_exec_fair:2630) DEBUG: CPU usage: 13%
(gem_exec_fair:2630) INFO:   3 clients, range: [17.6, 17.6], iqr: [17.6, 17.6], median: 17.6 [0.7, 2.0], mean: 17.6 ± 0.01 ms, cpu: 13%
(gem_exec_fair:2630) DEBUG: CPU usage: 29%
(gem_exec_fair:2630) INFO:   7 clients, range: [14.4, 18.4], iqr: [14.5, 16.4], median: 15.4 [8.8, 10.0], mean: 15.6 ± 1.34 ms, cpu: 29%
(gem_exec_fair:2630) DEBUG: CPU usage: 61%
(gem_exec_fair:2630) INFO:  15 clients, range: [4.7, 25.9], iqr: [16.9, 25.2], median: 18.8 [18.0, 26.2], mean: 19.1 ± 6.57 ms, cpu: 61%
(gem_exec_fair:2630) CRITICAL: Test assertion failure function fairness, file ../tests/intel/gem_exec_fair.c:958:
(gem_exec_fair:2630) CRITICAL: Failed assertion: iqr[nchild / 2] < result[nchild / 2]
(gem_exec_fair:2630) CRITICAL: Child frame IQR 25.99ms exceeded median threshold 18.82ms
(gem_exec_fair:2630) igt_core-INFO: Stack trace:
(gem_exec_fair:2630) igt_core-INFO:   #0 ../lib/igt_core.c:2051 __igt_fail_assert()
(gem_exec_fair:2630) igt_core-INFO:   #1 ../tests/intel/gem_exec_fair.c:963 fairness()
(gem_exec_fair:2630) igt_core-INFO:   #2 ../tests/intel/gem_exec_fair.c:1423 __igt_unique____real_main1292()
(gem_exec_fair:2630) igt_core-INFO:   #3 ../tests/intel/gem_exec_fair.c:1292 main()
(gem_exec_fair:2630) igt_core-INFO:   #4 [__libc_init_first+0x8a]
(gem_exec_fair:2630) igt_core-INFO:   #5 [__libc_start_main+0x8b]
(gem_exec_fair:2630) igt_core-INFO:   #6 [_start+0x25]
****  END  ****
Dynamic subtest bcs0: FAIL (4.384s)
Starting dynamic subtest: vcs0
(gem_exec_fair:2630) CRITICAL: Test assertion failure function fairness, file ../tests/intel/gem_exec_fair.c:958:
(gem_exec_fair:2630) CRITICAL: Failed assertion: iqr[nchild / 2] < result[nchild / 2]
(gem_exec_fair:2630) CRITICAL: Child frame IQR 26.05ms exceeded median threshold 23.00ms
Dynamic subtest vcs0 failed.
**** DEBUG ****
(gem_exec_fair:2630) DEBUG: Test requirement passed: has_ctx_timestamp(i915, cfg, e)
(gem_exec_fair:2630) DEBUG: Test requirement passed: gem_class_has_mutable_submission(i915, e->class)
(gem_exec_fair:2630) DEBUG: CPU usage: 4%
(gem_exec_fair:2630) INFO: Interval 16.79ms, range 0.00ms
(gem_exec_fair:2630) DEBUG: CPU usage: 13%
(gem_exec_fair:2630) INFO:   3 clients, range: [17.6, 17.6], iqr: [17.6, 17.6], median: 17.6 [1.9, 2.0], mean: 17.6 ± 0.01 ms, cpu: 13%
(gem_exec_fair:2630) DEBUG: CPU usage: 28%
(gem_exec_fair:2630) INFO:   7 clients, range: [14.4, 18.4], iqr: [14.5, 18.4], median: 14.5 [8.9, 10.0], mean: 15.6 ± 1.76 ms, cpu: 28%
(gem_exec_fair:2630) DEBUG: CPU usage: 58%
(gem_exec_fair:2630) INFO:  15 clients, range: [12.4, 25.2], iqr: [15.0, 24.2], median: 23.0 [12.3, 28.0], mean: 20.2 ± 4.59 ms, cpu: 58%
(gem_exec_fair:2630) CRITICAL: Test assertion failure function fairness, file ../tests/intel/gem_exec_fair.c:958:
(gem_exec_fair:2630) CRITICAL: Failed assertion: iqr[nchild / 2] < result[nchild / 2]
(gem_exec_fair:2630) CRITICAL: Child frame IQR 26.05ms exceeded median threshold 23.00ms
(gem_exec_fair:2630) igt_core-INFO: Stack trace:
(gem_exec_fair:2630) igt_core-INFO:   #0 ../lib/igt_core.c:2051 __igt_fail_assert()
(gem_exec_fair:2630) igt_core-INFO:   #1 ../tests/intel/gem_exec_fair.c:963 fairness()
(gem_exec_fair:2630) igt_core-INFO:   #2 ../tests/intel/gem_exec_fair.c:1423 __igt_unique____real_main1292()
(gem_exec_fair:2630) igt_core-INFO:   #3 ../tests/intel/gem_exec_fair.c:1292 main()
(gem_exec_fair:2630) igt_core-INFO:   #4 [__libc_init_first+0x8a]
(gem_exec_fair:2630) igt_core-INFO:   #5 [__libc_start_main+0x8b]
(gem_exec_fair:2630) igt_core-INFO:   #6 [_start+0x25]
****  END  ****
Dynamic subtest vcs0: FAIL (4.378s)
Starting dynamic subtest: vecs0
(gem_exec_fair:2630) CRITICAL: Test assertion failure function fairness, file ../tests/intel/gem_exec_fair.c:958:
(gem_exec_fair:2630) CRITICAL: Failed assertion: iqr[nchild / 2] < result[nchild / 2]
(gem_exec_fair:2630) CRITICAL: Child frame IQR 23.24ms exceeded median threshold 22.06ms
Dynamic subtest vecs0 failed.
**** DEBUG ****
(gem_exec_fair:2630) DEBUG: Test requirement passed: has_ctx_timestamp(i915, cfg, e)
(gem_exec_fair:2630) DEBUG: Test requirement passed: gem_class_has_mutable_submission(i915, e->class)
(gem_exec_fair:2630) DEBUG: CPU usage: 5%
(gem_exec_fair:2630) INFO: Interval 16.79ms, range 0.00ms
(gem_exec_fair:2630) DEBUG: CPU usage: 12%
(gem_exec_fair:2630) INFO:   3 clients, range: [17.6, 17.6], iqr: [17.6, 17.6], median: 17.6 [1.9, 2.0], mean: 17.6 ± 0.00 ms, cpu: 12%
(gem_exec_fair:2630) DEBUG: CPU usage: 28%
(gem_exec_fair:2630) INFO:   7 clients, range: [14.4, 18.6], iqr: [14.4, 14.5], median: 14.5 [10.0, 10.0], mean: 15.0 ± 1.44 ms, cpu: 28%
(gem_exec_fair:2630) DEBUG: CPU usage: 61%
(gem_exec_fair:2630) INFO:  15 clients, range: [12.0, 26.0], iqr: [18.0, 25.3], median: 22.1 [16.4, 27.0], mean: 21.2 ± 4.46 ms, cpu: 61%
(gem_exec_fair:2630) CRITICAL: Test assertion failure function fairness, file ../tests/intel/gem_exec_fair.c:958:
(gem_exec_fair:2630) CRITICAL: Failed assertion: iqr[nchild / 2] < result[nchild / 2]
(gem_exec_fair:2630) CRITICAL: Child frame IQR 23.24ms exceeded median threshold 22.06ms
(gem_exec_fair:2630) igt_core-INFO: Stack trace:
(gem_exec_fair:2630) igt_core-INFO:   #0 ../lib/igt_core.c:2051 __igt_fail_assert()
(gem_exec_fair:2630) igt_core-INFO:   #1 ../tests/intel/gem_exec_fair.c:963 fairness()
(gem_exec_fair:2630) igt_core-INFO:   #2 ../tests/intel/gem_exec_fair.c:1423 __igt_unique____real_main1292()
(gem_exec_fair:2630) igt_core-INFO:   #3 ../tests/intel/gem_exec_fair.c:1292 main()
(gem_exec_fair:2630) igt_core-INFO:   #4 [__libc_init_first+0x8a]
(gem_exec_fair:2630) igt_core-INFO:   #5 [__libc_start_main+0x8b]
(gem_exec_fair:2630) igt_core-INFO:   #6 [_start+0x25]
****  END  ****
Dynamic subtest vecs0: FAIL (4.375s)
Subtest basic-pace: FAIL (18.652s)
Dmesg
<6> [502.846573] Console: switching to colour dummy device 80x25
<6> [502.846796] [IGT] gem_exec_fair: executing
<7> [502.849471] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [502.849915] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [502.850054] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [502.850444] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<6> [502.855367] [IGT] gem_exec_fair: starting subtest basic-pace
<6> [502.855580] [IGT] gem_exec_fair: starting dynamic subtest rcs0
<7> [502.856124] [drm:i915_gem_do_execbuffer [i915]] EINVAL at eb_validate_vma:509
<6> [508.369351] [IGT] gem_exec_fair: finished subtest rcs0, SUCCESS
<6> [508.369607] [IGT] gem_exec_fair: starting dynamic subtest bcs0
<7> [508.370033] [drm:i915_gem_do_execbuffer [i915]] EINVAL at eb_validate_vma:509
<6> [512.753766] [IGT] gem_exec_fair: finished subtest bcs0, FAIL
<6> [512.754025] [IGT] gem_exec_fair: starting dynamic subtest vcs0
<7> [512.754312] [drm:i915_gem_do_execbuffer [i915]] EINVAL at eb_validate_vma:509
<6> [517.131872] [IGT] gem_exec_fair: finished subtest vcs0, FAIL
<6> [517.132159] [IGT] gem_exec_fair: starting dynamic subtest vecs0
<7> [517.132500] [drm:i915_gem_do_execbuffer [i915]] EINVAL at eb_validate_vma:509
<6> [521.507495] [IGT] gem_exec_fair: finished subtest vecs0, FAIL
<6> [521.507832] [IGT] gem_exec_fair: finished subtest basic-pace, FAIL
<6> [521.616411] [IGT] gem_exec_fair: exiting, ret=98
<7> [521.616843] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [521.629796] i915 0000:00:02.0: [drm:drm_client_dev_restore] intel-fbdev: ret=0
<6> [521.630960] Console: switching to colour frame buffer device 240x67
Created at 2024-10-30 23:26:03