Results for igt@gem_exec_fair@basic-pace

Machine description: shard-rkl-1

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.65 seconds
Hostname 
shard-rkl-1
Igt-Version 
IGT-Version: 1.29-NO-GIT (x86_64) (Linux: 6.12.0-rc6-CI_DRM_15648-g5ce87c5ad2cb+ x86_64)
Out 
Using IGT_SRANDOM=1730942966 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 [0.1, 2.0], mean: 17.7 ± 0.01 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.01 ms, cpu: 27%
 15 clients, range: [6.6, 31.7], iqr: [11.7, 30.1], median: 19.8 [10.6, 22.4], mean: 19.8 ± 8.78 ms, cpu: 56%
Dynamic subtest rcs0: SUCCESS (5.520s)
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.1, 2.0], mean: 17.6 ± 0.01 ms, cpu: 12%
  7 clients, range: [14.4, 16.5], iqr: [14.4, 15.4], median: 14.5 [9.9, 10.0], mean: 14.9 ± 0.73 ms, cpu: 28%
 15 clients, range: [6.0, 25.7], iqr: [14.0, 25.4], median: 24.2 [4.7, 26.4], mean: 20.3 ± 6.17 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.372s)
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 [0.1, 2.0], mean: 17.6 ± 0.01 ms, cpu: 13%
  7 clients, range: [14.5, 18.5], iqr: [14.5, 18.4], median: 16.6 [9.9, 10.0], mean: 16.7 ± 1.65 ms, cpu: 29%
 15 clients, range: [12.0, 25.7], iqr: [19.0, 25.2], median: 23.0 [14.6, 26.3], mean: 21.4 ± 4.34 ms, cpu: 59%
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.385s)
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.5, 18.4], median: 14.5 [9.9, 10.0], mean: 15.9 ± 1.78 ms, cpu: 28%
 15 clients, range: [13.8, 27.2], iqr: [17.9, 25.6], median: 23.4 [9.8, 27.8], mean: 21.3 ± 4.60 ms, cpu: 57%
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.654s)
Err 
Starting subtest: basic-pace
Starting dynamic subtest: rcs0
Dynamic subtest rcs0: SUCCESS (5.520s)
Starting dynamic subtest: bcs0
(gem_exec_fair:2213) CRITICAL: Test assertion failure function fairness, file ../tests/intel/gem_exec_fair.c:958:
(gem_exec_fair:2213) CRITICAL: Failed assertion: iqr[nchild / 2] < result[nchild / 2]
(gem_exec_fair:2213) CRITICAL: Child frame IQR 26.02ms exceeded median threshold 24.19ms
Dynamic subtest bcs0 failed.
**** DEBUG ****
(gem_exec_fair:2213) DEBUG: Test requirement passed: has_ctx_timestamp(i915, cfg, e)
(gem_exec_fair:2213) DEBUG: Test requirement passed: gem_class_has_mutable_submission(i915, e->class)
(gem_exec_fair:2213) DEBUG: CPU usage: 4%
(gem_exec_fair:2213) INFO: Interval 16.79ms, range 0.00ms
(gem_exec_fair:2213) DEBUG: CPU usage: 12%
(gem_exec_fair:2213) INFO:   3 clients, range: [17.6, 17.6], iqr: [17.6, 17.6], median: 17.6 [0.1, 2.0], mean: 17.6 ± 0.01 ms, cpu: 12%
(gem_exec_fair:2213) DEBUG: CPU usage: 28%
(gem_exec_fair:2213) INFO:   7 clients, range: [14.4, 16.5], iqr: [14.4, 15.4], median: 14.5 [9.9, 10.0], mean: 14.9 ± 0.73 ms, cpu: 28%
(gem_exec_fair:2213) DEBUG: CPU usage: 61%
(gem_exec_fair:2213) INFO:  15 clients, range: [6.0, 25.7], iqr: [14.0, 25.4], median: 24.2 [4.7, 26.4], mean: 20.3 ± 6.17 ms, cpu: 61%
(gem_exec_fair:2213) CRITICAL: Test assertion failure function fairness, file ../tests/intel/gem_exec_fair.c:958:
(gem_exec_fair:2213) CRITICAL: Failed assertion: iqr[nchild / 2] < result[nchild / 2]
(gem_exec_fair:2213) CRITICAL: Child frame IQR 26.02ms exceeded median threshold 24.19ms
(gem_exec_fair:2213) igt_core-INFO: Stack trace:
(gem_exec_fair:2213) igt_core-INFO:   #0 ../lib/igt_core.c:2051 __igt_fail_assert()
(gem_exec_fair:2213) igt_core-INFO:   #1 ../tests/intel/gem_exec_fair.c:963 fairness()
(gem_exec_fair:2213) igt_core-INFO:   #2 ../tests/intel/gem_exec_fair.c:1423 __igt_unique____real_main1292()
(gem_exec_fair:2213) igt_core-INFO:   #3 ../tests/intel/gem_exec_fair.c:1292 main()
(gem_exec_fair:2213) igt_core-INFO:   #4 [__libc_init_first+0x8a]
(gem_exec_fair:2213) igt_core-INFO:   #5 [__libc_start_main+0x8b]
(gem_exec_fair:2213) igt_core-INFO:   #6 [_start+0x25]
****  END  ****
Dynamic subtest bcs0: FAIL (4.372s)
Starting dynamic subtest: vcs0
(gem_exec_fair:2213) CRITICAL: Test assertion failure function fairness, file ../tests/intel/gem_exec_fair.c:958:
(gem_exec_fair:2213) CRITICAL: Failed assertion: iqr[nchild / 2] < result[nchild / 2]
(gem_exec_fair:2213) CRITICAL: Child frame IQR 24.88ms exceeded median threshold 23.01ms
Dynamic subtest vcs0 failed.
**** DEBUG ****
(gem_exec_fair:2213) DEBUG: Test requirement passed: has_ctx_timestamp(i915, cfg, e)
(gem_exec_fair:2213) DEBUG: Test requirement passed: gem_class_has_mutable_submission(i915, e->class)
(gem_exec_fair:2213) DEBUG: CPU usage: 4%
(gem_exec_fair:2213) INFO: Interval 16.79ms, range 0.00ms
(gem_exec_fair:2213) DEBUG: CPU usage: 13%
(gem_exec_fair:2213) INFO:   3 clients, range: [17.6, 17.6], iqr: [17.6, 17.6], median: 17.6 [0.1, 2.0], mean: 17.6 ± 0.01 ms, cpu: 13%
(gem_exec_fair:2213) DEBUG: CPU usage: 29%
(gem_exec_fair:2213) INFO:   7 clients, range: [14.5, 18.5], iqr: [14.5, 18.4], median: 16.6 [9.9, 10.0], mean: 16.7 ± 1.65 ms, cpu: 29%
(gem_exec_fair:2213) DEBUG: CPU usage: 59%
(gem_exec_fair:2213) INFO:  15 clients, range: [12.0, 25.7], iqr: [19.0, 25.2], median: 23.0 [14.6, 26.3], mean: 21.4 ± 4.34 ms, cpu: 59%
(gem_exec_fair:2213) CRITICAL: Test assertion failure function fairness, file ../tests/intel/gem_exec_fair.c:958:
(gem_exec_fair:2213) CRITICAL: Failed assertion: iqr[nchild / 2] < result[nchild / 2]
(gem_exec_fair:2213) CRITICAL: Child frame IQR 24.88ms exceeded median threshold 23.01ms
(gem_exec_fair:2213) igt_core-INFO: Stack trace:
(gem_exec_fair:2213) igt_core-INFO:   #0 ../lib/igt_core.c:2051 __igt_fail_assert()
(gem_exec_fair:2213) igt_core-INFO:   #1 ../tests/intel/gem_exec_fair.c:963 fairness()
(gem_exec_fair:2213) igt_core-INFO:   #2 ../tests/intel/gem_exec_fair.c:1423 __igt_unique____real_main1292()
(gem_exec_fair:2213) igt_core-INFO:   #3 ../tests/intel/gem_exec_fair.c:1292 main()
(gem_exec_fair:2213) igt_core-INFO:   #4 [__libc_init_first+0x8a]
(gem_exec_fair:2213) igt_core-INFO:   #5 [__libc_start_main+0x8b]
(gem_exec_fair:2213) igt_core-INFO:   #6 [_start+0x25]
****  END  ****
Dynamic subtest vcs0: FAIL (4.385s)
Starting dynamic subtest: vecs0
(gem_exec_fair:2213) CRITICAL: Test assertion failure function fairness, file ../tests/intel/gem_exec_fair.c:958:
(gem_exec_fair:2213) CRITICAL: Failed assertion: iqr[nchild / 2] < result[nchild / 2]
(gem_exec_fair:2213) CRITICAL: Child frame IQR 24.37ms exceeded median threshold 23.36ms
Dynamic subtest vecs0 failed.
**** DEBUG ****
(gem_exec_fair:2213) DEBUG: Test requirement passed: has_ctx_timestamp(i915, cfg, e)
(gem_exec_fair:2213) DEBUG: Test requirement passed: gem_class_has_mutable_submission(i915, e->class)
(gem_exec_fair:2213) DEBUG: CPU usage: 4%
(gem_exec_fair:2213) INFO: Interval 16.79ms, range 0.00ms
(gem_exec_fair:2213) DEBUG: CPU usage: 12%
(gem_exec_fair:2213) 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:2213) DEBUG: CPU usage: 28%
(gem_exec_fair:2213) INFO:   7 clients, range: [14.4, 18.6], iqr: [14.5, 18.4], median: 14.5 [9.9, 10.0], mean: 15.9 ± 1.78 ms, cpu: 28%
(gem_exec_fair:2213) DEBUG: CPU usage: 57%
(gem_exec_fair:2213) INFO:  15 clients, range: [13.8, 27.2], iqr: [17.9, 25.6], median: 23.4 [9.8, 27.8], mean: 21.3 ± 4.60 ms, cpu: 57%
(gem_exec_fair:2213) CRITICAL: Test assertion failure function fairness, file ../tests/intel/gem_exec_fair.c:958:
(gem_exec_fair:2213) CRITICAL: Failed assertion: iqr[nchild / 2] < result[nchild / 2]
(gem_exec_fair:2213) CRITICAL: Child frame IQR 24.37ms exceeded median threshold 23.36ms
(gem_exec_fair:2213) igt_core-INFO: Stack trace:
(gem_exec_fair:2213) igt_core-INFO:   #0 ../lib/igt_core.c:2051 __igt_fail_assert()
(gem_exec_fair:2213) igt_core-INFO:   #1 ../tests/intel/gem_exec_fair.c:963 fairness()
(gem_exec_fair:2213) igt_core-INFO:   #2 ../tests/intel/gem_exec_fair.c:1423 __igt_unique____real_main1292()
(gem_exec_fair:2213) igt_core-INFO:   #3 ../tests/intel/gem_exec_fair.c:1292 main()
(gem_exec_fair:2213) igt_core-INFO:   #4 [__libc_init_first+0x8a]
(gem_exec_fair:2213) igt_core-INFO:   #5 [__libc_start_main+0x8b]
(gem_exec_fair:2213) igt_core-INFO:   #6 [_start+0x25]
****  END  ****
Dynamic subtest vecs0: FAIL (4.375s)
Subtest basic-pace: FAIL (18.654s)
Dmesg
<6> [279.102152] Console: switching to colour dummy device 80x25
<6> [279.102380] [IGT] gem_exec_fair: executing
<7> [279.104893] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [279.105299] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [279.105449] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [279.105827] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<6> [279.109799] [IGT] gem_exec_fair: starting subtest basic-pace
<6> [279.110014] [IGT] gem_exec_fair: starting dynamic subtest rcs0
<7> [279.110266] [drm:i915_gem_do_execbuffer [i915]] EINVAL at eb_validate_vma:509
<6> [284.630637] [IGT] gem_exec_fair: finished subtest rcs0, SUCCESS
<6> [284.630882] [IGT] gem_exec_fair: starting dynamic subtest bcs0
<7> [284.631121] [drm:i915_gem_do_execbuffer [i915]] EINVAL at eb_validate_vma:509
<6> [289.002880] [IGT] gem_exec_fair: finished subtest bcs0, FAIL
<6> [289.003365] [IGT] gem_exec_fair: starting dynamic subtest vcs0
<7> [289.003601] [drm:i915_gem_do_execbuffer [i915]] EINVAL at eb_validate_vma:509
<6> [293.388228] [IGT] gem_exec_fair: finished subtest vcs0, FAIL
<6> [293.388517] [IGT] gem_exec_fair: starting dynamic subtest vecs0
<7> [293.388830] [drm:i915_gem_do_execbuffer [i915]] EINVAL at eb_validate_vma:509
<6> [297.763442] [IGT] gem_exec_fair: finished subtest vecs0, FAIL
<6> [297.763729] [IGT] gem_exec_fair: finished subtest basic-pace, FAIL
<6> [297.872361] [IGT] gem_exec_fair: exiting, ret=98
<7> [297.872793] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [297.882578] i915 0000:00:02.0: [drm:drm_client_dev_restore] intel-fbdev: ret=0
<6> [297.901841] Console: switching to colour frame buffer device 240x67
Created at 2024-11-07 02:09:05