igt@kms_vblank@query-forked-hang@pipe-d-edp-1 on shard-mtlp@IGTPW

Detail	Value
Duration	unknown
Hostname	shard-mtlp-4
Igt-Version	IGT-Version: 2.3-ge09441051 (x86_64) (Linux: 7.0.0-rc4-CI_DRM_18160-ga25d8c583e1e+ x86_64)
Out	Skipping pipe B Skipping pipe C Starting dynamic subtest: pipe-D-eDP-1 Time to query current counter (idle): 33.393Âµs Time to query current counter (idle): 32.620Âµs Time to query current counter (idle): 31.984Âµs Time to query current counter (idle): 34.793Âµs Time to query current counter (idle): 34.541Âµs Time to query current counter (idle): 35.049Âµs Time to query current counter (idle): 34.071Âµs Time to query current counter (idle): 35.232Âµs Time to query current counter (idle): 35.511Âµs Time to query current counter (idle): 35.673Âµs Time to query current counter (idle): 35.421Âµs Time to query current counter (idle): 35.271Âµs Time to query current counter (idle): 35.600Âµs Time to query current counter (idle): 35.547Âµs Time to query current counter (idle): 35.171Âµs Time to query current counter (idle): 35.092Âµs Time to query current counter (idle): 35.257Âµs Time to query current counter (idle): 33.822Âµs Time to query current counter (idle): 34.184Âµs Time to query current counter (idle): 34.165Âµs Time to query current counter (idle): 29.709Âµs Time to query current counter (idle): 29.687Âµs Dynamic subtest pipe-D-eDP-1: SUCCESS (24.011s) Subtest query-forked-hang: SUCCESS (50.466s) This test caused an abort condition: Kernel badly tainted (0x4244, 0x200) (check dmesg for details): TAINT_WARN: WARN_ON has happened.
Err	Starting dynamic subtest: pipe-D-eDP-1 Dynamic subtest pipe-D-eDP-1: SUCCESS (24.011s) Subtest query-forked-hang: SUCCESS (50.466s)
Dmesg Scroll to first warning	<6> [537.746696] [IGT] kms_vblank: starting dynamic subtest pipe-D-eDP-1 <7> [537.747112] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:566] <7> [537.747570] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:150:pipe A] <7> [537.749884] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:268:pipe B] <7> [537.752180] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:386:pipe C] <7> [537.754559] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:504:pipe D] <7> [537.754585] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:507:eDP-1] <7> [537.754635] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:507:eDP-1] Limiting target display pipe bpp to 30 (EDID bpp 30, max requested bpp 36, max platform bpp 36) <7> [537.754838] i915 0000:00:02.0: [drm:intel_dp_compute_config_limits [i915]] [ENCODER:506:DDI A/PHY A][CRTC:504:pipe D] DP link limits: pixel clock 513820 kHz DSC off max lanes 4 max rate 540000 max pipe_bpp 30 min link_bpp 18.0000 max link_bpp 30.0000 <7> [537.754981] i915 0000:00:02.0: [drm:intel_dp_compute_link_config [i915]] DP lane count 4 clock 540000 bpp input 30 compressed 0.0000 HDR no link rate required 1926825 available 2160000 <7> [537.755128] i915 0000:00:02.0: [drm:intel_psr_compute_config [i915]] PSR2 not supported in transcoder D <7> [537.755307] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:504:pipe D] hw max bpp: 30, pipe bpp: 30, dithering: 0 <7> [537.755460] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:506:DDI A/PHY A] [CRTC:504:pipe D] <7> [537.755607] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.enable (expected no, found yes) <7> [537.755780] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.active (expected no, found yes) <7> [537.755940] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in cpu_transcoder (expected -1, found 3) <7> [537.756099] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in lane_count (expected 0, found 4) <7> [537.756250] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in dp_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 7483046/8388608 link 498869/524288) <7> [537.756419] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100) <7> [537.756573] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in framestart_delay (expected 0, found 1) <7> [537.756738] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 2880) <7> [537.756892] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 3040) <7> [537.757041] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 2880) <7> [537.757188] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 3040) <7> [537.757334] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2928) <7> [537.757477] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2960) <7> [537.757621] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1800) <7> [537.757780] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1801) <7> [537.757930] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1803) <7> [537.758082] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1809) <7> [537.758222] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1878) <7> [537.758388] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1878) <7> [537.758541] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 2880) <7> [537.758717] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 3040) <7> [537.758880] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 2880) <7> [537.759036] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 3040) <7> [537.759184] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2928) <7> [537.759332] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2960) <7> [537.759480] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1800) <7> [537.759627] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1801) <7> [537.759784] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1803) <7> [537.759935] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1809) <7> [537.760088] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1878) <7> [537.760251] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1878) <7> [537.760395] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in pixel_multiplier (expected 0, found 1) <7> [537.760553] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2) <7> [537.760713] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8) <7> [537.760864] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in enhanced_framing (expected no, found yes) <7> [537.761010] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in pipe_bpp (expected 0, found 30) <7> [537.761165] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 513820) <7> [537.761315] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 513820) <7> [537.761481] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in port_clock (expected 0, found 540000) <7> [537.761628] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in set_context_latency (expected 0, found 1) <7> [537.761789] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:504:pipe D] fastset requirement not met, forcing full modeset <7> [537.761956] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0xf), mbus joined? no->no <7> [537.762115] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:504:pipe D] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x8 <7> [537.762281] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:388:plane 1D] ddb ( 0 - 0) -> ( 0 - 1953), size 0 -> 1953 <7> [537.762420] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:498:cursor D] ddb ( 0 - 0) -> (1953 - 2048), size 0 -> 95 <7> [537.762557] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:388: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> [537.762708] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:388:plane 1D] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 2, 16, 16, 19, 19, 28, 0, 0, 0, 3, 0 <7> [537.762867] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:388:plane 1D] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 49, 385, 385, 457, 457, 673, 0, 0, 63, 73, 87 <7> [537.763014] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:388:plane 1D] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 55, 425, 425, 504, 504, 742, 0, 0, 64, 82, 88 <7> [537.763174] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:504:pipe D] data rate 2055280 num active planes 1 <7> [537.763332] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 28913 required 2159 qgv_peak_bw: 51200 <7> [537.763493] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 1: max bw 38000 required 2159 qgv_peak_bw: 51200 <7> [537.763647] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 2: max bw 38000 required 2159 qgv_peak_bw: 51200 <7> [537.763903] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 3: max bw 38000 required 2159 qgv_peak_bw: 51200 <7> [537.764058] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] Matching peaks QGV bw: 51200 for required data rate: 2159 <7> [537.764235] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:504:pipe D] min cdclk: 0 kHz -> 256910 kHz <7> [537.764395] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 32114 kHz <7> [537.764549] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Can change cdclk via squashing <7> [537.764921] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 307200 kHz, actual 307200 kHz <7> [537.765082] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0 <7> [537.765266] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:504:pipe D] allocated DPLL 0 <7> [537.765416] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:504:pipe D] reserving DPLL 0 <7> [537.765570] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:504:pipe D] enable: yes [modeset] <7> [537.765729] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB <7> [537.765886] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: D, pipe bpp: 30, dithering: 0 <7> [537.766032] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid> <7> [537.766178] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0 <7> [537.766340] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0 <7> [537.766496] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0 <7> [537.766647] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dp m_n: lanes: 4; data_m: 7483046, data_n: 8388608, link_m: 498869, link_n: 524288, tu: 64 <7> [537.766815] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dp m2_n2: lanes: 4; data_m: 0, data_n: 0, link_m: 0, link_n: 0, tu: 0 <7> [537.766961] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] fec: disabled, enhanced framing: enabled <7> [537.767114] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sdp split: disabled <7> [537.767259] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] psr: enabled, selective update: disabled, panel replay: disabled, selective fetch: disabled <7> [537.767419] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] minimum HBlank: 0 <7> [537.767586] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x4 <7> [537.767794] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] DP SDP: VSC, revision 2, length 8 <7> [537.767981] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pixelformat: RGB <7> [537.768129] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] colorimetry: sRGB <7> [537.768320] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] bpc: 0 <7> [537.768464] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dynamic range: VESA range <7> [537.768630] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] content type: Not defined <7> [537.769004] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 1 <7> [537.769155] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 1 <7> [537.769306] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: yes, vmin: 1878, vmax: 1878, flipline: 1878, pipeline full: 0, guardband: 76 vsync start: 75, vsync end: 69 <7> [537.769451] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1802, vmax vblank: 1802, vmin vtotal: 1878, vmax vtotal: 1878 <7> [537.769596] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0 <7> [537.769770] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "2880x1800": 90 513820 2880 2928 2960 3040 1800 1803 1809 1878 0x48 0xa <7> [537.769933] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "2880x1800": 90 513820 2880 2928 2960 3040 1800 1803 1809 1878 0x48 0xa <7> [537.770075] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=513820, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1801-1878 vs=1803-1809 vt=1878, flags=0xa <7> [537.770229] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "2880x1800": 90 513820 2880 2928 2960 3040 1800 1803 1809 1878 0x40 0xa <7> [537.770374] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=513820, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1801-1878 vs=1803-1809 vt=1878, flags=0xa <7> [537.770526] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 540000, pipe src: 2880x1800+0+0, pixel rate 513820, min cdclk 256910 <7> [537.770670] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 48, ips linetime: 0 <7> [537.770834] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0 <7> [537.771004] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no <7> [537.771150] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0 <7> [537.771302] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cx0pll_hw_state: lane_count: 4, ssc_enabled: no, use_c10: yes, tbt_mode: no <7> [537.771447] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] c10pll_hw_state: fracen: yes, <7> [537.771596] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] quot: 40960, rem: 0, den: 1, <7> [537.771767] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] multiplier: 140, tx_clk_div: 0. <7> [537.771915] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] c10pll_rawhw_state: <7> [537.771815] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off <7> [537.772077] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] tx: 0x10, cmn: 0x21 <7> [537.772219] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[0] = 0xf4, pll[1] = 0x0, pll[2] = 0xf8, pll[3] = 0x0 <7> [537.772366] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[4] = 0x0, pll[5] = 0x0, pll[6] = 0x0, pll[7] = 0x0 <7> [537.772519] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[8] = 0x0, pll[9] = 0x1, pll[10] = 0x0, pll[11] = 0x0 <7> [537.772355] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6 <7> [537.772663] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[12] = 0xa0, pll[13] = 0x0, pll[14] = 0x0, pll[15] = 0x0 <7> [537.772837] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[16] = 0x84, pll[17] = 0x4f, pll[18] = 0xe5, pll[19] = 0x23 <7> [537.772985] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0 <7> [537.772942] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02 <7> [537.773129] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries <7> [537.773273] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000 <7> [537.773443] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000 <7> [537.773588] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000 <7> [537.773750] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000 <7> [537.773895] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000 <7> [537.774043] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000 <7> [537.774187] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7> [537.774331] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7> [537.774491] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7> [537.774638] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000 <7> [537.774793] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0 <7> [537.774944] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:388:plane 1D] fb: [FB:566] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes <7> [537.775092] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0 <7> [537.775238] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0 <7> [537.775380] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:418:plane 2D] fb: [NOFB], visible: no <7> [537.775542] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:448:plane 3D] fb: [NOFB], visible: no <7> [537.775697] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:478:plane 4D] fb: [NOFB], visible: no <7> [537.775845] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:488:plane 5D] fb: [NOFB], visible: no <7> [537.775994] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:498:cursor D] fb: [NOFB], visible: no <7> [537.781777] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off <7> [537.782112] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00 <7> [537.782328] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_2 <7> [537.782516] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_D <7> [537.782729] i915 0000:00:02.0: [drm:dss_pipe_gating_enable_disable [i915]] DSS clock gating enabled for pipe_mask=0x8 (CLKGATE_DIS_DSSDSC=0x2a000000) <7> [537.782894] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Pre changing CDCLK to 307200 kHz, VCO 614400 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0 <7> [537.783077] i915 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [i915]] aud_ts_cdclk set to M=60, N=768 <7> [537.783222] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:506:DDI A/PHY A] <7> [537.783388] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:515:DDI B/PHY B] <7> [537.783528] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:517:DP-MST A] <7> [537.783673] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:518:DP-MST B] <7> [537.783833] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:519:DP-MST C] <7> [537.783975] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:520:DP-MST D] <7> [537.784111] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:533:DDI TC1/PHY TC1] <7> [537.784245] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:535:DP-MST A] <7> [537.784409] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:536:DP-MST B] <7> [537.784548] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:537:DP-MST C] <7> [537.784699] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:538:DP-MST D] <7> [537.784841] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:542:DDI TC2/PHY TC2] <7> [537.784981] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:546:DDI TC3/PHY TC3] <7> [537.785119] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:548:DP-MST A] <7> [537.785254] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:549:DP-MST B] <7> [537.785409] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:550:DP-MST C] <7> [537.785546] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:551:DP-MST D] <7> [537.785679] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:555:DDI TC4/PHY TC4] <7> [537.785842] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:557:DP-MST A] <7> [537.785980] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:558:DP-MST B] <7> [537.786126] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:559:DP-MST C] <7> [537.786263] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:560:DP-MST D] <7> [537.786429] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3 <7> [537.786675] i915 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [i915]] Updating dbuf ratio to 2 (mbus joined: no) <7> [537.786911] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling AUX_A <7> [537.787698] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x8, on? 0) for [CRTC:504:pipe D] <7> [537.787846] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0 <7> [537.789528] i915 0000:00:02.0: [drm:intel_pps_on_unlocked [i915]] [ENCODER:506:DDI A/PHY A] PPS 0 turn panel power on <7> [537.789717] i915 0000:00:02.0: [drm:wait_panel_power_cycle [i915]] [ENCODER:506:DDI A/PHY A] PPS 0 wait for panel power cycle (445 ms remaining) <7> [538.241276] i915 0000:00:02.0: [drm:wait_panel_status [i915]] [ENCODER:506:DDI A/PHY A] PPS 0 mask: 0xb800000f value: 0x00000000 PP_STATUS: 0x08000001 PP_CONTROL: 0x00000060 <7> [538.252108] i915 0000:00:02.0: [drm:wait_panel_status [i915]] Wait complete <7> [538.252798] i915 0000:00:02.0: [drm:intel_pps_on_unlocked [i915]] [ENCODER:506:DDI A/PHY A] PPS 0 wait for panel power on <7> [538.253514] i915 0000:00:02.0: [drm:wait_panel_status [i915]] [ENCODER:506:DDI A/PHY A] PPS 0 mask: 0xb000000f value: 0x80000008 PP_STATUS: 0x9000000a PP_CONTROL: 0x00000063 <7> [538.284875] i915 0000:00:02.0: [drm:icp_irq_handler [i915]] hotplug event received, stat 0x00010000, dig 0x0000008a, pins 0x00000010, long 0x00000010 <7> [538.285429] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] digital hpd on [ENCODER:506:DDI A/PHY A] - long <7> [538.285937] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] Received HPD interrupt on PIN 4 - cnt: 20 <7> [538.286465] i915 0000:00:02.0: [drm:intel_dp_hpd_pulse [i915]] ignoring long hpd on eDP [ENCODER:506:DDI A/PHY A] <7> [538.457152] i915 0000:00:02.0: [drm:wait_panel_status [i915]] Wait complete <7> [538.457971] i915 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [i915]] [ENCODER:506:DDI A/PHY A] PPS 0 turning VDD on <7> [538.458542] i915 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [i915]] [ENCODER:506:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006b <7> [538.461792] i915 0000:00:02.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX A/DDI A/PHY A: DPCD: 14 14 c4 41 00 00 01 c0 02 00 00 00 00 0b 80 <7> [538.462917] i915 0000:00:02.0: [drm:intel_dp_start_link_train [i915]] [CONNECTOR:507:eDP-1][ENCODER:506:DDI A/PHY A][DPRX] Reloading eDP link rates <7> [538.463632] i915 0000:00:02.0: [drm:intel_dp_start_link_train [i915]] [CONNECTOR:507:eDP-1][ENCODER:506:DDI A/PHY A][DPRX] Using LINK_RATE_SET value 07 <7> [538.464730] i915 0000:00:02.0: [drm:intel_dp_set_signal_levels [i915]] [CONNECTOR:507:eDP-1][ENCODER:506:DDI A/PHY A][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0 <7> [538.465558] i915 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [i915]] [CONNECTOR:507:eDP-1][ENCODER:506:DDI A/PHY A][DPRX] Using DP training pattern TPS1 <7> [538.466565] i915 0000:00:02.0: [drm:intel_dp_link_train_phy [i915]] [CONNECTOR:507:eDP-1][ENCODER:506:DDI A/PHY A][DPRX] Clock recovery OK <7> [538.466703] i915 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [i915]] [CONNECTOR:507:eDP-1][ENCODER:506:DDI A/PHY A][DPRX] Using DP training pattern TPS3 <7> [538.468025] i915 0000:00:02.0: [drm:intel_dp_link_train_phy [i915]] [CONNECTOR:507:eDP-1][ENCODER:506:DDI A/PHY A][DPRX] Channel EQ done. DP Training successful <7> [538.468153] i915 0000:00:02.0: [drm:intel_dp_link_train_phy [i915]] [CONNECTOR:507:eDP-1][ENCODER:506:DDI A/PHY A][DPRX] Link Training passed at link rate = 540000, lane count = 4 <7> [538.468770] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe D <7> [538.469032] i915 0000:00:02.0: [drm:intel_edp_backlight_on [i915]] <7> [538.469163] i915 0000:00:02.0: [drm:intel_backlight_enable [i915]] pipe D <7> [538.469365] i915 0000:00:02.0: [drm:intel_backlight_set_pwm_level [i915]] [CONNECTOR:507:eDP-1] set backlight PWM = 13892 <7> [538.480270] i915 0000:00:02.0: [drm:intel_psr_post_plane_update [i915]] Enabling PSR1 <7> [538.481534] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:507:eDP-1] <7> [538.481778] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:504:pipe D] <7> [538.503637] i915 0000:00:02.0: [drm:i915_fifo_underrun_reset_write [i915]] Re-arming FIFO underruns on pipe D <7> [538.513635] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509 <7> [541.033081] i915 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [i915]] [ENCODER:506:DDI A/PHY A] PPS 0 turning VDD off <7> [541.034244] i915 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [i915]] [ENCODER:506:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x00000067 <3> [554.088877] INFO: task prometheus-node:856 blocked for more than 61 seconds. <3> [554.089047] Tainted: G S U W L 7.0.0-rc4-CI_DRM_18160-ga25d8c583e1e+ #1 <3> [554.089054] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. <6> [554.089059] task:prometheus-node state:D stack:0 pid:856 tgid:748 ppid:1 task_flags:0x400040 flags:0x00080000 <6> [554.089070] Call Trace: <6> [554.089075] <TASK> <6> [554.089088] __schedule+0x5bc/0x1dd0 <6> [554.089101] ? lock_acquire+0xc4/0x2f0 <6> [554.089116] ? schedule+0x10e/0x180 <6> [554.089125] ? lock_release+0xd0/0x2b0 <6> [554.089139] schedule+0x3a/0x180 <6> [554.089147] schedule_preempt_disabled+0x15/0x30 <6> [554.089154] __mutex_lock+0x738/0x10e0 <6> [554.089163] ? hwmon_attr_show+0x3e/0x1e0 <6> [554.089184] mutex_lock_nested+0x1b/0x30 <6> [554.089188] ? mutex_lock_nested+0x1b/0x30 <6> [554.089193] hwmon_attr_show+0x3e/0x1e0 <6> [554.089200] ? lock_is_held_type+0xa3/0x130 <6> [554.089214] dev_attr_show+0x1d/0x70 <6> [554.089224] sysfs_kf_seq_show+0xad/0x120 <6> [554.089237] kernfs_seq_show+0x44/0x60 <6> [554.089246] seq_read_iter+0x13c/0x4f0 <6> [554.089264] kernfs_fop_read_iter+0x194/0x210 <6> [554.089270] ? rw_verify_area+0x166/0x1f0 <6> [554.089278] ? find_held_lock+0x31/0x90 <6> [554.089288] vfs_read+0x21e/0x350 <6> [554.089309] ksys_read+0x6f/0xf0 <6> [554.089319] __x64_sys_read+0x19/0x30 <6> [554.089325] x64_sys_call+0x2554/0x26e0 <6> [554.089333] do_syscall_64+0xdd/0x1470 <6> [554.089341] ? __lock_acquire+0x43e/0x2790 <6> [554.089358] ? lock_acquire+0xc4/0x2f0 <6> [554.089367] ? find_held_lock+0x31/0x90 <6> [554.089373] ? do_fcntl+0x24e/0x9d0 <6> [554.089433] ? lock_release+0xd0/0x2b0 <6> [554.089448] ? _raw_spin_unlock+0x22/0x50 <6> [554.089456] ? do_fcntl+0x24e/0x9d0 <6> [554.089463] ? __fget_files+0xcc/0x1c0 <6> [554.089477] ? __x64_sys_fcntl+0xa8/0x130 <6> [554.089486] ? trace_hardirqs_on_prepare+0xe1/0x100 <6> [554.089498] ? do_syscall_64+0x22e/0x1470 <6> [554.089505] ? do_syscall_64+0x22e/0x1470 <6> [554.089513] ? do_syscall_64+0x22e/0x1470 <6> [554.089519] ? do_syscall_64+0x22e/0x1470 <6> [554.089527] ? do_syscall_64+0x22e/0x1470 <6> [554.089535] ? exc_page_fault+0xbd/0x2c0 <6> [554.089547] entry_SYSCALL_64_after_hwframe+0x76/0x7e <6> [554.089674] RIP: 0033:0x40852e <6> [554.089688] RSP: 002b:000000c0003b94f0 EFLAGS: 00000212 ORIG_RAX: 0000000000000000 <6> [554.089697] RAX: ffffffffffffffda RBX: 000000000000000c RCX: 000000000040852e <6> [554.089703] RDX: 0000000000000080 RSI: 000000c000129d80 RDI: 000000000000000c <6> [554.089707] RBP: 000000c0003b9530 R08: 0000000000000000 R09: 0000000000000000 <6> [554.089712] R10: 0000000000000000 R11: 0000000000000212 R12: 0000000000000000 <6> [554.089716] R13: 0000000000000000 R14: 000000c000007880 R15: 000000000000000d <6> [554.089737] </TASK> <3> [554.089767] INFO: task prometheus-node:856 is blocked on a mutex likely owned by task prometheus-node:857. <6> [554.089777] task:prometheus-node state:D stack:0 pid:857 tgid:748 ppid:1 task_flags:0x400040 flags:0x00080000 <6> [554.089789] Call Trace: <6> [554.089794] <TASK> <6> [554.089802] __schedule+0x5bc/0x1dd0 <6> [554.089814] ? lock_acquire+0xc4/0x2f0 <6> [554.089827] ? schedule+0x10e/0x180 <6> [554.089838] ? lock_release+0xd0/0x2b0 <6> [554.089853] schedule+0x3a/0x180 <6> [554.089863] schedule_timeout+0x83/0x130 <6> [554.089872] ? __pfx_process_timeout+0x10/0x10 <6> [554.089886] ? fb_create_modedb+0x8f0/0xb00 <6> [554.089897] io_schedule_timeout+0x57/0x90 <6> [554.089909] __wait_for_common+0xc0/0x1f0 <6> [554.089920] ? __pfx_io_schedule_timeout+0x10/0x10 <6> [554.089935] wait_for_completion_io_timeout+0x1d/0x30 <6> [554.089946] blk_execute_rq+0xd6/0x160 <6> [554.089966] __nvme_submit_sync_cmd+0xbe/0x190 <6> [554.089980] nvme_get_log+0x84/0xc0 <6> [554.089998] nvme_hwmon_read+0x83/0x1d0 <6> [554.090013] hwmon_attr_show+0x66/0x1e0 <6> [554.090022] ? lock_is_held_type+0xa3/0x130 <6> [554.090036] dev_attr_show+0x1d/0x70 <6> [554.090045] sysfs_kf_seq_show+0xad/0x120 <6> [554.090058] kernfs_seq_show+0x44/0x60 <6> [554.090069] seq_read_iter+0x13c/0x4f0 <6> [554.090085] kernfs_fop_read_iter+0x194/0x210 <6> [554.090094] ? rw_verify_area+0x166/0x1f0 <6> [554.090102] ? find_held_lock+0x31/0x90 <6> [554.090114] vfs_read+0x21e/0x350 <6> [554.090132] ksys_read+0x6f/0xf0 <6> [554.090142] __x64_sys_read+0x19/0x30 <6> [554.090149] x64_sys_call+0x2554/0x26e0 <6> [554.090157] do_syscall_64+0xdd/0x1470 <6> [554.090170] ? lock_acquire+0xc4/0x2f0 <6> [554.090181] ? find_held_lock+0x31/0x90 <6> [554.090189] ? do_fcntl+0x24e/0x9d0 <6> [554.090199] ? lock_release+0xd0/0x2b0 <6> [554.090213] ? _raw_spin_unlock+0x22/0x50 <6> [554.090222] ? do_fcntl+0x24e/0x9d0 <6> [554.090231] ? __fget_files+0xcc/0x1c0 <6> [554.090245] ? __x64_sys_fcntl+0xa8/0x130 <6> [554.090256] ? trace_hardirqs_on_prepare+0xe1/0x100 <6> [554.090268] ? do_syscall_64+0x22e/0x1470 <6> [554.090280] ? trace_hardirqs_on_prepare+0xe1/0x100 <6> [554.090292] ? do_syscall_64+0x22e/0x1470 <6> [554.090302] ? do_syscall_64+0x22e/0x1470 <6> [554.090314] entry_SYSCALL_64_after_hwframe+0x76/0x7e <6> [554.090321] RIP: 0033:0x40852e <6> [554.090328] RSP: 002b:000000c0003b74f0 EFLAGS: 00000212 ORIG_RAX: 0000000000000000 <6> [554.090336] RAX: ffffffffffffffda RBX: 0000000000000010 RCX: 000000000040852e <6> [554.090341] RDX: 0000000000000080 RSI: 000000c000129e80 RDI: 0000000000000010 <6> [554.090345] RBP: 000000c0003b7530 R08: 0000000000000000 R09: 0000000000000000 <6> [554.090350] R10: 0000000000000000 R11: 0000000000000212 R12: 0000000000000000 <6> [554.090354] R13: 0000000000000000 R14: 000000c000022700 R15: 0000000000000003 <6> [554.090374] </TASK> <3> [554.090400] INFO: task prometheus-node:2559 blocked for more than 61 seconds. <3> [554.090408] Tainted: G S U W L 7.0.0-rc4-CI_DRM_18160-ga25d8c583e1e+ #1 <3> [554.090415] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. <6> [554.090421] task:prometheus-node state:D stack:0 pid:2559 tgid:748 ppid:1 task_flags:0x400040 flags:0x00080000 <6> [554.090430] Call Trace: <6> [554.090434] <TASK> <6> [554.090442] __schedule+0x5bc/0x1dd0 <6> [554.090451] ? lock_acquire+0xc4/0x2f0 <6> [554.090464] ? schedule+0x10e/0x180 <6> [554.090475] ? lock_release+0xd0/0x2b0 <6> [554.090490] schedule+0x3a/0x180 <6> [554.090500] schedule_preempt_disabled+0x15/0x30 <6> [554.090509] __mutex_lock+0x738/0x10e0 <6> [554.090518] ? hwmon_attr_show+0x3e/0x1e0 <6> [554.090535] mutex_lock_nested+0x1b/0x30 <6> [554.090541] ? mutex_lock_nested+0x1b/0x30 <6> [554.090549] hwmon_attr_show+0x3e/0x1e0 <6> [554.090556] ? lock_is_held_type+0xa3/0x130 <6> [554.090570] dev_attr_show+0x1d/0x70 <6> [554.090579] sysfs_kf_seq_show+0xad/0x120 <6> [554.090591] kernfs_seq_show+0x44/0x60 <6> [554.090602] seq_read_iter+0x13c/0x4f0 <6> [554.090617] kernfs_fop_read_iter+0x194/0x210 <6> [554.090625] ? rw_verify_area+0x166/0x1f0 <6> [554.090632] ? find_held_lock+0x31/0x90 <6> [554.090643] vfs_read+0x21e/0x350 <6> [554.090660] ksys_read+0x6f/0xf0 <6> [554.090670] __x64_sys_read+0x19/0x30 <6> [554.090676] x64_sys_call+0x2554/0x26e0 <6> [554.090683] do_syscall_64+0xdd/0x1470 <6> [554.090691] ? do_syscall_64+0x22e/0x1470 <6> [554.090699] ? __lock_acquire+0x43e/0x2790 <6> [554.090711] ? __lock_acquire+0x43e/0x2790 <6> [554.090723] ? __lock_acquire+0x43e/0x2790 <6> [554.090734] ? __lock_acquire+0x43e/0x2790 <6> [554.090746] ? lock_acquire+0xc4/0x2f0 <6> [554.090759] ? lock_acquire+0xc4/0x2f0 <6> [554.090769] ? find_held_lock+0x31/0x90 <6> [554.090777] ? do_fcntl+0x24e/0x9d0 <6> [554.090785] ? lock_release+0xd0/0x2b0 <6> [554.090799] ? _raw_spin_unlock+0x22/0x50 <6> [554.090808] ? do_fcntl+0x24e/0x9d0 <6> [554.090816] ? __fget_files+0xcc/0x1c0 <6> [554.090830] ? __x64_sys_fcntl+0xa8/0x130 <6> [554.090839] ? trace_hardirqs_on_prepare+0xe1/0x100 <6> [554.090851] ? do_syscall_64+0x22e/0x1470 <6> [554.090868] ? __fget_files+0xcc/0x1c0 <6> [554.090882] ? __x64_sys_fcntl+0xa8/0x130 <6> [554.090891] ? trace_hardirqs_on_prepare+0xe1/0x100 <6> [554.090902] ? do_syscall_64+0x22e/0x1470 <6> [554.090911] ? do_syscall_64+0x22e/0x1470 <6> [554.090918] ? do_syscall_64+0x22e/0x1470 <6> [554.090926] ? exc_page_fault+0xbd/0x2c0 <6> [554.090938] entry_SYSCALL_64_after_hwframe+0x76/0x7e <6> [554.090943] RIP: 0033:0x40852e <6> [554.090948] RSP: 002b:000000c0001754f0 EFLAGS: 00000212 ORIG_RAX: 0000000000000000 <6> [554.090954] RAX: ffffffffffffffda RBX: 0000000000000015 RCX: 000000000040852e <6> [554.090958] RDX: 0000000000000080 RSI: 000000c000129c80 RDI: 0000000000000015 <6> [554.090963] RBP: 000000c000175530 R08: 0000000000000000 R09: 0000000000000000 <6> [554.090967] R10: 0000000000000000 R11: 0000000000000212 R12: 0000000000000000 <6> [554.090970] R13: 0000000000000000 R14: 000000c00036a1c0 R15: 0000000000000035 <6> [554.090989] </TASK> <3> [554.091009] INFO: task prometheus-node:2559 is blocked on a mutex likely owned by task prometheus-node:857. <6> [554.091015] task:prometheus-node state:D stack:0 pid:857 tgid:748 ppid:1 task_flags:0x400040 flags:0x00080000 <6> [554.091021] Call Trace: <6> [554.091024] <TASK> <6> [554.091032] __schedule+0x5bc/0x1dd0 <6> [554.091040] ? lock_acquire+0xc4/0x2f0 <6> [554.091052] ? schedule+0x10e/0x180 <6> [554.091062] ? lock_release+0xd0/0x2b0 <6> [554.091076] schedule+0x3a/0x180 <6> [554.091085] schedule_timeout+0x83/0x130 <6> [554.091093] ? __pfx_process_timeout+0x10/0x10 <6> [554.091105] ? fb_create_modedb+0x8f0/0xb00 <6> [554.091114] io_schedule_timeout+0x57/0x90 <6> [554.091124] __wait_for_common+0xc0/0x1f0 <6> [554.091135] ? __pfx_io_schedule_timeout+0x10/0x10 <6> [554.091149] wait_for_completion_io_timeout+0x1d/0x30 <6> [554.091159] blk_execute_rq+0xd6/0x160 <6> [554.091177] __nvme_submit_sync_cmd+0xbe/0x190 <6> [554.091189] nvme_get_log+0x84/0xc0 <6> [554.091206] nvme_hwmon_read+0x83/0x1d0 <6> [554.091220] hwmon_attr_show+0x66/0x1e0 <6> [554.091227] ? lock_is_held_type+0xa3/0x130 <6> [554.091240] dev_attr_show+0x1d/0x70 <6> [554.091248] sysfs_kf_seq_show+0xad/0x120 <6> [554.091260] kernfs_seq_show+0x44/0x60 <6> [554.091270] seq_read_iter+0x13c/0x4f0 <6> [554.091285] kernfs_fop_read_iter+0x194/0x210 <6> [554.091293] ? rw_verify_area+0x166/0x1f0 <6> [554.091300] ? find_held_lock+0x31/0x90 <6> [554.091310] vfs_read+0x21e/0x350 <6> [554.091327] ksys_read+0x6f/0xf0 <6> [554.091336] __x64_sys_read+0x19/0x30 <6> [554.091342] x64_sys_call+0x2554/0x26e0 <6> [554.091350] do_syscall_64+0xdd/0x1470 <6> [554.091361] ? lock_acquire+0xc4/0x2f0 <6> [554.091371] ? find_held_lock+0x31/0x90 <6> [554.091391] ? do_fcntl+0x24e/0x9d0 <6> [554.091400] ? lock_release+0xd0/0x2b0 <6> [554.091413] ? _raw_spin_unlock+0x22/0x50 <6> [554.091423] ? do_fcntl+0x24e/0x9d0 <6> [554.091430] ? __fget_files+0xcc/0x1c0 <6> [554.091444] ? __x64_sys_fcntl+0xa8/0x130 <6> [554.091453] ? trace_hardirqs_on_prepare+0xe1/0x100 <6> [554.091464] ? do_syscall_64+0x22e/0x1470 <6> [554.091476] ? trace_hardirqs_on_prepare+0xe1/0x100 <6> [554.091486] ? do_syscall_64+0x22e/0x1470 <6> [554.091496] ? do_syscall_64+0x22e/0x1470 <6> [554.091507] entry_SYSCALL_64_after_hwframe+0x76/0x7e <6> [554.091512] RIP: 0033:0x40852e <6> [554.091517] RSP: 002b:000000c0003b74f0 EFLAGS: 00000212 ORIG_RAX: 0000000000000000 <6> [554.091523] RAX: ffffffffffffffda RBX: 0000000000000010 RCX: 000000000040852e <6> [554.091526] RDX: 0000000000000080 RSI: 000000c000129e80 RDI: 0000000000000010 <6> [554.091529] RBP: 000000c0003b7530 R08: 0000000000000000 R09: 0000000000000000 <6> [554.091533] R10: 0000000000000000 R11: 0000000000000212 R12: 0000000000000000 <6> [554.091537] R13: 0000000000000000 R14: 000000c000022700 R15: 0000000000000003 <6> [554.091557] </TASK> <4> [554.091589] Showing all locks held in the system: <4> [554.091603] 1 lock held by khungtaskd/156: <4> [554.091607] #0: ffffffff835c5be0 (rcu_read_lock){....}-{1:2}, at: debug_show_all_locks+0x37/0x220 <4> [554.091640] 5 locks held by prometheus-node/748: <4> [554.091644] #0: ffff888131206b38 (&f->f_pos_lock){+.+.}-{3:3}, at: fdget_pos+0x81/0xd0 <4> [554.091661] #1: ffff8881097e8ec0 (&p->lock){+.+.}-{3:3}, at: seq_read_iter+0x65/0x4f0 <4> [554.091679] #2: ffff8881019de888 (&of->mutex){+.+.}-{3:3}, at: kernfs_seq_start+0x26/0x140 <4> [554.091698] #3: ffff888105763440 (kn->active#122){.+.+}-{0:0}, at: kernfs_seq_start+0x3b/0x140 <4> [554.091718] #4: ffff888104afd550 (&hwdev->lock){+.+.}-{3:3}, at: hwmon_attr_show+0x3e/0x1e0 <4> [554.091734] 5 locks held by prometheus-node/855: <4> [554.091737] #0: ffff888117cd4d38 (&f->f_pos_lock){+.+.}-{3:3}, at: fdget_pos+0x81/0xd0 <4> [554.091752] #1: ffff8881097e81f0 (&p->lock){+.+.}-{3:3}, at: seq_read_iter+0x65/0x4f0 <4> [554.091769] #2: ffff888126bc0e88 (&of->mutex){+.+.}-{3:3}, at: kernfs_seq_start+0x26/0x140 <4> [554.091784] #3: ffff888105763440 (kn->active#122){.+.+}-{0:0}, at: kernfs_seq_start+0x3b/0x140 <4> [554.091802] #4: ffff888104afd550 (&hwdev->lock){+.+.}-{3:3}, at: hwmon_attr_show+0x3e/0x1e0 <4> [554.091816] 5 locks held by prometheus-node/856: <4> [554.091821] #0: ffff8881d9637b38 (&f->f_pos_lock){+.+.}-{3:3}, at: fdget_pos+0x81/0xd0 <4> [554.091835] #1: ffff8881097e9a48 (&p->lock){+.+.}-{3:3}, at: seq_read_iter+0x65/0x4f0 <4> [554.091851] #2: ffff88815cd9d688 (&of->mutex){+.+.}-{3:3}, at: kernfs_seq_start+0x26/0x140 <4> [554.091867] #3: ffff888105763440 (kn->active#122){.+.+}-{0:0}, at: kernfs_seq_start+0x3b/0x140 <4> [554.091884] #4: ffff888104afd550 (&hwdev->lock){+.+.}-{3:3}, at: hwmon_attr_show+0x3e/0x1e0 <4> [554.091899] 6 locks held by prometheus-node/857: <4> [554.091903] #0: ffff888128d87338 (&f->f_pos_lock){+.+.}-{3:3}, at: fdget_pos+0x81/0xd0 <4> [554.091917] #1: ffff8881097e8710 (&p->lock){+.+.}-{3:3}, at: seq_read_iter+0x65/0x4f0 <4> [554.091934] #2: ffff88815cd9a688 (&of->mutex){+.+.}-{3:3}, at: kernfs_seq_start+0x26/0x140 <4> [554.091950] #3: ffff888105763440 (kn->active#122){.+.+}-{0:0}, at: kernfs_seq_start+0x3b/0x140 <4> [554.091967] #4: ffff888104afd550 (&hwdev->lock){+.+.}-{3:3}, at: hwmon_attr_show+0x3e/0x1e0 <4> [554.091981] #5: ffff888105fe2db8 (&data->read_lock){+.+.}-{3:3}, at: nvme_hwmon_read+0x5f/0x1d0 <4> [554.091996] 5 locks held by prometheus-node/2368: <4> [554.092000] #0: ffff8881278fb138 (&f->f_pos_lock){+.+.}-{3:3}, at: fdget_pos+0x81/0xd0 <4> [554.092015] #1: ffff8881097e8338 (&p->lock){+.+.}-{3:3}, at: seq_read_iter+0x65/0x4f0 <4> [554.092031] #2: ffff888126bc6e88 (&of->mutex){+.+.}-{3:3}, at: kernfs_seq_start+0x26/0x140 <4> [554.092046] #3: ffff888105763440 (kn->active#122){.+.+}-{0:0}, at: kernfs_seq_start+0x3b/0x140 <4> [554.092064] #4: ffff888104afd550 (&hwdev->lock){+.+.}-{3:3}, at: hwmon_attr_show+0x3e/0x1e0 <4> [554.092078] 5 locks held by prometheus-node/2559: <4> [554.092082] #0: ffff888127818338 (&f->f_pos_lock){+.+.}-{3:3}, at: fdget_pos+0x81/0xd0 <4> [554.092096] #1: ffff8881097e9cd8 (&p->lock){+.+.}-{3:3}, at: seq_read_iter+0x65/0x4f0 <4> [554.092112] #2: ffff88815cd9a088 (&of->mutex){+.+.}-{3:3}, at: kernfs_seq_start+0x26/0x140 <4> [554.092128] #3: ffff888105763440 (kn->active#122){.+.+}-{0:0}, at: kernfs_seq_start+0x3b/0x140 <4> [554.092146] #4: ffff888104afd550 (&hwdev->lock){+.+.}-{3:3}, at: hwmon_attr_show+0x3e/0x1e0 <4> [554.092158] 5 locks held by prometheus-node/2567: <4> [554.092161] #0: ffff888131207938 (&f->f_pos_lock){+.+.}-{3:3}, at: fdget_pos+0x81/0xd0 <4> [554.092173] #1: ffff8881097e8d78 (&p->lock){+.+.}-{3:3}, at: seq_read_iter+0x65/0x4f0 <4> [554.092186] #2: ffff8881019dfa88 (&of->mutex){+.+.}-{3:3}, at: kernfs_seq_start+0x26/0x140 <4> [554.092200] #3: ffff888105763110 (kn->active#122){.+.+}-{0:0}, at: kernfs_seq_start+0x3b/0x140 <4> [554.092216] #4: ffff888104afd550 (&hwdev->lock){+.+.}-{3:3}, at: hwmon_attr_show_string+0x42/0x1b0 <4> [554.092229] 5 locks held by prometheus-node/2605: <4> [554.092232] #0: ffff8881278f8d38 (&f->f_pos_lock){+.+.}-{3:3}, at: fdget_pos+0x81/0xd0 <4> [554.092245] #1: ffff8881097e8c30 (&p->lock){+.+.}-{3:3}, at: seq_read_iter+0x65/0x4f0 <4> [554.092258] #2: ffff888126bc2688 (&of->mutex){+.+.}-{3:3}, at: kernfs_seq_start+0x26/0x140 <4> [554.092271] #3: ffff888105763440 (kn->active#122){.+.+}-{0:0}, at: kernfs_seq_start+0x3b/0x140 <4> [554.092287] #4: ffff888104afd550 (&hwdev->lock){+.+.}-{3:3}, at: hwmon_attr_show+0x3e/0x1e0 <4> [554.092301] 4 locks held by rs:main Q:Reg/890: <4> [554.092307] 3 locks held by dmesg/2316: <4> [554.092311] 3 locks held by igt_runner/2333: <4> [554.092317] 1 lock held by kms_vblank/2582: <4> [554.092320] #0: ffff888125c85a68 (&gt->reset.mutex){+.+.}-{3:3}, at: i915_request_wait_timeout+0x94/0x6d0 [i915] <4> [554.092808] <4> [554.092811] ============================================= <5> [558.515377] Fence expiration time out i915-0000:00:02.0:0000:00:02.0:4! <7> [561.397980] i915 0000:00:02.0: [drm:intel_guc_context_reset_process_msg [i915]] GT0: GUC: Got context reset notification: 0x1004 on rcs0, exiting = no, banned = no <6> [561.444636] i915 0000:00:02.0: [drm] GPU HANG: ecode 12:0:00000000 <6> [561.444640] i915 0000:00:02.0: [drm] GPU error state saved to /sys/class/drm/card0/error <7> [561.445598] i915 0000:00:02.0: [drm:error_state_write [i915]] Resetting error state <7> [561.446071] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:504:pipe D] <7> [561.446164] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.enable (expected yes, found no) <7> [561.446335] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.active (expected yes, found no) <7> [561.446483] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in cpu_transcoder (expected 3, found -1) <7> [561.446628] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in lane_count (expected 4, found 0) <7> [561.446769] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in dp_m_n (expected tu 64 data 7483046/8388608 link 498869/524288, found tu 0, data 0/0 link 0/0) <7> [561.446912] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000) <7> [561.447062] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in framestart_delay (expected 1, found 0) <7> [561.447208] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 2880, found 0) <7> [561.447378] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 3040, found 0) <7> [561.447533] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 2880, found 0) <7> [561.447675] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 3040, found 0) <7> [561.447817] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2928, found 0) <7> [561.447955] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2960, found 0) <7> [561.448094] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1800, found 0) <7> [561.448236] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1801, found 0) <7> [561.448391] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1803, found 0) <7> [561.448531] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1809, found 0) <7> [561.448668] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1878, found 0) <7> [561.448806] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1878, found 0) <7> [561.448943] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 2880, found 0) <7> [561.449088] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 3040, found 0) <7> [561.449228] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 2880, found 0) <7> [561.449442] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 3040, found 0) <7> [561.449651] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2928, found 0) <7> [561.449851] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2960, found 0) <7> [561.450065] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1800, found 0) <7> [561.450258] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1801, found 0) <7> [561.450409] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1803, found 0) <7> [561.450559] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1809, found 0) <7> [561.450702] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1878, found 0) <7> [561.450845] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1878, found 0) <7> [561.450990] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in pixel_multiplier (expected 1, found 0) <7> [561.451150] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0) <7> [561.451312] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0) <7> [561.451461] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in enhanced_framing (expected yes, found no) <7> [561.451641] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in pipe_bpp (expected 30, found 0) <7> [561.451849] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 513820, found 0) <7> [561.452057] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 513820, found 0) <7> [561.452285] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in port_clock (expected 540000, found 0) <7> [561.452451] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in set_context_latency (expected 1, found 0) <7> [561.452603] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:504:pipe D] fastset requirement not met, forcing full modeset <7> [561.452752] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:504:pipe D] releasing DPLL 0 <7> [561.452929] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0xf), mbus joined? no->no <7> [561.453070] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:504:pipe D] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x8 -> 0x0 <7> [561.453232] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:388:plane 1D] ddb ( 0 - 1953) -> ( 0 - 0), size 1953 -> 0 <7> [561.453413] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:498:cursor D] ddb (1953 - 2048) -> ( 0 - 0), size 95 -> 0 <7> [561.453612] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:388: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> [561.453811] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:388:plane 1D] lines 2, 16, 16, 19, 19, 28, 0, 0, 0, 3, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 <7> [561.454005] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:388:plane 1D] blocks 49, 385, 385, 457, 457, 673, 0, 0, 63, 73, 87 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 <7> [561.454162] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:388:plane 1D] min_ddb 55, 425, 425, 504, 504, 742, 0, 0, 64, 82, 88 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 <7> [561.454336] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:504:pipe D] data rate 0 num active planes 0 <7> [561.454492] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 28913 required 0 qgv_peak_bw: 51200 <7> [561.454647] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 1: max bw 38000 required 0 qgv_peak_bw: 51200 <7> [561.454825] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 2: max bw 38000 required 0 qgv_peak_bw: 51200 <7> [561.455047] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 3: max bw 38000 required 0 qgv_peak_bw: 51200 <7> [561.455271] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] Matching peaks QGV bw: 51200 for required data rate: 0 <7> [561.455504] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:504:pipe D] min cdclk: 256910 kHz -> 0 kHz <7> [561.455683] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 32114 kHz -> 0 kHz <7> [561.455844] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Can change cdclk via squashing <7> [561.455995] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 172800 kHz, actual 172800 kHz <7> [561.456150] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0 <7> [561.456336] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:504:pipe D] enable: no [modeset] <7> [561.456502] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:388:plane 1D] fb: [NOFB], visible: no <7> [561.456689] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:418:plane 2D] fb: [NOFB], visible: no <7> [561.456909] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:448:plane 3D] fb: [NOFB], visible: no <7> [561.457128] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:478:plane 4D] fb: [NOFB], visible: no <7> [561.457345] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:488:plane 5D] fb: [NOFB], visible: no <7> [561.457503] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:498:cursor D] fb: [NOFB], visible: no <7> [561.457703] i915 0000:00:02.0: [drm:intel_psr_disable_locked [i915]] Disabling PSR1 <7> [561.464386] i915 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [i915]] [ENCODER:506:DDI A/PHY A] PPS 0 turning VDD on <7> [561.464641] i915 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [i915]] [ENCODER:506:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006f <7> [561.465264] i915 0000:00:02.0: [drm:intel_edp_backlight_off [i915]] <7> [561.672718] i915 0000:00:02.0: [drm:intel_backlight_set_pwm_level [i915]] [CONNECTOR:507:eDP-1] set backlight PWM = 0 <7> [561.673685] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe D <7> [561.687517] i915 0000:00:02.0: [drm:intel_pps_off_unlocked [i915]] [ENCODER:506:DDI A/PHY A] PPS 0 turn panel power off <7> [561.688059] i915 0000:00:02.0: [drm:intel_pps_off_unlocked [i915]] [ENCODER:506:DDI A/PHY A] PPS 0 wait for panel power off time <7> [561.688562] i915 0000:00:02.0: [drm:wait_panel_status [i915]] [ENCODER:506:DDI A/PHY A] PPS 0 mask: 0xb0000000 value: 0x00000000 PP_STATUS: 0xa0000002 PP_CONTROL: 0x00000060 <7> [561.740628] i915 0000:00:02.0: [drm:icp_irq_handler [i915]] hotplug event received, stat 0x00010000, dig 0x0000008a, pins 0x00000010, long 0x00000010 <7> [561.741179] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] digital hpd on [ENCODER:506:DDI A/PHY A] - long <7> [561.741684] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] Received HPD interrupt on PIN 4 - cnt: 10 <7> [561.742272] i915 0000:00:02.0: [drm:intel_dp_hpd_pulse [i915]] ignoring long hpd on eDP [ENCODER:506:DDI A/PHY A] <7> [561.744673] i915 0000:00:02.0: [drm:wait_panel_status [i915]] Wait complete <7> [561.745172] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x8, on? 1) for [CRTC:504:pipe D] <7> [561.746923] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0 <7> [561.747267] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling AUX_A <7> [561.747569] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:506:DDI A/PHY A] <7> [561.747844] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:515:DDI B/PHY B] <7> [561.748286] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:517:DP-MST A] <7> [561.748564] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:518:DP-MST B] <7> [561.748891] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:519:DP-MST C] <7> [561.749077] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:520:DP-MST D] <7> [561.749247] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:533:DDI TC1/PHY TC1] <7> [561.749411] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:535:DP-MST A] <7> [561.749626] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:536:DP-MST B] <7> [561.750055] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:537:DP-MST C] <7> [561.750621] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:538:DP-MST D] <7> [561.751115] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:542:DDI TC2/PHY TC2] <7> [561.751812] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:546:DDI TC3/PHY TC3] <7> [561.752290] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:548:DP-MST A] <7> [561.752743] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:549:DP-MST B] <7> [561.753201] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:550:DP-MST C] <7> [561.753688] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:551:DP-MST D] <7> [561.754168] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:555:DDI TC4/PHY TC4] <7> [561.754595] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:557:DP-MST A] <7> [561.754832] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:558:DP-MST B] <7> [561.755058] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:559:DP-MST C] <7> [561.755297] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:560:DP-MST D] <7> [561.755530] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:507:eDP-1] <7> [561.755858] i915 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [i915]] Updating dbuf ratio to 2 (mbus joined: no) <7> [561.756122] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1 <7> [561.756501] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_D <7> [561.756758] i915 0000:00:02.0: [drm:dss_pipe_gating_enable_disable [i915]] DSS clock gating disabled for pipe_mask=0x8 (CLKGATE_DIS_DSSDSC=0xaa000000) <7> [561.757041] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_2 <7> [561.757229] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:504:pipe D] <7> [561.757438] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Post changing CDCLK to 172800 kHz, VCO 614400 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0 <7> [561.757626] i915 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [i915]] aud_ts_cdclk set to M=60, N=432 <6> [561.758494] [IGT] kms_vblank: finished subtest pipe-D-eDP-1, SUCCESS <7> [561.758933] i915 0000:00:02.0: [drm:error_state_write [i915]] Resetting error state <6> [561.759354] [IGT] kms_vblank: finished subtest query-forked-hang, SUCCESS <6> [561.759923] [IGT] kms_vblank: exiting, ret=0 <7> [561.760261] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc] <7> [561.775314] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off <7> [561.775879] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6 <7> [561.776401] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02 <7> [561.776998] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on <7> [561.783421] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on <7> [561.784870] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:507:eDP-1] Limiting target display pipe bpp to 30 (EDID bpp 30, max requested bpp 36, max platform bpp 36) <7> [561.785107] i915 0000:00:02.0: [drm:intel_dp_compute_config_limits [i915]] [ENCODER:506:DDI A/PHY A][CRTC:150:pipe A] DP link limits: pixel clock 513820 kHz DSC off max lanes 4 max rate 540000 max pipe_bpp 30 min link_bpp 18.0000 max link_bpp 30.0000 <7> [561.785327] i915 0000:00:02.0: [drm:intel_dp_compute_link_config [i915]] DP lane count 4 clock 540000 bpp input 30 compressed 0.0000 HDR no link rate required 1926825 available 2160000 <7> [561.785527] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:150:pipe A] hw max bpp: 30, pipe bpp: 30, dithering: 0 <7> [561.785737] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:506:DDI A/PHY A] [CRTC:150:pipe A] <7> [561.785930] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.enable (expected no, found yes) <7> [561.786130] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.active (expected no, found yes) <7> [561.786296] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0) <7> [561.786457] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in lane_count (expected 0, found 4) <7> [561.786603] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in dp_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 7483046/8388608 link 498869/524288) <7> [561.786745] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100) <7> [561.786889] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1) <7> [561.787035] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 2880) <7> [561.787177] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 3040) <7> [561.787326] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 2880) <7> [561.787469] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 3040) <7> [561.787609] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2928) <7> [561.787749] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2960) <7> [561.787887] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1800) <7> [561.788029] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1801) <7> [561.788171] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1803) <7> [561.788321] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1809) <7> [561.788462] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1878) <7> [561.788600] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1878) <7> [561.788746] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 2880) <7> [561.788886] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 3040) <7> [561.789025] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 2880) <7> [561.789166] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 3040) <7> [561.789314] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2928) <7> [561.789456] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2960) <7> [561.789597] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1800) <7> [561.789737] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1801) <7> [561.789875] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1803) <7> [561.790013] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1809) <7> [561.790152] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1878) <7> [561.790376] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1878) <7> [561.790521] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1) <7> [561.790665] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2) <7> [561.790805] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8) <7> [561.790948] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in enhanced_framing (expected no, found yes) <7> [561.791090] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 30) <7> [561.791237] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 513820) <7> [561.791380] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 513820) <7> [561.791519] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in port_clock (expected 0, found 540000) <7> [561.791657] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in set_context_latency (expected 0, found 1) <7> [561.791796] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:150:pipe A] fastset requirement not met, forcing full modeset <7> [561.791972] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0xf (total dbuf slices 0xf), mbus joined? no->yes <7> [561.792112] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:150:pipe A] dbuf slices 0x0 -> 0xf, ddb (0 - 0) -> (0 - 4096), active pipes 0x0 -> 0x1 <7> [561.792256] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:34:plane 1A] ddb ( 0 - 0) -> ( 0 - 4001), size 0 -> 4001 <7> [561.792396] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:144:cursor A] ddb ( 0 - 0) -> (4001 - 4096), size 0 -> 95 <7> [561.792531] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34: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> [561.792667] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 2, 16, 16, 19, 19, 28, 0, 0, 0, 3, 0 <7> [561.792802] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 49, 385, 385, 457, 457, 673, 0, 0, 63, 73, 87 <7> [561.792936] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 55, 425, 425, 504, 504, 742, 0, 0, 64, 82, 88 <7> [561.793088] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:150:pipe A] data rate 2055280 num active planes 1 <7> [561.793240] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 28913 required 2159 qgv_peak_bw: 51200 <7> [561.793387] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 1: max bw 38000 required 2159 qgv_peak_bw: 51200 <7> [561.793530] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 2: max bw 38000 required 2159 qgv_peak_bw: 51200 <7> [561.793670] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 3: max bw 38000 required 2159 qgv_peak_bw: 51200 <7> [561.793809] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] Matching peaks QGV bw: 51200 for required data rate: 2159 <7> [561.793949] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:150:pipe A] min cdclk: 0 kHz -> 256910 kHz <7> [561.794101] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 32114 kHz <7> [561.794252] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Can change cdclk via squashing <7> [561.794399] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 307200 kHz, actual 307200 kHz <7> [561.794542] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0 <7> [561.794693] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:150:pipe A] allocated DPLL 0 <7> [561.794839] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:150:pipe A] reserving DPLL 0 <7> [561.794986] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:150:pipe A] enable: yes [modeset] <7> [561.795134] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB <7> [561.795287] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 30, dithering: 0 <7> [561.795432] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid> <7> [561.795574] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0 <7> [561.795721] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0 <7> [561.795863] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0 <7> [561.796002] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dp m_n: lanes: 4; data_m: 7483046, data_n: 8388608, link_m: 498869, link_n: 524288, tu: 64 <7> [561.796145] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dp m2_n2: lanes: 4; data_m: 0, data_n: 0, link_m: 0, link_n: 0, tu: 0 <7> [561.796297] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] fec: disabled, enhanced framing: enabled <7> [561.796440] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sdp split: disabled <7> [561.796579] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] psr: enabled, selective update: enabled, panel replay: disabled, selective fetch: enabled <7> [561.796717] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] minimum HBlank: 0 <7> [561.796856] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x4 <7> [561.796995] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] DP SDP: VSC, revision 4, length 14 <7> [561.797137] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pixelformat: RGB <7> [561.797284] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] colorimetry: sRGB <7> [561.797426] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] bpc: 0 <7> [561.797565] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dynamic range: VESA range <7> [561.797712] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] content type: Not defined <7> [561.797859] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 1 <7> [561.797997] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 1 <7> [561.798138] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: yes, vmin: 1878, vmax: 1878, flipline: 1878, pipeline full: 0, guardband: 76 vsync start: 75, vsync end: 69 <7> [561.798311] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1802, vmax vblank: 1802, vmin vtotal: 1878, vmax vtotal: 1878 <7> [561.798454] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0 <7> [561.798594] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "2880x1800": 90 513820 2880 2928 2960 3040 1800 1803 1809 1878 0x48 0xa <7> [561.798735] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "2880x1800": 90 513820 2880 2928 2960 3040 1800 1803 1809 1878 0x48 0xa <7> [561.798875] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=513820, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1801-1878 vs=1803-1809 vt=1878, flags=0xa <7> [561.799015] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "2880x1800": 90 513820 2880 2928 2960 3040 1800 1803 1809 1878 0x40 0xa <7> [561.799154] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=513820, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1801-1878 vs=1803-1809 vt=1878, flags=0xa <7> [561.799303] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 540000, pipe src: 2880x1800+0+0, pixel rate 513820, min cdclk 256910 <7> [561.799444] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 48, ips linetime: 0 <7> [561.799584] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0 <7> [561.799724] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no <7> [561.799862] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0 <7> [561.800000] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cx0pll_hw_state: lane_count: 4, ssc_enabled: no, use_c10: yes, tbt_mode: no <7> [561.800140] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] c10pll_hw_state: fracen: yes, <7> [561.800287] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] quot: 40960, rem: 0, den: 1, <7> [561.800427] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] multiplier: 140, tx_clk_div: 0. <7> [561.800568] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] c10pll_rawhw_state: <7> [561.800710] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] tx: 0x10, cmn: 0x21 <7> [561.800850] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[0] = 0xf4, pll[1] = 0x0, pll[2] = 0xf8, pll[3] = 0x0 <7> [561.800991] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[4] = 0x0, pll[5] = 0x0, pll[6] = 0x0, pll[7] = 0x0 <7> [561.801131] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[8] = 0x0, pll[9] = 0x1, pll[10] = 0x0, pll[11] = 0x0 <7> [561.801277] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[12] = 0xa0, pll[13] = 0x0, pll[14] = 0x0, pll[15] = 0x0 <7> [561.801420] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[16] = 0x84, pll[17] = 0x4f, pll[18] = 0xe5, pll[19] = 0x23 <7> [561.801559] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0 <7> [561.801701] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries <7> [561.801842] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000 <7> [561.801983] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000 <7> [561.802124] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000 <7> [561.802271] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000 <7> [561.802416] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000 <7> [561.802557] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000 <7> [561.802699] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7> [561.802837] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7> [561.802975] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7> [561.803113] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000 <7> [561.803255] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0 <7> [561.803394] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:plane 1A] fb: [FB:508] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes <7> [561.803531] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0 <7> [561.803673] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0 <7> [561.803814] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:64:plane 2A] fb: [NOFB], visible: no <7> [561.803956] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:94:plane 3A] fb: [NOFB], visible: no <7> [561.804095] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:124:plane 4A] fb: [NOFB], visible: no <7> [561.804237] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:134:plane 5A] fb: [NOFB], visible: no <7> [561.804376] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:144:cursor A] fb: [NOFB], visible: no <7> [561.804612] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off <7> [561.804919] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00 <7> [561.805137] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_A <7> [561.805366] i915 0000:00:02.0: [drm:dss_pipe_gating_enable_disable [i915]] DSS clock gating enabled for pipe_mask=0x1 (CLKGATE_DIS_DSSDSC=0xa8000000) <7> [561.805535] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Pre changing CDCLK to 307200 kHz, VCO 614400 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0 <7> [561.805720] i915 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [i915]] aud_ts_cdclk set to M=60, N=768 <7> [561.805866] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:506:DDI A/PHY A] <7> [561.806007] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:515:DDI B/PHY B] <7> [561.806144] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:517:DP-MST A] <7> [561.806300] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:518:DP-MST B] <7> [561.806441] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:519:DP-MST C] <7> [561.806576] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:520:DP-MST D] <7> [561.806718] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:533:DDI TC1/PHY TC1] <7> [561.806862] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:535:DP-MST A] <7> [561.807001] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:536:DP-MST B] <7> [561.807140] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:537:DP-MST C] <7> [561.807284] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:538:DP-MST D] <7> [561.807423] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:542:DDI TC2/PHY TC2] <7> [561.807558] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:546:DDI TC3/PHY TC3] <7> [561.807693] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:548:DP-MST A] <7> [561.807831] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:549:DP-MST B] <7> [561.807966] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:550:DP-MST C] <7> [561.808102] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:551:DP-MST D] <7> [561.808241] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:555:DDI TC4/PHY TC4] <7> [561.808378] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:557:DP-MST A] <7> [561.808513] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:558:DP-MST B] <7> [561.808649] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:559:DP-MST C] <7> [561.808785] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:560:DP-MST D] <7> [561.808951] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0xf <7> [561.809195] i915 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [i915]] Changing mbus joined: no -> yes (pipe: ) <7> [561.809354] i915 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [i915]] Updating dbuf ratio to 4 (mbus joined: yes) <7> [561.809572] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling AUX_A <7> [561.810947] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:150:pipe A] <7> [561.811147] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0 <7> [561.813004] i915 0000:00:02.0: [drm:intel_pps_on_unlocked [i915]] [ENCODER:506:DDI A/PHY A] PPS 0 turn panel power on <7> [561.813188] i915 0000:00:02.0: [drm:wait_panel_power_cycle [i915]] [ENCODER:506:DDI A/PHY A] PPS 0 wait for panel power cycle (432 ms remaining) <7> [562.248700] i915 0000:00:02.0: [drm:wait_panel_status [i915]] [ENCODER:506:DDI A/PHY A] PPS 0 mask: 0xb800000f value: 0x00000000 PP_STATUS: 0x08000001 PP_CONTROL: 0x00000060 <4> [562.286442] nvme nvme0: I/O tag 24 (8018) QID 0 timeout, completion polled <7> [562.289329] i915 0000:00:02.0: [drm:wait_panel_status [i915]] Wait complete <7> [562.289997] i915 0000:00:02.0: [drm:intel_pps_on_unlocked [i915]] [ENCODER:506:DDI A/PHY A] PPS 0 wait for panel power on <7> [562.290692] i915 0000:00:02.0: [drm:wait_panel_status [i915]] [ENCODER:506:DDI A/PHY A] PPS 0 mask: 0xb000000f value: 0x80000008 PP_STATUS: 0x9000000a PP_CONTROL: 0x00000063 <7> [562.322117] i915 0000:00:02.0: [drm:icp_irq_handler [i915]] hotplug event received, stat 0x00010000, dig 0x0000008a, pins 0x00000010, long 0x00000010 <7> [562.322673] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] digital hpd on [ENCODER:506:DDI A/PHY A] - long <7> [562.323182] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] Received HPD interrupt on PIN 4 - cnt: 20 <7> [562.323762] i915 0000:00:02.0: [drm:intel_dp_hpd_pulse [i915]] ignoring long hpd on eDP [ENCODER:506:DDI A/PHY A] <7> [562.495907] i915 0000:00:02.0: [drm:wait_panel_status [i915]] Wait complete <7> [562.496930] i915 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [i915]] [ENCODER:506:DDI A/PHY A] PPS 0 turning VDD on <7> [562.497716] i915 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [i915]] [ENCODER:506:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006b <7> [562.501109] i915 0000:00:02.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX A/DDI A/PHY A: DPCD: 14 14 c4 41 00 00 01 c0 02 00 00 00 00 0b 80 <7> [562.502354] i915 0000:00:02.0: [drm:intel_dp_start_link_train [i915]] [CONNECTOR:507:eDP-1][ENCODER:506:DDI A/PHY A][DPRX] Reloading eDP link rates <7> [562.503207] i915 0000:00:02.0: [drm:intel_dp_start_link_train [i915]] [CONNECTOR:507:eDP-1][ENCODER:506:DDI A/PHY A][DPRX] Using LINK_RATE_SET value 07 <7> [562.504350] i915 0000:00:02.0: [drm:intel_dp_set_signal_levels [i915]] [CONNECTOR:507:eDP-1][ENCODER:506:DDI A/PHY A][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0 <7> [562.505212] i915 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [i915]] [CONNECTOR:507:eDP-1][ENCODER:506:DDI A/PHY A][DPRX] Using DP training pattern TPS1 <7> [562.506210] i915 0000:00:02.0: [drm:intel_dp_link_train_phy [i915]] [CONNECTOR:507:eDP-1][ENCODER:506:DDI A/PHY A][DPRX] Clock recovery OK <7> [562.506362] i915 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [i915]] [CONNECTOR:507:eDP-1][ENCODER:506:DDI A/PHY A][DPRX] Using DP training pattern TPS3 <7> [562.507725] i915 0000:00:02.0: [drm:intel_dp_link_train_phy [i915]] [CONNECTOR:507:eDP-1][ENCODER:506:DDI A/PHY A][DPRX] Channel EQ done. DP Training successful <7> [562.507852] i915 0000:00:02.0: [drm:intel_dp_link_train_phy [i915]] [CONNECTOR:507:eDP-1][ENCODER:506:DDI A/PHY A][DPRX] Link Training passed at link rate = 540000, lane count = 4 <7> [562.508473] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A <7> [562.508730] i915 0000:00:02.0: [drm:intel_edp_backlight_on [i915]] <7> [562.508858] i915 0000:00:02.0: [drm:intel_backlight_enable [i915]] pipe A <7> [562.509056] i915 0000:00:02.0: [drm:intel_backlight_set_pwm_level [i915]] [CONNECTOR:507:eDP-1] set backlight PWM = 13892 <7> [562.520006] i915 0000:00:02.0: [drm:intel_psr_post_plane_update [i915]] Enabling PSR2 <7> [562.521641] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:507:eDP-1] <7> [562.521853] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:150:pipe A] <7> [562.524404] i915 0000:00:02.0: [drm:drm_client_dev_restore] fbdev: ret=0 <6> [562.531322] Console: switching to colour frame buffer device 180x56

<6> [537.746696] [IGT] kms_vblank: starting dynamic subtest pipe-D-eDP-1

<7> [537.747112] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:566]

