Result:
integration-manifest git-log-oneline i915_display_info23 igt_runner23 runtimes23 results23.json results23-i915-load.json guc_logs23.tar i915_display_info_post_exec23 boot23 dmesg23
| Detail | Value |
|---|---|
| Duration | 6.12 seconds |
| Hostname |
shard-dg1-16 |
| Igt-Version |
IGT-Version: 2.4-g636a3d128 (x86_64) (Linux: 7.1.0-rc4-CI_DRM_18532-g5f72c8b6f89e+ x86_64) |
| Out |
Using IGT_SRANDOM=1779434175 for randomisation
Opened device: /dev/dri/card0
Starting subtest: semaphore-busy
Starting dynamic subtest: rcs0
rcs0, target: {49.9% [50], 99.8% [100]}, measured: {50.1%, 99.9%}
rcs0, target: {25.0% [25], 50.0% [50]}, measured: {25.0%, 50.0%}
rcs0, target: {74.9% [75], 75.0% [75]}, measured: {74.9%, 75.0%}
Dynamic subtest rcs0: SUCCESS (1.650s)
Starting dynamic subtest: bcs0
bcs0, target: {50.0% [50], 100.0% [100]}, measured: {50.0%, 100.0%}
bcs0, target: {25.0% [25], 50.0% [50]}, measured: {25.0%, 50.0%}
bcs0, target: {75.0% [75], 75.0% [75]}, measured: {74.9%, 75.0%}
Dynamic subtest bcs0: SUCCESS (1.651s)
Starting dynamic subtest: vcs0
vcs0, target: {50.0% [50], 100.0% [100]}, measured: {50.0%, 49.1%}
Stack trace:
#0 ../lib/igt_core.c:2074 __igt_fail_assert()
#1 ../tests/intel/perf_pmu.c:964 __sema_busy()
#2 ../tests/intel/perf_pmu.c:988 __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 vcs0: FAIL (0.603s)
Starting dynamic subtest: vcs1
vcs1, target: {49.9% [50], 99.7% [100]}, measured: {49.8%, 48.4%}
Stack trace:
#0 ../lib/igt_core.c:2074 __igt_fail_assert()
#1 ../tests/intel/perf_pmu.c:964 __sema_busy()
#2 ../tests/intel/perf_pmu.c:988 __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 vcs1: FAIL (0.554s)
Starting dynamic subtest: vecs0
vecs0, target: {50.0% [50], 100.0% [100]}, measured: {50.0%, 100.0%}
vecs0, target: {25.0% [25], 50.0% [50]}, measured: {25.0%, 50.0%}
vecs0, target: {75.0% [75], 75.0% [75]}, measured: {74.9%, 75.0%}
Dynamic subtest vecs0: SUCCESS (1.643s)
Subtest semaphore-busy: FAIL (6.122s)
Opened device: /dev/dri/renderD128
|
| Err |
Starting subtest: semaphore-busy
Starting dynamic subtest: rcs0
Dynamic subtest rcs0: SUCCESS (1.650s)
Starting dynamic subtest: bcs0
Dynamic subtest bcs0: SUCCESS (1.651s)
Starting dynamic subtest: vcs0
[634.047148] (perf_pmu:2745) CRITICAL: Test assertion failure function __sema_busy, file ../tests/intel/perf_pmu.c:965:
[634.047235] (perf_pmu:2745) CRITICAL: Failed assertion: (double)(val[BUSY]) <= (1.0 + (tolerance)) * (double)(busy) && (double)(val[BUSY]) >= (1.0 - (tolerance)) * (double)(busy)
[634.047375] (perf_pmu:2745) CRITICAL: 'val[BUSY]' != 'busy' (245519271.000000 not within +5.0%/-5.0% tolerance of 500292000.000000)
Dynamic subtest vcs0 failed.
**** DEBUG ****
[633.506560] (perf_pmu:2745) intel_allocator_reloc-DEBUG: Using reloc allocator
[633.506569] (perf_pmu:2745) DEBUG: Test requirement passed: intel_gen(intel_get_drm_devid(gem_fd)) >= 8
[633.506934] (perf_pmu:2745) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
[633.507287] (perf_pmu:2745) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
[633.544918] (perf_pmu:2745) igt_dummyload-DEBUG: Test requirement passed: nengine
[633.546181] (perf_pmu:2745) DEBUG: bo_busy = 1, *map = 1, timeout: [0/3]
[634.047040] (perf_pmu:2745) INFO: vcs0, target: {50.0% [50], 100.0% [100]}, measured: {50.0%, 49.1%}
[634.047148] (perf_pmu:2745) CRITICAL: Test assertion failure function __sema_busy, file ../tests/intel/perf_pmu.c:965:
[634.047235] (perf_pmu:2745) CRITICAL: Failed assertion: (double)(val[BUSY]) <= (1.0 + (tolerance)) * (double)(busy) && (double)(val[BUSY]) >= (1.0 - (tolerance)) * (double)(busy)
[634.047375] (perf_pmu:2745) CRITICAL: 'val[BUSY]' != 'busy' (245519271.000000 not within +5.0%/-5.0% tolerance of 500292000.000000)
[634.060863] (perf_pmu:2745) igt_core-INFO: Stack trace:
[634.093508] (perf_pmu:2745) igt_core-INFO: #0 ../lib/igt_core.c:2074 __igt_fail_assert()
[634.097532] (perf_pmu:2745) igt_core-INFO: #1 ../tests/intel/perf_pmu.c:964 __sema_busy()
[634.097616] (perf_pmu:2745) igt_core-INFO: #2 ../tests/intel/perf_pmu.c:988 __igt_unique____real_main2286()
[634.097690] (perf_pmu:2745) igt_core-INFO: #3 ../tests/intel/perf_pmu.c:2286 main()
[634.105872] (perf_pmu:2745) igt_core-INFO: #4 [__libc_init_first+0x8a]
[634.107411] (perf_pmu:2745) igt_core-INFO: #5 [__libc_start_main+0x8b]
[634.107819] (perf_pmu:2745) igt_core-INFO: #6 [_start+0x25]
**** END ****
Dynamic subtest vcs0: FAIL (0.603s)
Starting dynamic subtest: vcs1
[634.655043] (perf_pmu:2745) CRITICAL: Test assertion failure function __sema_busy, file ../tests/intel/perf_pmu.c:965:
[634.655127] (perf_pmu:2745) CRITICAL: Failed assertion: (double)(val[BUSY]) <= (1.0 + (tolerance)) * (double)(busy) && (double)(val[BUSY]) >= (1.0 - (tolerance)) * (double)(busy)
[634.655219] (perf_pmu:2745) CRITICAL: 'val[BUSY]' != 'busy' (242671563.000000 not within +5.0%/-5.0% tolerance of 500303000.000000)
Dynamic subtest vcs1 failed.
**** DEBUG ****
[634.111648] (perf_pmu:2745) intel_allocator_reloc-DEBUG: Using reloc allocator
[634.111661] (perf_pmu:2745) DEBUG: Test requirement passed: intel_gen(intel_get_drm_devid(gem_fd)) >= 8
[634.112205] (perf_pmu:2745) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
[634.112809] (perf_pmu:2745) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
[634.151407] (perf_pmu:2745) igt_dummyload-DEBUG: Test requirement passed: nengine
[634.152857] (perf_pmu:2745) DEBUG: bo_busy = 1, *map = 1, timeout: [0/3]
[634.654936] (perf_pmu:2745) INFO: vcs1, target: {49.9% [50], 99.7% [100]}, measured: {49.8%, 48.4%}
[634.655043] (perf_pmu:2745) CRITICAL: Test assertion failure function __sema_busy, file ../tests/intel/perf_pmu.c:965:
[634.655127] (perf_pmu:2745) CRITICAL: Failed assertion: (double)(val[BUSY]) <= (1.0 + (tolerance)) * (double)(busy) && (double)(val[BUSY]) >= (1.0 - (tolerance)) * (double)(busy)
[634.655219] (perf_pmu:2745) CRITICAL: 'val[BUSY]' != 'busy' (242671563.000000 not within +5.0%/-5.0% tolerance of 500303000.000000)
[634.658998] (perf_pmu:2745) igt_core-INFO: Stack trace:
[634.660136] (perf_pmu:2745) igt_core-INFO: #0 ../lib/igt_core.c:2074 __igt_fail_assert()
[634.661061] (perf_pmu:2745) igt_core-INFO: #1 ../tests/intel/perf_pmu.c:964 __sema_busy()
[634.661086] (perf_pmu:2745) igt_core-INFO: #2 ../tests/intel/perf_pmu.c:988 __igt_unique____real_main2286()
[634.661104] (perf_pmu:2745) igt_core-INFO: #3 ../tests/intel/perf_pmu.c:2286 main()
[634.662817] (perf_pmu:2745) igt_core-INFO: #4 [__libc_init_first+0x8a]
[634.663812] (perf_pmu:2745) igt_core-INFO: #5 [__libc_start_main+0x8b]
[634.664091] (perf_pmu:2745) igt_core-INFO: #6 [_start+0x25]
**** END ****
Dynamic subtest vcs1: FAIL (0.554s)
Starting dynamic subtest: vecs0
Dynamic subtest vecs0: SUCCESS (1.643s)
Subtest semaphore-busy: FAIL (6.122s)
|
| Dmesg |
<6> [630.317932] Console: switching to colour dummy device 80x25
<6> [630.318634] [IGT] perf_pmu: executing
<7> [630.321555] i915 0000:03:00.0: [drm:i915_gem_open [i915]]
<7> [630.322664] i915 0000:03:00.0: [drm:i915_gem_open [i915]]
<6> [630.329799] [IGT] perf_pmu: starting subtest semaphore-busy
<6> [630.330634] [IGT] perf_pmu: starting dynamic subtest rcs0
<7> [630.330986] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [630.348792] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [630.878838] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [631.429233] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<6> [631.983933] [IGT] perf_pmu: finished subtest rcs0, SUCCESS
<6> [631.988069] [IGT] perf_pmu: starting dynamic subtest bcs0
<7> [631.988750] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [631.996036] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [632.537161] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [633.088004] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<6> [633.642534] [IGT] perf_pmu: finished subtest bcs0, SUCCESS
<6> [633.644064] [IGT] perf_pmu: starting dynamic subtest vcs0
<7> [633.644197] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [633.645544] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<6> [634.247994] [IGT] perf_pmu: finished subtest vcs0, FAIL
<6> [634.249094] [IGT] perf_pmu: starting dynamic subtest vcs1
<7> [634.249293] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [634.251122] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<6> [634.803414] [IGT] perf_pmu: finished subtest vcs1, FAIL
<6> [634.804186] [IGT] perf_pmu: starting dynamic subtest vecs0
<7> [634.804351] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [634.805821] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [635.347432] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [635.897619] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<6> [636.450785] [IGT] perf_pmu: finished subtest vecs0, SUCCESS
<6> [636.454894] [IGT] perf_pmu: finished subtest semaphore-busy, FAIL
<7> [636.456352] i915 0000:03:00.0: [drm:i915_gem_open [i915]]
<7> [636.461365] i915 0000:03:00.0: [drm:i915_gem_open [i915]]
<7> [636.472535] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [636.514938] i915 0000:03:00.0: [drm:drm_client_dev_restore] fbdev: ret=0
<6> [636.515752] [IGT] perf_pmu: exiting, ret=98
<6> [636.531730] Console: switching to colour frame buffer device 240x67
|