Results for igt@xe_pm_residency@gt-c6-freeze@gt1

Machine description: shard-bmg-5

Result: Fail

i915_display_info18 igt_runner18 results18.json results18-xe-load.json i915_display_info_post_exec18 boot18 dmesg18

DetailValue
Duration 20.16 seconds
Hostname 
shard-bmg-5
Igt-Version 
IGT-Version: 1.30-g911258161 (x86_64) (Linux: 6.13.0-rc3-xe+ x86_64)
Out 
Starting dynamic subtest: gt1
[cmd] rtcwake: assuming RTC uses UTC ...
[cmd] rtcwake: wakeup from "freeze" using /dev/rtc0 at Sat Dec 21 22:50:05 2024
Stack trace:
  #0 ../lib/igt_core.c:2051 __igt_fail_assert()
  #1 ../lib/igt_aux.c:909 igt_system_suspend_autoresume()
  #2 ../tests/intel/xe_pm_residency.c:221 test_idle_residency()
  #3 ../tests/intel/xe_pm_residency.c:419 __igt_unique____real_main389()
  #4 ../tests/intel/xe_pm_residency.c:389 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest gt1: FAIL (20.162s)
Subtest gt-c6-freeze: FAIL (30.125s)
Err 
Starting dynamic subtest: gt1
(xe_pm_residency:3961) igt_core-WARNING: [cmd] rtcwake: write error
(xe_pm_residency:3961) igt_aux-WARNING: rtcwake failed with 1
Check dmesg for further details.
(xe_pm_residency:3961) igt_aux-CRITICAL: Test assertion failure function suspend_via_rtcwake, file ../lib/igt_aux.c:828:
(xe_pm_residency:3961) igt_aux-CRITICAL: Failed assertion: ret == 0
(xe_pm_residency:3961) igt_aux-CRITICAL: error: 1 != 0
Dynamic subtest gt1 failed.
**** DEBUG ****
(xe_pm_residency:3961) DEBUG: xe_is_gt_in_c6(fd, gt) took 0ms
(xe_pm_residency:3961) igt_aux-DEBUG: Test requirement passed: (power_dir = open("/sys/power", O_RDONLY)) >= 0
(xe_pm_residency:3961) igt_aux-DEBUG: Test requirement passed: is_state_supported(power_dir, state)
(xe_pm_residency:3961) igt_aux-DEBUG: Test requirement passed: test == SUSPEND_TEST_NONE || faccessat(power_dir, "pm_test", R_OK | W_OK, 0) == 0
(xe_pm_residency:3961) igt_aux-DEBUG: Test requirement passed: !(state == SUSPEND_STATE_DISK && !igt_get_total_swap_mb())
(xe_pm_residency:3961) igt_aux-DEBUG: Test requirement passed: ret == 0
(xe_pm_residency:3961) igt_core-INFO: [cmd] rtcwake: assuming RTC uses UTC ...
(xe_pm_residency:3961) igt_core-WARNING: [cmd] rtcwake: write error
(xe_pm_residency:3961) igt_core-INFO: [cmd] rtcwake: wakeup from "freeze" using /dev/rtc0 at Sat Dec 21 22:50:05 2024
(xe_pm_residency:3961) igt_aux-WARNING: rtcwake failed with 1
Check dmesg for further details.
(xe_pm_residency:3961) igt_aux-DEBUG: suspend_stats:
success: 1
fail: 1
failed_freeze: 1
failed_prepare: 0
failed_suspend: 0
failed_suspend_late: 0
failed_suspend_noirq: 0
failed_resume_noirq: 0
failed_resume_early: 0
failed_resume: 1
failures:
  last_failed_dev:	3-0051
			3-0053
  last_failed_errno:	-16
			0
  last_failed_step:	freeze
			resume