<7> [537.747570] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:150:pipe A]

<7> [537.749884] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:268:pipe B]

<7> [537.752180] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:386:pipe C]

<7> [537.754559] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:504:pipe D]

<7> [537.754585] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:507:eDP-1]

<7> [537.754635] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:507:eDP-1] Limiting target display pipe bpp to 30 (EDID bpp 30, max requested bpp 36, max platform bpp 36)

<7> [537.754838] i915 0000:00:02.0: [drm:intel_dp_compute_config_limits [i915]] [ENCODER:506:DDI A/PHY A][CRTC:504:pipe D] DP link limits: pixel clock 513820 kHz DSC off max lanes 4 max rate 540000 max pipe_bpp 30 min link_bpp 18.0000 max link_bpp 30.0000

<7> [537.754981] i915 0000:00:02.0: [drm:intel_dp_compute_link_config [i915]] DP lane count 4 clock 540000 bpp input 30 compressed 0.0000 HDR no link rate required 1926825 available 2160000

<7> [537.755128] i915 0000:00:02.0: [drm:intel_psr_compute_config [i915]] PSR2 not supported in transcoder D

<7> [537.755307] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:504:pipe D] hw max bpp: 30, pipe bpp: 30, dithering: 0

<7> [537.755460] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:506:DDI A/PHY A] [CRTC:504:pipe D]

