<14>[ 76.541474] [IGT] kms_ccs: starting subtest pipe-A-crc-primary-rotation-180-4-tiled-mtl-rc-ccs <14>[ 76.543059] [IGT] kms_ccs: finished subtest pipe-A-crc-primary-rotation-180-4-tiled-mtl-rc-ccs, SKIP <14>[ 76.679580] [IGT] kms_ccs: exiting, ret=77 <7>[ 76.680634] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc] <7>[ 76.682378] i915 0000:00:02.0: [drm:intel_dp_compute_link_config [i915]] DP link computation with max lane count 4 max rate 540000 max bpp 36 pixel clock 148500KHz <7>[ 76.682862] i915 0000:00:02.0: [drm:intel_dp_compute_link_config [i915]] DP lane count 4 clock 270000 bpp 36 <7>[ 76.683575] i915 0000:00:02.0: [drm:intel_dp_compute_link_config [i915]] DP link rate required 668250 available 1080000 <7>[ 76.684057] i915 0000:00:02.0: [drm:intel_dp_compute_config [i915]] [CONNECTOR:111:DP-1] SDP split enable: no <7>[ 76.684631] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:59:pipe A] hw max bpp: 36, pipe bpp: 36, dithering: 0 <7>[ 76.685139] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:110:DDI B/PHY B] [CRTC:59:pipe A] <7>[ 76.685748] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:59:pipe A] fastset requirement not met in hw.enable (expected 0, found 1) <7>[ 76.686467] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:59:pipe A] fastset requirement not met in hw.active (expected 0, found 1) <7>[ 76.686970] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:59:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0) <7>[ 76.687563] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:59:pipe A] fastset requirement not met in lane_count (expected 0, found 4) <7>[ 76.688064] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:59:pipe A] fastset requirement not met in lane_lat_optim_mask (expected 0x00000000, found 0x0000000d) <7>[ 76.688692] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:59:pipe A] fastset requirement not met in dp_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 5190451/8388608 link 288358/524288) <7>[ 76.689251] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:59:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000080) <7>[ 76.689755] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:59:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1) <7>[ 76.690480] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:59:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920) <7>[ 76.690982] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:59:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200) <7>[ 76.691618] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:59:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920) <7>[ 76.692121] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:59:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200) <7>[ 76.692713] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:59:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008) <7>[ 76.693259] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:59:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052) <7>[ 76.693771] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:59:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080) <7>[ 76.694448] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:59:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125) <7>[ 76.694949] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:59:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080) <7>[ 76.695593] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off <7>[ 76.695566] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:59:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125) <7>[ 76.696124] i915 0000:00:02.0: [drm:gen9_enable_dc5 [i915]] Enabling DC5 <7>[ 76.696099] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:59:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083) <7>[ 76.696703] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 01 <7>[ 76.696744] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:59:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088) <7>[ 76.697288] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:59:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920) <7>[ 76.697787] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:59:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200) <7>[ 76.698117] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on <7>[ 76.698446] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:59:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920) <7>[ 76.698957] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:59:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200) <7>[ 76.699560] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:59:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008) <7>[ 76.700062] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:59:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052) <7>[ 76.700638] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:59:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080) <7>[ 76.701156] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:59:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125) <7>[ 76.701751] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:59:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080) <7>[ 76.702285] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:59:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125) <7>[ 76.702788] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:59:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083) <7>[ 76.703360] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:59:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088) <7>[ 76.703876] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:59:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1) <7>[ 76.704485] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:59:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1) <7>[ 76.704998] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:59:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8) <7>[ 76.705593] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:59:pipe A] fastset requirement not met in has_audio (expected no, found yes) <7>[ 76.706115] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] fastset requirement not met in eld buffer <7>[ 76.706771] expected: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 <7>[ 76.706782] expected: 00 00 00 00 00 00 00 00 00 00 00 00 00 <7>[ 76.706789] found: 10 00 07 00 66 14 00 01 00 00 00 00 00 00 00 00 <7>[ 76.706795] found: 05 e3 cd 0c 32 38 45 38 35 30 09 07 07 <7>[ 76.706805] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:59:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 36) <7>[ 76.707394] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:59:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500) <7>[ 76.707906] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:59:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500) <7>[ 76.708493] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:59:pipe A] fastset requirement not met in port_clock (expected 0, found 270000) <7>[ 76.708999] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] fastset requirement not met, forcing full modeset <7>[ 76.709699] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:59:pipe A] dbuf slices 0x0 -> 0x1, ddb (0 - 0) -> (0 - 508), active pipes 0x0 -> 0x1 <7>[ 76.710345] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:31:plane 1A] ddb ( 0 - 0) -> ( 0 - 476), size 0 -> 476 <7>[ 76.710842] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:55:cursor A] ddb ( 0 - 0) -> ( 476 - 508), size 0 -> 32 <7>[ 76.711400] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:31: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>[ 76.711889] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:31:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 0, 1, 1, 2, 2, 2, 2, 2, 0, 0, 0 <7>[ 76.712529] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:31:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 10, 17, 17, 28, 28, 28, 28, 28, 0, 0, 0 <7>[ 76.713022] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:31:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 11, 18, 18, 29, 29, 29, 29, 29, 0, 0, 0 <7>[ 76.713659] i915 0000:00:02.0: [drm:intel_plane_calc_min_cdclk [i915]] [PLANE:31:plane 1A] min cdclk (148500 kHz) > [CRTC:59:pipe A] min cdclk (0 kHz) <7>[ 76.714248] i915 0000:00:02.0: [drm:intel_bw_calc_min_cdclk [i915]] new bandwidth min cdclk (9282 kHz) > old min cdclk (0 kHz) <7>[ 76.714778] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] Modeset required for cdclk change <7>[ 76.715434] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 288000 kHz, actual 288000 kHz <7>[ 76.715944] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 12, actual 12 <7>[ 76.716533] i915 0000:00:02.0: [drm:bxt_get_dpll [i915]] [CRTC:59:pipe A] using pre-allocated PORT PLL B <7>[ 76.717044] i915 0000:00:02.0: [drm:intel_reference_shared_dpll_crtc.isra.0 [i915]] [CRTC:59:pipe A] reserving PORT PLL B <7>[ 76.717709] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:59:pipe A] enable: yes [modeset] <7>[ 76.718301] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: DP (0x80), output format: RGB, sink format: RGB <7>[ 76.718803] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 36, dithering: 0 <7>[ 76.719381] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <7>[ 76.719896] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: , slave transcoder bitmask = 0x0 <7>[ 76.720553] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] bigjoiner: no, pipes: 0x0 <7>[ 76.721061] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0 <7>[ 76.721598] i915 0000:00:02.0: [drm:intel_dump_m_n_config.isra.0 [i915]] dp m_n: lanes: 4; data_m: 5190451, data_n: 8388608, link_m: 288358, link_n: 524288, tu: 64 <7>[ 76.722097] i915 0000:00:02.0: [drm:intel_dump_m_n_config.isra.0 [i915]] dp m2_n2: lanes: 4; data_m: 0, data_n: 0, link_m: 0, link_n: 0, tu: 0 <7>[ 76.722754] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0 <7>[ 76.723339] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 0, infoframes enabled: 0x0 <7>[ 76.723850] ELD: 10 00 07 00 66 14 00 01 00 00 00 00 00 00 00 00 <7>[ 76.723859] ELD: 05 e3 cd 0c 32 38 45 38 35 30 09 07 07 00 00 00 <7>[ 76.723868] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, vmin: 0, vmax: 0, pipeline full: 0, guardband: 0 flipline: 0, vmin vblank: -1, vmax vblank: -2 <7>[ 76.724454] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9 <7>[ 76.724971] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9 <7>[ 76.725622] i915 0000:00:02.0: [drm:intel_dump_crtc_timings [i915]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1083-1088 vt=1125, flags=0x9 <7>[ 76.726140] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x40 0x9 <7>[ 76.726732] i915 0000:00:02.0: [drm:intel_dump_crtc_timings [i915]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1083-1088 vt=1125, flags=0x9 <7>[ 76.727317] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 270000, pipe src: 1920x1080+0+0, pixel rate 148500 <7>[ 76.727830] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0 <7>[ 76.728504] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0 <7>[ 76.729001] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no <7>[ 76.729588] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0 <7>[ 76.730102] i915 0000:00:02.0: [drm:bxt_dump_hw_state [i915]] dpll_hw_state: ebb0: 0x8100, ebb4: 0x2000,pll0: 0x1b, pll1: 0x100, pll2: 0x0, pll3: 0x0, pll6: 0x10803, pll8: 0x9, pll9: 0xa, pll10: 0x8003c00, pcsdw12: 0x4d <7>[ 76.730747] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x2 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0 <7>[ 76.731318] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries <7>[ 76.731828] i915 0000:00:02.0: [drm:ilk_dump_csc [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000 <7>[ 76.732422] i915 0000:00:02.0: [drm:ilk_dump_csc [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7>[ 76.732938] i915 0000:00:02.0: [drm:ilk_dump_csc [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7>[ 76.733589] i915 0000:00:02.0: [drm:ilk_dump_csc [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7>[ 76.734101] i915 0000:00:02.0: [drm:ilk_dump_csc [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000 <7>[ 76.734686] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:31:plane 1A] fb: [FB:130] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes <7>[ 76.735277] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0 <7>[ 76.735784] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0 <7>[ 76.736442] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:39:plane 2A] fb: [NOFB], visible: no <7>[ 76.736950] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:47:plane 3A] fb: [NOFB], visible: no <7>[ 76.737535] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:55:cursor A] fb: [NOFB], visible: no <7>[ 76.738432] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on <7>[ 76.738933] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off <7>[ 76.739830] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 01 to 00 <7>[ 76.740767] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_2 <7>[ 76.741415] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling dpio-common-bc <7>[ 76.742326] i915 0000:00:02.0: [drm:intel_cdclk_dump_config [i915]] Changing CDCLK to 288000 kHz, VCO 1152000 kHz, ref 19200 kHz, bypass 19200 kHz, voltage level 12 <7>[ 76.743145] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:110:DDI B/PHY B] <7>[ 76.743726] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:112:DP-MST A] <7>[ 76.744273] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:113:DP-MST B] <7>[ 76.744768] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:114:DP-MST C] <7>[ 76.745364] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:122:DDI C/PHY C] <7>[ 76.745858] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:124:DP-MST A] <7>[ 76.746473] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:125:DP-MST B] <7>[ 76.746969] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:126:DP-MST C] <7>[ 76.747529] i915 0000:00:02.0: [drm:verify_single_dpll_state.isra.0 [i915]] PORT PLL A <7>[ 76.748256] i915 0000:00:02.0: [drm:verify_single_dpll_state.isra.0 [i915]] PORT PLL B <7>[ 76.748791] i915 0000:00:02.0: [drm:verify_single_dpll_state.isra.0 [i915]] PORT PLL C <7>[ 76.749517] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enable PORT PLL B (active 0x1, on? 0) for [CRTC:59:pipe A] <7>[ 76.750017] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enabling PORT PLL B <7>[ 76.751502] i915 0000:00:02.0: [drm:lspcon_wake_native_aux_ch [i915]] Native AUX CH up, DPCD version: 1.2 <7>[ 76.752038] i915 0000:00:02.0: [drm:lspcon_resume [i915]] LSPCON recovering in PCON mode after 0 ms <7>[ 76.753286] i915 0000:00:02.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX B/DDI B/PHY B: native defer <7>[ 76.754709] i915 0000:00:02.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX B/DDI B/PHY B: native defer <7>[ 76.756118] i915 0000:00:02.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX B/DDI B/PHY B: native defer <7>[ 76.757524] i915 0000:00:02.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX B/DDI B/PHY B: native defer <7>[ 76.759191] i915 0000:00:02.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX B/DDI B/PHY B: native defer <7>[ 76.760967] i915 0000:00:02.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX B/DDI B/PHY B: native defer <7>[ 76.762685] i915 0000:00:02.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX B/DDI B/PHY B: native defer <7>[ 76.764419] i915 0000:00:02.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX B/DDI B/PHY B: native defer <7>[ 76.765803] i915 0000:00:02.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX B/DDI B/PHY B: native defer <7>[ 76.766697] i915 0000:00:02.0: [drm:lspcon_wait_mode [i915]] Current LSPCON mode PCON <7>[ 76.768002] i915 0000:00:02.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX B/DDI B/PHY B: native defer <7>[ 76.769436] i915 0000:00:02.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX B/DDI B/PHY B: native defer <7>[ 76.770819] i915 0000:00:02.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX B/DDI B/PHY B: native defer <7>[ 76.772229] i915 0000:00:02.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX B/DDI B/PHY B: native defer <7>[ 76.773917] i915 0000:00:02.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX B/DDI B/PHY B: native defer <7>[ 76.775587] i915 0000:00:02.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX B/DDI B/PHY B: native defer <7>[ 76.777226] i915 0000:00:02.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX B/DDI B/PHY B: native defer <7>[ 76.779875] i915 0000:00:02.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX B/DDI B/PHY B: native defer <7>[ 76.781367] i915 0000:00:02.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX B/DDI B/PHY B: native defer <7>[ 76.782333] i915 0000:00:02.0: [drm:lspcon_wait_mode [i915]] Current LSPCON mode PCON <7>[ 76.783530] i915 0000:00:02.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX B/DDI B/PHY B: DPCD: 12 14 c4 01 01 15 01 81 00 00 04 00 0f 00 04 <7>[ 76.784298] i915 0000:00:02.0: [drm:intel_dp_start_link_train [i915]] [CONNECTOR:111:DP-1][ENCODER:110:DDI B/PHY B][DPRX] Using LINK_BW_SET value 0a <7>[ 76.785529] i915 0000:00:02.0: [drm:intel_dp_set_signal_levels [i915]] [CONNECTOR:111:DP-1][ENCODER:110:DDI B/PHY B][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0 <7>[ 76.786061] i915 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [i915]] [CONNECTOR:111:DP-1][ENCODER:110:DDI B/PHY B][DPRX] Using DP training pattern TPS1 <7>[ 76.822708] i915 0000:00:02.0: [drm:intel_dp_link_train_phy [i915]] [CONNECTOR:111:DP-1][ENCODER:110:DDI B/PHY B][DPRX] Clock recovery OK <7>[ 76.823262] i915 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [i915]] [CONNECTOR:111:DP-1][ENCODER:110:DDI B/PHY B][DPRX] Using DP training pattern TPS3 <7>[ 76.859854] i915 0000:00:02.0: [drm:intel_dp_link_train_phy [i915]] [CONNECTOR:111:DP-1][ENCODER:110:DDI B/PHY B][DPRX] Channel EQ done. DP Training successful <7>[ 76.860426] i915 0000:00:02.0: [drm:intel_dp_link_train_phy [i915]] [CONNECTOR:111:DP-1][ENCODER:110:DDI B/PHY B][DPRX] Link Training passed at link rate = 270000, lane count = 4 <7>[ 76.891546] i915 0000:00:02.0: [drm:lspcon_write_infoframe [i915]] AVI IF handled by FW <7>[ 76.892109] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A <7>[ 76.892975] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:111:DP-1][ENCODER:110:DDI B/PHY B] Enable audio codec on [CRTC:59:pipe A], 32 bytes ELD <7>[ 76.909713] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic Maud, Naud <7>[ 76.910423] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 16588800 bytes of contiguous stolen space for FBC, limit: 1 <7>[ 76.910925] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:31:plane 1A] <7>[ 76.926648] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:111:DP-1] <7>[ 76.927359] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:59:pipe A] <7>[ 76.930199] i915 0000:00:02.0: [drm:verify_single_dpll_state.isra.0 [i915]] PORT PLL B <6>[ 76.943696] Console: switching to colour frame buffer device 240x67 <6>[ 77.183952] Console: switching to colour dummy device 80x25 <14>[ 77.184139] [IGT] gem_exec_fence: executing <7>[ 77.226374] i915 0000:00:02.0: [drm:i915_gem_open [i915]] <7>[ 77.228084] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc] <7>[ 77.228936] i915 0000:00:02.0: [drm:i915_gem_open [i915]] <7>[ 77.230008] i915 0000:00:02.0: [drm:i915_gem_open [i915]] <7>[ 77.516496] i915 0000:00:02.0: [drm:i915_gem_open [i915]] <7>[ 77.540905] i915 0000:00:02.0: [drm:i915_gem_open [i915]] <7>[ 77.652973] i915 0000:00:02.0: [drm:i915_gem_open [i915]] <7>[ 77.677158] i915 0000:00:02.0: [drm:i915_gem_open [i915]] <14>[ 77.849000] [IGT] gem_exec_fence: starting subtest syncobj-backward-timeline-chain-engines <14>[ 77.977912] [IGT] gem_exec_fence: finished subtest syncobj-backward-timeline-chain-engines, SUCCESS <14>[ 78.095478] [IGT] gem_exec_fence: exiting, ret=0 <7>[ 78.096063] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc] <6>[ 78.161023] Console: switching to colour frame buffer device 240x67 <6>[ 78.393682] Console: switching to colour dummy device 80x25 <14>[ 78.393883] [IGT] gem_exec_alignment: executing <7>[ 78.438792] i915 0000:00:02.0: [drm:i915_gem_open [i915]] <7>[ 78.440528] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc] <7>[ 78.441369] i915 0000:00:02.0: [drm:i915_gem_open [i915]] <7>[ 78.442459] i915 0000:00:02.0: [drm:i915_gem_open [i915]] <14>[ 78.452701] [IGT] gem_exec_alignment: starting subtest forked <7>[ 78.452886] i915 0000:00:02.0: [drm:i915_gem_open [i915]] <7>[ 78.454103] i915 0000:00:02.0: [drm:i915_gem_open [i915]] <7>[ 78.455421] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x00000070 [0x00000070] <7>[ 78.589134] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x00000070 [0x00000070] <4>[ 80.177381] ------------[ cut here ]------------ <4>[ 80.177403] DEBUG_LOCKS_WARN_ON(hlock->references < references) <4>[ 80.177413] WARNING: CPU: 3 PID: 1164 at kernel/locking/lockdep.c:5055 __lock_acquire+0x886/0x2330 <4>[ 80.177438] Modules linked in: amdgpu drm_ttm_helper drm_suballoc_helper amdxcp gpu_sched drm_exec vgem drm_shmem_helper fuse snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic ledtrig_audio x86_pkg_temp_thermal coretemp kvm_intel mei_pxp mei_hdcp wmi_bmof i915 kvm irqbypass crct10dif_pclmul crc32_pclmul ghash_clmulni_intel r8169 i2c_i801 realtek i2c_smbus lpc_ich snd_hda_intel snd_intel_dspcfg snd_hda_codec i2c_algo_bit ttm snd_hwdep video snd_hda_core drm_display_helper prime_numbers mei_me pinctrl_broxton drm_buddy snd_pcm mei wmi intel_lpss_pci <4>[ 80.177547] CPU: 3 PID: 1164 Comm: gem_exec_alignm Not tainted 6.6.0-linus-v6.6-gffc253263a13+ #1 <4>[ 80.177560] Hardware name: /NUC6CAYB, BIOS AYAPLCEL.86A.0049.2018.0508.1356 05/08/2018 <4>[ 80.177570] RIP: 0010:__lock_acquire+0x886/0x2330 <4>[ 80.177579] Code: c0 0f 84 f5 fe ff ff 44 8b 3d 2a cc 72 01 45 85 ff 0f 85 e5 fe ff ff 48 c7 c6 b0 4a 44 82 48 c7 c7 43 a2 3d 82 e8 1a 3e f7 ff <0f> 0b e9 ce fe ff ff 45 31 e4 e9 f8 f9 ff ff 45 85 c9 0f 85 fc fb <4>[ 80.177598] RSP: 0018:ffffc90000f37788 EFLAGS: 00010086 <4>[ 80.177607] RAX: 0000000000000000 RBX: ffffc90000f37b70 RCX: 0000000000000000 <4>[ 80.177616] RDX: 0000000000000003 RSI: ffffffff823ec2d4 RDI: 00000000ffffffff <4>[ 80.177625] RBP: ffff888124515c20 R08: 0000000000000000 R09: ffffc90000f37620 <4>[ 80.177634] R10: 0000000000000001 R11: 0000000000000001 R12: 0000000000000000 <4>[ 80.177642] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000 <4>[ 80.177650] FS: 00007f4de36f2c40(0000) GS:ffff888277980000(0000) knlGS:0000000000000000 <4>[ 80.177661] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 <4>[ 80.177668] CR2: 00007f4de290d000 CR3: 000000010a43c000 CR4: 00000000003506e0 <4>[ 80.177677] Call Trace: <4>[ 80.177684] <4>[ 80.177690] ? __warn+0x7f/0x170 <4>[ 80.177700] ? __lock_acquire+0x886/0x2330 <4>[ 80.177708] ? report_bug+0x1f8/0x200 <4>[ 80.177718] ? handle_bug+0x3c/0x70 <4>[ 80.177727] ? exc_invalid_op+0x18/0x70 <4>[ 80.177736] ? asm_exc_invalid_op+0x1a/0x20 <4>[ 80.177747] ? __lock_acquire+0x886/0x2330 <4>[ 80.177755] ? __lock_acquire+0x886/0x2330 <4>[ 80.177765] ? eb_validate_vmas+0x137/0xd80 [i915] <4>[ 80.178224] lock_acquire+0xd8/0x2d0 <4>[ 80.178238] ? eb_validate_vmas+0x137/0xd80 [i915] <4>[ 80.178706] __ww_mutex_lock.constprop.0+0xbf/0x13e0 <4>[ 80.178721] ? eb_validate_vmas+0x137/0xd80 [i915] <4>[ 80.179190] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 <4>[ 80.179204] ? lockdep_hardirqs_on+0xc3/0x140 <4>[ 80.179214] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 <4>[ 80.179224] ? eb_validate_vmas+0x137/0xd80 [i915] <4>[ 80.179696] ? ww_mutex_lock_interruptible+0x37/0x100 <4>[ 80.179708] ww_mutex_lock_interruptible+0x37/0x100 <4>[ 80.179719] eb_validate_vmas+0x137/0xd80 [i915] <4>[ 80.180198] i915_gem_do_execbuffer+0xcbc/0x2700 [i915] <4>[ 80.180674] ? do_pte_missing+0x37a/0xd00 <4>[ 80.180691] ? preempt_count_add+0x4c/0xa0 <4>[ 80.180701] ? up_read+0xba/0x250 <4>[ 80.180709] ? do_user_addr_fault+0x445/0x9d0 <4>[ 80.180719] ? exc_page_fault+0xb2/0x1a0 <4>[ 80.180729] ? lockdep_hardirqs_on+0xc3/0x140 <4>[ 80.180738] ? asm_exc_page_fault+0x26/0x30 <4>[ 80.180751] i915_gem_execbuffer2_ioctl+0x123/0x2a0 [i915] <4>[ 80.181221] ? __pfx_i915_gem_execbuffer2_ioctl+0x10/0x10 [i915] <4>[ 80.181693] drm_ioctl_kernel+0xb6/0x150 <4>[ 80.181707] drm_ioctl+0x21d/0x420 <4>[ 80.181715] ? __pfx_i915_gem_execbuffer2_ioctl+0x10/0x10 [i915] <4>[ 80.182187] __x64_sys_ioctl+0x7c/0xb0 <4>[ 80.182202] do_syscall_64+0x3f/0x90 <4>[ 80.182213] entry_SYSCALL_64_after_hwframe+0x6e/0xd8 <4>[ 80.182224] RIP: 0033:0x7f4de5f203ab <4>[ 80.182234] Code: 0f 1e fa 48 8b 05 e5 7a 0d 00 64 c7 00 26 00 00 00 48 c7 c0 ff ff ff ff c3 66 0f 1f 44 00 00 f3 0f 1e fa b8 10 00 00 00 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d b5 7a 0d 00 f7 d8 64 89 01 48 <4>[ 80.182254] RSP: 002b:00007fff6f2e6728 EFLAGS: 00000246 ORIG_RAX: 0000000000000010 <4>[ 80.182267] RAX: ffffffffffffffda RBX: 00007fff6f2e67c0 RCX: 00007f4de5f203ab <4>[ 80.182276] RDX: 00007fff6f2e67c0 RSI: 0000000040406469 RDI: 0000000000000007 <4>[ 80.182285] RBP: 0000000040406469 R08: 0000000000000004 R09: 0000000000000000 <4>[ 80.182294] R10: 00007fff6f3b41b0 R11: 0000000000000246 R12: 0000000000000007 <4>[ 80.182303] R13: 0000000000000007 R14: 00007f4de28f1010 R15: 00007f4de290d010 <4>[ 80.182316] <0>[ 80.182322] Kernel panic - not syncing: kernel: panic_on_warn set ... <4>[ 80.182332] CPU: 3 PID: 1164 Comm: gem_exec_alignm Not tainted 6.6.0-linus-v6.6-gffc253263a13+ #1 <4>[ 80.182344] Hardware name: /NUC6CAYB, BIOS AYAPLCEL.86A.0049.2018.0508.1356 05/08/2018 <4>[ 80.182354] Call Trace: <4>[ 80.182362] <4>[ 80.182367] dump_stack_lvl+0x64/0xb0 <4>[ 80.182380] panic+0x2c0/0x2f0 <4>[ 80.182390] ? __lock_acquire+0x886/0x2330 <4>[ 80.182400] check_panic_on_warn+0x4f/0x50 <4>[ 80.182408] __warn+0x8b/0x170 <4>[ 80.182416] ? __lock_acquire+0x886/0x2330 <4>[ 80.182423] report_bug+0x1f8/0x200 <4>[ 80.182433] handle_bug+0x3c/0x70 <4>[ 80.182441] exc_invalid_op+0x18/0x70 <4>[ 80.182449] asm_exc_invalid_op+0x1a/0x20 <4>[ 80.182457] RIP: 0010:__lock_acquire+0x886/0x2330 <4>[ 80.182466] Code: c0 0f 84 f5 fe ff ff 44 8b 3d 2a cc 72 01 45 85 ff 0f 85 e5 fe ff ff 48 c7 c6 b0 4a 44 82 48 c7 c7 43 a2 3d 82 e8 1a 3e f7 ff <0f> 0b e9 ce fe ff ff 45 31 e4 e9 f8 f9 ff ff 45 85 c9 0f 85 fc fb <4>[ 80.182486] RSP: 0018:ffffc90000f37788 EFLAGS: 00010086 <4>[ 80.182495] RAX: 0000000000000000 RBX: ffffc90000f37b70 RCX: 0000000000000000 <4>[ 80.182504] RDX: 0000000000000003 RSI: ffffffff823ec2d4 RDI: 00000000ffffffff <4>[ 80.182512] RBP: ffff888124515c20 R08: 0000000000000000 R09: ffffc90000f37620 <4>[ 80.182521] R10: 0000000000000001 R11: 0000000000000001 R12: 0000000000000000 <4>[ 80.182529] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000 <4>[ 80.182541] ? __lock_acquire+0x886/0x2330 <4>[ 80.182552] ? eb_validate_vmas+0x137/0xd80 [i915] <4>[ 80.183023] lock_acquire+0xd8/0x2d0 <4>[ 80.183036] ? eb_validate_vmas+0x137/0xd80 [i915] <4>[ 80.183513] __ww_mutex_lock.constprop.0+0xbf/0x13e0 <4>[ 80.183529] ? eb_validate_vmas+0x137/0xd80 [i915] <4>[ 80.183998] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 <4>[ 80.184012] ? lockdep_hardirqs_on+0xc3/0x140 <4>[ 80.184022] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 <4>[ 80.184031] ? eb_validate_vmas+0x137/0xd80 [i915] <4>[ 80.184504] ? ww_mutex_lock_interruptible+0x37/0x100 <4>[ 80.184516] ww_mutex_lock_interruptible+0x37/0x100 <4>[ 80.184527] eb_validate_vmas+0x137/0xd80 [i915] <4>[ 80.185006] i915_gem_do_execbuffer+0xcbc/0x2700 [i915] <4>[ 80.185481] ? do_pte_missing+0x37a/0xd00 <4>[ 80.185499] ? preempt_count_add+0x4c/0xa0 <4>[ 80.185509] ? up_read+0xba/0x250 <4>[ 80.185517] ? do_user_addr_fault+0x445/0x9d0 <4>[ 80.185527] ? exc_page_fault+0xb2/0x1a0 <4>[ 80.185537] ? lockdep_hardirqs_on+0xc3/0x140 <4>[ 80.185545] ? asm_exc_page_fault+0x26/0x30 <4>[ 80.185558] i915_gem_execbuffer2_ioctl+0x123/0x2a0 [i915] <4>[ 80.186032] ? __pfx_i915_gem_execbuffer2_ioctl+0x10/0x10 [i915] <4>[ 80.186500] drm_ioctl_kernel+0xb6/0x150 <4>[ 80.186513] drm_ioctl+0x21d/0x420 <4>[ 80.186522] ? __pfx_i915_gem_execbuffer2_ioctl+0x10/0x10 [i915] <4>[ 80.187001] __x64_sys_ioctl+0x7c/0xb0 <4>[ 80.187015] do_syscall_64+0x3f/0x90 <4>[ 80.187026] entry_SYSCALL_64_after_hwframe+0x6e/0xd8 <4>[ 80.187037] RIP: 0033:0x7f4de5f203ab <4>[ 80.187046] Code: 0f 1e fa 48 8b 05 e5 7a 0d 00 64 c7 00 26 00 00 00 48 c7 c0 ff ff ff ff c3 66 0f 1f 44 00 00 f3 0f 1e fa b8 10 00 00 00 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d b5 7a 0d 00 f7 d8 64 89 01 48 <4>[ 80.187066] RSP: 002b:00007fff6f2e6728 EFLAGS: 00000246 ORIG_RAX: 0000000000000010 <4>[ 80.187078] RAX: ffffffffffffffda RBX: 00007fff6f2e67c0 RCX: 00007f4de5f203ab <4>[ 80.187087] RDX: 00007fff6f2e67c0 RSI: 0000000040406469 RDI: 0000000000000007 <4>[ 80.187096] RBP: 0000000040406469 R08: 0000000000000004 R09: 0000000000000000 <4>[ 80.187105] R10: 00007fff6f3b41b0 R11: 0000000000000246 R12: 0000000000000007 <4>[ 80.187113] R13: 0000000000000007 R14: 00007f4de28f1010 R15: 00007f4de290d010 <4>[ 80.187126] <0>[ 80.187153] Kernel Offset: disabled