(xe_pm_residency:3961) igt_aux-CRITICAL: Test assertion failure function suspend_via_rtcwake, file ../lib/igt_aux.c:828:
(xe_pm_residency:3961) igt_aux-CRITICAL: Failed assertion: ret == 0
(xe_pm_residency:3961) igt_aux-CRITICAL: error: 1 != 0
(xe_pm_residency:3961) igt_core-INFO: Stack trace:
(xe_pm_residency:3961) igt_core-INFO:   #0 ../lib/igt_core.c:2051 __igt_fail_assert()
(xe_pm_residency:3961) igt_core-INFO:   #1 ../lib/igt_aux.c:909 igt_system_suspend_autoresume()
(xe_pm_residency:3961) igt_core-INFO:   #2 ../tests/intel/xe_pm_residency.c:221 test_idle_residency()
(xe_pm_residency:3961) igt_core-INFO:   #3 ../tests/intel/xe_pm_residency.c:419 __igt_unique____real_main389()
(xe_pm_residency:3961) igt_core-INFO:   #4 ../tests/intel/xe_pm_residency.c:389 main()
(xe_pm_residency:3961) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(xe_pm_residency:3961) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(xe_pm_residency:3961) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest gt1: FAIL (20.162s)
Subtest gt-c6-freeze: FAIL (30.125s)
Dmesg
<6> [200.651821] [IGT] xe_pm_residency: starting dynamic subtest gt1
<7> [200.651981] xe 0000:03:00.0: [drm:drm_dp_dump_desc [drm_display_helper]] AUX USBC2/DDI TC2/PHY G: DP branch: OUI 00-1c-f8 dev-ID 176GB0 HW-rev 0.0 SW-rev 199.85 quirks 0x0000
<7> [200.654444] xe 0000:03:00.0: [drm:drm_dp_read_downstream_info [drm_display_helper]] AUX USBC2/DDI TC2/PHY G: DPCD DFP: 0b f0 02 11
<7> [200.655468] xe 0000:03:00.0: [drm:intel_dp_detect [xe]] [ENCODER:259:DDI TC2/PHY G] MST support: port: yes, sink: SST, modparam: yes -> enable: SST
<7> [200.657285] xe 0000:03:00.0: [drm:intel_dp_print_rates [xe]] source rates: 162000, 216000, 243000, 270000, 324000, 432000, 540000, 675000, 810000, 1000000
<7> [200.657367] xe 0000:03:00.0: [drm:intel_dp_print_rates [xe]] sink rates: 162000, 270000, 540000
<7> [200.657439] xe 0000:03:00.0: [drm:intel_dp_print_rates [xe]] common rates: 162000, 270000, 540000
<7> [200.664284] xe 0000:03:00.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX USBC2/DDI TC2/PHY G: native defer
<7> [200.667978] xe 0000:03:00.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX USBC2/DDI TC2/PHY G: native defer
<7> [200.669676] xe 0000:03:00.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX USBC2/DDI TC2/PHY G: native defer
<7> [200.672723] xe 0000:03:00.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX USBC2/DDI TC2/PHY G: native defer
<7> [200.674489] xe 0000:03:00.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX USBC2/DDI TC2/PHY G: native defer
<7> [200.676213] xe 0000:03:00.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX USBC2/DDI TC2/PHY G: native defer
<7> [200.678241] xe 0000:03:00.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX USBC2/DDI TC2/PHY G: native defer
<7> [200.679982] xe 0000:03:00.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX USBC2/DDI TC2/PHY G: native defer
<7> [200.683477] xe 0000:03:00.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX USBC2/DDI TC2/PHY G: native defer
<7> [200.685475] xe 0000:03:00.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX USBC2/DDI TC2/PHY G: native defer
<7> [200.687310] xe 0000:03:00.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX USBC2/DDI TC2/PHY G: native defer
<7> [200.688983] xe 0000:03:00.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX USBC2/DDI TC2/PHY G: native defer
<7> [200.690994] xe 0000:03:00.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX USBC2/DDI TC2/PHY G: native defer
<7> [200.692987] xe 0000:03:00.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX USBC2/DDI TC2/PHY G: native defer
<7> [200.694982] xe 0000:03:00.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX USBC2/DDI TC2/PHY G: native defer
<7> [200.696979] xe 0000:03:00.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX USBC2/DDI TC2/PHY G: native defer
<7> [200.698503] xe 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:260:DP-2] HDMI: DVI dual 0, max TMDS clock 300000 kHz
<7> [200.698511] xe 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:260:DP-2] HDMI sink does deep color 30.
<7> [200.698516] xe 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:260:DP-2] HDMI sink does deep color 36.
<7> [200.698520] xe 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:260:DP-2] HDMI sink does deep color 48.
<7> [200.698524] xe 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:260:DP-2] Assigning HDMI sink color depth as 16 bpc.
<7> [200.698528] xe 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:260:DP-2] HDMI sink does YCRCB444 in deep color.
<7> [200.698533] xe 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:260:DP-2] ELD monitor DPF90435
<7> [200.698538] xe 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:260:DP-2] HDMI: latency present 0 0, video latency 0 0, audio latency 0 0
<7> [200.698543] xe 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:260:DP-2] ELD size 40, SAD count 3
<7> [200.698564] xe 0000:03:00.0: [drm:intel_dp_set_edid [xe]] [CONNECTOR:260:DP-2] VRR capable: no
<7> [200.698693] xe 0000:03:00.0: [drm:intel_dp_set_edid [xe]] [CONNECTOR:260:DP-2] DFP max bpc 12, max dotclock 0, TMDS clock 25000-600000, PCON Max FRL BW 0Gbps
<6> [200.699577] ata6: SATA link down (SStatus 4 SControl 300)
<6> [200.700178] ata8: SATA link down (SStatus 4 SControl 300)
<7> [200.700169] xe 0000:03:00.0: [drm:intel_dp_set_edid [xe]] PCON ENCODER DSC DPCD: 00 00 00 00 00 00 00 00 00 00 00 00 00
<6> [200.700234] ata7: SATA link down (SStatus 4 SControl 300)
<7> [200.700264] xe 0000:03:00.0: [drm:intel_dp_set_edid [xe]] [CONNECTOR:260:DP-2] RGB->YcbCr conversion? no, YCbCr 4:2:0 allowed? no, YCbCr 4:4:4->4:2:0 conversion? no
<6> [200.700429] ata5: SATA link down (SStatus 4 SControl 300)
<7> [200.702973] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_C
<7> [200.703124] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling PW_D
<7> [200.703246] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling AUX_TC2
<6> [200.734077] Registered IR keymap rc-cec
<6> [200.734240] rc rc0: DP-2 as /devices/pci0000:00/0000:00:01.0/0000:01:00.0/0000:02:01.0/0000:03:00.0/rc/rc0
<6> [200.734463] input: DP-2 as /devices/pci0000:00/0000:00:01.0/0000:01:00.0/0000:02:01.0/0000:03:00.0/rc/rc0/input51
<7> [200.734846] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling AUX_TC2
<7> [200.736673] xe 0000:03:00.0: [drm:intel_hdmi_detect [xe]] [CONNECTOR:268:HDMI-A-2]
<7> [200.736933] xe 0000:03:00.0: [drm:do_gmbus_xfer [xe]] GMBUS [i915 gmbus tc2] NAK for addr: 0050 w(1)
<6> [200.737014] PM: suspend entry (s2idle)
<7> [200.737013] xe 0000:03:00.0: [drm:do_gmbus_xfer [xe]] GMBUS [i915 gmbus tc2] NAK on first message, retry
<7> [200.737272] xe 0000:03:00.0: [drm:do_gmbus_xfer [xe]] GMBUS [i915 gmbus tc2] NAK for addr: 0050 w(1)
<7> [200.737338] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus tc2
<7> [200.737342] xe 0000:03:00.0: [drm:intel_hdmi_set_edid [xe]] HDMI GMBUS EDID read failed, retry using GPIO bit-banging
<7> [200.737405] xe 0000:03:00.0: [drm:intel_gmbus_force_bit [xe]] enabling bit-banging on i915 gmbus tc2. force bit now 1
<7> [200.738526] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus tc2
<7> [200.738529] xe 0000:03:00.0: [drm:intel_gmbus_force_bit [xe]] disabling bit-banging on i915 gmbus tc2. force bit now 0
<7> [200.738599] xe 0000:03:00.0: [drm:intel_hdmi_detect [xe]] [CONNECTOR:272:HDMI-A-3]
<6> [200.759597] Filesystems sync: 0.022 seconds
<6> [200.760685] Freezing user space processes
<7> [200.764356] xe 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:272:HDMI-A-3] HDMI: DVI dual 0, max TMDS clock 340000 kHz
<7> [200.764363] xe 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:272:HDMI-A-3] HDMI sink does deep color 30.
<7> [200.764366] xe 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:272:HDMI-A-3] HDMI sink does deep color 36.
<7> [200.764370] xe 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:272:HDMI-A-3] Assigning HDMI sink color depth as 12 bpc.
<7> [200.764373] xe 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:272:HDMI-A-3] HDMI sink does YCRCB444 in deep color.
<7> [200.764376] xe 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:272:HDMI-A-3] HF-VSDB: max TMDS clock: 600000 KHz, HDMI 2.1 support: no, DSC 1.2 support: no
<7> [200.764380] xe 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:272:HDMI-A-3] ELD monitor 28E850
<7> [200.764384] xe 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:272:HDMI-A-3] HDMI: latency present 0 0, video latency 0 0, audio latency 0 0
<7> [200.764388] xe 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:272:HDMI-A-3] ELD size 32, SAD count 1
<7> [200.764579] xe 0000:03:00.0: [drm:do_gmbus_xfer [xe]] GMBUS [i915 gmbus tc3] NAK for addr: 0040 w(1)
<7> [200.764653] xe 0000:03:00.0: [drm:do_gmbus_xfer [xe]] GMBUS [i915 gmbus tc3] NAK on first message, retry
<7> [200.764891] xe 0000:03:00.0: [drm:do_gmbus_xfer [xe]] GMBUS [i915 gmbus tc3] NAK for addr: 0040 w(1)
<7> [200.764958] xe 0000:03:00.0: [drm:drm_dp_dual_mode_detect [drm_display_helper]] DP dual mode HDMI ID: (err -6)
<7> [200.764976] xe 0000:03:00.0: [drm:intel_dp_detect [xe]] [CONNECTOR:276:DP-3]
<7> [200.769958] xe 0000:03:00.0: [drm:intel_hdmi_detect [xe]] [CONNECTOR:284:HDMI-A-4]
<6> [200.784174] Freezing user space processes completed (elapsed 0.023 seconds)
<6> [200.784182] OOM killer disabled.
<6> [200.784186] Freezing remaining freezable tasks
<7> [200.838109] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling AUX_TC2
<7> [202.566199] xe 0000:03:00.0: [drm:intel_power_well_enable [xe]] enabling AUX_TC2
<7> [202.670259] xe 0000:03:00.0: [drm:intel_power_well_disable [xe]] disabling AUX_TC2
<6> [203.477605] r8169 0000:09:00.0 eno1: Link is Up - 1Gbps/Full - flow control off
<6> [212.855366] usb usb2-port8: attempt power cycle
<3> [220.791922] Freezing remaining freezable tasks failed after 20.008 seconds (0 tasks refusing to freeze, wq_busy=1):
<6> [220.792087] Showing freezable workqueues that are still busy:
<6> [220.792117] workqueue events_freezable: flags=0x4
<6> [220.792148] pwq 26: cpus=6 node=0 flags=0x0 nice=0 active=0 refcnt=2
<6> [220.792162] inactive: pci_pme_list_scan
<6> [220.792186] workqueue pm: flags=0x4
<6> [220.792198] pwq 14: cpus=3 node=0 flags=0x0 nice=0 active=0 refcnt=5
<6> [220.792205] inactive: 4*pm_runtime_work
<6> [220.792229] workqueue usb_hub_wq: flags=0x4
<6> [220.792247] pwq 58: cpus=14 node=0 flags=0x0 nice=0 active=2 refcnt=3
<6> [220.792254] in-flight: 130:hub_event hub_event
<6> [220.792390] Restarting kernel threads ... done.
<6> [220.794724] OOM killer enabled.
<6> [220.794734] Restarting tasks ... done.
<5> [220.798223] random: crng reseeded on system resumption
<6> [220.799700] PM: suspend exit
<6> [220.814996] [IGT] xe_pm_residency: finished subtest gt1, FAIL
<6> [220.816459] [IGT] xe_pm_residency: finished subtest gt-c6-freeze, FAIL
<7> [220.837356] xe 0000:03:00.0: [drm:drm_client_dev_restore] intel-fbdev: ret=0
<6> [220.837670] [IGT] xe_pm_residency: exiting, ret=98
<6> [220.838156] Console: switching to colour frame buffer device 240x67
<3> [221.311396] usb usb2-port8: Cannot enable. Maybe the USB cable is bad?
<4> [221.312449] usb usb2-port8: config error
Created at 2024-12-21 23:37:07