<7> [537.755607] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.enable (expected no, found yes)

<7> [537.755780] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.active (expected no, found yes)

<7> [537.755940] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in cpu_transcoder (expected -1, found 3)

<7> [537.756099] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in lane_count (expected 0, found 4)

<7> [537.756250] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in dp_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 7483046/8388608 link 498869/524288)

<7> [537.756419] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100)

<7> [537.756573] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in framestart_delay (expected 0, found 1)

<7> [537.756738] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 2880)

<7> [537.756892] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 3040)

<7> [537.757041] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 2880)

<7> [537.757188] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 3040)

<7> [537.757334] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2928)

<7> [537.757477] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2960)

<7> [537.757621] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1800)

<7> [537.757780] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1801)

<7> [537.757930] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1803)

<7> [537.758082] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1809)

<7> [537.758222] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1878)

<7> [537.758388] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1878)

<7> [537.758541] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 2880)

<7> [537.758717] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 3040)

<7> [537.758880] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 2880)

<7> [537.759036] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 3040)

<7> [537.759184] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2928)

<7> [537.759332] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2960)

<7> [537.759480] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1800)

<7> [537.759627] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1801)

<7> [537.759784] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1803)

<7> [537.759935] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1809)

<7> [537.760088] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1878)

<7> [537.760251] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1878)

