Results for igt@gem_exec_fair@basic-pace

Machine description: shard-rkl-7

Result: Fail

integration-manifest git-log-oneline i915_display_info15 igt_runner15 run15 runtimes15 results15.json i915_display_info_post_exec15 boot15 dmesg15

DetailValue
Duration 18.69 seconds
Hostname 
shard-rkl-7
Igt-Version 
IGT-Version: 1.29-NO-GIT (x86_64) (Linux: 6.12.0-rc5-CI_DRM_15616-gbaf0f5492ee6+ x86_64)
Out 
Using IGT_SRANDOM=1730383260 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.6, 17.7], iqr: [17.6, 17.7], median: 17.7 [1.9, 1.9], mean: 17.7 ± 0.01 ms, cpu: 12%
  7 clients, range: [14.5, 14.5], iqr: [14.5, 14.5], median: 14.5 [8.0, 9.9], mean: 14.5 ± 0.00 ms, cpu: 28%
 15 clients, range: [6.1, 31.7], iqr: [11.0, 31.2], median: 15.9 [5.9, 21.9], mean: 19.7 ± 9.88 ms, cpu: 59%
Stack trace:
  #0 ../lib/igt_core.c:2051 __igt_fail_assert()
  #1 ../tests/intel/gem_exec_fair.c:969 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 rcs0: FAIL (4.402s)
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 [1.9, 2.0], mean: 17.6 ± 0.01 ms, cpu: 13%
  7 clients, range: [14.5, 18.4], iqr: [14.5, 16.4], median: 14.5 [9.9, 10.0], mean: 15.5 ± 1.39 ms, cpu: 27%
 15 clients, range: [12.5, 26.6], iqr: [14.8, 26.2], median: 22.9 [7.4, 26.4], mean: 20.8 ± 5.43 ms, cpu: 58%
