Stdout
Opened device: /dev/dri/card0
Running on kabylake
Using GuC submission
Starting subtest: in-flight-suspend
Opened device: /dev/dri/card1
[cmd] rtcwake: wakeup from "mem" using /dev/rtc0 at Sun Aug 13 09:42:05 2023
Stderr
Starting subtest: in-flight-suspend
Dmesg
<6> [243.310830] [IGT] gem_eio: executing
<7> [243.313845] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [243.314448] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [243.314740] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [243.315324] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [243.316336] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7> [243.316489] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [243.316966] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<5> [243.320100] i915 0000:00:02.0: [drm] vecs0 heartbeat interval adjusted to a non-default value which may cause engine resets to target innocent contexts!
<5> [243.320224] i915 0000:00:02.0: [drm] vcs0 heartbeat interval adjusted to a non-default value which may cause engine resets to target innocent contexts!
<5> [243.320317] i915 0000:00:02.0: [drm] bcs0 heartbeat interval adjusted to a non-default value which may cause engine resets to target innocent contexts!
<5> [243.320410] i915 0000:00:02.0: [drm] rcs0 heartbeat interval adjusted to a non-default value which may cause engine resets to target innocent contexts!
<6> [243.381891] i915 0000:00:02.0: [drm] GPU HANG: ecode 9:0:00000000
<5> [243.383123] i915 0000:00:02.0: [drm] Resetting chip for Manually reset engine mask to ffffffffffffffff
<6> [243.384200] i915 0000:00:02.0: [drm] GT0: GuC firmware i915/kbl_guc_70.1.1.bin version 70.1.1
<6> [243.384204] i915 0000:00:02.0: [drm] GT0: HuC firmware i915/kbl_huc_4.0.0.bin version 4.0.0
<7> [243.390092] i915 0000:00:02.0: [drm:intel_guc_fw_upload [i915]] GT0: GUC: init took 4ms, freq = 350MHz, before = 350MHz, status = 0x8002F0EC, count = 0, ret = 0
<7> [243.390998] i915 0000:00:02.0: [drm:guc_enable_communication [i915]] GT0: GUC: communication enabled
<6> [243.391132] i915 0000:00:02.0: [drm] GT0: GUC: submission disabled
<6> [243.391135] i915 0000:00:02.0: [drm] GT0: GUC: SLPC disabled
<6> [243.391737] [IGT] gem_eio: starting subtest in-flight-suspend
<6> [243.392234] PM: suspend entry (deep)
<6> [243.394333] Filesystems sync: 0.002 seconds
<6> [243.400040] Freezing user space processes
<6> [243.401739] Freezing user space processes completed (elapsed 0.001 seconds)
<6> [243.401745] OOM killer disabled.
<6> [243.401747] Freezing remaining freezable tasks
<6> [243.403102] Freezing remaining freezable tasks completed (elapsed 0.001 seconds)
<6> [243.420541] serial 00:02: disabled
<6> [243.420818] e1000e: EEE TX LPI TIMER: 00000011
<7> [243.423956] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [243.424116] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [243.424240] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [243.424794] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<5> [243.430012] sd 2:0:0:0: [sda] Synchronizing SCSI cache
<5> [243.430969] sd 2:0:0:0: [sda] Stopping disk
<7> [243.432352] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7> [243.432503] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [243.432990] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [243.433531] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [243.433757] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_A_E
<7> [243.433882] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [243.434023] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_C
<7> [243.434147] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_D
<7> [243.489553] PM: suspend of devices complete after 74.172 msecs
<7> [243.489561] PM: start suspend of devices complete after 86.228 msecs
<6> [243.489565] PM: suspend debug: Waiting for 5 second(s).
<6> [248.492858] i915 0000:00:02.0: [drm] GT0: GuC firmware i915/kbl_guc_70.1.1.bin version 70.1.1
<6> [248.492865] i915 0000:00:02.0: [drm] GT0: HuC firmware i915/kbl_huc_4.0.0.bin version 4.0.0
<7> [248.495004] i915 0000:00:02.0: [drm:intel_guc_fw_upload [i915]] GT0: GUC: init took 1ms, freq = 350MHz, before = 1150MHz, status = 0x8002F0EC, count = 0, ret = 0
<7> [248.495859] i915 0000:00:02.0: [drm:guc_enable_communication [i915]] GT0: GUC: communication enabled
<6> [248.496005] i915 0000:00:02.0: [drm] GT0: GUC: submission disabled
<6> [248.496008] i915 0000:00:02.0: [drm] GT0: GUC: SLPC disabled
<7> [248.497249] i915 0000:00:02.0: [drm:intel_cdclk_dump_config [i915]] Current CDCLK 337500 kHz, VCO 8100000 kHz, ref 24000 kHz, bypass 24000 kHz, voltage level 0
<7> [248.497616] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [CRTC:51:pipe A] hw state readout: disabled
<7> [248.497776] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [CRTC:72:pipe B] hw state readout: disabled
<7> [248.497941] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [CRTC:93:pipe C] hw state readout: disabled
<7> [248.498074] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:31:plane 1A] hw state readout: disabled, pipe A
<7> [248.498205] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:39:plane 2A] hw state readout: disabled, pipe A
<7> [248.498337] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:47:cursor A] hw state readout: disabled, pipe A
<7> [248.498467] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:52:plane 1B] hw state readout: disabled, pipe B
<7> [248.498598] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:60:plane 2B] hw state readout: disabled, pipe B
<7> [248.498728] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:68:cursor B] hw state readout: disabled, pipe B
<7> [248.498858] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:73:plane 1C] hw state readout: disabled, pipe C
<7> [248.498997] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:81:plane 2C] hw state readout: disabled, pipe C
<7> [248.499127] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:89:cursor C] hw state readout: disabled, pipe C
<7> [248.499259] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [ENCODER:94:DDI B/PHY B] hw state readout: disabled, pipe A
<7> [248.499390] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [ENCODER:104:DDI C/PHY C] hw state readout: disabled, pipe A
<7> [248.499519] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [ENCODER:108:DDI E/PHY E] hw state readout: disabled, pipe A
<7> [248.499651] i915 0000:00:02.0: [drm:intel_dpll_readout_hw_state [i915]] DPLL 0 hw state readout: pipe_mask 0x0, on 1
<7> [248.499785] i915 0000:00:02.0: [drm:intel_dpll_readout_hw_state [i915]] DPLL 1 hw state readout: pipe_mask 0x0, on 0
<6> [248.499899] serial 00:02: activated
<7> [248.499917] i915 0000:00:02.0: [drm:intel_dpll_readout_hw_state [i915]] DPLL 2 hw state readout: pipe_mask 0x0, on 0
<7> [248.500057] i915 0000:00:02.0: [drm:intel_dpll_readout_hw_state [i915]] DPLL 3 hw state readout: pipe_mask 0x0, on 0
<7> [248.500254] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [CONNECTOR:95:HDMI-A-1] hw state readout: disabled
<7> [248.500393] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [CONNECTOR:105:HDMI-A-2] hw state readout: disabled
<7> [248.500530] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [CONNECTOR:109:DP-1] hw state readout: disabled
<7> [248.500666] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:31:plane 1A] min_cdclk 0 kHz
<7> [248.500787] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:39:plane 2A] min_cdclk 0 kHz
<7> [248.500909] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:47:cursor A] min_cdclk 0 kHz
<7> [248.501080] i915 0000:00:02.0: [drm:intel_bw_crtc_update [i915]] pipe A data rate 0 num active planes 0
<7> [248.501220] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:52:plane 1B] min_cdclk 0 kHz
<7> [248.501341] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:60:plane 2B] min_cdclk 0 kHz
<7> [248.501476] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:68:cursor B] min_cdclk 0 kHz
<7> [248.501610] i915 0000:00:02.0: [drm:intel_bw_crtc_update [i915]] pipe B data rate 0 num active planes 0
<7> [248.501737] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:73:plane 1C] min_cdclk 0 kHz
<7> [248.501859] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:81:plane 2C] min_cdclk 0 kHz
<7> [248.502009] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:89:cursor C] min_cdclk 0 kHz
<7> [248.502143] i915 0000:00:02.0: [drm:intel_bw_crtc_update [i915]] pipe C data rate 0 num active planes 0
<7> [248.502292] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:51:pipe A] enable: no [setup_hw_state]
<7> [248.502420] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:72:pipe B] enable: no [setup_hw_state]
<7> [248.502545] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:93:pipe C] enable: no [setup_hw_state]
<7> [248.502688] i915 0000:00:02.0: [drm:intel_dpll_sanitize_state [i915]] DPLL 0 enabled but not in use, disabling
<7> [248.502826] i915 0000:00:02.0: [drm:skl_wm_get_hw_state_and_sanitize [i915]] [CRTC:51:pipe A] dbuf slices 0x0, ddb (0 - 0), active pipes 0x0, mbus joined: no
<7> [248.502968] i915 0000:00:02.0: [drm:skl_wm_get_hw_state_and_sanitize [i915]] [CRTC:72:pipe B] dbuf slices 0x0, ddb (0 - 0), active pipes 0x0, mbus joined: no
<7> [248.503128] i915 0000:00:02.0: [drm:skl_wm_get_hw_state_and_sanitize [i915]] [CRTC:93:pipe C] dbuf slices 0x0, ddb (0 - 0), active pipes 0x0, mbus joined: no
<7> [248.503336] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:51:pipe A] enable: no [fastset]
<7> [248.503464] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:31:plane 1A] fb: [NOFB], visible: no
<7> [248.503591] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:39:plane 2A] fb: [NOFB], visible: no
<7> [248.503717] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:47:cursor A] fb: [NOFB], visible: no
<7> [248.503843] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:72:pipe B] enable: no [fastset]
<7> [248.503991] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:52:plane 1B] fb: [NOFB], visible: no
<7> [248.504147] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:60:plane 2B] fb: [NOFB], visible: no
<7> [248.504301] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:68:cursor B] fb: [NOFB], visible: no
<7> [248.504456] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:93:pipe C] enable: no [fastset]
<7> [248.504586] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:73:plane 1C] fb: [NOFB], visible: no
<7> [248.504714] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:81:plane 2C] fb: [NOFB], visible: no
<7> [248.504844] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:89:cursor C] fb: [NOFB], visible: no
<7> [248.505054] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:51:pipe A]
<7> [248.505211] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:72:pipe B]
<7> [248.505362] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:93:pipe C]
<7> [248.505642] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:95:HDMI-A-1]
<7> [248.505847] i915 0000:00:02.0: [drm:intel_opregion_resume [i915]] 3 outputs detected
<7> [248.506037] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0050 w(1)
<7> [248.506153] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [248.506460] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0050 w(1)
<7> [248.506593] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus dpb
<7> [248.506598] i915 0000:00:02.0: [drm:intel_hdmi_set_edid [i915]] HDMI GMBUS EDID read failed, retry using GPIO bit-banging
<7> [248.506715] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus dpb. force bit now 1
<7> [248.509577] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus dpb
<7> [248.509582] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus dpb. force bit now 0
<7> [248.509695] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:105:HDMI-A-2]
<7> [248.511428] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpc] NAK for addr: 0050 w(1)
<7> [248.511536] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpc] NAK on first message, retry
<7> [248.511844] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpc] NAK for addr: 0050 w(1)
<7> [248.512004] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus dpc
<7> [248.512008] i915 0000:00:02.0: [drm:intel_hdmi_set_edid [i915]] HDMI GMBUS EDID read failed, retry using GPIO bit-banging
<7> [248.512128] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus dpc. force bit now 1
<7> [248.512562] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_D
<7> [248.512737] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_C
<7> [248.512870] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [248.513030] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_A_E
<7> [248.513683] i915 0000:00:02.0: [drm:i915_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x10
<7> [248.515017] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus dpc
<7> [248.515022] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus dpc. force bit now 0
<7> [248.515136] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:109:DP-1]
<7> [248.703987] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [248.704262] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [248.704423] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [248.704579] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [248.705180] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<7> [248.742771] PM: resume of devices complete after 252.709 msecs
<6> [248.745373] OOM killer enabled.
<6> [248.745377] Restarting tasks ... done.
<5> [248.747822] random: crng reseeded on system resumption
<6> [248.750539] PM: suspend exit
<7> [248.750781] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [248.751266] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7> [248.751448] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [248.751912] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [248.754526] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [248.771063] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [248.785166] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [248.811571] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<6> [248.814332] ata6: SATA link down (SStatus 4 SControl 300)
<6> [248.814357] ata1: SATA link down (SStatus 4 SControl 300)
<6> [248.814471] ata2: SATA link down (SStatus 4 SControl 300)
<6> [248.814493] ata4: SATA link down (SStatus 4 SControl 300)
<6> [248.814560] ata3: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
<6> [248.814601] ata5: SATA link down (SStatus 4 SControl 300)
<7> [248.829593] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<6> [248.833136] ata3.00: configured for UDMA/133
<7> [248.857321] i915 0000:00:02.0: [drm:i915_hdcp_component_bind [i915]] I915 HDCP comp bind
<6> [248.857693] mei_hdcp 0000:00:16.0-b638ab7e-94e2-4ea2-a552-d1c54b627f04: bound 0000:00:02.0 (ops i915_hdcp_ops [i915])
<7> [248.899396] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [248.928639] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [248.943908] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [248.958106] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [248.958407] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<5> [248.961215] i915 0000:00:02.0: [drm] vecs0 heartbeat interval adjusted to a non-default value which may cause engine resets to target innocent contexts!
<5> [248.961328] i915 0000:00:02.0: [drm] vcs0 heartbeat interval adjusted to a non-default value which may cause engine resets to target innocent contexts!
<5> [248.961419] i915 0000:00:02.0: [drm] bcs0 heartbeat interval adjusted to a non-default value which may cause engine resets to target innocent contexts!
<5> [248.961615] i915 0000:00:02.0: [drm] rcs0 heartbeat interval adjusted to a non-default value which may cause engine resets to target innocent contexts!
<6> [249.039562] PM: suspend entry (deep)
<6> [249.040308] Filesystems sync: 0.000 seconds
<6> [249.040910] Freezing user space processes