<7> [537.760395] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in pixel_multiplier (expected 0, found 1)

<7> [537.760553] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)

<7> [537.760713] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)

<7> [537.760864] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in enhanced_framing (expected no, found yes)

<7> [537.761010] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in pipe_bpp (expected 0, found 30)

<7> [537.761165] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 513820)

<7> [537.761315] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 513820)

<7> [537.761481] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in port_clock (expected 0, found 540000)

<7> [537.761628] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in set_context_latency (expected 0, found 1)

<7> [537.761789] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:504:pipe D] fastset requirement not met, forcing full modeset

<7> [537.761956] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0xf), mbus joined? no->no

<7> [537.762115] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:504:pipe D] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x8

<7> [537.762281] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:388:plane 1D] ddb ( 0 - 0) -> ( 0 - 1953), size 0 -> 1953

<7> [537.762420] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:498:cursor D] ddb ( 0 - 0) -> (1953 - 2048), size 0 -> 95

<7> [537.762557] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:388: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> [537.762708] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:388:plane 1D] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 2, 16, 16, 19, 19, 28, 0, 0, 0, 3, 0

<7> [537.762867] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:388:plane 1D] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 49, 385, 385, 457, 457, 673, 0, 0, 63, 73, 87

<7> [537.763014] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:388:plane 1D] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 55, 425, 425, 504, 504, 742, 0, 0, 64, 82, 88