Dynamic subtest bcs0: SUCCESS (5.508s)
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: 12%
  7 clients, range: [13.5, 16.5], iqr: [14.5, 16.5], median: 15.4 [9.9, 10.0], mean: 15.2 ± 1.05 ms, cpu: 29%
 15 clients, range: [13.2, 25.6], iqr: [19.6, 24.4], median: 23.2 [13.8, 27.2], mean: 21.6 ± 3.91 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 vcs0: FAIL (4.386s)
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 [2.0, 2.0], mean: 17.6 ± 0.01 ms, cpu: 12%
  7 clients, range: [14.4, 16.4], iqr: [14.4, 14.6], median: 14.5 [10.0, 10.0], mean: 14.8 ± 0.69 ms, cpu: 28%
 15 clients, range: [12.7, 25.2], iqr: [18.2, 23.3], median: 23.0 [21.0, 26.0], mean: 20.6 ± 3.79 ms, cpu: 60%
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.391s)
Subtest basic-pace: FAIL (18.688s)
Err 
Starting subtest: basic-pace
Starting dynamic subtest: rcs0
(gem_exec_fair:1516) CRITICAL: Test assertion failure function fairness, file ../tests/intel/gem_exec_fair.c:974:
(gem_exec_fair:1516) CRITICAL: Failed assertion: result[hi] - result[lo] < result[nchild / 2]
(gem_exec_fair:1516) CRITICAL: Interquartile range of client intervals 20.17ms is as large as the median threshold 15.90ms, clients are not evenly distributed!
Dynamic subtest rcs0 failed.
**** DEBUG ****
(gem_exec_fair:1516) DEBUG: Test requirement passed: has_ctx_timestamp(i915, cfg, e)
(gem_exec_fair:1516) DEBUG: Test requirement passed: gem_class_has_mutable_submission(i915, e->class)
(gem_exec_fair:1516) DEBUG: CPU usage: 4%
(gem_exec_fair:1516) INFO: Interval 16.82ms, range 0.00ms
(gem_exec_fair:1516) DEBUG: CPU usage: 12%
(gem_exec_fair:1516) INFO:   3 clients, range: [17.6, 17.7], iqr: [17.6, 17.7], median: 17.7 [1.9, 1.9], mean: 17.7 ± 0.01 ms, cpu: 12%
(gem_exec_fair:1516) DEBUG: CPU usage: 28%
(gem_exec_fair:1516) INFO:   7 clients, range: [14.5, 14.5], iqr: [14.5, 14.5], median: 14.5 [8.0, 9.9], mean: 14.5 ± 0.00 ms, cpu: 28%
(gem_exec_fair:1516) DEBUG: CPU usage: 59%
(gem_exec_fair:1516) INFO:  15 clients, range: [6.1, 31.7], iqr: [11.0, 31.2], median: 15.9 [5.9, 21.9], mean: 19.7 ± 9.88 ms, cpu: 59%
(gem_exec_fair:1516) CRITICAL: Test assertion failure function fairness, file ../tests/intel/gem_exec_fair.c:974:
(gem_exec_fair:1516) CRITICAL: Failed assertion: result[hi] - result[lo] < result[nchild / 2]
(gem_exec_fair:1516) CRITICAL: Interquartile range of client intervals 20.17ms is as large as the median threshold 15.90ms, clients are not evenly distributed!
(gem_exec_fair:1516) igt_core-INFO: Stack trace:
(gem_exec_fair:1516) igt_core-INFO:   #0 ../lib/igt_core.c:2051 __igt_fail_assert()
(gem_exec_fair:1516) igt_core-INFO:   #1 ../tests/intel/gem_exec_fair.c:969 fairness()
(gem_exec_fair:1516) igt_core-INFO:   #2 ../tests/intel/gem_exec_fair.c:1423 __igt_unique____real_main1292()
(gem_exec_fair:1516) igt_core-INFO:   #3 ../tests/intel/gem_exec_fair.c:1292 main()
(gem_exec_fair:1516) igt_core-INFO:   #4 [__libc_init_first+0x8a]
(gem_exec_fair:1516) igt_core-INFO:   #5 [__libc_start_main+0x8b]
(gem_exec_fair:1516) igt_core-INFO:   #6 [_start+0x25]
****  END  ****
Dynamic subtest rcs0: FAIL (4.402s)
Starting dynamic subtest: bcs0
Dynamic subtest bcs0: SUCCESS (5.508s)
Starting dynamic subtest: vcs0
(gem_exec_fair:1516) CRITICAL: Test assertion failure function fairness, file ../tests/intel/gem_exec_fair.c:958:
(gem_exec_fair:1516) CRITICAL: Failed assertion: iqr[nchild / 2] < result[nchild / 2]
(gem_exec_fair:1516) CRITICAL: Child frame IQR 24.36ms exceeded median threshold 23.18ms
Dynamic subtest vcs0 failed.
**** DEBUG ****
(gem_exec_fair:1516) DEBUG: Test requirement passed: has_ctx_timestamp(i915, cfg, e)
(gem_exec_fair:1516) DEBUG: Test requirement passed: gem_class_has_mutable_submission(i915, e->class)
(gem_exec_fair:1516) DEBUG: CPU usage: 4%
(gem_exec_fair:1516) INFO: Interval 16.79ms, range 0.00ms
(gem_exec_fair:1516) DEBUG: CPU usage: 12%
(gem_exec_fair:1516) 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: 12%
(gem_exec_fair:1516) DEBUG: CPU usage: 29%
(gem_exec_fair:1516) INFO:   7 clients, range: [13.5, 16.5], iqr: [14.5, 16.5], median: 15.4 [9.9, 10.0], mean: 15.2 ± 1.05 ms, cpu: 29%
(gem_exec_fair:1516) DEBUG: CPU usage: 61%
(gem_exec_fair:1516) INFO:  15 clients, range: [13.2, 25.6], iqr: [19.6, 24.4], median: 23.2 [13.8, 27.2], mean: 21.6 ± 3.91 ms, cpu: 61%
(gem_exec_fair:1516) CRITICAL: Test assertion failure function fairness, file ../tests/intel/gem_exec_fair.c:958:
(gem_exec_fair:1516) CRITICAL: Failed assertion: iqr[nchild / 2] < result[nchild / 2]
(gem_exec_fair:1516) CRITICAL: Child frame IQR 24.36ms exceeded median threshold 23.18ms
(gem_exec_fair:1516) igt_core-INFO: Stack trace:
(gem_exec_fair:1516) igt_core-INFO:   #0 ../lib/igt_core.c:2051 __igt_fail_assert()
(gem_exec_fair:1516) igt_core-INFO:   #1 ../tests/intel/gem_exec_fair.c:963 fairness()
(gem_exec_fair:1516) igt_core-INFO:   #2 ../tests/intel/gem_exec_fair.c:1423 __igt_unique____real_main1292()
(gem_exec_fair:1516) igt_core-INFO:   #3 ../tests/intel/gem_exec_fair.c:1292 main()
(gem_exec_fair:1516) igt_core-INFO:   #4 [__libc_init_first+0x8a]
(gem_exec_fair:1516) igt_core-INFO:   #5 [__libc_start_main+0x8b]
(gem_exec_fair:1516) igt_core-INFO:   #6 [_start+0x25]
****  END  ****
Dynamic subtest vcs0: FAIL (4.386s)
Starting dynamic subtest: vecs0
(gem_exec_fair:1516) CRITICAL: Test assertion failure function fairness, file ../tests/intel/gem_exec_fair.c:958:
(gem_exec_fair:1516) CRITICAL: Failed assertion: iqr[nchild / 2] < result[nchild / 2]
(gem_exec_fair:1516) CRITICAL: Child frame IQR 24.85ms exceeded median threshold 23.01ms
Dynamic subtest vecs0 failed.
**** DEBUG ****
(gem_exec_fair:1516) DEBUG: Test requirement passed: has_ctx_timestamp(i915, cfg, e)
(gem_exec_fair:1516) DEBUG: Test requirement passed: gem_class_has_mutable_submission(i915, e->class)
(gem_exec_fair:1516) DEBUG: CPU usage: 4%
(gem_exec_fair:1516) INFO: Interval 16.79ms, range 0.00ms
(gem_exec_fair:1516) DEBUG: CPU usage: 12%
(gem_exec_fair:1516) INFO:   3 clients, range: [17.6, 17.6], iqr: [17.6, 17.6], median: 17.6 [2.0, 2.0], mean: 17.6 ± 0.01 ms, cpu: 12%
(gem_exec_fair:1516) DEBUG: CPU usage: 28%
(gem_exec_fair:1516) INFO:   7 clients, range: [14.4, 16.4], iqr: [14.4, 14.6], median: 14.5 [10.0, 10.0], mean: 14.8 ± 0.69 ms, cpu: 28%
(gem_exec_fair:1516) DEBUG: CPU usage: 60%
(gem_exec_fair:1516) INFO:  15 clients, range: [12.7, 25.2], iqr: [18.2, 23.3], median: 23.0 [21.0, 26.0], mean: 20.6 ± 3.79 ms, cpu: 60%
(gem_exec_fair:1516) CRITICAL: Test assertion failure function fairness, file ../tests/intel/gem_exec_fair.c:958:
(gem_exec_fair:1516) CRITICAL: Failed assertion: iqr[nchild / 2] < result[nchild / 2]
(gem_exec_fair:1516) CRITICAL: Child frame IQR 24.85ms exceeded median threshold 23.01ms
(gem_exec_fair:1516) igt_core-INFO: Stack trace:
(gem_exec_fair:1516) igt_core-INFO:   #0 ../lib/igt_core.c:2051 __igt_fail_assert()
(gem_exec_fair:1516) igt_core-INFO:   #1 ../tests/intel/gem_exec_fair.c:963 fairness()
(gem_exec_fair:1516) igt_core-INFO:   #2 ../tests/intel/gem_exec_fair.c:1423 __igt_unique____real_main1292()
(gem_exec_fair:1516) igt_core-INFO:   #3 ../tests/intel/gem_exec_fair.c:1292 main()
(gem_exec_fair:1516) igt_core-INFO:   #4 [__libc_init_first+0x8a]
(gem_exec_fair:1516) igt_core-INFO:   #5 [__libc_start_main+0x8b]
(gem_exec_fair:1516) igt_core-INFO:   #6 [_start+0x25]
****  END  ****
Dynamic subtest vecs0: FAIL (4.391s)
Subtest basic-pace: FAIL (18.688s)
Dmesg
<6> [222.494543] Console: switching to colour dummy device 80x25
<6> [222.494661] [IGT] gem_exec_fair: executing
<7> [222.496891] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [222.497306] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [222.497441] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [222.497831] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<6> [222.501862] [IGT] gem_exec_fair: starting subtest basic-pace
<6> [222.501982] [IGT] gem_exec_fair: starting dynamic subtest rcs0
<7> [222.502416] [drm:i915_gem_do_execbuffer [i915]] EINVAL at eb_validate_vma:509
<6> [226.904094] [IGT] gem_exec_fair: finished subtest rcs0, FAIL
<6> [226.904284] [IGT] gem_exec_fair: starting dynamic subtest bcs0
<7> [226.904614] [drm:i915_gem_do_execbuffer [i915]] EINVAL at eb_validate_vma:509
<6> [230.281905] pcieport 0000:00:1b.0: AER: Correctable error message received from 0000:01:00.0
<4> [230.281931] nvme 0000:01:00.0: PCIe Bus Error: severity=Correctable, type=Physical Layer, (Receiver ID)
<4> [230.281938] nvme 0000:01:00.0: device [15b7:5006] error status/mask=00000001/0000e000
<4> [230.281944] nvme 0000:01:00.0: [ 0] RxErr
<6> [232.412459] [IGT] gem_exec_fair: finished subtest bcs0, SUCCESS
<6> [232.412589] [IGT] gem_exec_fair: starting dynamic subtest vcs0
<7> [232.412844] [drm:i915_gem_do_execbuffer [i915]] EINVAL at eb_validate_vma:509
<6> [233.613829] pcieport 0000:00:1b.0: AER: Correctable error message received from 0000:01:00.0
<4> [233.613856] nvme 0000:01:00.0: PCIe Bus Error: severity=Correctable, type=Physical Layer, (Receiver ID)
<4> [233.613862] nvme 0000:01:00.0: device [15b7:5006] error status/mask=00000001/0000e000
<4> [233.613868] nvme 0000:01:00.0: [ 0] RxErr
<6> [234.698239] pcieport 0000:00:1b.0: AER: Correctable error message received from 0000:01:00.0
<4> [234.698264] nvme 0000:01:00.0: PCIe Bus Error: severity=Correctable, type=Physical Layer, (Receiver ID)
<4> [234.698271] nvme 0000:01:00.0: device [15b7:5006] error status/mask=00000001/0000e000
<4> [234.698277] nvme 0000:01:00.0: [ 0] RxErr
<6> [236.798648] [IGT] gem_exec_fair: finished subtest vcs0, FAIL
<6> [236.798794] [IGT] gem_exec_fair: starting dynamic subtest vecs0
<7> [236.799024] [drm:i915_gem_do_execbuffer [i915]] EINVAL at eb_validate_vma:509
<6> [237.997603] pcieport 0000:00:1b.0: AER: Correctable error message received from 0000:01:00.0
<4> [237.997629] nvme 0000:01:00.0: PCIe Bus Error: severity=Correctable, type=Physical Layer, (Receiver ID)
<4> [237.997636] nvme 0000:01:00.0: device [15b7:5006] error status/mask=00000001/0000e000
<4> [237.997642] nvme 0000:01:00.0: [ 0] RxErr
<6> [241.190154] [IGT] gem_exec_fair: finished subtest vecs0, FAIL
<6> [241.190401] [IGT] gem_exec_fair: finished subtest basic-pace, FAIL
<6> [241.298677] [IGT] gem_exec_fair: exiting, ret=98
<7> [241.299106] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [241.301154] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [241.301779] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [241.309503] i915 0000:00:02.0: [drm:drm_client_dev_restore] intel-fbdev: ret=0
<6> [241.328573] Console: switching to colour frame buffer device 240x67
Created at 2024-10-31 14:42:27