Stdout
Using IGT_SRANDOM=1718448325 for randomisation
Opened device: /dev/dri/card0
Starting subtest: s2idle-d3hot-basic-exec
[cmd] rtcwake: wakeup from "freeze" using /dev/rtc0 at Sat Jun 15 10:45:42 2024
[cmd] rtcwake: wakeup from "freeze" using /dev/rtc0 at Sat Jun 15 10:46:03 2024
[cmd] rtcwake: wakeup from "freeze" using /dev/rtc0 at Sat Jun 15 10:46:25 2024
[cmd] rtcwake: wakeup from "freeze" using /dev/rtc0 at Sat Jun 15 10:46:49 2024
[cmd] rtcwake: wakeup from "freeze" using /dev/rtc0 at Sat Jun 15 10:47:09 2024
Subtest s2idle-d3hot-basic-exec: SUCCESS (106.792s)
Stderr
Starting subtest: s2idle-d3hot-basic-exec
Subtest s2idle-d3hot-basic-exec: SUCCESS (106.792s)
Dmesg
<7> [415.784735] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_TC1
<6> [415.799866] Console: switching to colour dummy device 80x25
<6> [415.800055] [IGT] xe_pm: executing
<6> [416.134844] [IGT] xe_pm: starting subtest s2idle-d3hot-basic-exec
<7> [416.135013] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CONNECTOR:189:eDP-1] Limiting display bpp to 30 (EDID bpp 30, max requested bpp 36, max platform bpp 36)
<7> [416.135154] xe 0000:00:02.0: [drm:intel_dp_compute_config_link_bpp_limits [xe]] [ENCODER:188:DDI A/PHY A][CRTC:82:pipe A] DP link limits: pixel clock 347710 kHz DSC off max lanes 4 max rate 810000 max pipe_bpp 30 max link_bpp 30.0000
<7> [416.135223] xe 0000:00:02.0: [drm:intel_dp_compute_link_config [xe]] DP lane count 4 clock 432000 bpp input 30 compressed 0.0000 link rate required 1303913 available 1728000
<7> [416.136077] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:82:pipe A] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [416.136143] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:188:DDI A/PHY A] [CRTC:82:pipe A]
<7> [416.136206] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [416.136270] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:82:pipe A] fastset requirement not met, forcing full modeset
<7> [416.136361] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [416.136403] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:82:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x3 -> 0x2
<7> [416.136447] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:134:pipe B] dbuf slices 0xc -> 0xf, ddb (0 - 2048) -> (0 - 4096), active pipes 0x3 -> 0x2
<7> [416.136566] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CONNECTOR:189:eDP-1] Limiting display bpp to 30 (EDID bpp 30, max requested bpp 36, max platform bpp 36)
<7> [416.136676] xe 0000:00:02.0: [drm:intel_dp_compute_config_link_bpp_limits [xe]] [ENCODER:188:DDI A/PHY A][CRTC:82:pipe A] DP link limits: pixel clock 347710 kHz DSC off max lanes 4 max rate 810000 max pipe_bpp 30 max link_bpp 30.0000
<7> [416.136773] xe 0000:00:02.0: [drm:intel_dp_compute_link_config [xe]] DP lane count 4 clock 432000 bpp input 30 compressed 0.0000 link rate required 1303913 available 1728000
<7> [416.137657] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:82:pipe A] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [416.137740] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:188:DDI A/PHY A] [CRTC:82:pipe A]
<7> [416.137824] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [416.137904] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:82:pipe A] fastset requirement not met, forcing full modeset
<7> [416.138037] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CONNECTOR:189:eDP-1] Limiting display bpp to 30 (EDID bpp 30, max requested bpp 36, max platform bpp 36)
<7> [416.138117] xe 0000:00:02.0: [drm:intel_dp_compute_config_link_bpp_limits [xe]] [ENCODER:188:DDI A/PHY A][CRTC:82:pipe A] DP link limits: pixel clock 347710 kHz DSC off max lanes 4 max rate 810000 max pipe_bpp 30 max link_bpp 30.0000
<7> [416.138189] xe 0000:00:02.0: [drm:intel_dp_compute_link_config [xe]] DP lane count 4 clock 432000 bpp input 30 compressed 0.0000 link rate required 1303913 available 1728000
<7> [416.139027] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:82:pipe A] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [416.139083] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:188:DDI A/PHY A] [CRTC:82:pipe A]
<7> [416.139144] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [416.139202] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:82:pipe A] fastset requirement not met, forcing full modeset
<7> [416.139269] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [416.139310] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:82:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x3 -> 0x2
<7> [416.139355] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:134:pipe B] dbuf slices 0xc -> 0xf, ddb (0 - 2048) -> (0 - 4096), active pipes 0x3 -> 0x2
<7> [416.139462] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CONNECTOR:189:eDP-1] Limiting display bpp to 30 (EDID bpp 30, max requested bpp 36, max platform bpp 36)
<7> [416.139517] xe 0000:00:02.0: [drm:intel_dp_compute_config_link_bpp_limits [xe]] [ENCODER:188:DDI A/PHY A][CRTC:82:pipe A] DP link limits: pixel clock 347710 kHz DSC off max lanes 4 max rate 810000 max pipe_bpp 30 max link_bpp 30.0000
<7> [416.139569] xe 0000:00:02.0: [drm:intel_dp_compute_link_config [xe]] DP lane count 4 clock 432000 bpp input 30 compressed 0.0000 link rate required 1303913 available 1728000
<7> [416.140378] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:82:pipe A] hw max bpp: 30, pipe bpp: 30, dithering: 0
<7> [416.140435] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:188:DDI A/PHY A] [CRTC:82:pipe A]
<7> [416.140494] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [416.140550] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:82:pipe A] fastset requirement not met, forcing full modeset
<7> [416.140615] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0xf (total dbuf slices 0xf), mbus joined? no->yes
<7> [416.140795] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:82:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x3 -> 0x2
<7> [416.140843] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:134:pipe B] dbuf slices 0xc -> 0xf, ddb (0 - 2048) -> (0 - 4096), active pipes 0x3 -> 0x2
<7> [416.140928] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:32:plane 1A] ddb ( 0 - 2009) -> ( 0 - 0), size 2009 -> 0
<7> [416.140965] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:77:cursor A] ddb (2009 - 2048) -> ( 0 - 0), size 39 -> 0
<7> [416.141000] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:32:plane 1A] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [416.141035] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:32:plane 1A] lines 1, 10, 10, 10, 9, 11, 0, 0, 0, 3, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [416.141071] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:32:plane 1A] blocks 24, 241, 241, 241, 217, 265, 0, 0, 38, 73, 87 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [416.141104] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:32:plane 1A] min_ddb 28, 267, 267, 267, 240, 293, 0, 0, 39, 82, 88 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [416.141134] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:84:plane 1B] ddb ( 0 - 2016) -> ( 0 - 4064), size 2016 -> 4064
<7> [416.141163] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:129:cursor B] ddb (2016 - 2048) -> (4064 - 4096), size 32 -> 32
<7> [416.141193] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:82:pipe A] data rate 0 num active planes 0
<7> [416.141247] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 1015 qgv_peak_bw: 38400
<7> [416.141298] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 1015 qgv_peak_bw: 38400
<7> [416.141347] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 1015 qgv_peak_bw: 38400
<7> [416.141395] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 1015 qgv_peak_bw: 38400
<7> [416.141440] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 1015
<7> [416.141489] xe 0000:00:02.0: [drm:intel_modeset_calc_cdclk [xe]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [416.141537] xe 0000:00:02.0: [drm:intel_modeset_calc_cdclk [xe]] New voltage level calculated to be logical 1, actual 1
<7> [416.141603] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:82:pipe A] enable: yes [modeset]
<7> [416.141654] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: no, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [416.141705] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: A, pipe bpp: 30, dithering: 0
<7> [416.141755] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [416.141802] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [416.141850] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [416.141898] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [416.141944] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dp m_n: lanes: 4; data_m: 6329869, data_n: 8388608, link_m: 421991, link_n: 524288, tu: 64
<7> [416.141991] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dp m2_n2: lanes: 4; data_m: 0, data_n: 0, link_m: 0, link_n: 0, tu: 0
<7> [416.142040] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: disabled
<7> [416.142087] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled
<7> [416.142132] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] psr: enabled, selective update: enabled, panel replay: disabled, selective fetch: enabled
<7> [416.142177] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] framestart delay: 1, MSA timing delay: 0
<7> [416.142222] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 0, infoframes: 0, infoframes enabled: 0x4
<7> [416.142270] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] DP SDP: VSC, revision 4, length 14
<7> [416.142317] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pixelformat: RGB
<7> [416.142364] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] colorimetry: sRGB
<7> [416.142410] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] bpc: 0
<7> [416.142456] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dynamic range: VESA range
<7> [416.142501] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] content type: Not defined
<7> [416.142541] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: no, vmin: 1905, vmax: 2859, pipeline full: 0, guardband: 106 flipline: 1906, vmin vblank: 1800, vmax vblank: 2753
<7> [416.142582] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] requested mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa
<7> [416.142623] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] adjusted mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa
<7> [416.142664] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=347710, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1800-1906 vs=1803-1809 vt=1906, flags=0xa
<7> [416.142708] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x40 0xa
<7> [416.142753] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=347710, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1800-1906 vs=1803-1809 vt=1906, flags=0xa
<7> [416.142797] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port clock: 432000, pipe src: 2880x1800+0+0, pixel rate 347710