<7> [537.763174] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:504:pipe D] data rate 2055280 num active planes 1

<7> [537.763332] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 28913 required 2159 qgv_peak_bw: 51200

<7> [537.763493] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 1: max bw 38000 required 2159 qgv_peak_bw: 51200

<7> [537.763647] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 2: max bw 38000 required 2159 qgv_peak_bw: 51200

<7> [537.763903] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 3: max bw 38000 required 2159 qgv_peak_bw: 51200

<7> [537.764058] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] Matching peaks QGV bw: 51200 for required data rate: 2159

<7> [537.764235] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:504:pipe D] min cdclk: 0 kHz -> 256910 kHz

<7> [537.764395] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 32114 kHz

<7> [537.764549] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Can change cdclk via squashing

<7> [537.764921] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 307200 kHz, actual 307200 kHz

<7> [537.765082] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0

<7> [537.765266] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:504:pipe D] allocated DPLL 0

<7> [537.765416] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:504:pipe D] reserving DPLL 0

<7> [537.765570] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:504:pipe D] enable: yes [modeset]

<7> [537.765729] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB

<7> [537.765886] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: D, pipe bpp: 30, dithering: 0

<7> [537.766032] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>

<7> [537.766178] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0

<7> [537.766340] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0

<7> [537.766496] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0

<7> [537.766647] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dp m_n: lanes: 4; data_m: 7483046, data_n: 8388608, link_m: 498869, link_n: 524288, tu: 64

<7> [537.766815] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dp m2_n2: lanes: 4; data_m: 0, data_n: 0, link_m: 0, link_n: 0, tu: 0

<7> [537.766961] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] fec: disabled, enhanced framing: enabled

<7> [537.767114] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sdp split: disabled

<7> [537.767259] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] psr: enabled, selective update: disabled, panel replay: disabled, selective fetch: disabled

<7> [537.767419] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] minimum HBlank: 0

<7> [537.767586] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x4

<7> [537.767794] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] DP SDP: VSC, revision 2, length 8

<7> [537.767981] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pixelformat: RGB

<7> [537.768129] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] colorimetry: sRGB

<7> [537.768320] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] bpc: 0

<7> [537.768464] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dynamic range: VESA range

<7> [537.768630] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] content type: Not defined

<7> [537.769004] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 1

<7> [537.769155] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 1

<7> [537.769306] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: yes, vmin: 1878, vmax: 1878, flipline: 1878, pipeline full: 0, guardband: 76 vsync start: 75, vsync end: 69

<7> [537.769451] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1802, vmax vblank: 1802, vmin vtotal: 1878, vmax vtotal: 1878

<7> [537.769596] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0

<7> [537.769770] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "2880x1800": 90 513820 2880 2928 2960 3040 1800 1803 1809 1878 0x48 0xa

<7> [537.769933] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "2880x1800": 90 513820 2880 2928 2960 3040 1800 1803 1809 1878 0x48 0xa

<7> [537.770075] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=513820, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1801-1878 vs=1803-1809 vt=1878, flags=0xa

<7> [537.770229] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "2880x1800": 90 513820 2880 2928 2960 3040 1800 1803 1809 1878 0x40 0xa

<7> [537.770374] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=513820, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1801-1878 vs=1803-1809 vt=1878, flags=0xa

<7> [537.770526] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 540000, pipe src: 2880x1800+0+0, pixel rate 513820, min cdclk 256910

<7> [537.770670] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 48, ips linetime: 0

<7> [537.770834] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0

<7> [537.771004] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no

<7> [537.771150] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0

<7> [537.771302] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cx0pll_hw_state: lane_count: 4, ssc_enabled: no, use_c10: yes, tbt_mode: no

<7> [537.771447] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] c10pll_hw_state: fracen: yes,

<7> [537.771596] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] quot: 40960, rem: 0, den: 1,

<7> [537.771767] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] multiplier: 140, tx_clk_div: 0.

<7> [537.771915] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] c10pll_rawhw_state:

<7> [537.771815] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off

<7> [537.772077] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] tx: 0x10, cmn: 0x21

<7> [537.772219] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[0] = 0xf4, pll[1] = 0x0, pll[2] = 0xf8, pll[3] = 0x0

<7> [537.772366] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[4] = 0x0, pll[5] = 0x0, pll[6] = 0x0, pll[7] = 0x0

<7> [537.772519] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[8] = 0x0, pll[9] = 0x1, pll[10] = 0x0, pll[11] = 0x0

<7> [537.772355] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6

<7> [537.772663] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[12] = 0xa0, pll[13] = 0x0, pll[14] = 0x0, pll[15] = 0x0

<7> [537.772837] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[16] = 0x84, pll[17] = 0x4f, pll[18] = 0xe5, pll[19] = 0x23

<7> [537.772985] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0

<7> [537.772942] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02

<7> [537.773129] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries

<7> [537.773273] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000

<7> [537.773443] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000

<7> [537.773588] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000

<7> [537.773750] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000

<7> [537.773895] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000

<7> [537.774043] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000

