Result:
git-log-oneline i915_display_info12 igt_runner12 runtimes12 results12.json results12-i915-load.json guc_logs12.tar i915_display_info_post_exec12 boot12 dmesg12
| Detail | Value |
|---|---|
| Duration | 7.17 seconds |
| Hostname |
shard-mtlp-6 |
| Igt-Version |
IGT-Version: 2.4-g8a40014d2 (x86_64) (Linux: 7.1.0-rc5-CI_DRM_18558-g14d3e29c36be+ x86_64) |
| Out |
Using IGT_SRANDOM=1779859610 for randomisation Opened device: /dev/dri/card0 Starting subtest: reset gt0 gt1 Stack trace: #0 ../lib/igt_core.c:2106 __igt_fail_assert() #1 ../tests/intel/i915_pm_rps.c:225 do_writeval.isra.0() #2 ../tests/intel/i915_pm_rps.c:677 waitboost() #3 ../tests/intel/i915_pm_rps.c:1278 __igt_unique____real_main1204() #4 ../tests/intel/i915_pm_rps.c:1204 main() #5 [__libc_init_first+0x8a] #6 [__libc_start_main+0x8b] #7 [_start+0x25] Subtest reset: FAIL (7.170s) Stack trace: #0 ../lib/igt_core.c:2106 __igt_fail_assert() #1 [load_helper_stop.part.0+0x2c] #2 [pm_rps_exit_handler+0x105] #3 ../lib/igt_core.c:2992 fatal_sig_handler() #4 [__sigaction+0x50] #5 [pthread_kill+0x11c] #6 [gsignal+0x1e] #7 [abort+0xdf] #8 [<unknown>+0xece2881b] #9 [__assert_fail+0x47] #10 ../lib/igt_core.c:2417 igt_exit() #11 [main+0x32] #12 [__libc_init_first+0x8a] #13 [__libc_start_main+0x8b] #14 [_start+0x25] Stack trace: #0 ../lib/igt_core.c:479 internal_assert() #1 ../lib/igt_core.c:1974 igt_fail() #2 ../lib/igt_core.c:2316 __igt_fail_assert() #3 [load_helper_stop.part.0+0x2c] #4 [pm_rps_exit_handler+0x105] #5 ../lib/igt_core.c:2992 fatal_sig_handler() #6 [__sigaction+0x50] #7 [pthread_kill+0x11c] #8 [gsignal+0x1e] #9 [abort+0xdf] #10 [<unknown>+0xece2881b] #11 [__assert_fail+0x47] #12 ../lib/igt_core.c:2417 igt_exit() #13 [main+0x32] #14 [__libc_init_first+0x8a] #15 [__libc_start_main+0x8b] #16 [_start+0x25] |
| Err |
Starting subtest: reset [371.437647] (i915_pm_rps:2601) CRITICAL: Test assertion failure function do_writeval, file ../tests/intel/i915_pm_rps.c:225: [371.437709] (i915_pm_rps:2601) CRITICAL: Failed assertion: 0 < ret [371.437876] (i915_pm_rps:2601) CRITICAL: Last errno: 22, Invalid argument [371.437912] (i915_pm_rps:2601) CRITICAL: error: 0 >= -1 Subtest reset failed. **** DEBUG **** [365.291157] (i915_pm_rps:2601) i915/gem_context-DEBUG: Test requirement passed: has_ban_period || has_bannable [365.292276] (i915_pm_rps:2601) igt_gt-DEBUG: Test requirement passed: igt_params_set(fd, "reset", "%d", allow_reset) [365.292291] (i915_pm_rps:2601) igt_gt-DEBUG: Test requirement passed: has_gpu_reset(fd) [365.292557] (i915_pm_rps:2601) INFO: gt0 [365.294378] (i915_pm_rps:2601) DEBUG: Test requirement passed: origfreqs[gt_id][RP0] > origfreqs[gt_id][RPn] [365.294388] (i915_pm_rps:2601) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) [365.297427] (i915_pm_rps:2601) i915/gem-DEBUG: Test requirement passed: dir >= 0 [365.301032] (i915_pm_rps:2601) i915/gem-DEBUG: Test requirement passed: err == 0 [365.314295] (i915_pm_rps:2601) DEBUG: Apply low load... [366.314996] (i915_pm_rps:2601) DEBUG: gt0 freq (MHz): act=800 cur=800 min=800 max=2250 RP0=2250 RP1=800 RPn=800 boost=2250 [366.565175] (i915_pm_rps:2601) DEBUG: gt0 freq (MHz): act=800 cur=800 min=800 max=2250 RP0=2250 RP1=800 RPn=800 boost=2250 [366.565366] (i915_pm_rps:2601) igt_debugfs-DEBUG: i915_rps_boost_info: RPS enabled? no RPS active? no GPU busy? yes Boosts outstanding? 0 Interactive? 68 Frequency requested 0, actual 800 min hard:0, soft:0; max soft:0, hard:0 idle:0, efficient:0, boost:0 Wait boosts: 0 [366.565369] (i915_pm_rps:2601) DEBUG: Waited 0 msec to stabilize cur [366.565370] (i915_pm_rps:2601) DEBUG: Reset gpu... [366.565371] (i915_pm_rps:2601) igt_gt-DEBUG: Triggering GPU reset [367.732036] (i915_pm_rps:2601) intel_ctx-DEBUG: Test requirement passed: gem_has_contexts(fd) || !gt [367.732121] (i915_pm_rps:2601) i915/gem_engine_topology-DEBUG: Test requirement passed: intel_graphics_ver(devid) <= IP_VER(12, 70) [367.732128] (i915_pm_rps:2601) i915/gem_engine_topology-DEBUG: Test requirement passed: intel_graphics_ver(devid) <= IP_VER(12, 70) [367.732133] (i915_pm_rps:2601) i915/gem_engine_topology-DEBUG: Test requirement passed: intel_graphics_ver(devid) <= IP_VER(12, 70) [367.732138] (i915_pm_rps:2601) i915/gem_engine_topology-DEBUG: Test requirement passed: intel_graphics_ver(devid) <= IP_VER(12, 70) [367.732143] (i915_pm_rps:2601) i915/gem_engine_topology-DEBUG: Test requirement passed: intel_graphics_ver(devid) <= IP_VER(12, 70) [367.732148] (i915_pm_rps:2601) i915/gem_engine_topology-DEBUG: Test requirement passed: intel_graphics_ver(devid) <= IP_VER(12, 70) [367.735941] (i915_pm_rps:2601) intel_allocator_reloc-DEBUG: Using reloc allocator [367.735953] (i915_pm_rps:2601) igt_dummyload-DEBUG: Test requirement passed: nengine [367.738321] (i915_pm_rps:2601) igt_dummyload-DEBUG: Test requirement passed: nengine [367.740711] (i915_pm_rps:2601) DEBUG: gt0 freq (MHz): act=1650 cur=2250 min=800 max=1525 RP0=2250 RP1=800 RPn=800 boost=2250 [367.741903] (i915_pm_rps:2601) DEBUG: Apply low load again... [368.742295] (i915_pm_rps:2601) DEBUG: gt0 freq (MHz): act=800 cur=800 min=800 max=2250 RP0=2250 RP1=800 RPn=800 boost=2250 [368.992839] (i915_pm_rps:2601) DEBUG: gt0 freq (MHz): act=800 cur=800 min=800 max=2250 RP0=2250 RP1=800 RPn=800 boost=2250 [368.993352] (i915_pm_rps:2601) igt_debugfs-DEBUG: i915_rps_boost_info: RPS enabled? no RPS active? no GPU busy? yes Boosts outstanding? 0 Interactive? 68 Frequency requested 0, actual 800 min hard:0, soft:0; max soft:0, hard:0 idle:0, efficient:0, boost:0 Wait boosts: 0 [368.993354] (i915_pm_rps:2601) DEBUG: Waited 0 msec to stabilize cur [368.993354] (i915_pm_rps:2601) DEBUG: Removing load... [369.004009] (i915_pm_rps:2601) DEBUG: gt0 freq (MHz): act=0 cur=800 min=800 max=2250 RP0=2250 RP1=800 RPn=800 boost=2250 [369.004088] (i915_pm_rps:2601) igt_debugfs-DEBUG: i915_rps_boost_info: RPS enabled? no RPS active? no GPU busy? no Boosts outstanding? 0 Interactive? 68 Frequency requested 0, actual 150 min hard:0, soft:0; max soft:0, hard:0 idle:0, efficient:0, boost:0 Wait boosts: 0 [369.004089] (i915_pm_rps:2601) DEBUG: Required 0 msec to reach cur=idle [369.004152] (i915_pm_rps:2601) INFO: gt1 [369.004582] (i915_pm_rps:2601) DEBUG: Test requirement passed: origfreqs[gt_id][RP0] > origfreqs[gt_id][RPn] [369.004586] (i915_pm_rps:2601) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) [369.005438] (i915_pm_rps:2601) i915/gem-DEBUG: Test requirement passed: dir >= 0 [369.008951] (i915_pm_rps:2601) i915/gem-DEBUG: Test requirement passed: err == 0 [369.017333] (i915_pm_rps:2601) DEBUG: Apply low load... [370.018107] (i915_pm_rps:2601) DEBUG: gt1 freq (MHz): act=800 cur=800 min=800 max=1300 RP0=1300 RP1=100 RPn=100 boost=1300 [370.268507] (i915_pm_rps:2601) DEBUG: gt1 freq (MHz): act=450 cur=800 min=800 max=1300 RP0=1300 RP1=100 RPn=100 boost=1300 [370.268560] (i915_pm_rps:2601) DEBUG: gt1 freq (MHz): act=100 cur=800 min=800 max=1300 RP0=1300 RP1=100 RPn=100 boost=1300 [370.268602] (i915_pm_rps:2601) DEBUG: gt1 freq (MHz): act=100 cur=800 min=800 max=1300 RP0=1300 RP1=100 RPn=100 boost=1300 [370.268738] (i915_pm_rps:2601) igt_debugfs-DEBUG: i915_rps_boost_info: RPS enabled? no RPS active? no GPU busy? yes Boosts outstanding? 0 Interactive? 68 Frequency requested 0, actual 300 min hard:0, soft:0; max soft:0, hard:0 idle:0, efficient:0, boost:0 Wait boosts: 0 [370.268741] (i915_pm_rps:2601) DEBUG: Waited 500 msec to stabilize cur [370.268742] (i915_pm_rps:2601) DEBUG: Reset gpu... [370.268743] (i915_pm_rps:2601) igt_gt-DEBUG: Triggering GPU reset [371.437632] (i915_pm_rps:2601) DEBUG: gt1 freq (MHz): act=0 cur=800 min=800 max=1300 RP0=1300 RP1=100 RPn=100 boost=1300 [371.437647] (i915_pm_rps:2601) CRITICAL: Test assertion failure function do_writeval, file ../tests/intel/i915_pm_rps.c:225: [371.437709] (i915_pm_rps:2601) CRITICAL: Failed assertion: 0 < ret [371.437876] (i915_pm_rps:2601) CRITICAL: Last errno: 22, Invalid argument [371.437912] (i915_pm_rps:2601) CRITICAL: error: 0 >= -1 [371.443770] (i915_pm_rps:2601) igt_core-INFO: Stack trace: [371.448764] (i915_pm_rps:2601) igt_core-INFO: #0 ../lib/igt_core.c:2106 __igt_fail_assert() [371.449196] (i915_pm_rps:2601) igt_core-INFO: #1 ../tests/intel/i915_pm_rps.c:225 do_writeval.isra.0() [371.449233] (i915_pm_rps:2601) igt_core-INFO: #2 ../tests/intel/i915_pm_rps.c:677 waitboost() [371.449245] (i915_pm_rps:2601) igt_core-INFO: #3 ../tests/intel/i915_pm_rps.c:1278 __igt_unique____real_main1204() [371.449261] (i915_pm_rps:2601) igt_core-INFO: #4 ../tests/intel/i915_pm_rps.c:1204 main() [371.457408] (i915_pm_rps:2601) igt_core-INFO: #5 [__libc_init_first+0x8a] [371.457864] (i915_pm_rps:2601) igt_core-INFO: #6 [__libc_start_main+0x8b] [371.457986] (i915_pm_rps:2601) igt_core-INFO: #7 [_start+0x25] **** END **** Subtest reset: FAIL (7.170s) Received signal SIGABRT. Stack trace: #0 [fatal_sig_handler+0x17b] #1 [__sigaction+0x50] #2 [pthread_kill+0x11c] #3 [gsignal+0x1e] #4 [abort+0xdf] #5 [<unknown>+0xdf] #6 [__assert_fail+0x47] #7 [igt_exit+0x159] #8 [main+0x32] #9 [__libc_init_first+0x8a] #10 [__libc_start_main+0x8b] #11 [_start+0x25] [371.940458] (i915_pm_rps:2601) CRITICAL: Test assertion failure function load_helper_stop, file ../tests/intel/i915_pm_rps.c:415: [371.940496] (i915_pm_rps:2601) CRITICAL: Failed assertion: igt_wait_helper(&lh.igt_proc) == 0 [371.940513] (i915_pm_rps:2601) CRITICAL: Last errno: 10, No child processes Test i915_pm_rps failed. **** DEBUG **** [371.460318] (i915_pm_rps:2601) igt_core-DEBUG: Exiting with status code 98 [371.940446] (i915_pm_rps:2601) igt_sysfs-DEBUG: Condition fstat(device, &st) occurred in function igt_sysfs_gt_path, file ../lib/igt_sysfs.c:371 [371.940458] (i915_pm_rps:2601) CRITICAL: Test assertion failure function load_helper_stop, file ../tests/intel/i915_pm_rps.c:415: [371.940496] (i915_pm_rps:2601) CRITICAL: Failed assertion: igt_wait_helper(&lh.igt_proc) == 0 [371.940513] (i915_pm_rps:2601) CRITICAL: Last errno: 10, No child processes [371.941062] (i915_pm_rps:2601) igt_core-INFO: Stack trace: [371.941703] (i915_pm_rps:2601) igt_core-INFO: #0 ../lib/igt_core.c:2106 __igt_fail_assert() [371.942059] (i915_pm_rps:2601) igt_core-INFO: #1 [load_helper_stop.part.0+0x2c] [371.942255] (i915_pm_rps:2601) igt_core-INFO: #2 [pm_rps_exit_handler+0x105] [371.942357] (i915_pm_rps:2601) igt_core-INFO: #3 ../lib/igt_core.c:2992 fatal_sig_handler() [371.943023] (i915_pm_rps:2601) igt_core-INFO: #4 [__sigaction+0x50] [371.943467] (i915_pm_rps:2601) igt_core-INFO: #5 [pthread_kill+0x11c] [371.943892] (i915_pm_rps:2601) igt_core-INFO: #6 [gsignal+0x1e] [371.944311] (i915_pm_rps:2601) igt_core-INFO: #7 [abort+0xdf] [371.944811] (i915_pm_rps:2601) igt_core-INFO: #8 [<unknown>+0xece2881b] [371.945232] (i915_pm_rps:2601) igt_core-INFO: #9 [__assert_fail+0x47] [371.945281] (i915_pm_rps:2601) igt_core-INFO: #10 ../lib/igt_core.c:2417 igt_exit() [371.945422] (i915_pm_rps:2601) igt_core-INFO: #11 [main+0x32] [371.945844] (i915_pm_rps:2601) igt_core-INFO: #12 [__libc_init_first+0x8a] [371.946263] (i915_pm_rps:2601) igt_core-INFO: #13 [__libc_start_main+0x8b] [371.946384] (i915_pm_rps:2601) igt_core-INFO: #14 [_start+0x25] **** END **** |
| Dmesg |
<6> [365.957892] Console: switching to colour dummy device 80x25
<6> [365.958259] [IGT] i915_pm_rps: executing
<7> [365.965037] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [365.966194] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [365.988114] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [365.990152] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<6> [365.998027] [IGT] i915_pm_rps: starting subtest reset
<7> [367.004890] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [367.015617] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<6> [368.342647] i915 0000:00:02.0: [drm] GPU HANG: ecode 12:0:00000000
<6> [368.342653] i915 0000:00:02.0: [drm] GPU error state saved to /sys/class/drm/card0/error
<5> [368.343720] i915 0000:00:02.0: [drm] GT0: Resetting chip for Manually reset engine mask to ffffffffffffffff
<6> [368.344505] i915 0000:00:02.0: [drm] GT0: GuC firmware i915/mtl_guc_70.bin version 70.53.0
<7> [368.356764] i915 0000:00:02.0: [drm:intel_guc_fw_upload [i915]] GT0: GUC: init took 10ms, freq = 1550MHz -> 1650MHz vs 2250MHz, status = 0x8002F034, count = 0, ret = 0
<7> [368.357649] i915 0000:00:02.0: [drm:guc_enable_communication [i915]] GT0: GUC: communication enabled
<6> [368.359290] i915 0000:00:02.0: [drm] GT0: GUC: submission enabled
<6> [368.359292] i915 0000:00:02.0: [drm] GT0: GUC: SLPC enabled
<7> [368.384803] 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> [368.385051] 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> [368.385176] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [368.385346] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [368.385484] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<6> [368.420047] i915 0000:00:02.0: [drm] GPU HANG: ecode 12:0:00000000
<5> [368.420453] i915 0000:00:02.0: [drm] GT1: Resetting chip for Manually reset engine mask to ffffffffffffffff
<6> [368.420820] i915 0000:00:02.0: [drm] GT1: GuC firmware i915/mtl_guc_70.bin version 70.53.0
<6> [368.420823] i915 0000:00:02.0: [drm] GT1: HuC firmware i915/mtl_huc_gsc.bin version 8.5.4
<7> [368.434821] i915 0000:00:02.0: [drm:intel_guc_fw_upload [i915]] GT1: GUC: init took 13ms, freq = 850MHz -> 1300MHz vs 1300MHz, status = 0x8002F034, count = 0, ret = 0
<7> [368.435678] i915 0000:00:02.0: [drm:guc_enable_communication [i915]] GT1: GUC: communication enabled
<6> [368.438571] i915 0000:00:02.0: [drm] GT1: GUC: submission enabled
<6> [368.438574] i915 0000:00:02.0: [drm] GT1: GUC: SLPC enabled
<7> [369.441546] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [370.705077] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x00000004 [0x00000004]
<7> [370.713841] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [370.721908] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<6> [372.039965] i915 0000:00:02.0: [drm] GPU HANG: ecode 12:0:00000000
<5> [372.041393] i915 0000:00:02.0: [drm] GT0: Resetting chip for Manually reset engine mask to ffffffffffffffff
<6> [372.041943] i915 0000:00:02.0: [drm] GT0: GuC firmware i915/mtl_guc_70.bin version 70.53.0
<7> [372.055240] i915 0000:00:02.0: [drm:intel_guc_fw_upload [i915]] GT0: GUC: init took 10ms, freq = 1700MHz -> 2000MHz vs 2250MHz, status = 0x8002F034, count = 0, ret = 0
<7> [372.056158] i915 0000:00:02.0: [drm:guc_enable_communication [i915]] GT0: GUC: communication enabled
<6> [372.058764] i915 0000:00:02.0: [drm] GT0: GUC: submission enabled
<6> [372.058767] i915 0000:00:02.0: [drm] GT0: GUC: SLPC enabled
<6> [372.128183] i915 0000:00:02.0: [drm] GPU HANG: ecode 12:0:00000000
<5> [372.128767] i915 0000:00:02.0: [drm] GT1: Resetting chip for Manually reset engine mask to ffffffffffffffff
<6> [372.130363] i915 0000:00:02.0: [drm] GT1: GuC firmware i915/mtl_guc_70.bin version 70.53.0
<6> [372.130374] i915 0000:00:02.0: [drm] GT1: HuC firmware i915/mtl_huc_gsc.bin version 8.5.4
<7> [372.144716] i915 0000:00:02.0: [drm:intel_guc_fw_upload [i915]] GT1: GUC: init took 13ms, freq = 750MHz -> 1300MHz vs 1300MHz, status = 0x8002F034, count = 0, ret = 0
<7> [372.145027] i915 0000:00:02.0: [drm:guc_enable_communication [i915]] GT1: GUC: communication enabled
<6> [372.146081] i915 0000:00:02.0: [drm] GT1: GUC: submission enabled
<6> [372.146083] i915 0000:00:02.0: [drm] GT1: GUC: SLPC enabled
<6> [373.168892] [IGT] i915_pm_rps: finished subtest reset, FAIL
<6> [373.169481] [IGT] i915_pm_rps: exiting, ret=98
<7> [373.645615] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x00000004 [0x00000004]
<7> [374.138895] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [374.139741] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [374.151344] i915 0000:00:02.0: [drm:drm_client_dev_restore] fbdev: ret=0
<7> [374.168554] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [374.169097] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [374.169695] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
|