Stdout
Starting dynamic subtest: D-eDP1
1920x1080 90 1920 1936 1952 2104 1080 1083 1097 1128 0xa 0x48 214000
Dynamic subtest D-eDP1: SUCCESS (3.839s)
This test caused an abort condition: Lockdep not active
/proc/lockdep_stats contents:
lock-classes: 2144 [max: 8192]
direct dependencies: 20866 [max: 524288]
indirect dependencies: 119760
all direct dependencies: 444874
dependency chains: 31282 [max: 524288]
dependency chain hlocks used: 127687 [max: 2621440]
dependency chain hlocks lost: 0
in-hardirq chains: 214
in-softirq chains: 675
in-process chains: 30393
stack-trace entries: 226968 [max: 524288]
number of stack traces: 10324
number of stack hash chains: 7639
combined max dependencies: 122496664
hardirq-safe locks: 92
hardirq-unsafe locks: 1359
softirq-safe locks: 203
softirq-unsafe locks: 1270
irq-safe locks: 220
irq-unsafe locks: 1359
hardirq-read-safe locks: 4
hardirq-read-unsafe locks: 361
softirq-read-safe locks: 8
softirq-read-unsafe locks: 356
irq-read-safe locks: 8
irq-read-unsafe locks: 361
uncategorized locks: 319
unused locks: 1
max locking depth: 15
max bfs queue depth: 324
max lock class index: 2143
chain lookup misses: 31718
chain lookup hits: 120816799
cyclic checks: 31285
redundant checks: 0
redundant links: 0
find-mask forwards checks: 4739
find-mask backwards checks: 3350
hardirq on events: 73638640
hardirq off events: 73638631
redundant hardirq ons: 36
redundant hardirq offs: 1
softirq on events: 220052
softirq off events: 220052
redundant softirq ons: 0
redundant softirq offs: 0
debug_locks: 0
zapped classes: 2
zapped lock chains: 161
large chain blocks: 1
Stderr
Starting dynamic subtest: D-eDP1
Dynamic subtest D-eDP1: SUCCESS (3.839s)
Dmesg
<6> [136.011987] [IGT] kms_flip: starting dynamic subtest D-eDP1
<7> [136.012275] xe 0000:00:02.0: [drm:drm_mode_addfb2 [drm]] [FB:242]
<7> [136.012376] xe 0000:00:02.0: [drm:drm_mode_addfb2 [drm]] [FB:296]
<7> [136.031033] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [136.031126] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [136.031191] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [136.031255] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling always-on
<7> [136.035365] xe 0000:00:02.0: [drm:drm_mode_setcrtc [drm]] [CRTC:82:pipe A]
<7> [136.035514] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling always-on
<7> [136.035600] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [136.035835] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [136.036015] xe 0000:00:02.0: [drm:drm_mode_setcrtc [drm]] [CRTC:134:pipe B]
<7> [136.036153] xe 0000:00:02.0: [drm:drm_mode_setcrtc [drm]] [CRTC:186:pipe C]
<7> [136.036263] xe 0000:00:02.0: [drm:drm_mode_setcrtc [drm]] [CRTC:238:pipe D]
<7> [136.036382] xe 0000:00:02.0: [drm:drm_mode_setcrtc [drm]] [CRTC:238:pipe D]
<7> [136.036423] xe 0000:00:02.0: [drm:drm_mode_setcrtc [drm]] [CONNECTOR:241:eDP-1]
<7> [136.036500] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CONNECTOR:241:eDP-1] Limiting display bpp to 18 (EDID bpp 18, max requested bpp 36, max platform bpp 36)
<7> [136.036591] xe 0000:00:02.0: [drm:intel_dp_compute_config_link_bpp_limits [xe]] [ENCODER:240:DDI A/PHY A][CRTC:238:pipe D] DP link limits: pixel clock 214000 kHz DSC off max lanes 2 max rate 270000 max pipe_bpp 18 max link_bpp 18.0000
<7> [136.036659] xe 0000:00:02.0: [drm:intel_dp_compute_link_config [xe]] DP lane count 2 clock 270000 bpp input 18 compressed 0.0000 link rate required 481500 available 540000
<7> [136.036723] xe 0000:00:02.0: [drm:intel_psr_compute_config [xe]] PSR2 not supported in transcoder D
<7> [136.036779] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:238:pipe D] hw max bpp: 18, pipe bpp: 18, dithering: 1
<7> [136.036843] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:240:DDI A/PHY A] [CRTC:238:pipe D]
<7> [136.036907] xe 0000:00:02.0: [drm] [CRTC:238:pipe D] fastset requirement not met in hw.enable (expected no, found yes)
<7> [136.036909] xe 0000:00:02.0: [drm] [CRTC:238:pipe D] fastset requirement not met in hw.active (expected no, found yes)
<7> [136.036910] xe 0000:00:02.0: [drm] [CRTC:238:pipe D] fastset requirement not met in cpu_transcoder (expected -1, found 3)
<7> [136.036912] xe 0000:00:02.0: [drm] [CRTC:238:pipe D] fastset requirement not met in lane_count (expected 0, found 2)
<7> [136.036914] xe 0000:00:02.0: [drm] [CRTC:238:pipe D] fastset requirement not met in dp_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 7479842/8388608 link 415546/524288)
<7> [136.036917] xe 0000:00:02.0: [drm] [CRTC:238:pipe D] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100)
<7> [136.036919] xe 0000:00:02.0: [drm] [CRTC:238:pipe D] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [136.036921] xe 0000:00:02.0: [drm] [CRTC:238:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [136.036922] xe 0000:00:02.0: [drm] [CRTC:238:pipe D] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2104)
<7> [136.036924] xe 0000:00:02.0: [drm] [CRTC:238:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [136.036925] xe 0000:00:02.0: [drm] [CRTC:238:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2104)
<7> [136.036927] xe 0000:00:02.0: [drm] [CRTC:238:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1936)
<7> [136.036929] xe 0000:00:02.0: [drm] [CRTC:238:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 1952)
<7> [136.036930] xe 0000:00:02.0: [drm] [CRTC:238:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [136.036932] xe 0000:00:02.0: [drm] [CRTC:238:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [136.036933] xe 0000:00:02.0: [drm] [CRTC:238:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [136.036935] xe 0000:00:02.0: [drm] [CRTC:238:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1097)
<7> [136.036936] xe 0000:00:02.0: [drm] [CRTC:238:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1128)
<7> [136.036938] xe 0000:00:02.0: [drm] [CRTC:238:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1128)
<7> [136.036940] xe 0000:00:02.0: [drm] [CRTC:238:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [136.036941] xe 0000:00:02.0: [drm] [CRTC:238:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2104)
<7> [136.036943] xe 0000:00:02.0: [drm] [CRTC:238:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [136.036944] xe 0000:00:02.0: [drm] [CRTC:238:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2104)
<7> [136.036946] xe 0000:00:02.0: [drm] [CRTC:238:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1936)
<7> [136.036947] xe 0000:00:02.0: [drm] [CRTC:238:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 1952)
<7> [136.036949] xe 0000:00:02.0: [drm] [CRTC:238:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [136.036950] xe 0000:00:02.0: [drm] [CRTC:238:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [136.036952] xe 0000:00:02.0: [drm] [CRTC:238:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [136.036954] xe 0000:00:02.0: [drm] [CRTC:238:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1097)
<7> [136.036955] xe 0000:00:02.0: [drm] [CRTC:238:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1128)
<7> [136.036957] xe 0000:00:02.0: [drm] [CRTC:238:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1128)
<7> [136.036958] xe 0000:00:02.0: [drm] [CRTC:238:pipe D] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [136.036960] xe 0000:00:02.0: [drm] [CRTC:238:pipe D] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [136.036962] xe 0000:00:02.0: [drm] [CRTC:238:pipe D] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [136.036963] xe 0000:00:02.0: [drm] [CRTC:238:pipe D] fastset requirement not met in enhanced_framing (expected no, found yes)
<7> [136.036966] xe 0000:00:02.0: [drm] [CRTC:238:pipe D] fastset requirement not met in dpll_hw_state
<7> [136.036967] xe 0000:00:02.0: [drm] expected:
<7> [136.036969] xe 0000:00:02.0: [drm] dpll_hw_state: cfgcr0: 0x0, cfgcr1: 0x0, div0: 0x0, mg_refclkin_ctl: 0x100, hg_clktop2_coreclkctl1: 0x500, mg_clktop2_hsclkctl: 0x1100, mg_pll_div0: 0x84269, mg_pll_div2: 0x1c0027, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x40002000, mg_pll_bias: 0x5e000000, mg_pll_tdc_coldst_bias: 0x52
<7> [136.036972] xe 0000:00:02.0: [drm] found:
<7> [136.036973] xe 0000:00:02.0: [drm] dpll_hw_state: cfgcr0: 0xe001a5, cfgcr1: 0x88, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [136.036976] xe 0000:00:02.0: [drm] [CRTC:238:pipe D] fastset requirement not met in pipe_bpp (expected 0, found 18)
<7> [136.036978] xe 0000:00:02.0: [drm] [CRTC:238:pipe D] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 214000)
<7> [136.036979] xe 0000:00:02.0: [drm] [CRTC:238:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 214000)
<7> [136.036981] xe 0000:00:02.0: [drm] [CRTC:238:pipe D] fastset requirement not met in port_clock (expected 0, found 270000)
<7> [136.036983] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:238:pipe D] fastset requirement not met, forcing full modeset
<7> [136.037065] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0xf), mbus joined? no->no
<7> [136.037116] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:238:pipe D] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x8
<7> [136.037194] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:188:plane 1D] ddb ( 0 - 0) -> ( 0 - 1996), size 0 -> 1996
<7> [136.037242] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:233:cursor D] ddb ( 0 - 0) -> (1996 - 2048), size 0 -> 52
<7> [136.037292] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:188:plane 1D] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm
<7> [136.037341] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:188:plane 1D] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 6, 9, 11, 15, 15, 0, 0, 0, 4, 0
<7> [136.037389] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:188:plane 1D] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 97, 145, 177, 241, 241, 0, 0, 30, 65, 79
<7> [136.037436] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:188:plane 1D] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 108, 161, 196, 267, 267, 0, 0, 31, 73, 80
<7> [136.037486] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:238:pipe D] data rate 856000 num active planes 1
<7> [136.037550] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 11064 required 899
<7> [136.037612] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 13813 required 899
<7> [136.037674] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 14899 required 899
<7> [136.037732] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 13010 required 899
<7> [136.037791] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] PSF GV point 0: max bw 34133 required 899
<7> [136.037849] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] PSF GV point 1: max bw 51200 required 899
<7> [136.037906] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] PSF GV point 2: max bw 51200 required 899
<7> [136.037974] xe 0000:00:02.0: [drm:intel_plane_calc_min_cdclk [xe]] [PLANE:188:plane 1D] min cdclk (107000 kHz) > [CRTC:238:pipe D] min cdclk (0 kHz)
<7> [136.038033] xe 0000:00:02.0: [drm:intel_bw_calc_min_cdclk [xe]] new bandwidth min cdclk (16719 kHz) > old min cdclk (0 kHz)
<7> [136.038091] xe 0000:00:02.0: [drm:intel_modeset_calc_cdclk [xe]] New cdclk calculated to be logical 179200 kHz, actual 179200 kHz
<7> [136.038149] xe 0000:00:02.0: [drm:intel_modeset_calc_cdclk [xe]] New voltage level calculated to be logical 0, actual 0
<7> [136.038208] xe 0000:00:02.0: [drm:intel_find_shared_dpll [xe]] [CRTC:238:pipe D] allocated DPLL 0
<7> [136.038267] xe 0000:00:02.0: [drm:intel_reference_shared_dpll_crtc.isra.0 [xe]] [CRTC:238:pipe D] reserving DPLL 0
<7> [136.038328] xe 0000:00:02.0: [drm] [CRTC:238:pipe D] enable: yes [modeset]
<7> [136.038330] xe 0000:00:02.0: [drm] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB
<7> [136.038332] xe 0000:00:02.0: [drm] cpu_transcoder: D, pipe bpp: 18, dithering: 1
<7> [136.038334] xe 0000:00:02.0: [drm] MST master transcoder: <invalid>
<7> [136.038336] xe 0000:00:02.0: [drm] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0