<7> [537.774187] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000

<7> [537.774331] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000

<7> [537.774491] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000

<7> [537.774638] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000

<7> [537.774793] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0

<7> [537.774944] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:388:plane 1D] fb: [FB:566] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes

<7> [537.775092] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0

<7> [537.775238] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0

<7> [537.775380] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:418:plane 2D] fb: [NOFB], visible: no

<7> [537.775542] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:448:plane 3D] fb: [NOFB], visible: no

<7> [537.775697] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:478:plane 4D] fb: [NOFB], visible: no

<7> [537.775845] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:488:plane 5D] fb: [NOFB], visible: no

<7> [537.775994] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:498:cursor D] fb: [NOFB], visible: no

<7> [537.781777] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off

<7> [537.782112] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00

<7> [537.782328] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_2

<7> [537.782516] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_D

<7> [537.782729] i915 0000:00:02.0: [drm:dss_pipe_gating_enable_disable [i915]] DSS clock gating enabled for pipe_mask=0x8 (CLKGATE_DIS_DSSDSC=0x2a000000)

<7> [537.782894] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Pre changing CDCLK to 307200 kHz, VCO 614400 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0

<7> [537.783077] i915 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [i915]] aud_ts_cdclk set to M=60, N=768

<7> [537.783222] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:506:DDI A/PHY A]

<7> [537.783388] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:515:DDI B/PHY B]

<7> [537.783528] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:517:DP-MST A]

<7> [537.783673] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:518:DP-MST B]

<7> [537.783833] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:519:DP-MST C]

<7> [537.783975] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:520:DP-MST D]

<7> [537.784111] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:533:DDI TC1/PHY TC1]

<7> [537.784245] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:535:DP-MST A]

<7> [537.784409] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:536:DP-MST B]

<7> [537.784548] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:537:DP-MST C]

<7> [537.784699] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:538:DP-MST D]

<7> [537.784841] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:542:DDI TC2/PHY TC2]

<7> [537.784981] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:546:DDI TC3/PHY TC3]

<7> [537.785119] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:548:DP-MST A]

<7> [537.785254] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:549:DP-MST B]

<7> [537.785409] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:550:DP-MST C]

<7> [537.785546] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:551:DP-MST D]

<7> [537.785679] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:555:DDI TC4/PHY TC4]

<7> [537.785842] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:557:DP-MST A]

<7> [537.785980] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:558:DP-MST B]

<7> [537.786126] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:559:DP-MST C]

<7> [537.786263] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:560:DP-MST D]

<7> [537.786429] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3

<7> [537.786675] i915 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [i915]] Updating dbuf ratio to 2 (mbus joined: no)

<7> [537.786911] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling AUX_A

<7> [537.787698] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x8, on? 0) for [CRTC:504:pipe D]

<7> [537.787846] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0

<7> [537.789528] i915 0000:00:02.0: [drm:intel_pps_on_unlocked [i915]] [ENCODER:506:DDI A/PHY A] PPS 0 turn panel power on

<7> [537.789717] i915 0000:00:02.0: [drm:wait_panel_power_cycle [i915]] [ENCODER:506:DDI A/PHY A] PPS 0 wait for panel power cycle (445 ms remaining)

<7> [538.241276] i915 0000:00:02.0: [drm:wait_panel_status [i915]] [ENCODER:506:DDI A/PHY A] PPS 0 mask: 0xb800000f value: 0x00000000 PP_STATUS: 0x08000001 PP_CONTROL: 0x00000060

<7> [538.252108] i915 0000:00:02.0: [drm:wait_panel_status [i915]] Wait complete

<7> [538.252798] i915 0000:00:02.0: [drm:intel_pps_on_unlocked [i915]] [ENCODER:506:DDI A/PHY A] PPS 0 wait for panel power on

<7> [538.253514] i915 0000:00:02.0: [drm:wait_panel_status [i915]] [ENCODER:506:DDI A/PHY A] PPS 0 mask: 0xb000000f value: 0x80000008 PP_STATUS: 0x9000000a PP_CONTROL: 0x00000063

<7> [538.284875] i915 0000:00:02.0: [drm:icp_irq_handler [i915]] hotplug event received, stat 0x00010000, dig 0x0000008a, pins 0x00000010, long 0x00000010

<7> [538.285429] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] digital hpd on [ENCODER:506:DDI A/PHY A] - long

<7> [538.285937] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] Received HPD interrupt on PIN 4 - cnt: 20

<7> [538.286465] i915 0000:00:02.0: [drm:intel_dp_hpd_pulse [i915]] ignoring long hpd on eDP [ENCODER:506:DDI A/PHY A]

<7> [538.457152] i915 0000:00:02.0: [drm:wait_panel_status [i915]] Wait complete

<7> [538.457971] i915 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [i915]] [ENCODER:506:DDI A/PHY A] PPS 0 turning VDD on

<7> [538.458542] i915 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [i915]] [ENCODER:506:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006b

<7> [538.461792] i915 0000:00:02.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX A/DDI A/PHY A: DPCD: 14 14 c4 41 00 00 01 c0 02 00 00 00 00 0b 80

<7> [538.462917] i915 0000:00:02.0: [drm:intel_dp_start_link_train [i915]] [CONNECTOR:507:eDP-1][ENCODER:506:DDI A/PHY A][DPRX] Reloading eDP link rates

<7> [538.463632] i915 0000:00:02.0: [drm:intel_dp_start_link_train [i915]] [CONNECTOR:507:eDP-1][ENCODER:506:DDI A/PHY A][DPRX] Using LINK_RATE_SET value 07

<7> [538.464730] i915 0000:00:02.0: [drm:intel_dp_set_signal_levels [i915]] [CONNECTOR:507:eDP-1][ENCODER:506:DDI A/PHY A][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0

<7> [538.465558] i915 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [i915]] [CONNECTOR:507:eDP-1][ENCODER:506:DDI A/PHY A][DPRX] Using DP training pattern TPS1

<7> [538.466565] i915 0000:00:02.0: [drm:intel_dp_link_train_phy [i915]] [CONNECTOR:507:eDP-1][ENCODER:506:DDI A/PHY A][DPRX] Clock recovery OK

<7> [538.466703] i915 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [i915]] [CONNECTOR:507:eDP-1][ENCODER:506:DDI A/PHY A][DPRX] Using DP training pattern TPS3

<7> [538.468025] i915 0000:00:02.0: [drm:intel_dp_link_train_phy [i915]] [CONNECTOR:507:eDP-1][ENCODER:506:DDI A/PHY A][DPRX] Channel EQ done. DP Training successful

<7> [538.468153] i915 0000:00:02.0: [drm:intel_dp_link_train_phy [i915]] [CONNECTOR:507:eDP-1][ENCODER:506:DDI A/PHY A][DPRX] Link Training passed at link rate = 540000, lane count = 4

<7> [538.468770] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe D

<7> [538.469032] i915 0000:00:02.0: [drm:intel_edp_backlight_on [i915]]

<7> [538.469163] i915 0000:00:02.0: [drm:intel_backlight_enable [i915]] pipe D

<7> [538.469365] i915 0000:00:02.0: [drm:intel_backlight_set_pwm_level [i915]] [CONNECTOR:507:eDP-1] set backlight PWM = 13892

<7> [538.480270] i915 0000:00:02.0: [drm:intel_psr_post_plane_update [i915]] Enabling PSR1

<7> [538.481534] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:507:eDP-1]

<7> [538.481778] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:504:pipe D]

<7> [538.503637] i915 0000:00:02.0: [drm:i915_fifo_underrun_reset_write [i915]] Re-arming FIFO underruns on pipe D

<7> [538.513635] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509

<7> [541.033081] i915 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [i915]] [ENCODER:506:DDI A/PHY A] PPS 0 turning VDD off

<7> [541.034244] i915 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [i915]] [ENCODER:506:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x00000067

<3> [554.088877] INFO: task prometheus-node:856 blocked for more than 61 seconds.

<3> [554.089047] Tainted: G S U W L 7.0.0-rc4-CI_DRM_18160-ga25d8c583e1e+ #1

<3> [554.089054] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.

<6> [554.089059] task:prometheus-node state:D stack:0 pid:856 tgid:748 ppid:1 task_flags:0x400040 flags:0x00080000

<6> [554.089147] schedule_preempt_disabled+0x15/0x30

<6> [554.089486] ? trace_hardirqs_on_prepare+0xe1/0x100

<6> [554.089547] entry_SYSCALL_64_after_hwframe+0x76/0x7e

<6> [554.089688] RSP: 002b:000000c0003b94f0 EFLAGS: 00000212 ORIG_RAX: 0000000000000000

<6> [554.089697] RAX: ffffffffffffffda RBX: 000000000000000c RCX: 000000000040852e

<6> [554.089703] RDX: 0000000000000080 RSI: 000000c000129d80 RDI: 000000000000000c

<6> [554.089707] RBP: 000000c0003b9530 R08: 0000000000000000 R09: 0000000000000000

<6> [554.089712] R10: 0000000000000000 R11: 0000000000000212 R12: 0000000000000000

<6> [554.089716] R13: 0000000000000000 R14: 000000c000007880 R15: 000000000000000d

<3> [554.089767] INFO: task prometheus-node:856 is blocked on a mutex likely owned by task prometheus-node:857.

<6> [554.089777] task:prometheus-node state:D stack:0 pid:857 tgid:748 ppid:1 task_flags:0x400040 flags:0x00080000

<6> [554.089920] ? __pfx_io_schedule_timeout+0x10/0x10

<6> [554.089935] wait_for_completion_io_timeout+0x1d/0x30

<6> [554.090256] ? trace_hardirqs_on_prepare+0xe1/0x100

<6> [554.090280] ? trace_hardirqs_on_prepare+0xe1/0x100

<6> [554.090314] entry_SYSCALL_64_after_hwframe+0x76/0x7e

<6> [554.090328] RSP: 002b:000000c0003b74f0 EFLAGS: 00000212 ORIG_RAX: 0000000000000000

<6> [554.090336] RAX: ffffffffffffffda RBX: 0000000000000010 RCX: 000000000040852e

<6> [554.090341] RDX: 0000000000000080 RSI: 000000c000129e80 RDI: 0000000000000010

<6> [554.090345] RBP: 000000c0003b7530 R08: 0000000000000000 R09: 0000000000000000

<6> [554.090350] R10: 0000000000000000 R11: 0000000000000212 R12: 0000000000000000

<6> [554.090354] R13: 0000000000000000 R14: 000000c000022700 R15: 0000000000000003

<3> [554.090400] INFO: task prometheus-node:2559 blocked for more than 61 seconds.

<3> [554.090408] Tainted: G S U W L 7.0.0-rc4-CI_DRM_18160-ga25d8c583e1e+ #1

<3> [554.090415] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.

<6> [554.090421] task:prometheus-node state:D stack:0 pid:2559 tgid:748 ppid:1 task_flags:0x400040 flags:0x00080000

<6> [554.090500] schedule_preempt_disabled+0x15/0x30

<6> [554.090839] ? trace_hardirqs_on_prepare+0xe1/0x100

<6> [554.090891] ? trace_hardirqs_on_prepare+0xe1/0x100

<6> [554.090938] entry_SYSCALL_64_after_hwframe+0x76/0x7e

<6> [554.090948] RSP: 002b:000000c0001754f0 EFLAGS: 00000212 ORIG_RAX: 0000000000000000

<6> [554.090954] RAX: ffffffffffffffda RBX: 0000000000000015 RCX: 000000000040852e

<6> [554.090958] RDX: 0000000000000080 RSI: 000000c000129c80 RDI: 0000000000000015

<6> [554.090963] RBP: 000000c000175530 R08: 0000000000000000 R09: 0000000000000000

<6> [554.090967] R10: 0000000000000000 R11: 0000000000000212 R12: 0000000000000000

<6> [554.090970] R13: 0000000000000000 R14: 000000c00036a1c0 R15: 0000000000000035

<3> [554.091009] INFO: task prometheus-node:2559 is blocked on a mutex likely owned by task prometheus-node:857.

<6> [554.091015] task:prometheus-node state:D stack:0 pid:857 tgid:748 ppid:1 task_flags:0x400040 flags:0x00080000

<6> [554.091135] ? __pfx_io_schedule_timeout+0x10/0x10

<6> [554.091149] wait_for_completion_io_timeout+0x1d/0x30

<6> [554.091453] ? trace_hardirqs_on_prepare+0xe1/0x100

<6> [554.091476] ? trace_hardirqs_on_prepare+0xe1/0x100

<6> [554.091507] entry_SYSCALL_64_after_hwframe+0x76/0x7e

<6> [554.091517] RSP: 002b:000000c0003b74f0 EFLAGS: 00000212 ORIG_RAX: 0000000000000000

<6> [554.091523] RAX: ffffffffffffffda RBX: 0000000000000010 RCX: 000000000040852e

<6> [554.091526] RDX: 0000000000000080 RSI: 000000c000129e80 RDI: 0000000000000010

<6> [554.091529] RBP: 000000c0003b7530 R08: 0000000000000000 R09: 0000000000000000

<6> [554.091533] R10: 0000000000000000 R11: 0000000000000212 R12: 0000000000000000

<6> [554.091537] R13: 0000000000000000 R14: 000000c000022700 R15: 0000000000000003

<4> [554.091607] #0: ffffffff835c5be0 (rcu_read_lock){....}-{1:2}, at: debug_show_all_locks+0x37/0x220

<4> [554.091640] 5 locks held by prometheus-node/748:

<4> [554.091644] #0: ffff888131206b38 (&f->f_pos_lock){+.+.}-{3:3}, at: fdget_pos+0x81/0xd0

<4> [554.091661] #1: ffff8881097e8ec0 (&p->lock){+.+.}-{3:3}, at: seq_read_iter+0x65/0x4f0

<4> [554.091679] #2: ffff8881019de888 (&of->mutex){+.+.}-{3:3}, at: kernfs_seq_start+0x26/0x140

<4> [554.091698] #3: ffff888105763440 (kn->active#122){.+.+}-{0:0}, at: kernfs_seq_start+0x3b/0x140

<4> [554.091718] #4: ffff888104afd550 (&hwdev->lock){+.+.}-{3:3}, at: hwmon_attr_show+0x3e/0x1e0

<4> [554.091734] 5 locks held by prometheus-node/855:

<4> [554.091737] #0: ffff888117cd4d38 (&f->f_pos_lock){+.+.}-{3:3}, at: fdget_pos+0x81/0xd0

<4> [554.091752] #1: ffff8881097e81f0 (&p->lock){+.+.}-{3:3}, at: seq_read_iter+0x65/0x4f0

<4> [554.091769] #2: ffff888126bc0e88 (&of->mutex){+.+.}-{3:3}, at: kernfs_seq_start+0x26/0x140

<4> [554.091784] #3: ffff888105763440 (kn->active#122){.+.+}-{0:0}, at: kernfs_seq_start+0x3b/0x140

<4> [554.091802] #4: ffff888104afd550 (&hwdev->lock){+.+.}-{3:3}, at: hwmon_attr_show+0x3e/0x1e0

<4> [554.091816] 5 locks held by prometheus-node/856:

<4> [554.091821] #0: ffff8881d9637b38 (&f->f_pos_lock){+.+.}-{3:3}, at: fdget_pos+0x81/0xd0

<4> [554.091835] #1: ffff8881097e9a48 (&p->lock){+.+.}-{3:3}, at: seq_read_iter+0x65/0x4f0

<4> [554.091851] #2: ffff88815cd9d688 (&of->mutex){+.+.}-{3:3}, at: kernfs_seq_start+0x26/0x140

<4> [554.091867] #3: ffff888105763440 (kn->active#122){.+.+}-{0:0}, at: kernfs_seq_start+0x3b/0x140

<4> [554.091884] #4: ffff888104afd550 (&hwdev->lock){+.+.}-{3:3}, at: hwmon_attr_show+0x3e/0x1e0

<4> [554.091899] 6 locks held by prometheus-node/857:

<4> [554.091903] #0: ffff888128d87338 (&f->f_pos_lock){+.+.}-{3:3}, at: fdget_pos+0x81/0xd0

<4> [554.091917] #1: ffff8881097e8710 (&p->lock){+.+.}-{3:3}, at: seq_read_iter+0x65/0x4f0

<4> [554.091934] #2: ffff88815cd9a688 (&of->mutex){+.+.}-{3:3}, at: kernfs_seq_start+0x26/0x140

<4> [554.091950] #3: ffff888105763440 (kn->active#122){.+.+}-{0:0}, at: kernfs_seq_start+0x3b/0x140

<4> [554.091967] #4: ffff888104afd550 (&hwdev->lock){+.+.}-{3:3}, at: hwmon_attr_show+0x3e/0x1e0

<4> [554.091981] #5: ffff888105fe2db8 (&data->read_lock){+.+.}-{3:3}, at: nvme_hwmon_read+0x5f/0x1d0

<4> [554.091996] 5 locks held by prometheus-node/2368:

<4> [554.092000] #0: ffff8881278fb138 (&f->f_pos_lock){+.+.}-{3:3}, at: fdget_pos+0x81/0xd0

<4> [554.092015] #1: ffff8881097e8338 (&p->lock){+.+.}-{3:3}, at: seq_read_iter+0x65/0x4f0

<4> [554.092031] #2: ffff888126bc6e88 (&of->mutex){+.+.}-{3:3}, at: kernfs_seq_start+0x26/0x140

<4> [554.092046] #3: ffff888105763440 (kn->active#122){.+.+}-{0:0}, at: kernfs_seq_start+0x3b/0x140

<4> [554.092064] #4: ffff888104afd550 (&hwdev->lock){+.+.}-{3:3}, at: hwmon_attr_show+0x3e/0x1e0

<4> [554.092078] 5 locks held by prometheus-node/2559:

<4> [554.092082] #0: ffff888127818338 (&f->f_pos_lock){+.+.}-{3:3}, at: fdget_pos+0x81/0xd0

<4> [554.092096] #1: ffff8881097e9cd8 (&p->lock){+.+.}-{3:3}, at: seq_read_iter+0x65/0x4f0

<4> [554.092112] #2: ffff88815cd9a088 (&of->mutex){+.+.}-{3:3}, at: kernfs_seq_start+0x26/0x140

<4> [554.092128] #3: ffff888105763440 (kn->active#122){.+.+}-{0:0}, at: kernfs_seq_start+0x3b/0x140

<4> [554.092146] #4: ffff888104afd550 (&hwdev->lock){+.+.}-{3:3}, at: hwmon_attr_show+0x3e/0x1e0

<4> [554.092158] 5 locks held by prometheus-node/2567:

<4> [554.092161] #0: ffff888131207938 (&f->f_pos_lock){+.+.}-{3:3}, at: fdget_pos+0x81/0xd0

<4> [554.092173] #1: ffff8881097e8d78 (&p->lock){+.+.}-{3:3}, at: seq_read_iter+0x65/0x4f0

<4> [554.092186] #2: ffff8881019dfa88 (&of->mutex){+.+.}-{3:3}, at: kernfs_seq_start+0x26/0x140

<4> [554.092200] #3: ffff888105763110 (kn->active#122){.+.+}-{0:0}, at: kernfs_seq_start+0x3b/0x140

<4> [554.092216] #4: ffff888104afd550 (&hwdev->lock){+.+.}-{3:3}, at: hwmon_attr_show_string+0x42/0x1b0

<4> [554.092229] 5 locks held by prometheus-node/2605:

<4> [554.092232] #0: ffff8881278f8d38 (&f->f_pos_lock){+.+.}-{3:3}, at: fdget_pos+0x81/0xd0

<4> [554.092245] #1: ffff8881097e8c30 (&p->lock){+.+.}-{3:3}, at: seq_read_iter+0x65/0x4f0

<4> [554.092258] #2: ffff888126bc2688 (&of->mutex){+.+.}-{3:3}, at: kernfs_seq_start+0x26/0x140

<4> [554.092271] #3: ffff888105763440 (kn->active#122){.+.+}-{0:0}, at: kernfs_seq_start+0x3b/0x140

<4> [554.092287] #4: ffff888104afd550 (&hwdev->lock){+.+.}-{3:3}, at: hwmon_attr_show+0x3e/0x1e0

<4> [554.092301] 4 locks held by rs:main Q:Reg/890:

<4> [554.092320] #0: ffff888125c85a68 (&gt->reset.mutex){+.+.}-{3:3}, at: i915_request_wait_timeout+0x94/0x6d0 [i915]

<4> [554.092811] =============================================

<5> [558.515377] Fence expiration time out i915-0000:00:02.0:0000:00:02.0:4!

<7> [561.397980] i915 0000:00:02.0: [drm:intel_guc_context_reset_process_msg [i915]] GT0: GUC: Got context reset notification: 0x1004 on rcs0, exiting = no, banned = no

<6> [561.444636] i915 0000:00:02.0: [drm] GPU HANG: ecode 12:0:00000000

<6> [561.444640] i915 0000:00:02.0: [drm] GPU error state saved to /sys/class/drm/card0/error

<7> [561.445598] i915 0000:00:02.0: [drm:error_state_write [i915]] Resetting error state

<7> [561.446071] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:504:pipe D]

