<7>[ 311.952815] 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>[ 311.953316] 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>[ 311.953925] 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>[ 311.954430] 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>[ 311.955040] 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>[ 311.955550] 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>[ 311.956203] 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>[ 311.956813] 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>[ 311.957324] 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>[ 311.957935] 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>[ 311.958446] 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>[ 311.959045] 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>[ 311.959555] 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>[ 311.960131] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] fastset requirement not met in eld buffer <7>[ 311.960661] expected: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 <7>[ 311.960671] expected: 00 00 00 00 00 00 00 00 00 00 00 00 00 <7>[ 311.960678] found: 10 00 07 00 66 14 00 01 00 00 00 00 00 00 00 00 <7>[ 311.960684] found: 05 e3 cd 0c 32 38 45 38 35 30 09 07 07 <7>[ 311.960764] 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>[ 311.961272] 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>[ 311.961943] 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>[ 311.962449] 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>[ 311.963047] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] fastset requirement not met, forcing full modeset <7>[ 311.963689] 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>[ 311.964404] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:31:plane 1A] ddb ( 0 - 0) -> ( 0 - 476), size 0 -> 476 <7>[ 311.964970] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:55:cursor A] ddb ( 0 - 0) -> ( 476 - 508), size 0 -> 32 <7>[ 311.965459] 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>[ 311.966033] 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>[ 311.966533] 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>[ 311.967184] 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>[ 311.967839] 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>[ 311.968388] i915 0000:00:02.0: [drm:intel_bw_calc_min_cdclk [i915]] new bandwidth min cdclk (9282 kHz) > old min cdclk (0 kHz) <7>[ 311.968990] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] Modeset required for cdclk change <7>[ 311.969524] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 288000 kHz, actual 288000 kHz <7>[ 311.970207] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 12, actual 12 <7>[ 311.970818] i915 0000:00:02.0: [drm:bxt_get_dpll [i915]] [CRTC:59:pipe A] using pre-allocated PORT PLL B <7>[ 311.971317] i915 0000:00:02.0: [drm:intel_reference_shared_dpll_crtc.isra.0 [i915]] [CRTC:59:pipe A] reserving PORT PLL B <7>[ 311.971911] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:59:pipe A] enable: yes [modeset] <7>[ 311.972424] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: DP (0x80), output format: RGB, sink format: RGB <7>[ 311.973027] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 36, dithering: 0 <7>[ 311.973562] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <7>[ 311.974125] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: , slave transcoder bitmask = 0x0 <7>[ 311.974657] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] bigjoiner: no, pipes: 0x0 <7>[ 311.975292] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0 <7>[ 311.975903] 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>[ 311.976449] 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>[ 311.977060] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0 <7>[ 311.977564] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 0, infoframes enabled: 0x0 <7>[ 311.978173] ELD: 10 00 07 00 66 14 00 01 00 00 00 00 00 00 00 00 <7>[ 311.978184] ELD: 05 e3 cd 0c 32 38 45 38 35 30 09 07 07 00 00 00 <7>[ 311.978195] 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>[ 311.978765] 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>[ 311.979274] 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>[ 311.979883] 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>[ 311.980418] 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>[ 311.981071] 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>[ 311.981600] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 270000, pipe src: 1920x1080+0+0, pixel rate 148500 <7>[ 311.982207] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0 <7>[ 311.982779] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0 <7>[ 311.983277] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no <7>[ 311.983909] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0 <7>[ 311.984415] 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>[ 311.984990] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x2 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0 <7>[ 311.985521] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries <7>[ 311.986129] i915 0000:00:02.0: [drm:ilk_dump_csc [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000 <7>[ 311.986669] i915 0000:00:02.0: [drm:ilk_dump_csc [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7>[ 311.987236] i915 0000:00:02.0: [drm:ilk_dump_csc [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7>[ 311.987803] i915 0000:00:02.0: [drm:ilk_dump_csc [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7>[ 311.988335] i915 0000:00:02.0: [drm:ilk_dump_csc [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000 <7>[ 311.988949] 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>[ 311.989456] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0 <7>[ 311.990017] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0 <7>[ 311.990529] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:39:plane 2A] fb: [NOFB], visible: no <7>[ 311.991166] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:47:plane 3A] fb: [NOFB], visible: no <7>[ 311.991746] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:55:cursor A] fb: [NOFB], visible: no <7>[ 311.992653] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on <7>[ 311.993297] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off <7>[ 311.994099] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 01 to 00 <7>[ 311.995054] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_2 <7>[ 311.995659] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling dpio-common-bc <7>[ 311.996656] 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>[ 311.997565] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:110:DDI B/PHY B] <7>[ 311.998152] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:112:DP-MST A] <7>[ 311.998644] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:113:DP-MST B] <7>[ 311.999243] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:114:DP-MST C] <7>[ 311.999765] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:122:DDI C/PHY C] <7>[ 312.000284] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:124:DP-MST A] <7>[ 312.000860] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:125:DP-MST B] <7>[ 312.001348] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:126:DP-MST C] <7>[ 312.001959] i915 0000:00:02.0: [drm:verify_single_dpll_state.isra.0 [i915]] PORT PLL A <7>[ 312.002528] i915 0000:00:02.0: [drm:verify_single_dpll_state.isra.0 [i915]] PORT PLL B <7>[ 312.003151] i915 0000:00:02.0: [drm:verify_single_dpll_state.isra.0 [i915]] PORT PLL C <7>[ 312.003810] 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>[ 312.004314] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enabling PORT PLL B <7>[ 312.005803] i915 0000:00:02.0: [drm:lspcon_wake_native_aux_ch [i915]] Native AUX CH up, DPCD version: 1.2 <7>[ 312.006572] i915 0000:00:02.0: [drm:lspcon_resume [i915]] LSPCON recovering in PCON mode after 0 ms <7>[ 312.007796] i915 0000:00:02.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX B/DDI B/PHY B: native defer <7>[ 312.009343] i915 0000:00:02.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX B/DDI B/PHY B: native defer <7>[ 312.010869] i915 0000:00:02.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX B/DDI B/PHY B: native defer <7>[ 312.012286] i915 0000:00:02.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX B/DDI B/PHY B: native defer <7>[ 312.014148] i915 0000:00:02.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX B/DDI B/PHY B: native defer <7>[ 312.015846] i915 0000:00:02.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX B/DDI B/PHY B: native defer <7>[ 312.017413] i915 0000:00:02.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX B/DDI B/PHY B: native defer <7>[ 312.019164] i915 0000:00:02.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX B/DDI B/PHY B: native defer <7>[ 312.020600] i915 0000:00:02.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX B/DDI B/PHY B: native defer <7>[ 312.021525] i915 0000:00:02.0: [drm:lspcon_wait_mode [i915]] Current LSPCON mode PCON <7>[ 312.023041] i915 0000:00:02.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX B/DDI B/PHY B: native defer <7>[ 312.024478] i915 0000:00:02.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX B/DDI B/PHY B: native defer <7>[ 312.026098] i915 0000:00:02.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX B/DDI B/PHY B: native defer <7>[ 312.027493] i915 0000:00:02.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX B/DDI B/PHY B: native defer <7>[ 312.029104] i915 0000:00:02.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX B/DDI B/PHY B: native defer <7>[ 312.030608] i915 0000:00:02.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX B/DDI B/PHY B: native defer <7>[ 312.032324] i915 0000:00:02.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX B/DDI B/PHY B: native defer <7>[ 312.034013] i915 0000:00:02.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX B/DDI B/PHY B: native defer <7>[ 312.035771] i915 0000:00:02.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX B/DDI B/PHY B: native defer <7>[ 312.036775] i915 0000:00:02.0: [drm:lspcon_wait_mode [i915]] Current LSPCON mode PCON <7>[ 312.037922] 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>[ 312.038824] 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>[ 312.039935] 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>[ 312.040463] 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>[ 312.076815] 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>[ 312.077302] 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>[ 312.112305] 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>[ 312.112875] 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>[ 312.144619] i915 0000:00:02.0: [drm:lspcon_write_infoframe [i915]] AVI IF handled by FW <7>[ 312.145402] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A <7>[ 312.146271] 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>[ 312.163050] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic Maud, Naud <7>[ 312.163690] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 16588800 bytes of contiguous stolen space for FBC, limit: 1 <7>[ 312.164246] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:31:plane 1A] <7>[ 312.179993] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:111:DP-1] <7>[ 312.180637] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:59:pipe A] <7>[ 312.183426] i915 0000:00:02.0: [drm:verify_single_dpll_state.isra.0 [i915]] PORT PLL B <6>[ 312.197229] Console: switching to colour frame buffer device 240x67 <6>[ 312.389826] Console: switching to colour dummy device 80x25 <14>[ 312.391086] [IGT] gem_exec_schedule: executing <7>[ 312.404468] i915 0000:00:02.0: [drm:i915_gem_open [i915]] <7>[ 312.406346] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc] <7>[ 312.407159] i915 0000:00:02.0: [drm:i915_gem_open [i915]] <7>[ 312.409129] i915 0000:00:02.0: [drm:i915_gem_open [i915]] <14>[ 312.436417] [IGT] gem_exec_schedule: starting subtest independent <14>[ 312.437844] [IGT] gem_exec_schedule: starting dynamic subtest rcs0 <7>[ 312.590165] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c] <14>[ 312.622687] [IGT] gem_exec_schedule: finished subtest rcs0, SUCCESS <14>[ 312.624632] [IGT] gem_exec_schedule: starting dynamic subtest bcs0 <7>[ 312.784538] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c] <14>[ 312.815574] [IGT] gem_exec_schedule: finished subtest bcs0, SUCCESS <14>[ 312.817249] [IGT] gem_exec_schedule: starting dynamic subtest vcs0 <7>[ 312.958110] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c] <14>[ 312.995444] [IGT] gem_exec_schedule: finished subtest vcs0, SUCCESS <14>[ 312.996944] [IGT] gem_exec_schedule: starting dynamic subtest vecs0 <7>[ 313.140851] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c] <14>[ 313.174155] [IGT] gem_exec_schedule: finished subtest vecs0, SUCCESS <14>[ 313.175854] [IGT] gem_exec_schedule: finished subtest independent, SUCCESS <14>[ 313.431575] [IGT] gem_exec_schedule: exiting, ret=0 <7>[ 313.432321] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc] <6>[ 313.464583] Console: switching to colour frame buffer device 240x67 <6>[ 313.655168] Console: switching to colour dummy device 80x25 <14>[ 313.656480] [IGT] prime_busy: executing <7>[ 313.663060] i915 0000:00:02.0: [drm:i915_gem_open [i915]] <7>[ 313.664846] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc] <7>[ 313.665854] i915 0000:00:02.0: [drm:i915_gem_open [i915]] <7>[ 313.667174] i915 0000:00:02.0: [drm:i915_gem_open [i915]] <14>[ 313.678077] [IGT] prime_busy: starting subtest hang-wait <14>[ 313.680296] [IGT] prime_busy: starting dynamic subtest rcs0 <7>[ 313.680852] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c] <5>[ 320.946520] i915 0000:00:02.0: [drm] Resetting rcs0 for preemption time out <6>[ 320.947089] i915 0000:00:02.0: [drm] GPU HANG: ecode 9:1:e757fefe, in prime_busy [17181] <14>[ 320.951489] [IGT] prime_busy: finished subtest rcs0, SUCCESS <14>[ 320.952859] [IGT] prime_busy: starting dynamic subtest bcs0 <7>[ 320.953304] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c] <5>[ 327.922638] i915 0000:00:02.0: [drm] Resetting bcs0 for preemption time out <6>[ 327.923151] i915 0000:00:02.0: [drm] GPU HANG: ecode 9:2:e77ffef2, in prime_busy [17181] <14>[ 327.926463] [IGT] prime_busy: finished subtest bcs0, SUCCESS <14>[ 327.927947] [IGT] prime_busy: starting dynamic subtest vcs0 <7>[ 327.928355] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c] <5>[ 334.898632] i915 0000:00:02.0: [drm] Resetting vcs0 for preemption time out <6>[ 334.899092] i915 0000:00:02.0: [drm] GPU HANG: ecode 9:4:a77ffefe, in prime_busy [17181] <14>[ 334.903294] [IGT] prime_busy: finished subtest vcs0, SUCCESS <14>[ 334.904582] [IGT] prime_busy: starting dynamic subtest vecs0 <7>[ 334.905072] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c] <5>[ 341.938634] i915 0000:00:02.0: [drm] Resetting vecs0 for preemption time out <6>[ 341.939073] i915 0000:00:02.0: [drm] GPU HANG: ecode 9:8:277ffefe, in prime_busy [17181] <14>[ 341.941841] [IGT] prime_busy: finished subtest vecs0, SUCCESS <14>[ 341.943640] [IGT] prime_busy: finished subtest hang-wait, SUCCESS <14>[ 341.945198] [IGT] prime_busy: exiting, ret=0 <7>[ 341.945867] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc] <6>[ 341.980308] Console: switching to colour frame buffer device 240x67 <6>[ 342.171856] Console: switching to colour dummy device 80x25 <14>[ 342.173171] [IGT] gem_userptr_blits: executing <7>[ 342.182580] i915 0000:00:02.0: [drm:i915_gem_open [i915]] <7>[ 342.184250] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc] <7>[ 342.185085] i915 0000:00:02.0: [drm:i915_gem_open [i915]] <7>[ 342.186174] i915 0000:00:02.0: [drm:i915_gem_open [i915]] <14>[ 342.228401] [IGT] gem_userptr_blits: starting subtest stress-mm-invalidate-close <14>[ 344.378618] [IGT] gem_userptr_blits: finished subtest stress-mm-invalidate-close, SUCCESS <14>[ 344.411571] [IGT] gem_userptr_blits: exiting, ret=0 <7>[ 344.412483] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc] <6>[ 344.463844] Console: switching to colour frame buffer device 240x67 <6>[ 344.663524] Console: switching to colour dummy device 80x25 <14>[ 344.665083] [IGT] gem_exec_alignment: executing <7>[ 344.671677] i915 0000:00:02.0: [drm:i915_gem_open [i915]] <7>[ 344.673489] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc] <7>[ 344.674320] i915 0000:00:02.0: [drm:i915_gem_open [i915]] <7>[ 344.675437] i915 0000:00:02.0: [drm:i915_gem_open [i915]] <14>[ 344.687661] [IGT] gem_exec_alignment: starting subtest forked <7>[ 344.688238] i915 0000:00:02.0: [drm:i915_gem_open [i915]] <7>[ 344.689520] i915 0000:00:02.0: [drm:i915_gem_open [i915]] <6>[ 344.690845] gem_exec_alignm (17187): drop_caches: 4 <7>[ 344.691080] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x00000070 [0x00000070] <7>[ 346.179888] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x00000070 [0x00000070] <4>[ 347.796644] ------------[ cut here ]------------ <4>[ 347.796665] DEBUG_LOCKS_WARN_ON(hlock->references < references) <4>[ 347.796675] WARNING: CPU: 2 PID: 17187 at kernel/locking/lockdep.c:5055 __lock_acquire+0x886/0x2330 <4>[ 347.796700] Modules linked in: vgem drm_shmem_helper kunit fuse snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic ledtrig_audio x86_pkg_temp_thermal mei_pxp mei_hdcp coretemp wmi_bmof kvm_intel kvm irqbypass i915 crct10dif_pclmul crc32_pclmul ghash_clmulni_intel r8169 i2c_i801 realtek lpc_ich i2c_smbus i2c_algo_bit ttm snd_hda_intel snd_intel_dspcfg snd_hda_codec drm_display_helper snd_hwdep video prime_numbers snd_hda_core mei_me snd_pcm mei drm_buddy pinctrl_broxton intel_lpss_pci wmi [last unloaded: drm_format_helper_test] <4>[ 347.796804] CPU: 2 PID: 17187 Comm: gem_exec_alignm Tainted: G N 6.6.0-rc3-linus-v6.6-rc3-g6465e260f487+ #1 <4>[ 347.796818] Hardware name: /NUC6CAYB, BIOS AYAPLCEL.86A.0049.2018.0508.1356 05/08/2018 <4>[ 347.796828] RIP: 0010:__lock_acquire+0x886/0x2330 <4>[ 347.796838] Code: c0 0f 84 f5 fe ff ff 44 8b 3d ca fc 72 01 45 85 ff 0f 85 e5 fe ff ff 48 c7 c6 80 53 44 82 48 c7 c7 ce ab 3d 82 e8 3a 3f 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>[ 347.796857] RSP: 0018:ffffc90001037788 EFLAGS: 00010086 <4>[ 347.796866] RAX: 0000000000000000 RBX: ffffc90001037b70 RCX: 0000000000000000 <4>[ 347.796874] RDX: 0000000000000003 RSI: ffffffff823ecc3e RDI: 00000000ffffffff <4>[ 347.796884] RBP: ffff8881023f5c20 R08: 0000000000000000 R09: ffffc90001037620 <4>[ 347.796892] R10: 00000000000f2698 R11: 00000000000f2758 R12: 0000000000000000 <4>[ 347.796901] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000 <4>[ 347.796909] FS: 00007f92d410ac40(0000) GS:ffff888277900000(0000) knlGS:0000000000000000 <4>[ 347.796919] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 <4>[ 347.796927] CR2: 00007f92d3325000 CR3: 000000010c95c000 CR4: 00000000003506e0 <4>[ 347.796936] Call Trace: <4>[ 347.796943] <4>[ 347.796949] ? __warn+0x7f/0x170 <4>[ 347.796959] ? __lock_acquire+0x886/0x2330 <4>[ 347.796967] ? report_bug+0x1f8/0x200 <4>[ 347.796977] ? handle_bug+0x3c/0x70 <4>[ 347.796986] ? exc_invalid_op+0x18/0x70 <4>[ 347.796994] ? asm_exc_invalid_op+0x1a/0x20 <4>[ 347.797005] ? __lock_acquire+0x886/0x2330 <4>[ 347.797012] ? __lock_acquire+0x886/0x2330 <4>[ 347.797020] ? mark_held_locks+0x49/0x80 <4>[ 347.797030] lock_acquire+0xd8/0x2d0 <4>[ 347.797041] ? eb_validate_vmas+0x137/0xd80 [i915] <4>[ 347.797509] __ww_mutex_lock.constprop.0+0xbf/0x13e0 <4>[ 347.797526] ? eb_validate_vmas+0x137/0xd80 [i915] <4>[ 347.797995] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 <4>[ 347.798009] ? lockdep_hardirqs_on+0xc3/0x140 <4>[ 347.798018] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 <4>[ 347.798028] ? eb_validate_vmas+0x137/0xd80 [i915] <4>[ 347.798498] ? ww_mutex_lock_interruptible+0x37/0x100 <4>[ 347.798512] ww_mutex_lock_interruptible+0x37/0x100 <4>[ 347.798523] eb_validate_vmas+0x137/0xd80 [i915] <4>[ 347.798996] i915_gem_do_execbuffer+0xcbc/0x2700 [i915] <4>[ 347.799475] ? do_pte_missing+0x37a/0xd00 <4>[ 347.799493] ? preempt_count_add+0x4c/0xa0 <4>[ 347.799503] ? up_read+0xba/0x250 <4>[ 347.799512] ? do_user_addr_fault+0x445/0x9d0 <4>[ 347.799521] ? exc_page_fault+0xb2/0x1a0 <4>[ 347.799532] ? lockdep_hardirqs_on+0xc3/0x140 <4>[ 347.799540] ? asm_exc_page_fault+0x26/0x30 <4>[ 347.799552] i915_gem_execbuffer2_ioctl+0x123/0x2a0 [i915] <4>[ 347.800023] ? __pfx_i915_gem_execbuffer2_ioctl+0x10/0x10 [i915] <4>[ 347.800497] drm_ioctl_kernel+0xb6/0x150 <4>[ 347.800511] drm_ioctl+0x21d/0x420 <4>[ 347.800520] ? __pfx_i915_gem_execbuffer2_ioctl+0x10/0x10 [i915] <4>[ 347.800999] __x64_sys_ioctl+0x7c/0xb0 <4>[ 347.801013] do_syscall_64+0x3f/0x90 <4>[ 347.801025] entry_SYSCALL_64_after_hwframe+0x6e/0xd8 <4>[ 347.801036] RIP: 0033:0x7f92d69383ab <4>[ 347.801047] 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>[ 347.801068] RSP: 002b:00007fff8bd42ff8 EFLAGS: 00000246 ORIG_RAX: 0000000000000010 <4>[ 347.801081] RAX: ffffffffffffffda RBX: 00007fff8bd43090 RCX: 00007f92d69383ab <4>[ 347.801090] RDX: 00007fff8bd43090 RSI: 0000000040406469 RDI: 0000000000000007 <4>[ 347.801099] RBP: 0000000040406469 R08: 0000000000000004 R09: 0000000000000000 <4>[ 347.801107] R10: 00007fff8bda01b0 R11: 0000000000000246 R12: 0000000000000007 <4>[ 347.801116] R13: 0000000000000007 R14: 00007f92d3309010 R15: 00007f92d3325010 <4>[ 347.801129] <0>[ 347.801136] Kernel panic - not syncing: kernel: panic_on_warn set ... <4>[ 347.801145] CPU: 2 PID: 17187 Comm: gem_exec_alignm Tainted: G N 6.6.0-rc3-linus-v6.6-rc3-g6465e260f487+ #1 <4>[ 347.801160] Hardware name: /NUC6CAYB, BIOS AYAPLCEL.86A.0049.2018.0508.1356 05/08/2018 <4>[ 347.801171] Call Trace: <4>[ 347.801177] <4>[ 347.801182] dump_stack_lvl+0x64/0xb0 <4>[ 347.801193] panic+0x2c0/0x2f0 <4>[ 347.801204] ? __lock_acquire+0x886/0x2330 <4>[ 347.801214] check_panic_on_warn+0x4f/0x50 <4>[ 347.801222] __warn+0x8b/0x170 <4>[ 347.801229] ? __lock_acquire+0x886/0x2330 <4>[ 347.801237] report_bug+0x1f8/0x200 <4>[ 347.801246] handle_bug+0x3c/0x70 <4>[ 347.801254] exc_invalid_op+0x18/0x70 <4>[ 347.801262] asm_exc_invalid_op+0x1a/0x20 <4>[ 347.801272] RIP: 0010:__lock_acquire+0x886/0x2330 <4>[ 347.801281] Code: c0 0f 84 f5 fe ff ff 44 8b 3d ca fc 72 01 45 85 ff 0f 85 e5 fe ff ff 48 c7 c6 80 53 44 82 48 c7 c7 ce ab 3d 82 e8 3a 3f 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>[ 347.801301] RSP: 0018:ffffc90001037788 EFLAGS: 00010086 <4>[ 347.801309] RAX: 0000000000000000 RBX: ffffc90001037b70 RCX: 0000000000000000 <4>[ 347.801318] RDX: 0000000000000003 RSI: ffffffff823ecc3e RDI: 00000000ffffffff <4>[ 347.801326] RBP: ffff8881023f5c20 R08: 0000000000000000 R09: ffffc90001037620 <4>[ 347.801335] R10: 00000000000f2698 R11: 00000000000f2758 R12: 0000000000000000 <4>[ 347.801344] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000 <4>[ 347.801356] ? __lock_acquire+0x886/0x2330 <4>[ 347.801364] ? mark_held_locks+0x49/0x80 <4>[ 347.801373] lock_acquire+0xd8/0x2d0 <4>[ 347.801382] ? eb_validate_vmas+0x137/0xd80 [i915] <4>[ 347.801852] __ww_mutex_lock.constprop.0+0xbf/0x13e0 <4>[ 347.801868] ? eb_validate_vmas+0x137/0xd80 [i915] <4>[ 347.802343] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 <4>[ 347.802356] ? lockdep_hardirqs_on+0xc3/0x140 <4>[ 347.802365] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 <4>[ 347.802376] ? eb_validate_vmas+0x137/0xd80 [i915] <4>[ 347.802846] ? ww_mutex_lock_interruptible+0x37/0x100 <4>[ 347.802861] ww_mutex_lock_interruptible+0x37/0x100 <4>[ 347.802873] eb_validate_vmas+0x137/0xd80 [i915] <4>[ 347.803348] i915_gem_do_execbuffer+0xcbc/0x2700 [i915] <4>[ 347.803827] ? do_pte_missing+0x37a/0xd00 <4>[ 347.803845] ? preempt_count_add+0x4c/0xa0 <4>[ 347.803855] ? up_read+0xba/0x250 <4>[ 347.803864] ? do_user_addr_fault+0x445/0x9d0 <4>[ 347.803874] ? exc_page_fault+0xb2/0x1a0 <4>[ 347.803885] ? lockdep_hardirqs_on+0xc3/0x140 <4>[ 347.803893] ? asm_exc_page_fault+0x26/0x30 <4>[ 347.803905] i915_gem_execbuffer2_ioctl+0x123/0x2a0 [i915] <4>[ 347.804381] ? __pfx_i915_gem_execbuffer2_ioctl+0x10/0x10 [i915] <4>[ 347.804857] drm_ioctl_kernel+0xb6/0x150 <4>[ 347.804871] drm_ioctl+0x21d/0x420 <4>[ 347.804880] ? __pfx_i915_gem_execbuffer2_ioctl+0x10/0x10 [i915] <4>[ 347.805350] __x64_sys_ioctl+0x7c/0xb0 <4>[ 347.805365] do_syscall_64+0x3f/0x90 <4>[ 347.805376] entry_SYSCALL_64_after_hwframe+0x6e/0xd8 <4>[ 347.805387] RIP: 0033:0x7f92d69383ab <4>[ 347.805397] 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>[ 347.805416] RSP: 002b:00007fff8bd42ff8 EFLAGS: 00000246 ORIG_RAX: 0000000000000010 <4>[ 347.805429] RAX: ffffffffffffffda RBX: 00007fff8bd43090 RCX: 00007f92d69383ab <4>[ 347.805438] RDX: 00007fff8bd43090 RSI: 0000000040406469 RDI: 0000000000000007 <4>[ 347.805447] RBP: 0000000040406469 R08: 0000000000000004 R09: 0000000000000000 <4>[ 347.805455] R10: 00007fff8bda01b0 R11: 0000000000000246 R12: 0000000000000007 <4>[ 347.805464] R13: 0000000000000007 R14: 00007f92d3309010 R15: 00007f92d3325010 <4>[ 347.805477] <0>[ 347.805505] Kernel Offset: disabled