<7> [561.446164] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.enable (expected yes, found no)

<7> [561.446335] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.active (expected yes, found no)

<7> [561.446483] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in cpu_transcoder (expected 3, found -1)

<7> [561.446628] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in lane_count (expected 4, found 0)

<7> [561.446769] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in dp_m_n (expected tu 64 data 7483046/8388608 link 498869/524288, found tu 0, data 0/0 link 0/0)

<7> [561.446912] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000)

<7> [561.447062] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in framestart_delay (expected 1, found 0)

<7> [561.447208] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 2880, found 0)

<7> [561.447378] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 3040, found 0)

<7> [561.447533] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 2880, found 0)

<7> [561.447675] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 3040, found 0)

<7> [561.447817] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2928, found 0)

<7> [561.447955] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2960, found 0)

<7> [561.448094] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1800, found 0)

<7> [561.448236] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1801, found 0)

<7> [561.448391] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1803, found 0)

<7> [561.448531] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1809, found 0)

<7> [561.448668] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1878, found 0)

<7> [561.448806] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1878, found 0)

<7> [561.448943] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 2880, found 0)

<7> [561.449088] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 3040, found 0)

<7> [561.449228] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 2880, found 0)

<7> [561.449442] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 3040, found 0)

<7> [561.449651] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2928, found 0)

<7> [561.449851] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2960, found 0)

<7> [561.450065] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1800, found 0)

<7> [561.450258] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1801, found 0)

<7> [561.450409] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1803, found 0)

<7> [561.450559] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1809, found 0)

<7> [561.450702] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1878, found 0)

<7> [561.450845] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1878, found 0)

<7> [561.450990] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in pixel_multiplier (expected 1, found 0)

<7> [561.451150] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)

<7> [561.451312] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)

<7> [561.451461] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in enhanced_framing (expected yes, found no)

<7> [561.451641] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in pipe_bpp (expected 30, found 0)

<7> [561.451849] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 513820, found 0)

<7> [561.452057] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 513820, found 0)

<7> [561.452285] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in port_clock (expected 540000, found 0)

<7> [561.452451] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:504:pipe D] fastset requirement not met in set_context_latency (expected 1, found 0)

<7> [561.452603] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:504:pipe D] fastset requirement not met, forcing full modeset

<7> [561.452752] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:504:pipe D] releasing DPLL 0

<7> [561.452929] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0xf), mbus joined? no->no

<7> [561.453070] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:504:pipe D] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x8 -> 0x0

<7> [561.453232] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:388:plane 1D] ddb ( 0 - 1953) -> ( 0 - 0), size 1953 -> 0

<7> [561.453413] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:498:cursor D] ddb (1953 - 2048) -> ( 0 - 0), size 95 -> 0

<7> [561.453612] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:388: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> [561.453811] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:388:plane 1D] lines 2, 16, 16, 19, 19, 28, 0, 0, 0, 3, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0

<7> [561.454005] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:388:plane 1D] blocks 49, 385, 385, 457, 457, 673, 0, 0, 63, 73, 87 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0

<7> [561.454162] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:388:plane 1D] min_ddb 55, 425, 425, 504, 504, 742, 0, 0, 64, 82, 88 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0

<7> [561.454336] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:504:pipe D] data rate 0 num active planes 0

<7> [561.454492] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 28913 required 0 qgv_peak_bw: 51200

<7> [561.454647] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 1: max bw 38000 required 0 qgv_peak_bw: 51200

<7> [561.454825] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 2: max bw 38000 required 0 qgv_peak_bw: 51200

<7> [561.455047] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 3: max bw 38000 required 0 qgv_peak_bw: 51200

<7> [561.455271] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] Matching peaks QGV bw: 51200 for required data rate: 0

<7> [561.455504] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:504:pipe D] min cdclk: 256910 kHz -> 0 kHz

<7> [561.455683] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 32114 kHz -> 0 kHz

<7> [561.455844] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Can change cdclk via squashing

<7> [561.455995] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 172800 kHz, actual 172800 kHz

<7> [561.456150] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0

<7> [561.456336] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:504:pipe D] enable: no [modeset]

<7> [561.456502] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:388:plane 1D] fb: [NOFB], visible: no

<7> [561.456689] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:418:plane 2D] fb: [NOFB], visible: no

<7> [561.456909] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:448:plane 3D] fb: [NOFB], visible: no

<7> [561.457128] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:478:plane 4D] fb: [NOFB], visible: no

<7> [561.457345] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:488:plane 5D] fb: [NOFB], visible: no

<7> [561.457503] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:498:cursor D] fb: [NOFB], visible: no

<7> [561.457703] i915 0000:00:02.0: [drm:intel_psr_disable_locked [i915]] Disabling PSR1

<7> [561.464386] i915 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [i915]] [ENCODER:506:DDI A/PHY A] PPS 0 turning VDD on

<7> [561.464641] i915 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [i915]] [ENCODER:506:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006f

<7> [561.465264] i915 0000:00:02.0: [drm:intel_edp_backlight_off [i915]]

<7> [561.672718] i915 0000:00:02.0: [drm:intel_backlight_set_pwm_level [i915]] [CONNECTOR:507:eDP-1] set backlight PWM = 0

<7> [561.673685] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe D

<7> [561.687517] i915 0000:00:02.0: [drm:intel_pps_off_unlocked [i915]] [ENCODER:506:DDI A/PHY A] PPS 0 turn panel power off

<7> [561.688059] i915 0000:00:02.0: [drm:intel_pps_off_unlocked [i915]] [ENCODER:506:DDI A/PHY A] PPS 0 wait for panel power off time

<7> [561.688562] i915 0000:00:02.0: [drm:wait_panel_status [i915]] [ENCODER:506:DDI A/PHY A] PPS 0 mask: 0xb0000000 value: 0x00000000 PP_STATUS: 0xa0000002 PP_CONTROL: 0x00000060

<7> [561.740628] i915 0000:00:02.0: [drm:icp_irq_handler [i915]] hotplug event received, stat 0x00010000, dig 0x0000008a, pins 0x00000010, long 0x00000010

<7> [561.741179] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] digital hpd on [ENCODER:506:DDI A/PHY A] - long

<7> [561.741684] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] Received HPD interrupt on PIN 4 - cnt: 10

<7> [561.742272] i915 0000:00:02.0: [drm:intel_dp_hpd_pulse [i915]] ignoring long hpd on eDP [ENCODER:506:DDI A/PHY A]

<7> [561.744673] i915 0000:00:02.0: [drm:wait_panel_status [i915]] Wait complete

<7> [561.745172] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x8, on? 1) for [CRTC:504:pipe D]

<7> [561.746923] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0

<7> [561.747267] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling AUX_A

<7> [561.747569] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:506:DDI A/PHY A]

<7> [561.747844] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:515:DDI B/PHY B]

<7> [561.748286] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:517:DP-MST A]

<7> [561.748564] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:518:DP-MST B]

<7> [561.748891] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:519:DP-MST C]

<7> [561.749077] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:520:DP-MST D]

<7> [561.749247] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:533:DDI TC1/PHY TC1]

<7> [561.749411] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:535:DP-MST A]

<7> [561.749626] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:536:DP-MST B]

<7> [561.750055] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:537:DP-MST C]

<7> [561.750621] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:538:DP-MST D]

<7> [561.751115] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:542:DDI TC2/PHY TC2]

<7> [561.751812] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:546:DDI TC3/PHY TC3]

<7> [561.752290] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:548:DP-MST A]

<7> [561.752743] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:549:DP-MST B]

<7> [561.753201] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:550:DP-MST C]

<7> [561.753688] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:551:DP-MST D]

<7> [561.754168] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:555:DDI TC4/PHY TC4]

<7> [561.754595] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:557:DP-MST A]

<7> [561.754832] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:558:DP-MST B]

<7> [561.755058] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:559:DP-MST C]

<7> [561.755297] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:560:DP-MST D]

<7> [561.755530] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:507:eDP-1]

<7> [561.755858] i915 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [i915]] Updating dbuf ratio to 2 (mbus joined: no)

<7> [561.756122] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1

<7> [561.756501] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_D

<7> [561.756758] i915 0000:00:02.0: [drm:dss_pipe_gating_enable_disable [i915]] DSS clock gating disabled for pipe_mask=0x8 (CLKGATE_DIS_DSSDSC=0xaa000000)

<7> [561.757041] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_2

<7> [561.757229] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:504:pipe D]

<7> [561.757438] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Post changing CDCLK to 172800 kHz, VCO 614400 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0

<7> [561.757626] i915 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [i915]] aud_ts_cdclk set to M=60, N=432

<6> [561.758494] [IGT] kms_vblank: finished subtest pipe-D-eDP-1, SUCCESS

<7> [561.758933] i915 0000:00:02.0: [drm:error_state_write [i915]] Resetting error state

<6> [561.759354] [IGT] kms_vblank: finished subtest query-forked-hang, SUCCESS

<7> [561.760261] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]

<7> [561.775314] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off

<7> [561.775879] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6

<7> [561.776401] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02

<7> [561.776998] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on

<7> [561.783421] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on

<7> [561.784870] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:507:eDP-1] Limiting target display pipe bpp to 30 (EDID bpp 30, max requested bpp 36, max platform bpp 36)

<7> [561.785107] i915 0000:00:02.0: [drm:intel_dp_compute_config_limits [i915]] [ENCODER:506:DDI A/PHY A][CRTC:150:pipe A] DP link limits: pixel clock 513820 kHz DSC off max lanes 4 max rate 540000 max pipe_bpp 30 min link_bpp 18.0000 max link_bpp 30.0000

<7> [561.785327] i915 0000:00:02.0: [drm:intel_dp_compute_link_config [i915]] DP lane count 4 clock 540000 bpp input 30 compressed 0.0000 HDR no link rate required 1926825 available 2160000

<7> [561.785527] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:150:pipe A] hw max bpp: 30, pipe bpp: 30, dithering: 0

<7> [561.785737] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:506:DDI A/PHY A] [CRTC:150:pipe A]

<7> [561.785930] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.enable (expected no, found yes)

<7> [561.786130] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.active (expected no, found yes)

<7> [561.786296] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)

<7> [561.786457] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in lane_count (expected 0, found 4)

<7> [561.786603] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in dp_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 7483046/8388608 link 498869/524288)

<7> [561.786745] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100)

<7> [561.786889] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)

<7> [561.787035] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 2880)

<7> [561.787177] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 3040)

<7> [561.787326] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 2880)

<7> [561.787469] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 3040)

<7> [561.787609] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2928)

<7> [561.787749] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2960)

<7> [561.787887] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1800)

<7> [561.788029] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1801)

<7> [561.788171] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1803)

<7> [561.788321] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1809)

<7> [561.788462] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1878)

<7> [561.788600] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1878)

<7> [561.788746] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 2880)

<7> [561.788886] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 3040)

<7> [561.789025] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 2880)

<7> [561.789166] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 3040)

<7> [561.789314] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2928)

<7> [561.789456] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2960)

<7> [561.789597] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1800)

<7> [561.789737] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1801)

<7> [561.789875] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1803)

<7> [561.790013] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1809)

<7> [561.790152] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1878)

<7> [561.790376] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1878)

<7> [561.790521] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)

<7> [561.790665] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)

<7> [561.790805] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)

<7> [561.790948] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in enhanced_framing (expected no, found yes)

<7> [561.791090] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 30)

<7> [561.791237] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 513820)

<7> [561.791380] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 513820)

<7> [561.791519] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in port_clock (expected 0, found 540000)

<7> [561.791657] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in set_context_latency (expected 0, found 1)

<7> [561.791796] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:150:pipe A] fastset requirement not met, forcing full modeset

<7> [561.791972] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0xf (total dbuf slices 0xf), mbus joined? no->yes

<7> [561.792112] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:150:pipe A] dbuf slices 0x0 -> 0xf, ddb (0 - 0) -> (0 - 4096), active pipes 0x0 -> 0x1

<7> [561.792256] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:34:plane 1A] ddb ( 0 - 0) -> ( 0 - 4001), size 0 -> 4001

<7> [561.792396] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:144:cursor A] ddb ( 0 - 0) -> (4001 - 4096), size 0 -> 95

<7> [561.792531] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34: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> [561.792667] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 2, 16, 16, 19, 19, 28, 0, 0, 0, 3, 0

<7> [561.792802] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 49, 385, 385, 457, 457, 673, 0, 0, 63, 73, 87

<7> [561.792936] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 55, 425, 425, 504, 504, 742, 0, 0, 64, 82, 88

<7> [561.793088] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:150:pipe A] data rate 2055280 num active planes 1

<7> [561.793240] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 28913 required 2159 qgv_peak_bw: 51200

<7> [561.793387] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 1: max bw 38000 required 2159 qgv_peak_bw: 51200

<7> [561.793530] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 2: max bw 38000 required 2159 qgv_peak_bw: 51200

<7> [561.793670] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 3: max bw 38000 required 2159 qgv_peak_bw: 51200

<7> [561.793809] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] Matching peaks QGV bw: 51200 for required data rate: 2159

<7> [561.793949] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:150:pipe A] min cdclk: 0 kHz -> 256910 kHz

<7> [561.794101] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 32114 kHz

<7> [561.794252] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Can change cdclk via squashing

<7> [561.794399] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 307200 kHz, actual 307200 kHz

<7> [561.794542] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0

<7> [561.794693] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:150:pipe A] allocated DPLL 0

<7> [561.794839] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:150:pipe A] reserving DPLL 0

<7> [561.794986] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:150:pipe A] enable: yes [modeset]

<7> [561.795134] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB

<7> [561.795287] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 30, dithering: 0

<7> [561.795432] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>

<7> [561.795574] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0

<7> [561.795721] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0

<7> [561.795863] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0

<7> [561.796002] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dp m_n: lanes: 4; data_m: 7483046, data_n: 8388608, link_m: 498869, link_n: 524288, tu: 64

<7> [561.796145] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dp m2_n2: lanes: 4; data_m: 0, data_n: 0, link_m: 0, link_n: 0, tu: 0

<7> [561.796297] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] fec: disabled, enhanced framing: enabled

<7> [561.796440] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sdp split: disabled

<7> [561.796579] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] psr: enabled, selective update: enabled, panel replay: disabled, selective fetch: enabled

<7> [561.796717] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] minimum HBlank: 0

<7> [561.796856] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x4

<7> [561.796995] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] DP SDP: VSC, revision 4, length 14

<7> [561.797137] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pixelformat: RGB

<7> [561.797284] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] colorimetry: sRGB

<7> [561.797426] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] bpc: 0

<7> [561.797565] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dynamic range: VESA range

<7> [561.797712] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] content type: Not defined

<7> [561.797859] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 1

<7> [561.797997] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 1

<7> [561.798138] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: yes, vmin: 1878, vmax: 1878, flipline: 1878, pipeline full: 0, guardband: 76 vsync start: 75, vsync end: 69

<7> [561.798311] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1802, vmax vblank: 1802, vmin vtotal: 1878, vmax vtotal: 1878

<7> [561.798454] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0

<7> [561.798594] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "2880x1800": 90 513820 2880 2928 2960 3040 1800 1803 1809 1878 0x48 0xa

<7> [561.798735] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "2880x1800": 90 513820 2880 2928 2960 3040 1800 1803 1809 1878 0x48 0xa

<7> [561.798875] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=513820, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1801-1878 vs=1803-1809 vt=1878, flags=0xa

<7> [561.799015] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "2880x1800": 90 513820 2880 2928 2960 3040 1800 1803 1809 1878 0x40 0xa

<7> [561.799154] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=513820, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1801-1878 vs=1803-1809 vt=1878, flags=0xa

<7> [561.799303] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 540000, pipe src: 2880x1800+0+0, pixel rate 513820, min cdclk 256910

<7> [561.799444] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 48, ips linetime: 0

<7> [561.799584] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0

<7> [561.799724] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no

<7> [561.799862] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0

<7> [561.800000] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cx0pll_hw_state: lane_count: 4, ssc_enabled: no, use_c10: yes, tbt_mode: no

<7> [561.800140] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] c10pll_hw_state: fracen: yes,

<7> [561.800287] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] quot: 40960, rem: 0, den: 1,

<7> [561.800427] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] multiplier: 140, tx_clk_div: 0.

<7> [561.800568] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] c10pll_rawhw_state:

<7> [561.800710] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] tx: 0x10, cmn: 0x21

<7> [561.800850] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[0] = 0xf4, pll[1] = 0x0, pll[2] = 0xf8, pll[3] = 0x0

<7> [561.800991] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[4] = 0x0, pll[5] = 0x0, pll[6] = 0x0, pll[7] = 0x0

<7> [561.801131] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[8] = 0x0, pll[9] = 0x1, pll[10] = 0x0, pll[11] = 0x0

<7> [561.801277] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[12] = 0xa0, pll[13] = 0x0, pll[14] = 0x0, pll[15] = 0x0

<7> [561.801420] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pll[16] = 0x84, pll[17] = 0x4f, pll[18] = 0xe5, pll[19] = 0x23

<7> [561.801559] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0

<7> [561.801701] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries

<7> [561.801842] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000

<7> [561.801983] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000

<7> [561.802124] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000

<7> [561.802271] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000

<7> [561.802416] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000

<7> [561.802557] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000

<7> [561.802699] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000

<7> [561.802837] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000

<7> [561.802975] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000

<7> [561.803113] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000

<7> [561.803255] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0

<7> [561.803394] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:plane 1A] fb: [FB:508] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes

<7> [561.803531] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0

<7> [561.803673] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0

<7> [561.803814] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:64:plane 2A] fb: [NOFB], visible: no

<7> [561.803956] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:94:plane 3A] fb: [NOFB], visible: no

<7> [561.804095] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:124:plane 4A] fb: [NOFB], visible: no

<7> [561.804237] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:134:plane 5A] fb: [NOFB], visible: no

<7> [561.804376] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:144:cursor A] fb: [NOFB], visible: no

<7> [561.804612] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off

<7> [561.804919] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00

<7> [561.805137] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_A

<7> [561.805366] i915 0000:00:02.0: [drm:dss_pipe_gating_enable_disable [i915]] DSS clock gating enabled for pipe_mask=0x1 (CLKGATE_DIS_DSSDSC=0xa8000000)

<7> [561.805535] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Pre changing CDCLK to 307200 kHz, VCO 614400 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0

<7> [561.805720] i915 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [i915]] aud_ts_cdclk set to M=60, N=768

<7> [561.805866] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:506:DDI A/PHY A]

<7> [561.806007] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:515:DDI B/PHY B]

<7> [561.806144] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:517:DP-MST A]

<7> [561.806300] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:518:DP-MST B]

<7> [561.806441] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:519:DP-MST C]

<7> [561.806576] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:520:DP-MST D]

<7> [561.806718] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:533:DDI TC1/PHY TC1]

<7> [561.806862] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:535:DP-MST A]

<7> [561.807001] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:536:DP-MST B]

<7> [561.807140] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:537:DP-MST C]

<7> [561.807284] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:538:DP-MST D]

<7> [561.807423] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:542:DDI TC2/PHY TC2]

<7> [561.807558] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:546:DDI TC3/PHY TC3]

<7> [561.807693] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:548:DP-MST A]

<7> [561.807831] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:549:DP-MST B]

<7> [561.807966] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:550:DP-MST C]

<7> [561.808102] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:551:DP-MST D]

<7> [561.808241] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:555:DDI TC4/PHY TC4]

<7> [561.808378] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:557:DP-MST A]

<7> [561.808513] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:558:DP-MST B]

<7> [561.808649] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:559:DP-MST C]

<7> [561.808785] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:560:DP-MST D]

<7> [561.808951] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0xf

<7> [561.809195] i915 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [i915]] Changing mbus joined: no -> yes (pipe: *)

<7> [561.809354] i915 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [i915]] Updating dbuf ratio to 4 (mbus joined: yes)

<7> [561.809572] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling AUX_A

<7> [561.810947] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:150:pipe A]

<7> [561.811147] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0

<7> [561.813004] i915 0000:00:02.0: [drm:intel_pps_on_unlocked [i915]] [ENCODER:506:DDI A/PHY A] PPS 0 turn panel power on

<7> [561.813188] i915 0000:00:02.0: [drm:wait_panel_power_cycle [i915]] [ENCODER:506:DDI A/PHY A] PPS 0 wait for panel power cycle (432 ms remaining)

<7> [562.248700] i915 0000:00:02.0: [drm:wait_panel_status [i915]] [ENCODER:506:DDI A/PHY A] PPS 0 mask: 0xb800000f value: 0x00000000 PP_STATUS: 0x08000001 PP_CONTROL: 0x00000060

<4> [562.286442] nvme nvme0: I/O tag 24 (8018) QID 0 timeout, completion polled

<7> [562.289329] i915 0000:00:02.0: [drm:wait_panel_status [i915]] Wait complete

<7> [562.289997] i915 0000:00:02.0: [drm:intel_pps_on_unlocked [i915]] [ENCODER:506:DDI A/PHY A] PPS 0 wait for panel power on

<7> [562.290692] i915 0000:00:02.0: [drm:wait_panel_status [i915]] [ENCODER:506:DDI A/PHY A] PPS 0 mask: 0xb000000f value: 0x80000008 PP_STATUS: 0x9000000a PP_CONTROL: 0x00000063

<7> [562.322117] i915 0000:00:02.0: [drm:icp_irq_handler [i915]] hotplug event received, stat 0x00010000, dig 0x0000008a, pins 0x00000010, long 0x00000010

<7> [562.322673] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] digital hpd on [ENCODER:506:DDI A/PHY A] - long

<7> [562.323182] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] Received HPD interrupt on PIN 4 - cnt: 20

<7> [562.323762] i915 0000:00:02.0: [drm:intel_dp_hpd_pulse [i915]] ignoring long hpd on eDP [ENCODER:506:DDI A/PHY A]

<7> [562.495907] i915 0000:00:02.0: [drm:wait_panel_status [i915]] Wait complete

<7> [562.496930] i915 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [i915]] [ENCODER:506:DDI A/PHY A] PPS 0 turning VDD on

<7> [562.497716] i915 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [i915]] [ENCODER:506:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006b

<7> [562.501109] i915 0000:00:02.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX A/DDI A/PHY A: DPCD: 14 14 c4 41 00 00 01 c0 02 00 00 00 00 0b 80

<7> [562.502354] i915 0000:00:02.0: [drm:intel_dp_start_link_train [i915]] [CONNECTOR:507:eDP-1][ENCODER:506:DDI A/PHY A][DPRX] Reloading eDP link rates

<7> [562.503207] i915 0000:00:02.0: [drm:intel_dp_start_link_train [i915]] [CONNECTOR:507:eDP-1][ENCODER:506:DDI A/PHY A][DPRX] Using LINK_RATE_SET value 07

<7> [562.504350] i915 0000:00:02.0: [drm:intel_dp_set_signal_levels [i915]] [CONNECTOR:507:eDP-1][ENCODER:506:DDI A/PHY A][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0

<7> [562.505212] i915 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [i915]] [CONNECTOR:507:eDP-1][ENCODER:506:DDI A/PHY A][DPRX] Using DP training pattern TPS1

<7> [562.506210] i915 0000:00:02.0: [drm:intel_dp_link_train_phy [i915]] [CONNECTOR:507:eDP-1][ENCODER:506:DDI A/PHY A][DPRX] Clock recovery OK

<7> [562.506362] i915 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [i915]] [CONNECTOR:507:eDP-1][ENCODER:506:DDI A/PHY A][DPRX] Using DP training pattern TPS3

<7> [562.507725] i915 0000:00:02.0: [drm:intel_dp_link_train_phy [i915]] [CONNECTOR:507:eDP-1][ENCODER:506:DDI A/PHY A][DPRX] Channel EQ done. DP Training successful

<7> [562.507852] i915 0000:00:02.0: [drm:intel_dp_link_train_phy [i915]] [CONNECTOR:507:eDP-1][ENCODER:506:DDI A/PHY A][DPRX] Link Training passed at link rate = 540000, lane count = 4

<7> [562.508473] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A

<7> [562.508730] i915 0000:00:02.0: [drm:intel_edp_backlight_on [i915]]

<7> [562.508858] i915 0000:00:02.0: [drm:intel_backlight_enable [i915]] pipe A

<7> [562.509056] i915 0000:00:02.0: [drm:intel_backlight_set_pwm_level [i915]] [CONNECTOR:507:eDP-1] set backlight PWM = 13892

<7> [562.520006] i915 0000:00:02.0: [drm:intel_psr_post_plane_update [i915]] Enabling PSR2

<7> [562.521641] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:507:eDP-1]

<7> [562.521853] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:150:pipe A]

<7> [562.524404] i915 0000:00:02.0: [drm:drm_client_dev_restore] fbdev: ret=0

<6> [562.531322] Console: switching to colour frame buffer device 180x56

Results for igt@kms_vblank@query-forked-hang@pipe-d-edp-1