igt@kms_setmode@basic@pipe-a-hdmi-a-3 on shard-dg2@CI_DRM

Detail	Value
Duration	unknown
Hostname	shard-dg2-7
Igt-Version	IGT-Version: 2.4-g4568b2c14 (x86_64) (Linux: 7.1.0-rc1-CI_DRM_18372-g1c60044e7e22+ x86_64)
Out	Using IGT_SRANDOM=1777340363 for randomisation Opened device: /dev/dri/card0 Starting subtest: basic Testing: basic 1 connector combinations Starting dynamic subtest: pipe-A-HDMI-A-3 Test id#1 CRTC count 1 CRTC[151] [Pipe A] Mode: 1920x1080@60Hz Connectors: HDMI-A-3[556] Expected frametime: 16667us; measured 16680.8us +- 3.286us accuracy 0.01% [0.06 scanlines] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (2.121s) This test caused an abort condition: Child refuses to die, tainted 0x42c4.
Err	Starting subtest: basic Starting dynamic subtest: pipe-A-HDMI-A-3 Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (2.121s) Received signal SIGQUIT. Stack trace: #0 [fatal_sig_handler+0x17b] #1 [__sigaction+0x50] #2 [ioctl+0x3d] #3 [drmIoctl+0x30] #4 [drmModeSetCrtc+0xa4] #5 [test_one_combination+0x7b6] #6 [__igt_unique____real_main1031+0x5b8] #7 [main+0x3a] #8 [__libc_init_first+0x8a] #9 [__libc_start_main+0x8b] #10 [_start+0x25]
Dmesg Scroll to first warning	<6> [561.526804] Console: switching to colour dummy device 80x25 <6> [561.527302] [IGT] kms_setmode: executing <7> [561.544809] i915 0000:03:00.0: [drm:i915_gem_open [i915]] <7> [561.546483] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc] <7> [561.556044] i915 0000:03:00.0: [drm:i915_gem_open [i915]] <6> [561.558140] [IGT] kms_setmode: starting subtest basic <7> [561.558379] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A] <7> [561.558479] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.enable (expected yes, found no) <7> [561.558712] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.active (expected yes, found no) <7> [561.558939] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1) <7> [561.559104] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in lane_count (expected 4, found 0) <7> [561.559267] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000) <7> [561.559430] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0) <7> [561.559598] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0) <7> [561.559813] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0) <7> [561.560049] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0) <7> [561.560211] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0) <7> [561.560372] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0) <7> [561.560535] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0) <7> [561.560748] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0) <7> [561.560972] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0) <7> [561.561133] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0) <7> [561.561295] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0) <7> [561.561458] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0) <7> [561.561641] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0) <7> [561.561820] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0) <7> [561.561984] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0) <7> [561.562147] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0) <7> [561.562308] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0) <7> [561.562486] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0) <7> [561.562685] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0) <7> [561.562892] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0) <7> [561.563053] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0) <7> [561.563215] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0) <7> [561.563377] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0) <7> [561.563568] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0) <7> [561.563825] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0) <7> [561.564007] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0) <7> [561.564168] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0) <7> [561.564360] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0) <7> [561.564522] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0) <7> [561.564723] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0) <7> [561.564957] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0) <7> [561.565149] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in port_clock (expected 148500, found 0) <7> [561.565312] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset <7> [561.565499] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0xf), mbus joined? no->no <7> [561.565773] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:151:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x1 -> 0x0 <7> [561.566112] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:35:plane 1A] ddb ( 0 - 1963) -> ( 0 - 0), size 1963 -> 0 <7> [561.566270] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:145:cursor A] ddb (1963 - 2048) -> ( 0 - 0), size 85 -> 0 <7> [561.566429] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35: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.566585] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] lines 1, 3, 4, 5, 9, 11, 17, 25, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 <7> [561.566799] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] blocks 16, 49, 65, 81, 145, 177, 273, 401, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 <7> [561.566996] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] min_ddb 19, 55, 73, 91, 161, 196, 302, 443, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 <7> [561.567170] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] [CRTC:151:pipe A] data rate 0 num active planes 0 <7> [561.567333] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 50000 required 0 <7> [561.567493] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1 <7> [561.567765] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:151:pipe A] min cdclk: 74250 kHz -> 0 kHz <7> [561.567958] i915 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 9282 kHz -> 0 kHz <7> [561.568153] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 163200 kHz, actual 163200 kHz <7> [561.568328] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0 <7> [561.568495] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:151:pipe A] enable: no [modeset] <7> [561.568686] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [NOFB], visible: no <7> [561.568897] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no <7> [561.569073] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no <7> [561.569264] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no <7> [561.569425] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no <7> [561.569587] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:cursor A] fb: [NOFB], visible: no <7> [561.582442] i915 0000:03:00.0: [drm:intel_disable_transcoder [i915]] disabling pipe A <7> [561.599803] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_D <7> [561.600041] i915 0000:03:00.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:35:plane 1A] <7> [561.600225] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:507:DDI A/PHY A] <7> [561.600390] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:509:DP-MST A] <7> [561.600554] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:510:DP-MST B] <7> [561.600726] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:511:DP-MST C] <7> [561.600892] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:512:DP-MST D] <7> [561.601058] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:520:DDI B/PHY B] <7> [561.601223] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:522:DP-MST A] <7> [561.601387] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:523:DP-MST B] <7> [561.601551] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:524:DP-MST C] <7> [561.601722] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:525:DP-MST D] <7> [561.601885] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:535:DDI C/PHY C] <7> [561.602050] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:537:DP-MST A] <7> [561.602216] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:538:DP-MST B] <7> [561.602380] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:539:DP-MST C] <7> [561.602545] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:540:DP-MST D] <7> [561.602716] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:547:DDI D/PHY D] <7> [561.602901] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:549:DP-MST A] <7> [561.603065] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:550:DP-MST B] <7> [561.603229] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:551:DP-MST C] <7> [561.603393] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:552:DP-MST D] <7> [561.603556] i915 0000:03:00.0: [drm:verify_connector_state [i915]] [CONNECTOR:556:HDMI-A-3] <7> [561.603762] i915 0000:03:00.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1 <7> [561.604035] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling PW_A <7> [561.604269] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling PW_2 <7> [561.604479] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:151:pipe A] <7> [561.604894] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:269:pipe C] <7> [561.605060] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe B] <7> [561.605207] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:505:pipe D] <7> [561.606066] i915 0000:03:00.0: [drm:drm_mode_addfb2] [FB:560] <7> [561.606587] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509 <7> [561.607023] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509 <3> [561.646758] guc_lrc_desc_unpin:3418 GEM_BUG_ON(!ctx_id_mapped(guc, ce->guc_id.id)) <0> [561.646813] Dumping ftrace buffer: <0> [561.646818] --------------------------------- <0> [561.646968] CPU:5 [LOST 4284 EVENTS] gem_lmem-2115 5D..1. 132195612us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1222, current 1220 <0> [561.647063] gem_lmem-2115 5..... 132202210us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1220, current 1222 <0> [561.647144] gem_lmem-2115 5..... 132202223us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1224, current 1222 <0> [561.647224] gem_lmem-2115 5D..1. 132202227us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1224, current 1222 <0> [561.647305] gem_lmem-2115 5..... 132205074us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1222, current 1224 <0> [561.647385] gem_lmem-2115 5..... 132205087us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1226, current 1224 <0> [561.647465] gem_lmem-2115 5D..1. 132205091us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1226, current 1224 <0> [561.647545] gem_lmem-2115 5..... 132214836us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1224, current 1226 <0> [561.647625] gem_lmem-2115 5..... 132214848us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1228, current 1226 <0> [561.647704] gem_lmem-2115 5D..1. 132214852us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1228, current 1226 <0> [561.647784] gem_lmem-2115 5..... 132225833us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1226, current 1228 <0> [561.647864] gem_lmem-2115 5..... 132225845us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1230, current 1228 <0> [561.647944] gem_lmem-2115 5D..1. 132225849us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1230, current 1228 <0> [561.648024] gem_lmem-2115 5..... 132245320us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1228, current 1230 <0> [561.648104] gem_lmem-2115 5..... 132245333us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1232, current 1230 <0> [561.648184] gem_lmem-2115 5D..1. 132245337us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1232, current 1230 <0> [561.648264] gem_lmem-2115 5..... 132260026us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1230, current 1232 <0> [561.648344] gem_lmem-2115 5..... 132260038us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1234, current 1232 <0> [561.648425] gem_lmem-2115 5D..1. 132260043us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1234, current 1232 <0> [561.648504] gem_lmem-2115 5..... 132267004us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1232, current 1234 <0> [561.648584] gem_lmem-2115 5..... 132267016us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1236, current 1234 <0> [561.648665] gem_lmem-2115 5D..1. 132267021us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1236, current 1234 <0> [561.648744] gem_lmem-2115 5..... 132283143us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1234, current 1236 <0> [561.648824] gem_lmem-2115 5..... 132283156us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1238, current 1236 <0> [561.648904] gem_lmem-2115 5D..1. 132283160us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1238, current 1236 <0> [561.648985] gem_lmem-2115 5..... 132295005us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1236, current 1238 <0> [561.649066] gem_lmem-2115 5..... 132295018us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1240, current 1238 <0> [561.649146] gem_lmem-2115 5D..1. 132295022us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1240, current 1238 <0> [561.649225] gem_lmem-2115 5..... 132297080us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1238, current 1240 <0> [561.649306] gem_lmem-2115 5..... 132297093us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1242, current 1240 <0> [561.649386] gem_lmem-2115 5D..1. 132297097us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1242, current 1240 <0> [561.649465] gem_lmem-2115 5..... 132305217us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1240, current 1242 <0> [561.649545] gem_lmem-2115 5..... 132305230us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1244, current 1242 <0> [561.649626] gem_lmem-2115 5D..1. 132305234us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1244, current 1242 <0> [561.649705] gem_lmem-2115 5..... 132310563us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1242, current 1244 <0> [561.649786] gem_lmem-2115 5..... 132310575us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1246, current 1244 <0> [561.649866] gem_lmem-2115 5D..1. 132310580us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1246, current 1244 <0> [561.649946] gem_lmem-2115 5..... 132326730us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1244, current 1246 <0> [561.650026] gem_lmem-2115 5..... 132326742us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1248, current 1246 <0> [561.650106] gem_lmem-2115 5D..1. 132326746us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1248, current 1246 <0> [561.650186] gem_lmem-2115 5..... 132341874us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1246, current 1248 <0> [561.650266] gem_lmem-2115 5..... 132341889us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1250, current 1248 <0> [561.650346] gem_lmem-2115 5D..1. 132341893us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1250, current 1248 <0> [561.650426] gem_lmem-2115 5..... 132351923us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1248, current 1250 <0> [561.650506] gem_lmem-2115 5..... 132351936us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1252, current 1250 <0> [561.650587] gem_lmem-2115 5D..1. 132351941us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1252, current 1250 <0> [561.650666] gem_lmem-2115 5..... 132358423us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1250, current 1252 <0> [561.650748] gem_lmem-2115 5..... 132358436us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1254, current 1252 <0> [561.650828] gem_lmem-2115 5D..1. 132358440us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1254, current 1252 <0> [561.650908] gem_lmem-2115 5..... 132368306us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1252, current 1254 <0> [561.650988] gem_lmem-2115 5..... 132368319us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1256, current 1254 <0> [561.651068] gem_lmem-2115 5D..1. 132368323us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1256, current 1254 <0> [561.651149] gem_lmem-2115 5..... 132377639us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1254, current 1256 <0> [561.651228] gem_lmem-2115 5..... 132377652us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1258, current 1256 <0> [561.651308] gem_lmem-2115 5D..1. 132377656us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1258, current 1256 <0> [561.651388] gem_lmem-2115 5..... 132386518us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1256, current 1258 <0> [561.651468] gem_lmem-2115 5..... 132386531us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1260, current 1258 <0> [561.651548] gem_lmem-2115 5D..1. 132386535us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1260, current 1258 <0> [561.651628] gem_lmem-2115 5..... 132397091us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1258, current 1260 <0> [561.651707] gem_lmem-2115 5..... 132397104us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1262, current 1260 <0> [561.651787] gem_lmem-2115 5D..1. 132397108us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1262, current 1260 <0> [561.651867] gem_lmem-2115 5..... 132400437us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1260, current 1262 <0> [561.651948] gem_lmem-2115 5..... 132400449us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1264, current 1262 <0> [561.652028] gem_lmem-2115 5D..1. 132400453us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1264, current 1262 <0> [561.652108] gem_lmem-2115 5..... 132402050us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1262, current 1264 <0> [561.652188] gem_lmem-2115 5..... 132402063us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1266, current 1264 <0> [561.652269] gem_lmem-2115 5D..1. 132402067us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1266, current 1264 <0> [561.652349] gem_lmem-2115 5..... 132423809us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1264, current 1266 <0> [561.652429] gem_lmem-2115 5..... 132423822us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1268, current 1266 <0> [561.652509] gem_lmem-2115 5D..1. 132423826us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1268, current 1266 <0> [561.652589] gem_lmem-2115 5..... 132437540us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1266, current 1268 <0> [561.652669] gem_lmem-2115 5..... 132437552us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1270, current 1268 <0> [561.652749] gem_lmem-2115 5D..1. 132437556us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1270, current 1268 <0> [561.652829] gem_lmem-2115 5..... 132455666us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1268, current 1270 <0> [561.652909] gem_lmem-2115 5..... 132455678us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1272, current 1270 <0> [561.652989] gem_lmem-2115 5D..1. 132455682us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1272, current 1270 <0> [561.653068] gem_lmem-2115 5..... 132464047us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1270, current 1272 <0> [561.653148] gem_lmem-2115 5..... 132464059us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1274, current 1272 <0> [561.653228] gem_lmem-2115 5D..1. 132464064us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1274, current 1272 <0> [561.653308] gem_lmem-2115 5..... 132475568us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1272, current 1274 <0> [561.653388] gem_lmem-2115 5..... 132475581us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1276, current 1274 <0> [561.653468] gem_lmem-2115 5D..1. 132475585us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1276, current 1274 <0> [561.653548] gem_lmem-2115 5..... 132485881us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1274, current 1276 <0> [561.653629] gem_lmem-2115 5..... 132485894us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1278, current 1276 <0> [561.653709] gem_lmem-2115 5D..1. 132485898us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1278, current 1276 <0> [561.653789] gem_lmem-2115 5..... 132499567us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1276, current 1278 <0> [561.653869] gem_lmem-2115 5..... 132499580us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1280, current 1278 <0> [561.653949] gem_lmem-2115 5D..1. 132499584us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1280, current 1278 <0> [561.654030] gem_lmem-2115 5..... 132505626us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1278, current 1280 <0> [561.654110] gem_lmem-2115 5..... 132505639us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1282, current 1280 <0> [561.654189] gem_lmem-2115 5D..1. 132505643us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1282, current 1280 <0> [561.654269] gem_lmem-2115 5..... 132516859us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1280, current 1282 <0> [561.654349] gem_lmem-2115 5..... 132516872us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1284, current 1282 <0> [561.654430] gem_lmem-2115 5D..1. 132516876us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1284, current 1282 <0> [561.654509] gem_lmem-2115 5..... 132526145us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1282, current 1284 <0> [561.654589] gem_lmem-2115 5..... 132526158us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1286, current 1284 <0> [561.654669] gem_lmem-2115 5D..1. 132526162us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1286, current 1284 <0> [561.654749] gem_lmem-2115 5..... 132536277us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1284, current 1286 <0> [561.654829] gem_lmem-2115 5..... 132536290us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1288, current 1286 <0> [561.654909] gem_lmem-2115 5D..1. 132536294us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1288, current 1286 <0> [561.654989] gem_lmem-2115 5..... 132549443us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1286, current 1288 <0> [561.655069] gem_lmem-2115 5..... 132549461us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1290, current 1288 <0> [561.655149] gem_lmem-2115 5D..1. 132549465us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1290, current 1288 <0> [561.655229] gem_lmem-2115 5..... 132554429us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1288, current 1290 <0> [561.655309] gem_lmem-2115 5..... 132554442us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1292, current 1290 <0> [561.655389] gem_lmem-2115 5D..1. 132554446us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1292, current 1290 <0> [561.655469] gem_lmem-2115 5..... 132563955us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1290, current 1292 <0> [561.655549] gem_lmem-2115 5..... 132563968us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1294, current 1292 <0> [561.655629] gem_lmem-2115 5D..1. 132563972us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1294, current 1292 <0> [561.655710] gem_lmem-2115 5..... 132573080us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1292, current 1294 <0> [561.655790] gem_lmem-2115 5..... 132573092us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1296, current 1294 <0> [561.655869] gem_lmem-2115 5D..1. 132573097us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1296, current 1294 <0> [561.655949] gem_lmem-2115 5..... 132585658us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1294, current 1296 <0> [561.656029] gem_lmem-2115 5..... 132585671us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1298, current 1296 <0> [561.656108] gem_lmem-2115 5D..1. 132585676us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1298, current 1296 <0> [561.656188] gem_lmem-2115 5..... 132597207us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1296, current 1298 <0> [561.656269] gem_lmem-2115 5..... 132597220us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1300, current 1298 <0> [561.656349] gem_lmem-2115 5D..1. 132597224us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1300, current 1298 <0> [561.656429] gem_lmem-2115 5..... 132605012us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1298, current 1300 <0> [561.656509] gem_lmem-2115 5..... 132605024us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1302, current 1300 <0> [561.656590] gem_lmem-2115 5D..1. 132605029us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1302, current 1300 <0> [561.656670] gem_lmem-2115 5..... 132613611us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1300, current 1302 <0> [561.656749] gem_lmem-2115 5..... 132613624us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1304, current 1302 <0> [561.656829] gem_lmem-2115 5D..1. 132613628us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1304, current 1302 <0> [561.656909] gem_lmem-2115 5..... 132626186us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1302, current 1304 <0> [561.656989] gem_lmem-2115 5..... 132626199us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1306, current 1304 <0> [561.657069] gem_lmem-2115 5D..1. 132626203us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1306, current 1304 <0> [561.657149] gem_lmem-2115 5..... 132643154us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1304, current 1306 <0> [561.657229] gem_lmem-2115 5..... 132643167us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1308, current 1306 <0> [561.657309] gem_lmem-2115 5D..1. 132643171us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1308, current 1306 <0> [561.657389] gem_lmem-2115 5..... 132649787us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1306, current 1308 <0> [561.657469] gem_lmem-2115 5..... 132649799us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1310, current 1308 <0> [561.657549] gem_lmem-2115 5D..1. 132649804us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1310, current 1308 <0> [561.657630] gem_lmem-2115 5..... 132658748us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1308, current 1310 <0> [561.657710] gem_lmem-2115 5..... 132658774us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1312, current 1310 <0> [561.657790] gem_lmem-2115 5D..1. 132658778us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1312, current 1310 <0> [561.657870] gem_lmem-2115 5..... 132665467us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1310, current 1312 <0> [561.657949] gem_lmem-2115 5..... 132665480us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1314, current 1312 <0> [561.658029] gem_lmem-2115 5D..1. 132665484us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1314, current 1312 <0> [561.658109] gem_lmem-2115 5..... 132681725us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1312, current 1314 <0> [561.658189] gem_lmem-2115 5..... 132681737us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1316, current 1314 <0> [561.658269] gem_lmem-2115 5D..1. 132681741us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1316, current 1314 <0> [561.658349] gem_lmem-2115 5..... 132698481us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1314, current 1316 <0> [561.658429] gem_lmem-2115 5..... 132698494us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1318, current 1316 <0> [561.658509] gem_lmem-2115 5D..1. 132698498us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1318, current 1316 <0> [561.658589] gem_lmem-2115 5..... 132714453us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1316, current 1318 <0> [561.658669] gem_lmem-2115 5..... 132714466us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1320, current 1318 <0> [561.658749] gem_lmem-2115 5D..1. 132714470us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1320, current 1318 <0> [561.658829] gem_lmem-2115 5..... 132718327us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1318, current 1320 <0> [561.658909] gem_lmem-2115 5..... 132718339us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1322, current 1320 <0> [561.658990] gem_lmem-2115 5D..1. 132718343us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1322, current 1320 <0> [561.659070] gem_lmem-2115 5..... 132735067us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1320, current 1322 <0> [561.659149] gem_lmem-2115 5..... 132735079us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1324, current 1322 <7> [561.659147] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509 <0> [561.659229] gem_lmem-2115 5D..1. 132735083us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1324, current 1322 <0> [561.659309] gem_lmem-2115 5..... 132752527us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1322, current 1324 <0> [561.659389] gem_lmem-2115 5..... 132752540us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1326, current 1324 <0> [561.659469] gem_lmem-2115 5D..1. 132752544us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1326, current 1324 <7> [561.659451] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509 <0> [561.659549] gem_lmem-2115 5..... 132756779us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1324, current 1326 <0> [561.659628] gem_lmem-2115 5..... 132756792us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1328, current 1326 <0> [561.659747] gem_lmem-2115 5D..1. 132756796us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1328, current 1326 <0> [561.659849] gem_lmem-2115 5..... 132767162us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1326, current 1328 <0> [561.659936] gem_lmem-2115 5..... 132767175us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1330, current 1328 <0> [561.660034] gem_lmem-2115 5D..1. 132767179us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1330, current 1328 <0> [561.660116] gem_lmem-2115 5..... 132770608us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1328, current 1330 <0> [561.660197] gem_lmem-2115 5..... 132770620us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1332, current 1330 <0> [561.660279] gem_lmem-2115 5D..1. 132770625us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1332, current 1330 <0> [561.660359] gem_lmem-2115 5..... 132779797us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1330, current 1332 <0> [561.660439] gem_lmem-2115 5..... 132779809us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1334, current 1332 <0> [561.660518] gem_lmem-2115 5D..1. 132779814us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1334, current 1332 <0> [561.660615] gem_lmem-2115 5..... 132790901us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1332, current 1334 <0> [561.660709] gem_lmem-2115 5..... 132790914us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1336, current 1334 <0> [561.660788] gem_lmem-2115 5D..1. 132790918us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1336, current 1334 <0> [561.660868] gem_lmem-2115 5..... 132804975us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1334, current 1336 <0> [561.660948] gem_lmem-2115 5..... 132804988us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1338, current 1336 <0> [561.661029] gem_lmem-2115 5D..1. 132804992us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1338, current 1336 <0> [561.661109] gem_lmem-2115 5..... 132820448us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1336, current 1338 <0> [561.661238] gem_lmem-2115 5..... 132820460us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1340, current 1338 <0> [561.661320] gem_lmem-2115 5D..1. 132820465us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1340, current 1338 <0> [561.661414] gem_lmem-2115 5..... 132832672us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1338, current 1340 <0> [561.661495] gem_lmem-2115 5..... 132832685us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1342, current 1340 <0> [561.661603] gem_lmem-2115 5D..1. 132832689us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1342, current 1340 <0> [561.661683] gem_lmem-2115 5..... 132846278us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1340, current 1342 <0> [561.661763] gem_lmem-2115 5..... 132846291us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1344, current 1342 <0> [561.661843] gem_lmem-2115 5D..1. 132846295us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1344, current 1342 <0> [561.661923] gem_lmem-2115 5..... 132849671us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1342, current 1344 <0> [561.662003] gem_lmem-2115 5..... 132849683us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1346, current 1344 <0> [561.662083] gem_lmem-2115 5D..1. 132849688us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1346, current 1344 <0> [561.662195] gem_lmem-2115 5..... 132854463us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1344, current 1346 <0> [561.662276] gem_lmem-2115 5..... 132854476us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1348, current 1346 <0> [561.662358] gem_lmem-2115 5D..1. 132854480us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1348, current 1346 <0> [561.662439] gem_lmem-2115 5..... 132866910us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1346, current 1348 <0> [561.662521] gem_lmem-2115 5..... 132866923us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1350, current 1348 <0> [561.662633] gem_lmem-2115 5D..1. 132866928us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1350, current 1348 <0> [561.662715] gem_lmem-2115 5..... 132877512us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1348, current 1350 <0> [561.662796] gem_lmem-2115 5..... 132877525us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1352, current 1350 <0> [561.662876] gem_lmem-2115 5D..1. 132877529us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1352, current 1350 <0> [561.662955] gem_lmem-2115 5..... 132890689us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1350, current 1352 <0> [561.663053] gem_lmem-2115 5..... 132890788us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1354, current 1352 <0> [561.663175] gem_lmem-2115 5D..1. 132890792us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1354, current 1352 <0> [561.663256] gem_lmem-2115 5..... 132900870us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1352, current 1354 <0> [561.663365] gem_lmem-2115 5..... 132900887us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1356, current 1354 <0> [561.663445] gem_lmem-2115 5D..1. 132900892us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1356, current 1354 <0> [561.663525] gem_lmem-2115 5..... 132911901us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1354, current 1356 <0> [561.663633] gem_lmem-2115 5..... 132911913us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1358, current 1356 <0> [561.663713] gem_lmem-2115 5D..1. 132911917us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1358, current 1356 <0> [561.663793] gem_lmem-2115 5..... 132928094us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1356, current 1358 <0> [561.663889] gem_lmem-2115 5..... 132928107us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1360, current 1358 <0> [561.663985] gem_lmem-2115 5D..1. 132928111us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1360, current 1358 <0> [561.664066] gem_lmem-2115 5..... 132941910us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1358, current 1360 <0> [561.664148] gem_lmem-2115 5..... 132941924us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1362, current 1360 <0> [561.664229] gem_lmem-2115 5D..1. 132941928us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1362, current 1360 <0> [561.664456] gem_lmem-2115 5..... 132959656us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1360, current 1362 <0> [561.664539] gem_lmem-2115 5..... 132959668us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1364, current 1362 <0> [561.664635] gem_lmem-2115 5D..1. 132959673us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1364, current 1362 <0> [561.664715] gem_lmem-2115 5..... 132965419us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1362, current 1364 <0> [561.664795] gem_lmem-2115 5..... 132965431us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1366, current 1364 <0> [561.664875] gem_lmem-2115 5D..1. 132965436us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1366, current 1364 <0> [561.664984] gem_lmem-2115 5..... 132978610us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1364, current 1366 <0> [561.665096] gem_lmem-2115 5..... 132978623us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1368, current 1366 <0> [561.665176] gem_lmem-2115 5D..1. 132978627us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1368, current 1366 <0> [561.665256] gem_lmem-2115 5..... 132982848us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1366, current 1368 <0> [561.665336] gem_lmem-2115 5..... 132982860us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1370, current 1368 <0> [561.665416] gem_lmem-2115 5D..1. 132982864us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1370, current 1368 <0> [561.665498] gem_lmem-2115 5..... 132994052us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1368, current 1370 <0> [561.665593] gem_lmem-2115 5..... 132994065us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1372, current 1370 <0> [561.665676] gem_lmem-2115 5D..1. 132994069us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1372, current 1370 <7> [561.665613] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DC_off <0> [561.665759] gem_lmem-2115 5..... 133004205us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1370, current 1372 <0> [561.665841] gem_lmem-2115 5..... 133004218us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1374, current 1372 <0> [561.666240] gem_lmem-2115 5D..1. 133004222us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1374, current 1372 <7> [561.666194] i915 0000:03:00.0: [drm:gen9_enable_dc5 [i915]] Enabling DC5 <0> [561.666323] gem_lmem-2115 5..... 133012849us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1372, current 1374 <0> [561.666405] gem_lmem-2115 5..... 133012862us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1376, current 1374 <7> [561.666376] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 01 <0> [561.666487] gem_lmem-2115 5D..1. 133012866us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1376, current 1374 <0> [561.666569] gem_lmem-2115 5..... 133017892us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1374, current 1376 <0> [561.666652] gem_lmem-2115 5..... 133017904us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1378, current 1376 <0> [561.666735] gem_lmem-2115 5D..1. 133017908us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1378, current 1376 <0> [561.666818] gem_lmem-2115 5..... 133038029us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1376, current 1378 <0> [561.666901] gem_lmem-2115 5..... 133038042us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1380, current 1378 <0> [561.666984] gem_lmem-2115 5D..1. 133038047us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1380, current 1378 <0> [561.667079] gem_lmem-2115 5..... 133045065us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1378, current 1380 <0> [561.667159] gem_lmem-2115 5..... 133045078us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1382, current 1380 <0> [561.667240] gem_lmem-2115 5D..1. 133045083us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1382, current 1380 <0> [561.667337] gem_lmem-2115 5..... 133054085us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1380, current 1382 <0> [561.667420] gem_lmem-2115 5..... 133054098us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1384, current 1382 <0> [561.667502] gem_lmem-2115 5D..1. 133054102us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1384, current 1382 <0> [561.667585] gem_lmem-2115 5..... 133061991us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1382, current 1384 <0> [561.667668] gem_lmem-2115 5..... 133062004us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1386, current 1384 <0> [561.667751] gem_lmem-2115 5D..1. 133062008us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1386, current 1384 <0> [561.667835] gem_lmem-2115 5..... 133073333us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1384, current 1386 <6> [561.667866] [IGT] kms_setmode: starting dynamic subtest pipe-A-HDMI-A-3 <0> [561.667931] gem_lmem-2115 5..... 133073347us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1388, current 1386 <0> [561.668011] gem_lmem-2115 5D..1. 133073351us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1388, current 1386 <7> [561.668029] i915 0000:03:00.0: [drm:drm_mode_addfb2] [FB:562] <0> [561.668091] gem_lmem-2115 5..... 133084825us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1386, current 1388 <0> [561.668171] gem_lmem-2115 5..... 133084838us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1390, current 1388 <0> [561.668251] gem_lmem-2115 5D..1. 133084843us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1390, current 1388 <7> [561.668179] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509 <0> [561.668331] gem_lmem-2115 5..... 133102373us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1388, current 1390 <0> [561.668428] gem_lmem-2115 5..... 133102386us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1392, current 1390 <0> [561.668510] gem_lmem-2115 5D..1. 133102390us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1392, current 1390 <7> [561.668492] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509 <0> [561.668592] gem_lmem-2115 5..... 133116388us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1390, current 1392 <0> [561.668687] gem_lmem-2115 5..... 133116401us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1394, current 1392 <0> [561.668786] gem_lmem-2115 5D..1. 133116405us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1394, current 1392 <0> [561.668880] gem_lmem-2115 5..... 133127787us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1392, current 1394 <0> [561.668960] gem_lmem-2115 5..... 133127802us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1396, current 1394 <0> [561.669040] gem_lmem-2115 5D..1. 133127806us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1396, current 1394 <0> [561.669122] gem_lmem-2115 5..... 133139399us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1394, current 1396 <0> [561.669202] gem_lmem-2115 5..... 133139412us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1398, current 1396 <0> [561.669281] gem_lmem-2115 5D..1. 133139416us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1398, current 1396 <0> [561.669361] gem_lmem-2115 5..... 133152358us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1396, current 1398 <0> [561.669441] gem_lmem-2115 5..... 133152371us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1400, current 1398 <0> [561.669538] gem_lmem-2115 5D..1. 133152375us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1400, current 1398 <0> [561.669635] gem_lmem-2115 5..... 133154646us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1398, current 1400 <0> [561.669732] gem_lmem-2115 5..... 133154659us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1402, current 1400 <0> [561.669826] gem_lmem-2115 5D..1. 133154663us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1402, current 1400 <0> [561.669905] gem_lmem-2115 5..... 133161606us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1400, current 1402 <0> [561.669985] gem_lmem-2115 5..... 133161618us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1404, current 1402 <0> [561.670065] gem_lmem-2115 5D..1. 133161622us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1404, current 1402 <0> [561.670167] gem_lmem-2115 5..... 133176517us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1402, current 1404 <0> [561.670250] gem_lmem-2115 5..... 133176530us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1406, current 1404 <0> [561.670348] gem_lmem-2115 5D..1. 133176534us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1406, current 1404 <0> [561.670443] gem_lmem-2115 5.l... 133196780us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1404, current 1406 <0> [561.670526] gem_lmem-2115 5.l... 133196793us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1408, current 1406 <0> [561.670651] gem_lmem-2115 5Dl.1. 133196797us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1408, current 1406 <0> [561.670731] gem_lmem-2115 5..... 133208495us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1406, current 1408 <0> [561.670810] gem_lmem-2115 5..... 133208508us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1410, current 1408 <0> [561.670890] gem_lmem-2115 5D..1. 133208512us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1410, current 1408 <0> [561.670970] gem_lmem-2115 5..... 133224176us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1408, current 1410 <0> [561.671050] gem_lmem-2115 5..... 133224188us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1412, current 1410 <0> [561.671131] gem_lmem-2115 5D..1. 133224192us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1412, current 1410 <0> [561.671210] gem_lmem-2115 5..... 133225584us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1410, current 1412 <0> [561.671290] gem_lmem-2115 5..... 133225596us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1414, current 1412 <0> [561.671384] gem_lmem-2115 5D..1. 133225601us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1414, current 1412 <0> [561.671468] gem_lmem-2115 5..... 133230185us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1412, current 1414 <0> [561.671550] gem_lmem-2115 5..... 133230197us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1416, current 1414 <0> [561.671646] gem_lmem-2115 5D..1. 133230201us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1416, current 1414 <0> [561.671758] gem_lmem-2115 5..... 133236838us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1414, current 1416 <0> [561.671838] gem_lmem-2115 5..... 133236851us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1418, current 1416 <0> [561.671918] gem_lmem-2115 5D..1. 133236855us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1418, current 1416 <0> [561.671999] gem_lmem-2115 5..... 133252338us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1416, current 1418 <0> [561.672079] gem_lmem-2115 5..... 133252351us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1420, current 1418 <0> [561.672174] gem_lmem-2115 5D..1. 133252356us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1420, current 1418 <0> [561.672267] gem_lmem-2115 5..... 133265999us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1418, current 1420 <0> [561.672347] gem_lmem-2115 5..... 133266012us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1422, current 1420 <0> [561.672491] gem_lmem-2115 5D..1. 133266016us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1422, current 1420 <0> [561.672588] gem_lmem-2115 5..... 133273020us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1420, current 1422 <0> [561.672670] gem_lmem-2115 5..... 133273033us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1424, current 1422 <0> [561.672751] gem_lmem-2115 5D..1. 133273037us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1424, current 1422 <0> [561.672833] gem_lmem-2115 5..... 133288626us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1422, current 1424 <0> [561.672914] gem_lmem-2115 5..... 133288639us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1426, current 1424 <0> [561.672994] gem_lmem-2115 5D..1. 133288643us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1426, current 1424 <0> [561.673074] gem_lmem-2115 5..... 133294988us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1424, current 1426 <0> [561.673154] gem_lmem-2115 5..... 133295000us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1428, current 1426 <0> [561.673234] gem_lmem-2115 5D..1. 133295004us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1428, current 1426 <0> [561.673315] gem_lmem-2115 5..... 133302051us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1426, current 1428 <0> [561.673413] gem_lmem-2115 5..... 133302064us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1430, current 1428 <0> [561.673495] gem_lmem-2115 5D..1. 133302068us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1430, current 1428 <0> [561.673577] gem_lmem-2115 5..... 133309412us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1428, current 1430 <0> [561.673673] gem_lmem-2115 5..... 133309425us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1432, current 1430 <0> [561.673816] gem_lmem-2115 5D..1. 133309429us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1432, current 1430 <0> [561.673896] gem_lmem-2115 5..... 133321117us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1430, current 1432 <0> [561.673977] gem_lmem-2115 5..... 133321129us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1434, current 1432 <0> [561.674056] gem_lmem-2115 5D..1. 133321133us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1434, current 1432 <0> [561.674137] gem_lmem-2115 5..... 133327706us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1432, current 1434 <0> [561.674217] gem_lmem-2115 5..... 133327738us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1436, current 1434 <0> [561.674297] gem_lmem-2115 5D..1. 133327743us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1436, current 1434 <0> [561.674378] gem_lmem-2115 5..... 133335223us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1434, current 1436 <0> [561.674458] gem_lmem-2115 5..... 133335239us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1438, current 1436 <0> [561.674559] gem_lmem-2115 5D..1. 133335243us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1438, current 1436 <0> [561.674674] gem_lmem-2115 5..... 133348584us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1436, current 1438 <0> [561.674756] gem_lmem-2115 5..... 133348601us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1440, current 1438 <0> [561.674840] gem_lmem-2115 5D..1. 133348606us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1440, current 1438 <0> [561.674923] gem_lmem-2115 5..... 133362077us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1438, current 1440 <0> [561.675006] gem_lmem-2115 5..... 133362093us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1442, current 1440 <0> [561.675102] gem_lmem-2115 5D..1. 133362098us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1442, current 1440 <0> [561.675183] gem_lmem-2115 5..... 133381552us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1440, current 1442 <0> [561.675264] gem_lmem-2115 5..... 133381569us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1444, current 1442 <0> [561.675345] gem_lmem-2115 5D..1. 133381574us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1444, current 1442 <0> [561.675354] gem_lmem-2115 5...1. 133393006us : tracing_mark_write: gem_lmem_swapping: finished subtest lmem0, SUCCESS <0> [561.675359] gem_lmem-2115 5...1. 133393289us : tracing_mark_write: gem_lmem_swapping: finished subtest verify-random, SUCCESS <0> [561.675434] gem_lmem-2115 5d..2. 133397571us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.675527] gem_lmem-2115 5d..2. 133398613us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.675633] gem_lmem-2115 5d..2. 133399173us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.675724] gem_lmem-2115 5d..2. 133399351us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.675820] gem_lmem-2115 5d..2. 133399458us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.675910] gem_lmem-2115 5d..2. 133399683us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.675986] gem_lmem-2115 5d..2. 133399938us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.676061] gem_lmem-2115 5d..2. 133400084us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.676166] gem_lmem-2115 5d..2. 133400204us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.676241] gem_lmem-2115 5d..2. 133400305us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.676316] gem_lmem-2115 5d..2. 133400484us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.676405] gem_lmem-2115 5d..2. 133400602us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.676482] gem_lmem-2115 5d..2. 133400854us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.676560] gem_lmem-2115 5d..2. 133400951us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.676650] gem_lmem-2115 5d..2. 133401146us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.676726] gem_lmem-2115 5d..2. 133401278us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.676804] gem_lmem-2115 5d..2. 133401397us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.676879] gem_lmem-2115 5d..2. 133401593us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.676954] gem_lmem-2115 5d..2. 133402126us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.677029] gem_lmem-2115 5d..2. 133402264us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.677105] gem_lmem-2115 5d..2. 133402395us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.677180] gem_lmem-2115 5d..2. 133402587us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.677254] gem_lmem-2115 5d..2. 133402698us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.677349] gem_lmem-2115 5d..2. 133402818us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.677425] gem_lmem-2115 5d..2. 133402909us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.677502] gem_lmem-2115 5d..2. 133403003us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.677592] gem_lmem-2115 5d..2. 133403122us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.677666] gem_lmem-2115 5d..2. 133403296us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.677742] gem_lmem-2115 5d..2. 133403462us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.677818] gem_lmem-2115 5d..2. 133403644us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.677894] gem_lmem-2115 5d..2. 133404536us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.677968] gem_lmem-2115 5d..2. 133404674us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.678044] gem_lmem-2115 5d..2. 133404808us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.678119] gem_lmem-2115 5d..2. 133404960us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.678194] gem_lmem-2115 5d..2. 133405100us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.678269] gem_lmem-2115 5d..2. 133405321us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.678344] gem_lmem-2115 5d..2. 133405461us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.678420] gem_lmem-2115 5d..2. 133405556us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.678496] gem_lmem-2115 5d..2. 133405655us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.678604] gem_lmem-2115 5d..2. 133406242us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.678679] gem_lmem-2115 5d..2. 133406372us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.678754] gem_lmem-2115 5d..2. 133406607us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.678828] gem_lmem-2115 5d..2. 133406822us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.678936] gem_lmem-2115 5d..2. 133407073us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.679011] gem_lmem-2115 5d..2. 133407309us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.679087] gem_lmem-2115 5d..2. 133407471us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.679161] gem_lmem-2115 5d..2. 133407579us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.679237] gem_lmem-2115 5d..2. 133408706us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.679312] gem_lmem-2115 5d..2. 133409202us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.679389] gem_lmem-2115 5d..2. 133409292us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.679463] gem_lmem-2115 5d..2. 133409490us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.679553] gem_lmem-2115 5d..2. 133409674us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.679671] gem_lmem-2115 5d..2. 133409931us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.679746] gem_lmem-2115 5d..2. 133410100us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.679821] gem_lmem-2115 5d..2. 133410285us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.679896] gem_lmem-2115 5d..2. 133410364us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.679972] gem_lmem-2115 5d..2. 133410502us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.680047] gem_lmem-2115 5d..2. 133410679us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.680122] gem_lmem-2115 5d..2. 133411117us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.680197] gem_lmem-2115 5d..2. 133411203us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.680271] gem_lmem-2115 5d..2. 133411388us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.680347] gem_lmem-2115 5d..2. 133411492us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.680423] gem_lmem-2115 5d..2. 133411673us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.680518] gem_lmem-2115 5dl.2. 133412046us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.680609] gem_lmem-2115 5dl.2. 133412168us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.680683] gem_lmem-2115 5dl.2. 133412261us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.680758] gem_lmem-2115 5dl.2. 133412406us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.680833] gem_lmem-2115 5dl.2. 133412562us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.680909] gem_lmem-2115 5dl.2. 133412644us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001 <0> [561.680989] kworker/-50 5..... 133768719us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 76:3, current 3 <0> [561.681069] kworker/-50 5..... 133768736us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1442, current 1444 <0> [561.681149] kworker/-50 5..... 133769355us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1444, current 1444 <0> [561.681218] kworker/-50 5..... 133769367us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:85 unpin <0> [561.681286] kworker/-50 5..... 133770165us : __intel_context_retire: 0000:03:00.0 rcs0: context:85 retire runtime: { total:38635428ns, avg:38635428ns } <0> [561.681356] kworker/-50 5..... 133770555us : __engine_park: 0000:03:00.0 rcs0: parked <0> [561.681426] kworker/-50 5..... 133770630us : __gt_park: 0000:03:00.0 <0> [561.681504] gem_lmem-2115 5..... 133811814us : i915_gem_suspend: 0000:03:00.0 <0> [561.681608] kms_fron-2179 5..... 135160221us : __gt_unpark: 0000:03:00.0 <0> [561.681680] kworker/-50 5..... 135162117us : __gt_park: 0000:03:00.0 <0> [561.681749] kms_fron-2179 5..... 135163493us : __intel_context_do_pin_ww: 0000:03:00.0 rcs'0: context:8a active <0> [561.681825] kms_fron-2179 5..... 135163781us : intel_timeline_pin: 0000:03:00.0 timeline:8a using HWSP offset:fedef100 <0> [561.681894] kms_fron-2179 5..... 135164236us : __intel_context_do_pin_ww: 0000:03:00.0 rcs'0: context:8a pin ring:{start:fedd0000, head:0000, tail:0000} <0> [561.681962] kms_fron-2179 5..... 135164917us : __intel_context_do_pin_ww: 0000:03:00.0 bcs'0: context:8b active <0> [561.682036] kms_fron-2179 5..... 135165028us : intel_timeline_pin: 0000:03:00.0 timeline:8b using HWSP offset:fedee100 <0> [561.682104] kms_fron-2179 5..... 135165134us : __intel_context_do_pin_ww: 0000:03:00.0 bcs'0: context:8b pin ring:{start:feda0000, head:0000, tail:0000} <0> [561.682174] kms_fron-2179 5..... 135165680us : __intel_context_do_pin_ww: 0000:03:00.0 vcs'0: context:8c active <0> [561.682269] kms_fron-2179 5..... 135165863us : intel_timeline_pin: 0000:03:00.0 timeline:8c using HWSP offset:feded100 <0> [561.682354] kms_fron-2179 5..... 135165982us : __intel_context_do_pin_ww: 0000:03:00.0 vcs'0: context:8c pin ring:{start:fed90000, head:0000, tail:0000} <0> [561.682423] kms_fron-2179 5..... 135166542us : __intel_context_do_pin_ww: 0000:03:00.0 vcs'2: context:8d active <0> [561.682498] kms_fron-2179 5..... 135166664us : intel_timeline_pin: 0000:03:00.0 timeline:8d using HWSP offset:fedec100 <0> [561.682595] kms_fron-2179 5..... 135166970us : __intel_context_do_pin_ww: 0000:03:00.0 vcs'2: context:8d pin ring:{start:fed70000, head:0000, tail:0000} <0> [561.682663] kms_fron-2179 5..... 135167514us : __intel_context_do_pin_ww: 0000:03:00.0 vecs'0: context:8e active <0> [561.682737] kms_fron-2179 5..... 135167644us : intel_timeline_pin: 0000:03:00.0 timeline:8e using HWSP offset:fedeb100 <0> [561.682806] kms_fron-2179 5..... 135167880us : __intel_context_do_pin_ww: 0000:03:00.0 vecs'0: context:8e pin ring:{start:fed50000, head:0000, tail:0000} <0> [561.682874] kms_fron-2179 5..... 135168425us : __intel_context_do_pin_ww: 0000:03:00.0 vecs'1: context:8f active <0> [561.682948] kms_fron-2179 5..... 135168546us : intel_timeline_pin: 0000:03:00.0 timeline:8f using HWSP offset:fedea100 <0> [561.683017] kms_fron-2179 5..... 135168662us : __intel_context_do_pin_ww: 0000:03:00.0 vecs'1: context:8f pin ring:{start:fed30000, head:0000, tail:0000} <0> [561.683084] kms_fron-2179 5..... 135169286us : __intel_context_do_pin_ww: 0000:03:00.0 ccs'0: context:90 active <0> [561.683160] kms_fron-2179 5..... 135169512us : intel_timeline_pin: 0000:03:00.0 timeline:90 using HWSP offset:fede9100 <0> [561.683230] kms_fron-2179 5..... 135169624us : __intel_context_do_pin_ww: 0000:03:00.0 ccs'0: context:90 pin ring:{start:fed10000, head:0000, tail:0000} <0> [561.683302] kms_fron-2179 5..... 135211931us : intel_gt_resume.part.0: 0000:03:00.0 <0> [561.683373] kms_fron-2179 5..... 135211932us : gt_sanitize: 0000:03:00.0 force:yes <0> [561.683446] kms_fron-2179 5..... 135211948us : __intel_gt_unset_wedged: 0000:03:00.0 start <0> [561.683518] kms_fron-2179 5..... 135212163us : __intel_gt_reset: 0000:03:00.0 engine_mask=ffffffff <0> [561.683623] kms_fron-2179 5..... 135212373us : __intel_gt_unset_wedged: 0000:03:00.0 end <0> [561.683692] kms_fron-2179 5..... 135212468us : intel_engine_stop_cs: 0000:03:00.0 rcs'0: <0> [561.683761] kms_fron-2179 5..... 135212477us : intel_engine_stop_cs: 0000:03:00.0 bcs'0: <0> [561.683829] kms_fron-2179 5..... 135212486us : intel_engine_stop_cs: 0000:03:00.0 vcs'0: <0> [561.683897] kms_fron-2179 5..... 135212496us : intel_engine_stop_cs: 0000:03:00.0 vcs'2: <0> [561.683965] kms_fron-2179 5..... 135212505us : intel_engine_stop_cs: 0000:03:00.0 vecs'0: <0> [561.684034] kms_fron-2179 5..... 135212515us : intel_engine_stop_cs: 0000:03:00.0 vecs'1: <0> [561.684102] kms_fron-2179 5..... 135212524us : intel_engine_stop_cs: 0000:03:00.0 ccs'0: <0> [561.684176] kms_fron-2179 5..... 135212534us : __intel_gt_reset: 0000:03:00.0 engine_mask=ffffffff <0> [561.684247] kms_fron-2179 5..... 135212797us : __gt_unpark: 0000:03:00.0 <0> [561.684315] kms_fron-2179 5..... 135226442us : __engine_unpark: 0000:03:00.0 rcs'0: <0> [561.684384] kms_fron-2179 5..... 135226497us : __engine_unpark: 0000:03:00.0 rcs'0: context:8a reset { seqno:0, hwsp:0, ring:0 } <0> [561.684452] kms_fron-2179 5..... 135226607us : __engine_park: 0000:03:00.0 rcs'0: parked <0> [561.684522] kms_fron-2179 5..... 135226629us : __engine_unpark: 0000:03:00.0 bcs'0: <0> [561.684621] kms_fron-2179 5..... 135226651us : __engine_unpark: 0000:03:00.0 bcs'0: context:8b reset { seqno:0, hwsp:0, ring:0 } <0> [561.684689] kms_fron-2179 5..... 135226663us : __engine_park: 0000:03:00.0 bcs'0: parked <0> [561.684759] kms_fron-2179 5..... 135226675us : __engine_unpark: 0000:03:00.0 vcs'0: <0> [561.684827] kms_fron-2179 5..... 135226732us : __engine_unpark: 0000:03:00.0 vcs'0: context:8c reset { seqno:0, hwsp:0, ring:0 } <0> [561.684897] kms_fron-2179 5..... 135226758us : __engine_park: 0000:03:00.0 vcs'0: parked <0> [561.684966] kms_fron-2179 5..... 135226771us : __engine_unpark: 0000:03:00.0 vcs'2: <0> [561.685035] kms_fron-2179 5..... 135226792us : __engine_unpark: 0000:03:00.0 vcs'2: context:8d reset { seqno:0, hwsp:0, ring:0 } <0> [561.685104] kms_fron-2179 5..... 135226802us : __engine_park: 0000:03:00.0 vcs'2: parked <0> [561.685172] kms_fron-2179 5..... 135226814us : __engine_unpark: 0000:03:00.0 vecs'0: <0> [561.685240] kms_fron-2179 5..... 135226834us : __engine_unpark: 0000:03:00.0 vecs'0: context:8e reset { seqno:0, hwsp:0, ring:0 } <0> [561.685327] kms_fron-2179 5..... 135226845us : __engine_park: 0000:03:00.0 vecs'0: parked <0> [561.685398] kms_fron-2179 5..... 135226857us : __engine_unpark: 0000:03:00.0 vecs'1: <0> [561.685469] kms_fron-2179 5..... 135226878us : __engine_unpark: 0000:03:00.0 vecs'1: context:8f reset { seqno:0, hwsp:0, ring:0 } <0> [561.685539] kms_fron-2179 5..... 135226888us : __engine_park: 0000:03:00.0 vecs'1: parked <0> [561.685622] kms_fron-2179 5..... 135226900us : __engine_unpark: 0000:03:00.0 ccs'0: <0> [561.685690] kms_fron-2179 5..... 135226933us : __engine_unpark: 0000:03:00.0 ccs'0: context:90 reset { seqno:0, hwsp:0, ring:0 } <0> [561.685758] kms_fron-2179 5..... 135226967us : __engine_park: 0000:03:00.0 ccs'0: parked <0> [561.685829] kms_fron-2179 5..... 135227148us : __gt_park: 0000:03:00.0 <0> [561.685898] kms_fron-2179 5..... 135229454us : __intel_context_do_pin_ww: 0000:03:00.0 rcs'0: context:91 active <0> [561.685974] kms_fron-2179 5..... 135229630us : intel_timeline_pin: 0000:03:00.0 timeline:91 using HWSP offset:fede8000 <0> [561.686043] kms_fron-2179 5..... 135229812us : __engine_unpark: 0000:03:00.0 rcs'0: <0> [561.686114] kms_fron-2179 5..... 135229854us : __gt_unpark: 0000:03:00.0 <0> [561.686183] kms_fron-2179 5..... 135230333us : __engine_unpark: 0000:03:00.0 rcs'0: context:8a reset { seqno:0, hwsp:0, ring:0 } <0> [561.686253] kms_fron-2179 5..... 135230337us : __intel_context_do_pin_ww: 0000:03:00.0 rcs'0: context:91 pin ring:{start:fece0000, head:0000, tail:0000} <0> [561.686334] kms_fron-2179 5..... 135230648us : __i915_request_commit: 0000:03:00.0 rcs'0: fence 91:2, current 0 <0> [561.686415] kms_fron-2179 5D..1. 135230761us : __i915_request_submit: 0000:03:00.0 rcs'0: fence 91:2, current 0 <0> [561.686484] kms_fron-2179 5..... 135230988us : __intel_context_do_pin_ww: 0000:03:00.0 bcs'0: context:92 active <0> [561.686591] kms_fron-2179 5..... 135231138us : intel_timeline_pin: 0000:03:00.0 timeline:92 using HWSP offset:fede7000 <0> [561.686661] kms_fron-2179 5..... 135231283us : __engine_unpark: 0000:03:00.0 bcs'0: <0> [561.686730] kms_fron-2179 5..... 135231305us : __engine_unpark: 0000:03:00.0 bcs'0: context:8b reset { seqno:0, hwsp:0, ring:0 } <0> [561.686798] kms_fron-2179 5..... 135231305us : __intel_context_do_pin_ww: 0000:03:00.0 bcs'0: context:92 pin ring:{start:fecb0000, head:0000, tail:0000} <0> [561.686878] kms_fron-2179 5..... 135231347us : __i915_request_commit: 0000:03:00.0 bcs'0: fence 92:2, current 0 <0> [561.686957] kms_fron-2179 5D..1. 135231351us : __i915_request_submit: 0000:03:00.0 bcs'0: fence 92:2, current 0 <0> [561.687072] kms_fron-2179 5..... 135231478us : __intel_context_do_pin_ww: 0000:03:00.0 vcs'0: context:93 active <0> [561.687163] kms_fron-2179 5..... 135231623us : intel_timeline_pin: 0000:03:00.0 timeline:93 using HWSP offset:fede6000 <0> [561.687232] kms_fron-2179 5..... 135231797us : __engine_unpark: 0000:03:00.0 vcs'0: <0> [561.687302] kms_fron-2179 5..... 135231820us : __engine_unpark: 0000:03:00.0 vcs'0: context:8c reset { seqno:0, hwsp:0, ring:0 } <0> [561.687370] kms_fron-2179 5..... 135231821us : __intel_context_do_pin_ww: 0000:03:00.0 vcs'0: context:93 pin ring:{start:fec90000, head:0000, tail:0000} <0> [561.687449] kms_fron-2179 5..... 135231860us : __i915_request_commit: 0000:03:00.0 vcs'0: fence 93:2, current 0 <0> [561.687546] kms_fron-2179 5D..1. 135231864us : __i915_request_submit: 0000:03:00.0 vcs'0: fence 93:2, current 0 <0> [561.687630] kms_fron-2179 5..... 135231995us : __intel_context_do_pin_ww: 0000:03:00.0 vcs'2: context:94 active <7> [561.687567] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509 <0> [561.687704] kms_fron-2179 5..... 135232137us : intel_timeline_pin: 0000:03:00.0 timeline:94 using HWSP offset:fede5000 <0> [561.687797] kms_fron-2179 5..... 135232278us : __engine_unpark: 0000:03:00.0 vcs'2: <0> [561.687881] kms_fron-2179 5..... 135232300us : __engine_unpark: 0000:03:00.0 vcs'2: context:8d reset { seqno:0, hwsp:0, ring:0 } <0> [561.687950] kms_fron-2179 5..... 135232301us : __intel_context_do_pin_ww: 0000:03:00.0 vcs'2: context:94 pin ring:{start:fec70000, head:0000, tail:0000} <7> [561.687930] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509 <0> [561.688029] kms_fron-2179 5..... 135232340us : __i915_request_commit: 0000:03:00.0 vcs'2: fence 94:2, current 0 <0> [561.688126] kms_fron-2179 5D..1. 135232344us : __i915_request_submit: 0000:03:00.0 vcs'2: fence 94:2, current 0 <0> [561.688210] kms_fron-2179 5..... 135232469us : __intel_context_do_pin_ww: 0000:03:00.0 vecs'0: context:95 active <0> [561.688286] kms_fron-2179 5..... 135232614us : intel_timeline_pin: 0000:03:00.0 timeline:95 using HWSP offset:fede4000 <0> [561.688356] kms_fron-2179 5..... 135232789us : __engine_unpark: 0000:03:00.0 vecs'0: <0> [561.688426] kms_fron-2179 5..... 135232811us : __engine_unpark: 0000:03:00.0 vecs'0: context:8e reset { seqno:0, hwsp:0, ring:0 } <0> [561.688493] kms_fron-2179 5..... 135232811us : __intel_context_do_pin_ww: 0000:03:00.0 vecs'0: context:95 pin ring:{start:fec50000, head:0000, tail:0000} <0> [561.688588] kms_fron-2179 5..... 135232851us : __i915_request_commit: 0000:03:00.0 vecs'0: fence 95:2, current 0 <0> [561.688670] kms_fron-2179 5D..1. 135232855us : __i915_request_submit: 0000:03:00.0 vecs'0: fence 95:2, current 0 <0> [561.688771] kms_fron-2179 5..... 135232980us : __intel_context_do_pin_ww: 0000:03:00.0 vecs'1: context:96 active <0> [561.688861] kms_fron-2179 5..... 135233140us : intel_timeline_pin: 0000:03:00.0 timeline:96 using HWSP offset:fede3000 <0> [561.688959] kms_fron-2179 5..... 135233293us : __engine_unpark: 0000:03:00.0 vecs'1: <0> [561.689028] kms_fron-2179 5..... 135233315us : __engine_unpark: 0000:03:00.0 vecs'1: context:8f reset { seqno:0, hwsp:0, ring:0 } <0> [561.689096] kms_fron-2179 5..... 135233316us : __intel_context_do_pin_ww: 0000:03:00.0 vecs'1: context:96 pin ring:{start:fec30000, head:0000, tail:0000} <0> [561.689176] kms_fron-2179 5..... 135233355us : __i915_request_commit: 0000:03:00.0 vecs'1: fence 96:2, current 0 <0> [561.689255] kms_fron-2179 5D..1. 135233359us : __i915_request_submit: 0000:03:00.0 vecs'1: fence 96:2, current 0 <0> [561.689324] kms_fron-2179 5..... 135233484us : __intel_context_do_pin_ww: 0000:03:00.0 ccs'0: context:97 active <0> [561.689399] kms_fron-2179 5..... 135233628us : intel_timeline_pin: 0000:03:00.0 timeline:97 using HWSP offset:fede2000 <0> [561.689468] kms_fron-2179 5..... 135233817us : __engine_unpark: 0000:03:00.0 ccs'0: <0> [561.689556] kms_fron-2179 5..... 135233850us : __engine_unpark: 0000:03:00.0 ccs'0: context:90 reset { seqno:0, hwsp:0, ring:0 } <0> [561.689639] kms_fron-2179 5..... 135233851us : __intel_context_do_pin_ww: 0000:03:00.0 ccs'0: context:97 pin ring:{start:fec10000, head:0000, tail:0000} <0> [561.689736] kms_fron-2179 5..... 135233890us : __i915_request_commit: 0000:03:00.0 ccs'0: fence 97:2, current 0 <0> [561.689818] kms_fron-2179 5D..1. 135233894us : __i915_request_submit: 0000:03:00.0 ccs'0: fence 97:2, current 0 <0> [561.689914] kms_fron-2179 5..... 135233963us : i915_request_retire.part.0: 0000:03:00.0 rcs'0: fence 91:2, current 2 <0> [561.689995] kms_fron-2179 5..... 135233980us : i915_request_retire.part.0: 0000:03:00.0 bcs'0: fence 92:2, current 2 <0> [561.690075] kms_fron-2179 5..... 135233986us : i915_request_retire.part.0: 0000:03:00.0 vcs'0: fence 93:2, current 2 <0> [561.690155] kms_fron-2179 5..... 135233990us : i915_request_retire.part.0: 0000:03:00.0 vcs'2: fence 94:2, current 2 <0> [561.690235] kms_fron-2179 5..... 135233995us : i915_request_retire.part.0: 0000:03:00.0 vecs'0: fence 95:2, current 2 <0> [561.690332] kms_fron-2179 5..... 135234000us : i915_request_retire.part.0: 0000:03:00.0 vecs'1: fence 96:2, current 2 <0> [561.690408] kms_fron-2179 5..... 135234047us : intel_rps_boost.part.0: 0000:03:00.0 boost fence:97:2 <0> [561.690490] kms_fron-2179 5.l... 135234592us : i915_request_retire.part.0: 0000:03:00.0 ccs'0: fence 97:2, current 2 <0> [561.690575] kms_fron-2179 5..... 135330783us : __engine_unpark: 0000:03:00.0 rcs'0: <0> [561.690646] kms_fron-2179 5..... 135330813us : __gt_unpark: 0000:03:00.0 <0> [561.690716] kms_fron-2179 5..... 135331505us : __engine_unpark: 0000:03:00.0 rcs'0: context:8a reset { seqno:0, hwsp:0, ring:0 } <0> [561.690795] kms_fron-2179 5..... 135332471us : __i915_request_commit: 0000:03:00.0 rcs'0: fence 8a:1, current 0 <0> [561.690875] kms_fron-2179 5D..1. 135332539us : __i915_request_submit: 0000:03:00.0 rcs'0: fence 8a:1, current 0 <0> [561.690955] kworker/-50 5..... 135333067us : i915_request_retire.part.0: 0000:03:00.0 rcs'0: fence 8a:1, current 1 <0> [561.691025] kms_fron-2179 5..... 135333119us : __engine_park: 0000:03:00.0 rcs'0: parked <0> [561.691094] kms_fron-2179 5.l... 135333452us : __engine_unpark: 0000:03:00.0 bcs'0: <0> [561.691181] kms_fron-2179 5.l... 135333473us : __engine_unpark: 0000:03:00.0 bcs'0: context:8b reset { seqno:0, hwsp:0, ring:0 } <0> [561.691262] kms_fron-2179 5.l... 135333536us : __i915_request_commit: 0000:03:00.0 bcs'0: fence 8b:1, current 0 <0> [561.691356] kms_fron-2179 5Dl.1. 135333540us : __i915_request_submit: 0000:03:00.0 bcs'0: fence 8b:1, current 0 <0> [561.691426] kms_fron-2179 5..... 135333863us : __engine_park: 0000:03:00.0 bcs'0: parked <0> [561.691500] kworker/-50 5..... 135335101us : __gt_park: 0000:03:00.0 <0> [561.691598] kms_fron-2179 5..... 135335130us : __engine_unpark: 0000:03:00.0 vcs'0: <0> [561.691670] kms_fron-2179 5..... 135335137us : __gt_unpark: 0000:03:00.0 <0> [561.691739] kms_fron-2179 5..... 135335177us : __engine_unpark: 0000:03:00.0 vcs'0: context:8c reset { seqno:0, hwsp:0, ring:0 } <0> [561.691820] kms_fron-2179 5..... 135335241us : __i915_request_commit: 0000:03:00.0 vcs'0: fence 8c:1, current 0 <0> [561.691900] kms_fron-2179 5D..1. 135335245us : __i915_request_submit: 0000:03:00.0 vcs'0: fence 8c:1, current 0 <0> [561.691970] kms_fron-2179 5..... 135335437us : __engine_unpark: 0000:03:00.0 vcs'2: <0> [561.692039] kms_fron-2179 5..... 135335458us : __engine_unpark: 0000:03:00.0 vcs'2: context:8d reset { seqno:0, hwsp:0, ring:0 } <0> [561.692164] kms_fron-2179 5..... 135335518us : __i915_request_commit: 0000:03:00.0 vcs'2: fence 8d:1, current 0 <0> [561.692246] kms_fron-2179 5D..1. 135335522us : __i915_request_submit: 0000:03:00.0 vcs'2: fence 8d:1, current 0 <0> [561.692319] kms_fron-2179 5..... 135335803us : __engine_unpark: 0000:03:00.0 vecs'0: <0> [561.692403] kms_fron-2179 5..... 135335824us : __engine_unpark: 0000:03:00.0 vecs'0: context:8e reset { seqno:0, hwsp:0, ring:0 } <0> [561.692483] kms_fron-2179 5..... 135335898us : __i915_request_commit: 0000:03:00.0 vecs'0: fence 8e:1, current 0 <0> [561.692592] kms_fron-2179 5D..1. 135335903us : __i915_request_submit: 0000:03:00.0 vecs'0: fence 8e:1, current 0 <0> [561.692662] kms_fron-2179 5..... 135336165us : __engine_park: 0000:03:00.0 vecs'0: parked <0> [561.692761] kms_fron-2179 5.l... 135336209us : __engine_unpark: 0000:03:00.0 vecs'1: <0> [561.692830] kms_fron-2179 5.l... 135336231us : __engine_unpark: 0000:03:00.0 vecs'1: context:8f reset { seqno:0, hwsp:0, ring:0 } <0> [561.692910] kms_fron-2179 5.l... 135336351us : __i915_request_commit: 0000:03:00.0 vecs'1: fence 8f:1, current 0 <0> [561.693021] kms_fron-2179 5Dl.1. 135336355us : __i915_request_submit: 0000:03:00.0 vecs'1: fence 8f:1, current 0 <0> [561.693091] kms_fron-2179 5..... 135336604us : __engine_park: 0000:03:00.0 vecs'1: parked <0> [561.693161] kms_fron-2179 5.l... 135336655us : __engine_unpark: 0000:03:00.0 ccs'0: <0> [561.693231] kms_fron-2179 5..... 135336816us : __engine_unpark: 0000:03:00.0 ccs'0: context:90 reset { seqno:0, hwsp:0, ring:0 } <0> [561.693329] kms_fron-2179 5..... 135336879us : __i915_request_commit: 0000:03:00.0 ccs'0: fence 90:1, current 0 <0> [561.693413] kms_fron-2179 5D..1. 135336884us : __i915_request_submit: 0000:03:00.0 ccs'0: fence 90:1, current 0 <0> [561.693485] kms_fron-2179 5..... 135337158us : __engine_park: 0000:03:00.0 ccs'0: parked <0> [561.693559] kworker/-50 5..... 135337693us : __gt_park: 0000:03:00.0 <0> [561.693633] kms_fron-2179 5..... 135338499us : __gt_unpark: 0000:03:00.0 <0> [561.693706] kworker/-50 5..... 135339697us : __gt_park: 0000:03:00.0 <7> [561.693699] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509 <0> [561.693775] kms_fron-2179 5..... 135341091us : __intel_context_do_pin_ww: 0000:03:00.0 bcs'0: context:98 active <0> [561.694007] kms_fron-2179 5..... 135341218us : intel_timeline_pin: 0000:03:00.0 timeline:98 using HWSP offset:fedee108 <0> [561.694090] kms_fron-2179 5..... 135341393us : __intel_context_do_pin_ww: 0000:03:00.0 bcs'0: context:98 pin ring:{start:fec70000, head:0000, tail:0000} <0> [561.694176] kms_fron-2179 5.l... 135386488us : __gt_unpark: 0000:03:00.0 <0> [561.694246] kworker/-207 5..... 135387434us : __gt_park: 0000:03:00.0 <0> [561.694254] kms_curs-2231 5...1. 153998670us : tracing_mark_write: kms_cursor_legacy: finished subtest pipe-B, SUCCESS <7> [561.694181] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509 <0> [561.694323] kms_curs-2231 5.l... 153999202us : __engine_unpark: 0000:03:00.0 bcs0: <0> [561.694412] kms_curs-2231 5.l... 153999212us : __gt_unpark: 0000:03:00.0 <0> [561.694483] kms_curs-2231 5.l... 153999455us : __engine_unpark: 0000:03:00.0 bcs0: context:8b reset { seqno:1, hwsp:1, ring:68 } <0> [561.694577] kms_curs-2231 5.l... 153999482us : __i915_request_commit: 0000:03:00.0 bcs0: fence 98:10, current 9 <0> [561.694659] kms_curs-2231 5Dl.1. 153999488us : __i915_request_submit: 0000:03:00.0 bcs0: fence 98:10, current 9 <0> [561.694733] kms_curs-2231 5..... 154000041us : intel_rps_mark_interactive: 0000:03:00.0 mark interactive: yes <0> [561.694738] kms_curs-2231 5...1. 159378053us : tracing_mark_write: kms_cursor_legacy: finished subtest pipe-C, SUCCESS <0> [561.694807] kms_curs-2231 5..... 159378627us : __engine_unpark: 0000:03:00.0 bcs0: <0> [561.694878] kms_curs-2231 5..... 159378637us : __gt_unpark: 0000:03:00.0 <0> [561.694948] kms_curs-2231 5..... 159378876us : __engine_unpark: 0000:03:00.0 bcs0: context:8b reset { seqno:1, hwsp:1, ring:68 } <0> [561.695028] kms_curs-2231 5..... 159378904us : __i915_request_commit: 0000:03:00.0 bcs0: fence 98:11, current 10 <0> [561.695110] kms_curs-2231 5D..1. 159378909us : __i915_request_submit: 0000:03:00.0 bcs0: fence 98:11, current 10 <0> [561.695184] kms_curs-2231 5..... 159379141us : intel_rps_mark_interactive: 0000:03:00.0 mark interactive: yes <0> [561.695190] kms_curs-2231 5...1. 164757469us : tracing_mark_write: kms_cursor_legacy: finished subtest pipe-D, SUCCESS <0> [561.695260] kms_curs-2231 5..... 164757936us : __engine_unpark: 0000:03:00.0 bcs0: <0> [561.695331] kms_curs-2231 5..... 164757946us : __gt_unpark: 0000:03:00.0 <0> [561.695399] kms_curs-2231 5..... 164758188us : __engine_unpark: 0000:03:00.0 bcs0: context:8b reset { seqno:1, *hwsp:1, ring:68 } <0> [561.695479] kms_curs-2231 5..... 164758215us : __i915_request_commit: 0000:03:00.0 bcs0: fence 98:12, current 11 <0> [561.695577] kms_curs-2231 5D..1. 164758221us : __i915_request_submit: 0000:03:00.0 bcs0: fence 98:12, current 11 <0> [561.695683] kms_curs-2231 5..... 164759054us : intel_rps_mark_interactive: 0000:03:00.0 mark interactive: yes <0> [561.695786] kworker/-81 5..... 164759101us : intel_rps_mark_interactive: 0000:03:00.0 mark interactive: no <0> [561.695889] kms_curs-2231 5..... 164759242us : intel_rps_mark_interactive: 0000:03:00.0 mark interactive: yes <0> [561.695978] kworker/-81 5..... 164759310us : intel_rps_mark_interactive: 0000:03:00.0 mark interactive: no <0> [561.696052] kms_curs-2231 5..... 164759453us : intel_rps_mark_interactive: 0000:03:00.0 mark interactive: yes <0> [561.696125] kworker/-81 5..... 164759492us : intel_rps_mark_interactive: 0000:03:00.0 mark interactive: no <0> [561.696131] kms_curs-2231 5...1. 170137511us : tracing_mark_write: kms_cursor_legacy: finished subtest all-pipes, SUCCESS <0> [561.696136] kms_curs-2231 5...1. 170137801us : tracing_mark_write: kms_cursor_legacy: finished subtest single-move, SUCCESS <0> [561.696208] kms_curs-2231 5..... 170140680us : intel_rps_mark_interactive: 0000:03:00.0 mark interactive: yes <0> [561.696282] kworker/-81 5..... 170156823us : intel_rps_mark_interactive: 0000:03:00.0 mark interactive: no <0> [561.696355] kms_curs-2231 5..... 170158188us : intel_rps_mark_interactive: 0000:03:00.0 mark interactive: yes <0> [561.696429] kworker/-81 5..... 170174436us : intel_rps_mark_interactive: 0000:03:00.0 mark interactive: no <0> [561.696502] kms_curs-2231 5..... 170174670us : intel_rps_mark_interactive: 0000:03:00.0 mark interactive: yes <0> [561.696608] kworker/-81 5..... 170190543us : intel_rps_mark_interactive: 0000:03:00.0 mark interactive: no <0> [561.696680] i915_pm_-2238 5..... 172030851us : __gt_unpark: 0000:03:00.0 <0> [561.696751] kworker/-50 5..... 172031989us : __gt_park: 0000:03:00.0 <0> [561.696757] kms_feat-2239 5...1. 172215373us : tracing_mark_write: kms_feature_discovery: executing <0> [561.696827] kms_feat-2239 5..... 172233839us : __gt_unpark: 0000:03:00.0 <0> [561.696899] kworker/-50 5..... 172233988us : __gt_park: 0000:03:00.0 <0> [561.696970] kms_feat-2239 5..... 172248122us : __gt_unpark: 0000:03:00.0 <0> [561.697041] kworker/-50 5..... 172248261us : __gt_park: 0000:03:00.0

<6> [561.526804] Console: switching to colour dummy device 80x25

<6> [561.527302] [IGT] kms_setmode: executing

<7> [561.544809] i915 0000:03:00.0: [drm:i915_gem_open [i915]]

<7> [561.546483] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]

<7> [561.556044] i915 0000:03:00.0: [drm:i915_gem_open [i915]]

<6> [561.558140] [IGT] kms_setmode: starting subtest basic

<7> [561.558379] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]

<7> [561.558479] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.enable (expected yes, found no)

<7> [561.558712] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.active (expected yes, found no)

<7> [561.558939] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)

<7> [561.559104] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in lane_count (expected 4, found 0)

<7> [561.559267] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)

<7> [561.559430] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)

<7> [561.559598] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)

<7> [561.559813] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)

<7> [561.560049] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)

<7> [561.560211] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)

<7> [561.560372] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)

<7> [561.560535] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)

<7> [561.560748] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)

<7> [561.560972] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)

<7> [561.561133] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)

<7> [561.561295] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)

<7> [561.561458] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)

<7> [561.561641] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)

<7> [561.561820] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)

<7> [561.561984] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)

<7> [561.562147] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)

<7> [561.562308] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)

<7> [561.562486] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)

<7> [561.562685] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)

<7> [561.562892] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)

<7> [561.563053] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)

<7> [561.563215] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)

<7> [561.563377] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)

<7> [561.563568] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)

<7> [561.563825] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)

<7> [561.564007] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)

<7> [561.564168] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)

<7> [561.564360] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)

<7> [561.564522] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)

<7> [561.564723] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)

<7> [561.564957] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)

<7> [561.565149] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in port_clock (expected 148500, found 0)

<7> [561.565312] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset

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

<7> [561.565773] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:151:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x1 -> 0x0

<7> [561.566112] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:35:plane 1A] ddb ( 0 - 1963) -> ( 0 - 0), size 1963 -> 0

<7> [561.566270] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:145:cursor A] ddb (1963 - 2048) -> ( 0 - 0), size 85 -> 0

<7> [561.566429] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35: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.566585] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] lines 1, 3, 4, 5, 9, 11, 17, 25, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0

<7> [561.566799] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] blocks 16, 49, 65, 81, 145, 177, 273, 401, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0

<7> [561.566996] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] min_ddb 19, 55, 73, 91, 161, 196, 302, 443, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0

<7> [561.567170] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] [CRTC:151:pipe A] data rate 0 num active planes 0

<7> [561.567333] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 50000 required 0

<7> [561.567493] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1

<7> [561.567765] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:151:pipe A] min cdclk: 74250 kHz -> 0 kHz

<7> [561.567958] i915 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 9282 kHz -> 0 kHz

<7> [561.568153] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 163200 kHz, actual 163200 kHz

<7> [561.568328] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0

<7> [561.568495] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:151:pipe A] enable: no [modeset]

<7> [561.568686] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [NOFB], visible: no

<7> [561.568897] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no

<7> [561.569073] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no

<7> [561.569264] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no

<7> [561.569425] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no

<7> [561.569587] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:cursor A] fb: [NOFB], visible: no

<7> [561.582442] i915 0000:03:00.0: [drm:intel_disable_transcoder [i915]] disabling pipe A

<7> [561.599803] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_D

<7> [561.600041] i915 0000:03:00.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:35:plane 1A]

<7> [561.600225] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:507:DDI A/PHY A]

<7> [561.600390] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:509:DP-MST A]

<7> [561.600554] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:510:DP-MST B]

<7> [561.600726] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:511:DP-MST C]

<7> [561.600892] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:512:DP-MST D]

<7> [561.601058] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:520:DDI B/PHY B]

<7> [561.601223] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:522:DP-MST A]

<7> [561.601387] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:523:DP-MST B]

<7> [561.601551] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:524:DP-MST C]

<7> [561.601722] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:525:DP-MST D]

<7> [561.601885] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:535:DDI C/PHY C]

<7> [561.602050] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:537:DP-MST A]

<7> [561.602216] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:538:DP-MST B]

<7> [561.602380] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:539:DP-MST C]

<7> [561.602545] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:540:DP-MST D]

<7> [561.602716] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:547:DDI D/PHY D]

<7> [561.602901] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:549:DP-MST A]

<7> [561.603065] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:550:DP-MST B]

<7> [561.603229] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:551:DP-MST C]

<7> [561.603393] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:552:DP-MST D]

<7> [561.603556] i915 0000:03:00.0: [drm:verify_connector_state [i915]] [CONNECTOR:556:HDMI-A-3]

<7> [561.603762] i915 0000:03:00.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1

<7> [561.604035] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling PW_A

<7> [561.604269] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling PW_2

<7> [561.604479] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:151:pipe A]

<7> [561.604894] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:269:pipe C]

<7> [561.605060] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe B]

<7> [561.605207] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:505:pipe D]

<7> [561.606066] i915 0000:03:00.0: [drm:drm_mode_addfb2] [FB:560]

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

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

<3> [561.646758] guc_lrc_desc_unpin:3418 GEM_BUG_ON(!ctx_id_mapped(guc, ce->guc_id.id))

<0> [561.646818] ---------------------------------

gem_lmem-2115 5D..1. 132195612us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1222, current 1220

<0> [561.647063] gem_lmem-2115 5..... 132202210us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1220, current 1222

<0> [561.647144] gem_lmem-2115 5..... 132202223us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1224, current 1222

<0> [561.647224] gem_lmem-2115 5D..1. 132202227us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1224, current 1222

<0> [561.647305] gem_lmem-2115 5..... 132205074us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1222, current 1224

<0> [561.647385] gem_lmem-2115 5..... 132205087us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1226, current 1224

<0> [561.647465] gem_lmem-2115 5D..1. 132205091us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1226, current 1224

<0> [561.647545] gem_lmem-2115 5..... 132214836us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1224, current 1226

<0> [561.647625] gem_lmem-2115 5..... 132214848us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1228, current 1226

<0> [561.647704] gem_lmem-2115 5D..1. 132214852us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1228, current 1226

<0> [561.647784] gem_lmem-2115 5..... 132225833us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1226, current 1228

<0> [561.647864] gem_lmem-2115 5..... 132225845us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1230, current 1228

<0> [561.647944] gem_lmem-2115 5D..1. 132225849us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1230, current 1228

<0> [561.648024] gem_lmem-2115 5..... 132245320us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1228, current 1230

<0> [561.648104] gem_lmem-2115 5..... 132245333us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1232, current 1230

<0> [561.648184] gem_lmem-2115 5D..1. 132245337us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1232, current 1230

<0> [561.648264] gem_lmem-2115 5..... 132260026us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1230, current 1232

<0> [561.648344] gem_lmem-2115 5..... 132260038us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1234, current 1232

<0> [561.648425] gem_lmem-2115 5D..1. 132260043us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1234, current 1232

<0> [561.648504] gem_lmem-2115 5..... 132267004us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1232, current 1234

<0> [561.648584] gem_lmem-2115 5..... 132267016us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1236, current 1234

<0> [561.648665] gem_lmem-2115 5D..1. 132267021us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1236, current 1234

<0> [561.648744] gem_lmem-2115 5..... 132283143us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1234, current 1236

<0> [561.648824] gem_lmem-2115 5..... 132283156us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1238, current 1236

<0> [561.648904] gem_lmem-2115 5D..1. 132283160us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1238, current 1236

<0> [561.648985] gem_lmem-2115 5..... 132295005us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1236, current 1238

<0> [561.649066] gem_lmem-2115 5..... 132295018us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1240, current 1238

<0> [561.649146] gem_lmem-2115 5D..1. 132295022us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1240, current 1238

<0> [561.649225] gem_lmem-2115 5..... 132297080us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1238, current 1240

<0> [561.649306] gem_lmem-2115 5..... 132297093us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1242, current 1240

<0> [561.649386] gem_lmem-2115 5D..1. 132297097us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1242, current 1240

<0> [561.649465] gem_lmem-2115 5..... 132305217us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1240, current 1242

<0> [561.649545] gem_lmem-2115 5..... 132305230us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1244, current 1242

<0> [561.649626] gem_lmem-2115 5D..1. 132305234us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1244, current 1242

<0> [561.649705] gem_lmem-2115 5..... 132310563us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1242, current 1244

<0> [561.649786] gem_lmem-2115 5..... 132310575us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1246, current 1244

<0> [561.649866] gem_lmem-2115 5D..1. 132310580us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1246, current 1244

<0> [561.649946] gem_lmem-2115 5..... 132326730us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1244, current 1246

<0> [561.650026] gem_lmem-2115 5..... 132326742us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1248, current 1246

<0> [561.650106] gem_lmem-2115 5D..1. 132326746us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1248, current 1246

<0> [561.650186] gem_lmem-2115 5..... 132341874us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1246, current 1248

<0> [561.650266] gem_lmem-2115 5..... 132341889us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1250, current 1248

<0> [561.650346] gem_lmem-2115 5D..1. 132341893us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1250, current 1248

<0> [561.650426] gem_lmem-2115 5..... 132351923us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1248, current 1250

<0> [561.650506] gem_lmem-2115 5..... 132351936us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1252, current 1250

<0> [561.650587] gem_lmem-2115 5D..1. 132351941us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1252, current 1250

<0> [561.650666] gem_lmem-2115 5..... 132358423us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1250, current 1252

<0> [561.650748] gem_lmem-2115 5..... 132358436us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1254, current 1252

<0> [561.650828] gem_lmem-2115 5D..1. 132358440us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1254, current 1252

<0> [561.650908] gem_lmem-2115 5..... 132368306us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1252, current 1254

<0> [561.650988] gem_lmem-2115 5..... 132368319us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1256, current 1254

<0> [561.651068] gem_lmem-2115 5D..1. 132368323us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1256, current 1254

<0> [561.651149] gem_lmem-2115 5..... 132377639us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1254, current 1256

<0> [561.651228] gem_lmem-2115 5..... 132377652us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1258, current 1256

<0> [561.651308] gem_lmem-2115 5D..1. 132377656us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1258, current 1256

<0> [561.651388] gem_lmem-2115 5..... 132386518us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1256, current 1258

<0> [561.651468] gem_lmem-2115 5..... 132386531us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1260, current 1258

<0> [561.651548] gem_lmem-2115 5D..1. 132386535us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1260, current 1258

<0> [561.651628] gem_lmem-2115 5..... 132397091us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1258, current 1260

<0> [561.651707] gem_lmem-2115 5..... 132397104us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1262, current 1260

<0> [561.651787] gem_lmem-2115 5D..1. 132397108us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1262, current 1260

<0> [561.651867] gem_lmem-2115 5..... 132400437us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1260, current 1262

<0> [561.651948] gem_lmem-2115 5..... 132400449us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1264, current 1262

<0> [561.652028] gem_lmem-2115 5D..1. 132400453us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1264, current 1262

<0> [561.652108] gem_lmem-2115 5..... 132402050us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1262, current 1264

<0> [561.652188] gem_lmem-2115 5..... 132402063us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1266, current 1264

<0> [561.652269] gem_lmem-2115 5D..1. 132402067us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1266, current 1264

<0> [561.652349] gem_lmem-2115 5..... 132423809us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1264, current 1266

<0> [561.652429] gem_lmem-2115 5..... 132423822us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1268, current 1266

<0> [561.652509] gem_lmem-2115 5D..1. 132423826us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1268, current 1266

<0> [561.652589] gem_lmem-2115 5..... 132437540us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1266, current 1268

<0> [561.652669] gem_lmem-2115 5..... 132437552us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1270, current 1268

<0> [561.652749] gem_lmem-2115 5D..1. 132437556us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1270, current 1268

<0> [561.652829] gem_lmem-2115 5..... 132455666us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1268, current 1270

<0> [561.652909] gem_lmem-2115 5..... 132455678us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1272, current 1270

<0> [561.652989] gem_lmem-2115 5D..1. 132455682us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1272, current 1270

<0> [561.653068] gem_lmem-2115 5..... 132464047us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1270, current 1272

<0> [561.653148] gem_lmem-2115 5..... 132464059us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1274, current 1272

<0> [561.653228] gem_lmem-2115 5D..1. 132464064us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1274, current 1272

<0> [561.653308] gem_lmem-2115 5..... 132475568us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1272, current 1274

<0> [561.653388] gem_lmem-2115 5..... 132475581us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1276, current 1274

<0> [561.653468] gem_lmem-2115 5D..1. 132475585us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1276, current 1274

<0> [561.653548] gem_lmem-2115 5..... 132485881us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1274, current 1276

<0> [561.653629] gem_lmem-2115 5..... 132485894us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1278, current 1276

<0> [561.653709] gem_lmem-2115 5D..1. 132485898us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1278, current 1276

<0> [561.653789] gem_lmem-2115 5..... 132499567us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1276, current 1278

<0> [561.653869] gem_lmem-2115 5..... 132499580us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1280, current 1278

<0> [561.653949] gem_lmem-2115 5D..1. 132499584us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1280, current 1278

<0> [561.654030] gem_lmem-2115 5..... 132505626us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1278, current 1280

<0> [561.654110] gem_lmem-2115 5..... 132505639us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1282, current 1280

<0> [561.654189] gem_lmem-2115 5D..1. 132505643us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1282, current 1280

<0> [561.654269] gem_lmem-2115 5..... 132516859us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1280, current 1282

<0> [561.654349] gem_lmem-2115 5..... 132516872us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1284, current 1282

<0> [561.654430] gem_lmem-2115 5D..1. 132516876us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1284, current 1282

<0> [561.654509] gem_lmem-2115 5..... 132526145us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1282, current 1284

<0> [561.654589] gem_lmem-2115 5..... 132526158us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1286, current 1284

<0> [561.654669] gem_lmem-2115 5D..1. 132526162us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1286, current 1284

<0> [561.654749] gem_lmem-2115 5..... 132536277us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1284, current 1286

<0> [561.654829] gem_lmem-2115 5..... 132536290us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1288, current 1286

<0> [561.654909] gem_lmem-2115 5D..1. 132536294us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1288, current 1286

<0> [561.654989] gem_lmem-2115 5..... 132549443us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1286, current 1288

<0> [561.655069] gem_lmem-2115 5..... 132549461us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1290, current 1288

<0> [561.655149] gem_lmem-2115 5D..1. 132549465us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1290, current 1288

<0> [561.655229] gem_lmem-2115 5..... 132554429us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1288, current 1290

<0> [561.655309] gem_lmem-2115 5..... 132554442us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1292, current 1290

<0> [561.655389] gem_lmem-2115 5D..1. 132554446us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1292, current 1290

<0> [561.655469] gem_lmem-2115 5..... 132563955us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1290, current 1292

<0> [561.655549] gem_lmem-2115 5..... 132563968us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1294, current 1292

<0> [561.655629] gem_lmem-2115 5D..1. 132563972us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1294, current 1292

<0> [561.655710] gem_lmem-2115 5..... 132573080us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1292, current 1294

<0> [561.655790] gem_lmem-2115 5..... 132573092us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1296, current 1294

<0> [561.655869] gem_lmem-2115 5D..1. 132573097us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1296, current 1294

<0> [561.655949] gem_lmem-2115 5..... 132585658us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1294, current 1296

<0> [561.656029] gem_lmem-2115 5..... 132585671us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1298, current 1296

<0> [561.656108] gem_lmem-2115 5D..1. 132585676us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1298, current 1296

<0> [561.656188] gem_lmem-2115 5..... 132597207us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1296, current 1298

<0> [561.656269] gem_lmem-2115 5..... 132597220us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1300, current 1298

<0> [561.656349] gem_lmem-2115 5D..1. 132597224us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1300, current 1298

<0> [561.656429] gem_lmem-2115 5..... 132605012us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1298, current 1300

<0> [561.656509] gem_lmem-2115 5..... 132605024us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1302, current 1300

<0> [561.656590] gem_lmem-2115 5D..1. 132605029us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1302, current 1300

<0> [561.656670] gem_lmem-2115 5..... 132613611us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1300, current 1302

<0> [561.656749] gem_lmem-2115 5..... 132613624us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1304, current 1302

<0> [561.656829] gem_lmem-2115 5D..1. 132613628us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1304, current 1302

<0> [561.656909] gem_lmem-2115 5..... 132626186us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1302, current 1304

<0> [561.656989] gem_lmem-2115 5..... 132626199us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1306, current 1304

<0> [561.657069] gem_lmem-2115 5D..1. 132626203us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1306, current 1304

<0> [561.657149] gem_lmem-2115 5..... 132643154us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1304, current 1306

<0> [561.657229] gem_lmem-2115 5..... 132643167us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1308, current 1306

<0> [561.657309] gem_lmem-2115 5D..1. 132643171us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1308, current 1306

<0> [561.657389] gem_lmem-2115 5..... 132649787us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1306, current 1308

<0> [561.657469] gem_lmem-2115 5..... 132649799us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1310, current 1308

<0> [561.657549] gem_lmem-2115 5D..1. 132649804us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1310, current 1308

<0> [561.657630] gem_lmem-2115 5..... 132658748us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1308, current 1310

<0> [561.657710] gem_lmem-2115 5..... 132658774us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1312, current 1310

<0> [561.657790] gem_lmem-2115 5D..1. 132658778us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1312, current 1310

<0> [561.657870] gem_lmem-2115 5..... 132665467us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1310, current 1312

<0> [561.657949] gem_lmem-2115 5..... 132665480us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1314, current 1312

<0> [561.658029] gem_lmem-2115 5D..1. 132665484us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1314, current 1312

<0> [561.658109] gem_lmem-2115 5..... 132681725us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1312, current 1314

<0> [561.658189] gem_lmem-2115 5..... 132681737us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1316, current 1314

<0> [561.658269] gem_lmem-2115 5D..1. 132681741us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1316, current 1314

<0> [561.658349] gem_lmem-2115 5..... 132698481us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1314, current 1316

<0> [561.658429] gem_lmem-2115 5..... 132698494us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1318, current 1316

<0> [561.658509] gem_lmem-2115 5D..1. 132698498us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1318, current 1316

<0> [561.658589] gem_lmem-2115 5..... 132714453us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1316, current 1318

<0> [561.658669] gem_lmem-2115 5..... 132714466us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1320, current 1318

<0> [561.658749] gem_lmem-2115 5D..1. 132714470us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1320, current 1318

<0> [561.658829] gem_lmem-2115 5..... 132718327us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1318, current 1320

<0> [561.658909] gem_lmem-2115 5..... 132718339us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1322, current 1320

<0> [561.658990] gem_lmem-2115 5D..1. 132718343us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1322, current 1320

<0> [561.659070] gem_lmem-2115 5..... 132735067us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1320, current 1322

<0> [561.659149] gem_lmem-2115 5..... 132735079us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1324, current 1322

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

<0> [561.659229] gem_lmem-2115 5D..1. 132735083us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1324, current 1322

<0> [561.659309] gem_lmem-2115 5..... 132752527us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1322, current 1324

<0> [561.659389] gem_lmem-2115 5..... 132752540us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1326, current 1324

<0> [561.659469] gem_lmem-2115 5D..1. 132752544us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1326, current 1324

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

<0> [561.659549] gem_lmem-2115 5..... 132756779us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1324, current 1326

<0> [561.659628] gem_lmem-2115 5..... 132756792us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1328, current 1326

<0> [561.659747] gem_lmem-2115 5D..1. 132756796us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1328, current 1326

<0> [561.659849] gem_lmem-2115 5..... 132767162us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1326, current 1328

<0> [561.659936] gem_lmem-2115 5..... 132767175us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1330, current 1328

<0> [561.660034] gem_lmem-2115 5D..1. 132767179us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1330, current 1328

<0> [561.660116] gem_lmem-2115 5..... 132770608us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1328, current 1330

<0> [561.660197] gem_lmem-2115 5..... 132770620us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1332, current 1330

<0> [561.660279] gem_lmem-2115 5D..1. 132770625us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1332, current 1330

<0> [561.660359] gem_lmem-2115 5..... 132779797us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1330, current 1332

<0> [561.660439] gem_lmem-2115 5..... 132779809us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1334, current 1332

<0> [561.660518] gem_lmem-2115 5D..1. 132779814us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1334, current 1332

<0> [561.660615] gem_lmem-2115 5..... 132790901us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1332, current 1334

<0> [561.660709] gem_lmem-2115 5..... 132790914us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1336, current 1334

<0> [561.660788] gem_lmem-2115 5D..1. 132790918us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1336, current 1334

<0> [561.660868] gem_lmem-2115 5..... 132804975us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1334, current 1336

<0> [561.660948] gem_lmem-2115 5..... 132804988us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1338, current 1336

<0> [561.661029] gem_lmem-2115 5D..1. 132804992us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1338, current 1336

<0> [561.661109] gem_lmem-2115 5..... 132820448us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1336, current 1338

<0> [561.661238] gem_lmem-2115 5..... 132820460us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1340, current 1338

<0> [561.661320] gem_lmem-2115 5D..1. 132820465us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1340, current 1338

<0> [561.661414] gem_lmem-2115 5..... 132832672us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1338, current 1340

<0> [561.661495] gem_lmem-2115 5..... 132832685us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1342, current 1340

<0> [561.661603] gem_lmem-2115 5D..1. 132832689us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1342, current 1340

<0> [561.661683] gem_lmem-2115 5..... 132846278us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1340, current 1342

<0> [561.661763] gem_lmem-2115 5..... 132846291us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1344, current 1342

<0> [561.661843] gem_lmem-2115 5D..1. 132846295us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1344, current 1342

<0> [561.661923] gem_lmem-2115 5..... 132849671us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1342, current 1344

<0> [561.662003] gem_lmem-2115 5..... 132849683us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1346, current 1344

<0> [561.662083] gem_lmem-2115 5D..1. 132849688us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1346, current 1344

<0> [561.662195] gem_lmem-2115 5..... 132854463us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1344, current 1346

<0> [561.662276] gem_lmem-2115 5..... 132854476us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1348, current 1346

<0> [561.662358] gem_lmem-2115 5D..1. 132854480us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1348, current 1346

<0> [561.662439] gem_lmem-2115 5..... 132866910us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1346, current 1348

<0> [561.662521] gem_lmem-2115 5..... 132866923us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1350, current 1348

<0> [561.662633] gem_lmem-2115 5D..1. 132866928us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1350, current 1348

<0> [561.662715] gem_lmem-2115 5..... 132877512us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1348, current 1350

<0> [561.662796] gem_lmem-2115 5..... 132877525us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1352, current 1350

<0> [561.662876] gem_lmem-2115 5D..1. 132877529us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1352, current 1350

<0> [561.662955] gem_lmem-2115 5..... 132890689us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1350, current 1352

<0> [561.663053] gem_lmem-2115 5..... 132890788us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1354, current 1352

<0> [561.663175] gem_lmem-2115 5D..1. 132890792us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1354, current 1352

<0> [561.663256] gem_lmem-2115 5..... 132900870us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1352, current 1354

<0> [561.663365] gem_lmem-2115 5..... 132900887us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1356, current 1354

<0> [561.663445] gem_lmem-2115 5D..1. 132900892us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1356, current 1354

<0> [561.663525] gem_lmem-2115 5..... 132911901us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1354, current 1356

<0> [561.663633] gem_lmem-2115 5..... 132911913us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1358, current 1356

<0> [561.663713] gem_lmem-2115 5D..1. 132911917us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1358, current 1356

<0> [561.663793] gem_lmem-2115 5..... 132928094us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1356, current 1358

<0> [561.663889] gem_lmem-2115 5..... 132928107us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1360, current 1358

<0> [561.663985] gem_lmem-2115 5D..1. 132928111us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1360, current 1358

<0> [561.664066] gem_lmem-2115 5..... 132941910us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1358, current 1360

<0> [561.664148] gem_lmem-2115 5..... 132941924us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1362, current 1360

<0> [561.664229] gem_lmem-2115 5D..1. 132941928us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1362, current 1360

<0> [561.664456] gem_lmem-2115 5..... 132959656us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1360, current 1362

<0> [561.664539] gem_lmem-2115 5..... 132959668us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1364, current 1362

<0> [561.664635] gem_lmem-2115 5D..1. 132959673us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1364, current 1362

<0> [561.664715] gem_lmem-2115 5..... 132965419us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1362, current 1364

<0> [561.664795] gem_lmem-2115 5..... 132965431us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1366, current 1364

<0> [561.664875] gem_lmem-2115 5D..1. 132965436us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1366, current 1364

<0> [561.664984] gem_lmem-2115 5..... 132978610us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1364, current 1366

<0> [561.665096] gem_lmem-2115 5..... 132978623us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1368, current 1366

<0> [561.665176] gem_lmem-2115 5D..1. 132978627us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1368, current 1366

<0> [561.665256] gem_lmem-2115 5..... 132982848us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1366, current 1368

<0> [561.665336] gem_lmem-2115 5..... 132982860us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1370, current 1368

<0> [561.665416] gem_lmem-2115 5D..1. 132982864us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1370, current 1368

<0> [561.665498] gem_lmem-2115 5..... 132994052us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1368, current 1370

<0> [561.665593] gem_lmem-2115 5..... 132994065us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1372, current 1370

<0> [561.665676] gem_lmem-2115 5D..1. 132994069us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1372, current 1370

<7> [561.665613] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DC_off

<0> [561.665759] gem_lmem-2115 5..... 133004205us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1370, current 1372

<0> [561.665841] gem_lmem-2115 5..... 133004218us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1374, current 1372

<0> [561.666240] gem_lmem-2115 5D..1. 133004222us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1374, current 1372

<7> [561.666194] i915 0000:03:00.0: [drm:gen9_enable_dc5 [i915]] Enabling DC5

<0> [561.666323] gem_lmem-2115 5..... 133012849us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1372, current 1374

<0> [561.666405] gem_lmem-2115 5..... 133012862us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1376, current 1374

<7> [561.666376] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 01

<0> [561.666487] gem_lmem-2115 5D..1. 133012866us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1376, current 1374

<0> [561.666569] gem_lmem-2115 5..... 133017892us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1374, current 1376

<0> [561.666652] gem_lmem-2115 5..... 133017904us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1378, current 1376

<0> [561.666735] gem_lmem-2115 5D..1. 133017908us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1378, current 1376

<0> [561.666818] gem_lmem-2115 5..... 133038029us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1376, current 1378

<0> [561.666901] gem_lmem-2115 5..... 133038042us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1380, current 1378

<0> [561.666984] gem_lmem-2115 5D..1. 133038047us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1380, current 1378

<0> [561.667079] gem_lmem-2115 5..... 133045065us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1378, current 1380

<0> [561.667159] gem_lmem-2115 5..... 133045078us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1382, current 1380

<0> [561.667240] gem_lmem-2115 5D..1. 133045083us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1382, current 1380

<0> [561.667337] gem_lmem-2115 5..... 133054085us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1380, current 1382

<0> [561.667420] gem_lmem-2115 5..... 133054098us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1384, current 1382

<0> [561.667502] gem_lmem-2115 5D..1. 133054102us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1384, current 1382

<0> [561.667585] gem_lmem-2115 5..... 133061991us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1382, current 1384

<0> [561.667668] gem_lmem-2115 5..... 133062004us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1386, current 1384

<0> [561.667751] gem_lmem-2115 5D..1. 133062008us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1386, current 1384

<0> [561.667835] gem_lmem-2115 5..... 133073333us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1384, current 1386

<6> [561.667866] [IGT] kms_setmode: starting dynamic subtest pipe-A-HDMI-A-3

<0> [561.667931] gem_lmem-2115 5..... 133073347us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1388, current 1386

<0> [561.668011] gem_lmem-2115 5D..1. 133073351us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1388, current 1386

<7> [561.668029] i915 0000:03:00.0: [drm:drm_mode_addfb2] [FB:562]

<0> [561.668091] gem_lmem-2115 5..... 133084825us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1386, current 1388

<0> [561.668171] gem_lmem-2115 5..... 133084838us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1390, current 1388

<0> [561.668251] gem_lmem-2115 5D..1. 133084843us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1390, current 1388

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

<0> [561.668331] gem_lmem-2115 5..... 133102373us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1388, current 1390

<0> [561.668428] gem_lmem-2115 5..... 133102386us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1392, current 1390

<0> [561.668510] gem_lmem-2115 5D..1. 133102390us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1392, current 1390

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

<0> [561.668592] gem_lmem-2115 5..... 133116388us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1390, current 1392

<0> [561.668687] gem_lmem-2115 5..... 133116401us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1394, current 1392

<0> [561.668786] gem_lmem-2115 5D..1. 133116405us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1394, current 1392

<0> [561.668880] gem_lmem-2115 5..... 133127787us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1392, current 1394

<0> [561.668960] gem_lmem-2115 5..... 133127802us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1396, current 1394

<0> [561.669040] gem_lmem-2115 5D..1. 133127806us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1396, current 1394

<0> [561.669122] gem_lmem-2115 5..... 133139399us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1394, current 1396

<0> [561.669202] gem_lmem-2115 5..... 133139412us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1398, current 1396

<0> [561.669281] gem_lmem-2115 5D..1. 133139416us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1398, current 1396

<0> [561.669361] gem_lmem-2115 5..... 133152358us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1396, current 1398

<0> [561.669441] gem_lmem-2115 5..... 133152371us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1400, current 1398

<0> [561.669538] gem_lmem-2115 5D..1. 133152375us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1400, current 1398

<0> [561.669635] gem_lmem-2115 5..... 133154646us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1398, current 1400

<0> [561.669732] gem_lmem-2115 5..... 133154659us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1402, current 1400

<0> [561.669826] gem_lmem-2115 5D..1. 133154663us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1402, current 1400

<0> [561.669905] gem_lmem-2115 5..... 133161606us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1400, current 1402

<0> [561.669985] gem_lmem-2115 5..... 133161618us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1404, current 1402

<0> [561.670065] gem_lmem-2115 5D..1. 133161622us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1404, current 1402

<0> [561.670167] gem_lmem-2115 5..... 133176517us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1402, current 1404

<0> [561.670250] gem_lmem-2115 5..... 133176530us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1406, current 1404

<0> [561.670348] gem_lmem-2115 5D..1. 133176534us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1406, current 1404

<0> [561.670443] gem_lmem-2115 5.l... 133196780us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1404, current 1406

<0> [561.670526] gem_lmem-2115 5.l... 133196793us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1408, current 1406

<0> [561.670651] gem_lmem-2115 5Dl.1. 133196797us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1408, current 1406

<0> [561.670731] gem_lmem-2115 5..... 133208495us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1406, current 1408

<0> [561.670810] gem_lmem-2115 5..... 133208508us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1410, current 1408

<0> [561.670890] gem_lmem-2115 5D..1. 133208512us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1410, current 1408

<0> [561.670970] gem_lmem-2115 5..... 133224176us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1408, current 1410

<0> [561.671050] gem_lmem-2115 5..... 133224188us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1412, current 1410

<0> [561.671131] gem_lmem-2115 5D..1. 133224192us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1412, current 1410

<0> [561.671210] gem_lmem-2115 5..... 133225584us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1410, current 1412

<0> [561.671290] gem_lmem-2115 5..... 133225596us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1414, current 1412

<0> [561.671384] gem_lmem-2115 5D..1. 133225601us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1414, current 1412

<0> [561.671468] gem_lmem-2115 5..... 133230185us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1412, current 1414

<0> [561.671550] gem_lmem-2115 5..... 133230197us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1416, current 1414

<0> [561.671646] gem_lmem-2115 5D..1. 133230201us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1416, current 1414

<0> [561.671758] gem_lmem-2115 5..... 133236838us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1414, current 1416

<0> [561.671838] gem_lmem-2115 5..... 133236851us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1418, current 1416

<0> [561.671918] gem_lmem-2115 5D..1. 133236855us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1418, current 1416

<0> [561.671999] gem_lmem-2115 5..... 133252338us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1416, current 1418

<0> [561.672079] gem_lmem-2115 5..... 133252351us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1420, current 1418

<0> [561.672174] gem_lmem-2115 5D..1. 133252356us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1420, current 1418

<0> [561.672267] gem_lmem-2115 5..... 133265999us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1418, current 1420

<0> [561.672347] gem_lmem-2115 5..... 133266012us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1422, current 1420

<0> [561.672491] gem_lmem-2115 5D..1. 133266016us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1422, current 1420

<0> [561.672588] gem_lmem-2115 5..... 133273020us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1420, current 1422

<0> [561.672670] gem_lmem-2115 5..... 133273033us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1424, current 1422

<0> [561.672751] gem_lmem-2115 5D..1. 133273037us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1424, current 1422

<0> [561.672833] gem_lmem-2115 5..... 133288626us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1422, current 1424

<0> [561.672914] gem_lmem-2115 5..... 133288639us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1426, current 1424

<0> [561.672994] gem_lmem-2115 5D..1. 133288643us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1426, current 1424

<0> [561.673074] gem_lmem-2115 5..... 133294988us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1424, current 1426

<0> [561.673154] gem_lmem-2115 5..... 133295000us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1428, current 1426

<0> [561.673234] gem_lmem-2115 5D..1. 133295004us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1428, current 1426

<0> [561.673315] gem_lmem-2115 5..... 133302051us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1426, current 1428

<0> [561.673413] gem_lmem-2115 5..... 133302064us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1430, current 1428

<0> [561.673495] gem_lmem-2115 5D..1. 133302068us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1430, current 1428

<0> [561.673577] gem_lmem-2115 5..... 133309412us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1428, current 1430

<0> [561.673673] gem_lmem-2115 5..... 133309425us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1432, current 1430

<0> [561.673816] gem_lmem-2115 5D..1. 133309429us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1432, current 1430

<0> [561.673896] gem_lmem-2115 5..... 133321117us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1430, current 1432

<0> [561.673977] gem_lmem-2115 5..... 133321129us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1434, current 1432

<0> [561.674056] gem_lmem-2115 5D..1. 133321133us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1434, current 1432

<0> [561.674137] gem_lmem-2115 5..... 133327706us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1432, current 1434

<0> [561.674217] gem_lmem-2115 5..... 133327738us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1436, current 1434

<0> [561.674297] gem_lmem-2115 5D..1. 133327743us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1436, current 1434

<0> [561.674378] gem_lmem-2115 5..... 133335223us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1434, current 1436

<0> [561.674458] gem_lmem-2115 5..... 133335239us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1438, current 1436

<0> [561.674559] gem_lmem-2115 5D..1. 133335243us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1438, current 1436

<0> [561.674674] gem_lmem-2115 5..... 133348584us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1436, current 1438

<0> [561.674756] gem_lmem-2115 5..... 133348601us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1440, current 1438

<0> [561.674840] gem_lmem-2115 5D..1. 133348606us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1440, current 1438

<0> [561.674923] gem_lmem-2115 5..... 133362077us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1438, current 1440

<0> [561.675006] gem_lmem-2115 5..... 133362093us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1442, current 1440

<0> [561.675102] gem_lmem-2115 5D..1. 133362098us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1442, current 1440

<0> [561.675183] gem_lmem-2115 5..... 133381552us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1440, current 1442

<0> [561.675264] gem_lmem-2115 5..... 133381569us : __i915_request_commit: 0000:03:00.0 rcs0: fence 85:1444, current 1442

<0> [561.675345] gem_lmem-2115 5D..1. 133381574us : __i915_request_submit: 0000:03:00.0 rcs0: fence 85:1444, current 1442

<0> [561.675354] gem_lmem-2115 5...1. 133393006us : tracing_mark_write: gem_lmem_swapping: finished subtest lmem0, SUCCESS

<0> [561.675359] gem_lmem-2115 5...1. 133393289us : tracing_mark_write: gem_lmem_swapping: finished subtest verify-random, SUCCESS

<0> [561.675434] gem_lmem-2115 5d..2. 133397571us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.675527] gem_lmem-2115 5d..2. 133398613us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.675633] gem_lmem-2115 5d..2. 133399173us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.675724] gem_lmem-2115 5d..2. 133399351us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.675820] gem_lmem-2115 5d..2. 133399458us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.675910] gem_lmem-2115 5d..2. 133399683us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.675986] gem_lmem-2115 5d..2. 133399938us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.676061] gem_lmem-2115 5d..2. 133400084us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.676166] gem_lmem-2115 5d..2. 133400204us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.676241] gem_lmem-2115 5d..2. 133400305us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.676316] gem_lmem-2115 5d..2. 133400484us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.676405] gem_lmem-2115 5d..2. 133400602us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.676482] gem_lmem-2115 5d..2. 133400854us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.676560] gem_lmem-2115 5d..2. 133400951us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.676650] gem_lmem-2115 5d..2. 133401146us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.676726] gem_lmem-2115 5d..2. 133401278us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.676804] gem_lmem-2115 5d..2. 133401397us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.676879] gem_lmem-2115 5d..2. 133401593us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.676954] gem_lmem-2115 5d..2. 133402126us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.677029] gem_lmem-2115 5d..2. 133402264us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.677105] gem_lmem-2115 5d..2. 133402395us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.677180] gem_lmem-2115 5d..2. 133402587us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.677254] gem_lmem-2115 5d..2. 133402698us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.677349] gem_lmem-2115 5d..2. 133402818us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.677425] gem_lmem-2115 5d..2. 133402909us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.677502] gem_lmem-2115 5d..2. 133403003us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.677592] gem_lmem-2115 5d..2. 133403122us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.677666] gem_lmem-2115 5d..2. 133403296us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.677742] gem_lmem-2115 5d..2. 133403462us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.677818] gem_lmem-2115 5d..2. 133403644us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.677894] gem_lmem-2115 5d..2. 133404536us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.677968] gem_lmem-2115 5d..2. 133404674us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.678044] gem_lmem-2115 5d..2. 133404808us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.678119] gem_lmem-2115 5d..2. 133404960us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.678194] gem_lmem-2115 5d..2. 133405100us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.678269] gem_lmem-2115 5d..2. 133405321us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.678344] gem_lmem-2115 5d..2. 133405461us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.678420] gem_lmem-2115 5d..2. 133405556us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.678496] gem_lmem-2115 5d..2. 133405655us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.678604] gem_lmem-2115 5d..2. 133406242us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.678679] gem_lmem-2115 5d..2. 133406372us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.678754] gem_lmem-2115 5d..2. 133406607us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.678828] gem_lmem-2115 5d..2. 133406822us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.678936] gem_lmem-2115 5d..2. 133407073us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.679011] gem_lmem-2115 5d..2. 133407309us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.679087] gem_lmem-2115 5d..2. 133407471us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.679161] gem_lmem-2115 5d..2. 133407579us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.679237] gem_lmem-2115 5d..2. 133408706us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.679312] gem_lmem-2115 5d..2. 133409202us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.679389] gem_lmem-2115 5d..2. 133409292us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.679463] gem_lmem-2115 5d..2. 133409490us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.679553] gem_lmem-2115 5d..2. 133409674us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.679671] gem_lmem-2115 5d..2. 133409931us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.679746] gem_lmem-2115 5d..2. 133410100us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.679821] gem_lmem-2115 5d..2. 133410285us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.679896] gem_lmem-2115 5d..2. 133410364us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.679972] gem_lmem-2115 5d..2. 133410502us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.680047] gem_lmem-2115 5d..2. 133410679us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.680122] gem_lmem-2115 5d..2. 133411117us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.680197] gem_lmem-2115 5d..2. 133411203us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.680271] gem_lmem-2115 5d..2. 133411388us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.680347] gem_lmem-2115 5d..2. 133411492us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.680423] gem_lmem-2115 5d..2. 133411673us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.680518] gem_lmem-2115 5dl.2. 133412046us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.680609] gem_lmem-2115 5dl.2. 133412168us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.680683] gem_lmem-2115 5dl.2. 133412261us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.680758] gem_lmem-2115 5dl.2. 133412406us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.680833] gem_lmem-2115 5dl.2. 133412562us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.680909] gem_lmem-2115 5dl.2. 133412644us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001

<0> [561.680989] kworker/-50 5..... 133768719us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 76:3, current 3

<0> [561.681069] kworker/-50 5..... 133768736us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1442, current 1444

<0> [561.681149] kworker/-50 5..... 133769355us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 85:1444, current 1444

<0> [561.681218] kworker/-50 5..... 133769367us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:85 unpin

<0> [561.681286] kworker/-50 5..... 133770165us : __intel_context_retire: 0000:03:00.0 rcs0: context:85 retire runtime: { total:38635428ns, avg:38635428ns }

<0> [561.681356] kworker/-50 5..... 133770555us : __engine_park: 0000:03:00.0 rcs0: parked

<0> [561.681426] kworker/-50 5..... 133770630us : __gt_park: 0000:03:00.0

<0> [561.681504] gem_lmem-2115 5..... 133811814us : i915_gem_suspend: 0000:03:00.0

<0> [561.681608] kms_fron-2179 5..... 135160221us : __gt_unpark: 0000:03:00.0

<0> [561.681680] kworker/-50 5..... 135162117us : __gt_park: 0000:03:00.0

<0> [561.681749] kms_fron-2179 5..... 135163493us : __intel_context_do_pin_ww: 0000:03:00.0 rcs'0: context:8a active

<0> [561.681825] kms_fron-2179 5..... 135163781us : intel_timeline_pin: 0000:03:00.0 timeline:8a using HWSP offset:fedef100

<0> [561.681894] kms_fron-2179 5..... 135164236us : __intel_context_do_pin_ww: 0000:03:00.0 rcs'0: context:8a pin ring:{start:fedd0000, head:0000, tail:0000}

<0> [561.681962] kms_fron-2179 5..... 135164917us : __intel_context_do_pin_ww: 0000:03:00.0 bcs'0: context:8b active

<0> [561.682036] kms_fron-2179 5..... 135165028us : intel_timeline_pin: 0000:03:00.0 timeline:8b using HWSP offset:fedee100

<0> [561.682104] kms_fron-2179 5..... 135165134us : __intel_context_do_pin_ww: 0000:03:00.0 bcs'0: context:8b pin ring:{start:feda0000, head:0000, tail:0000}

<0> [561.682174] kms_fron-2179 5..... 135165680us : __intel_context_do_pin_ww: 0000:03:00.0 vcs'0: context:8c active

<0> [561.682269] kms_fron-2179 5..... 135165863us : intel_timeline_pin: 0000:03:00.0 timeline:8c using HWSP offset:feded100

<0> [561.682354] kms_fron-2179 5..... 135165982us : __intel_context_do_pin_ww: 0000:03:00.0 vcs'0: context:8c pin ring:{start:fed90000, head:0000, tail:0000}

<0> [561.682423] kms_fron-2179 5..... 135166542us : __intel_context_do_pin_ww: 0000:03:00.0 vcs'2: context:8d active

<0> [561.682498] kms_fron-2179 5..... 135166664us : intel_timeline_pin: 0000:03:00.0 timeline:8d using HWSP offset:fedec100

<0> [561.682595] kms_fron-2179 5..... 135166970us : __intel_context_do_pin_ww: 0000:03:00.0 vcs'2: context:8d pin ring:{start:fed70000, head:0000, tail:0000}

<0> [561.682663] kms_fron-2179 5..... 135167514us : __intel_context_do_pin_ww: 0000:03:00.0 vecs'0: context:8e active

<0> [561.682737] kms_fron-2179 5..... 135167644us : intel_timeline_pin: 0000:03:00.0 timeline:8e using HWSP offset:fedeb100

<0> [561.682806] kms_fron-2179 5..... 135167880us : __intel_context_do_pin_ww: 0000:03:00.0 vecs'0: context:8e pin ring:{start:fed50000, head:0000, tail:0000}

<0> [561.682874] kms_fron-2179 5..... 135168425us : __intel_context_do_pin_ww: 0000:03:00.0 vecs'1: context:8f active

<0> [561.682948] kms_fron-2179 5..... 135168546us : intel_timeline_pin: 0000:03:00.0 timeline:8f using HWSP offset:fedea100

<0> [561.683017] kms_fron-2179 5..... 135168662us : __intel_context_do_pin_ww: 0000:03:00.0 vecs'1: context:8f pin ring:{start:fed30000, head:0000, tail:0000}

<0> [561.683084] kms_fron-2179 5..... 135169286us : __intel_context_do_pin_ww: 0000:03:00.0 ccs'0: context:90 active

<0> [561.683160] kms_fron-2179 5..... 135169512us : intel_timeline_pin: 0000:03:00.0 timeline:90 using HWSP offset:fede9100

<0> [561.683230] kms_fron-2179 5..... 135169624us : __intel_context_do_pin_ww: 0000:03:00.0 ccs'0: context:90 pin ring:{start:fed10000, head:0000, tail:0000}

<0> [561.683302] kms_fron-2179 5..... 135211931us : intel_gt_resume.part.0: 0000:03:00.0

<0> [561.683373] kms_fron-2179 5..... 135211932us : gt_sanitize: 0000:03:00.0 force:yes

<0> [561.683446] kms_fron-2179 5..... 135211948us : __intel_gt_unset_wedged: 0000:03:00.0 start

<0> [561.683518] kms_fron-2179 5..... 135212163us : __intel_gt_reset: 0000:03:00.0 engine_mask=ffffffff

<0> [561.683623] kms_fron-2179 5..... 135212373us : __intel_gt_unset_wedged: 0000:03:00.0 end

<0> [561.683692] kms_fron-2179 5..... 135212468us : intel_engine_stop_cs: 0000:03:00.0 rcs'0:

<0> [561.683761] kms_fron-2179 5..... 135212477us : intel_engine_stop_cs: 0000:03:00.0 bcs'0:

<0> [561.683829] kms_fron-2179 5..... 135212486us : intel_engine_stop_cs: 0000:03:00.0 vcs'0:

<0> [561.683897] kms_fron-2179 5..... 135212496us : intel_engine_stop_cs: 0000:03:00.0 vcs'2:

<0> [561.683965] kms_fron-2179 5..... 135212505us : intel_engine_stop_cs: 0000:03:00.0 vecs'0:

<0> [561.684034] kms_fron-2179 5..... 135212515us : intel_engine_stop_cs: 0000:03:00.0 vecs'1:

<0> [561.684102] kms_fron-2179 5..... 135212524us : intel_engine_stop_cs: 0000:03:00.0 ccs'0:

<0> [561.684176] kms_fron-2179 5..... 135212534us : __intel_gt_reset: 0000:03:00.0 engine_mask=ffffffff

<0> [561.684247] kms_fron-2179 5..... 135212797us : __gt_unpark: 0000:03:00.0

<0> [561.684315] kms_fron-2179 5..... 135226442us : __engine_unpark: 0000:03:00.0 rcs'0:

<0> [561.684384] kms_fron-2179 5..... 135226497us : __engine_unpark: 0000:03:00.0 rcs'0: context:8a reset { seqno:0, *hwsp:0, ring:0 }

<0> [561.684452] kms_fron-2179 5..... 135226607us : __engine_park: 0000:03:00.0 rcs'0: parked

<0> [561.684522] kms_fron-2179 5..... 135226629us : __engine_unpark: 0000:03:00.0 bcs'0:

<0> [561.684621] kms_fron-2179 5..... 135226651us : __engine_unpark: 0000:03:00.0 bcs'0: context:8b reset { seqno:0, *hwsp:0, ring:0 }

<0> [561.684689] kms_fron-2179 5..... 135226663us : __engine_park: 0000:03:00.0 bcs'0: parked

<0> [561.684759] kms_fron-2179 5..... 135226675us : __engine_unpark: 0000:03:00.0 vcs'0:

<0> [561.684827] kms_fron-2179 5..... 135226732us : __engine_unpark: 0000:03:00.0 vcs'0: context:8c reset { seqno:0, *hwsp:0, ring:0 }

<0> [561.684897] kms_fron-2179 5..... 135226758us : __engine_park: 0000:03:00.0 vcs'0: parked

<0> [561.684966] kms_fron-2179 5..... 135226771us : __engine_unpark: 0000:03:00.0 vcs'2:

<0> [561.685035] kms_fron-2179 5..... 135226792us : __engine_unpark: 0000:03:00.0 vcs'2: context:8d reset { seqno:0, *hwsp:0, ring:0 }

<0> [561.685104] kms_fron-2179 5..... 135226802us : __engine_park: 0000:03:00.0 vcs'2: parked

<0> [561.685172] kms_fron-2179 5..... 135226814us : __engine_unpark: 0000:03:00.0 vecs'0:

<0> [561.685240] kms_fron-2179 5..... 135226834us : __engine_unpark: 0000:03:00.0 vecs'0: context:8e reset { seqno:0, *hwsp:0, ring:0 }

<0> [561.685327] kms_fron-2179 5..... 135226845us : __engine_park: 0000:03:00.0 vecs'0: parked

<0> [561.685398] kms_fron-2179 5..... 135226857us : __engine_unpark: 0000:03:00.0 vecs'1:

<0> [561.685469] kms_fron-2179 5..... 135226878us : __engine_unpark: 0000:03:00.0 vecs'1: context:8f reset { seqno:0, *hwsp:0, ring:0 }

<0> [561.685539] kms_fron-2179 5..... 135226888us : __engine_park: 0000:03:00.0 vecs'1: parked

<0> [561.685622] kms_fron-2179 5..... 135226900us : __engine_unpark: 0000:03:00.0 ccs'0:

<0> [561.685690] kms_fron-2179 5..... 135226933us : __engine_unpark: 0000:03:00.0 ccs'0: context:90 reset { seqno:0, *hwsp:0, ring:0 }

<0> [561.685758] kms_fron-2179 5..... 135226967us : __engine_park: 0000:03:00.0 ccs'0: parked

<0> [561.685829] kms_fron-2179 5..... 135227148us : __gt_park: 0000:03:00.0

<0> [561.685898] kms_fron-2179 5..... 135229454us : __intel_context_do_pin_ww: 0000:03:00.0 rcs'0: context:91 active

<0> [561.685974] kms_fron-2179 5..... 135229630us : intel_timeline_pin: 0000:03:00.0 timeline:91 using HWSP offset:fede8000

<0> [561.686043] kms_fron-2179 5..... 135229812us : __engine_unpark: 0000:03:00.0 rcs'0:

<0> [561.686114] kms_fron-2179 5..... 135229854us : __gt_unpark: 0000:03:00.0

<0> [561.686183] kms_fron-2179 5..... 135230333us : __engine_unpark: 0000:03:00.0 rcs'0: context:8a reset { seqno:0, *hwsp:0, ring:0 }

<0> [561.686253] kms_fron-2179 5..... 135230337us : __intel_context_do_pin_ww: 0000:03:00.0 rcs'0: context:91 pin ring:{start:fece0000, head:0000, tail:0000}

<0> [561.686334] kms_fron-2179 5..... 135230648us : __i915_request_commit: 0000:03:00.0 rcs'0: fence 91:2, current 0

<0> [561.686415] kms_fron-2179 5D..1. 135230761us : __i915_request_submit: 0000:03:00.0 rcs'0: fence 91:2, current 0

<0> [561.686484] kms_fron-2179 5..... 135230988us : __intel_context_do_pin_ww: 0000:03:00.0 bcs'0: context:92 active

<0> [561.686591] kms_fron-2179 5..... 135231138us : intel_timeline_pin: 0000:03:00.0 timeline:92 using HWSP offset:fede7000

<0> [561.686661] kms_fron-2179 5..... 135231283us : __engine_unpark: 0000:03:00.0 bcs'0:

<0> [561.686730] kms_fron-2179 5..... 135231305us : __engine_unpark: 0000:03:00.0 bcs'0: context:8b reset { seqno:0, *hwsp:0, ring:0 }

<0> [561.686798] kms_fron-2179 5..... 135231305us : __intel_context_do_pin_ww: 0000:03:00.0 bcs'0: context:92 pin ring:{start:fecb0000, head:0000, tail:0000}

<0> [561.686878] kms_fron-2179 5..... 135231347us : __i915_request_commit: 0000:03:00.0 bcs'0: fence 92:2, current 0

<0> [561.686957] kms_fron-2179 5D..1. 135231351us : __i915_request_submit: 0000:03:00.0 bcs'0: fence 92:2, current 0

<0> [561.687072] kms_fron-2179 5..... 135231478us : __intel_context_do_pin_ww: 0000:03:00.0 vcs'0: context:93 active

<0> [561.687163] kms_fron-2179 5..... 135231623us : intel_timeline_pin: 0000:03:00.0 timeline:93 using HWSP offset:fede6000

<0> [561.687232] kms_fron-2179 5..... 135231797us : __engine_unpark: 0000:03:00.0 vcs'0:

<0> [561.687302] kms_fron-2179 5..... 135231820us : __engine_unpark: 0000:03:00.0 vcs'0: context:8c reset { seqno:0, *hwsp:0, ring:0 }

<0> [561.687370] kms_fron-2179 5..... 135231821us : __intel_context_do_pin_ww: 0000:03:00.0 vcs'0: context:93 pin ring:{start:fec90000, head:0000, tail:0000}

<0> [561.687449] kms_fron-2179 5..... 135231860us : __i915_request_commit: 0000:03:00.0 vcs'0: fence 93:2, current 0

<0> [561.687546] kms_fron-2179 5D..1. 135231864us : __i915_request_submit: 0000:03:00.0 vcs'0: fence 93:2, current 0

<0> [561.687630] kms_fron-2179 5..... 135231995us : __intel_context_do_pin_ww: 0000:03:00.0 vcs'2: context:94 active

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

<0> [561.687704] kms_fron-2179 5..... 135232137us : intel_timeline_pin: 0000:03:00.0 timeline:94 using HWSP offset:fede5000

<0> [561.687797] kms_fron-2179 5..... 135232278us : __engine_unpark: 0000:03:00.0 vcs'2:

<0> [561.687881] kms_fron-2179 5..... 135232300us : __engine_unpark: 0000:03:00.0 vcs'2: context:8d reset { seqno:0, *hwsp:0, ring:0 }

<0> [561.687950] kms_fron-2179 5..... 135232301us : __intel_context_do_pin_ww: 0000:03:00.0 vcs'2: context:94 pin ring:{start:fec70000, head:0000, tail:0000}

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

<0> [561.688029] kms_fron-2179 5..... 135232340us : __i915_request_commit: 0000:03:00.0 vcs'2: fence 94:2, current 0

<0> [561.688126] kms_fron-2179 5D..1. 135232344us : __i915_request_submit: 0000:03:00.0 vcs'2: fence 94:2, current 0

<0> [561.688210] kms_fron-2179 5..... 135232469us : __intel_context_do_pin_ww: 0000:03:00.0 vecs'0: context:95 active

<0> [561.688286] kms_fron-2179 5..... 135232614us : intel_timeline_pin: 0000:03:00.0 timeline:95 using HWSP offset:fede4000

<0> [561.688356] kms_fron-2179 5..... 135232789us : __engine_unpark: 0000:03:00.0 vecs'0:

<0> [561.688426] kms_fron-2179 5..... 135232811us : __engine_unpark: 0000:03:00.0 vecs'0: context:8e reset { seqno:0, *hwsp:0, ring:0 }

<0> [561.688493] kms_fron-2179 5..... 135232811us : __intel_context_do_pin_ww: 0000:03:00.0 vecs'0: context:95 pin ring:{start:fec50000, head:0000, tail:0000}

<0> [561.688588] kms_fron-2179 5..... 135232851us : __i915_request_commit: 0000:03:00.0 vecs'0: fence 95:2, current 0

<0> [561.688670] kms_fron-2179 5D..1. 135232855us : __i915_request_submit: 0000:03:00.0 vecs'0: fence 95:2, current 0

<0> [561.688771] kms_fron-2179 5..... 135232980us : __intel_context_do_pin_ww: 0000:03:00.0 vecs'1: context:96 active

<0> [561.688861] kms_fron-2179 5..... 135233140us : intel_timeline_pin: 0000:03:00.0 timeline:96 using HWSP offset:fede3000

<0> [561.688959] kms_fron-2179 5..... 135233293us : __engine_unpark: 0000:03:00.0 vecs'1:

<0> [561.689028] kms_fron-2179 5..... 135233315us : __engine_unpark: 0000:03:00.0 vecs'1: context:8f reset { seqno:0, *hwsp:0, ring:0 }

<0> [561.689096] kms_fron-2179 5..... 135233316us : __intel_context_do_pin_ww: 0000:03:00.0 vecs'1: context:96 pin ring:{start:fec30000, head:0000, tail:0000}

<0> [561.689176] kms_fron-2179 5..... 135233355us : __i915_request_commit: 0000:03:00.0 vecs'1: fence 96:2, current 0

<0> [561.689255] kms_fron-2179 5D..1. 135233359us : __i915_request_submit: 0000:03:00.0 vecs'1: fence 96:2, current 0

<0> [561.689324] kms_fron-2179 5..... 135233484us : __intel_context_do_pin_ww: 0000:03:00.0 ccs'0: context:97 active

<0> [561.689399] kms_fron-2179 5..... 135233628us : intel_timeline_pin: 0000:03:00.0 timeline:97 using HWSP offset:fede2000

<0> [561.689468] kms_fron-2179 5..... 135233817us : __engine_unpark: 0000:03:00.0 ccs'0:

<0> [561.689556] kms_fron-2179 5..... 135233850us : __engine_unpark: 0000:03:00.0 ccs'0: context:90 reset { seqno:0, *hwsp:0, ring:0 }

<0> [561.689639] kms_fron-2179 5..... 135233851us : __intel_context_do_pin_ww: 0000:03:00.0 ccs'0: context:97 pin ring:{start:fec10000, head:0000, tail:0000}

<0> [561.689736] kms_fron-2179 5..... 135233890us : __i915_request_commit: 0000:03:00.0 ccs'0: fence 97:2, current 0

<0> [561.689818] kms_fron-2179 5D..1. 135233894us : __i915_request_submit: 0000:03:00.0 ccs'0: fence 97:2, current 0

<0> [561.689914] kms_fron-2179 5..... 135233963us : i915_request_retire.part.0: 0000:03:00.0 rcs'0: fence 91:2, current 2

<0> [561.689995] kms_fron-2179 5..... 135233980us : i915_request_retire.part.0: 0000:03:00.0 bcs'0: fence 92:2, current 2

<0> [561.690075] kms_fron-2179 5..... 135233986us : i915_request_retire.part.0: 0000:03:00.0 vcs'0: fence 93:2, current 2

<0> [561.690155] kms_fron-2179 5..... 135233990us : i915_request_retire.part.0: 0000:03:00.0 vcs'2: fence 94:2, current 2

<0> [561.690235] kms_fron-2179 5..... 135233995us : i915_request_retire.part.0: 0000:03:00.0 vecs'0: fence 95:2, current 2

<0> [561.690332] kms_fron-2179 5..... 135234000us : i915_request_retire.part.0: 0000:03:00.0 vecs'1: fence 96:2, current 2

<0> [561.690408] kms_fron-2179 5..... 135234047us : intel_rps_boost.part.0: 0000:03:00.0 boost fence:97:2

<0> [561.690490] kms_fron-2179 5.l... 135234592us : i915_request_retire.part.0: 0000:03:00.0 ccs'0: fence 97:2, current 2

<0> [561.690575] kms_fron-2179 5..... 135330783us : __engine_unpark: 0000:03:00.0 rcs'0:

<0> [561.690646] kms_fron-2179 5..... 135330813us : __gt_unpark: 0000:03:00.0

<0> [561.690716] kms_fron-2179 5..... 135331505us : __engine_unpark: 0000:03:00.0 rcs'0: context:8a reset { seqno:0, *hwsp:0, ring:0 }

<0> [561.690795] kms_fron-2179 5..... 135332471us : __i915_request_commit: 0000:03:00.0 rcs'0: fence 8a:1, current 0

<0> [561.690875] kms_fron-2179 5D..1. 135332539us : __i915_request_submit: 0000:03:00.0 rcs'0: fence 8a:1, current 0

<0> [561.690955] kworker/-50 5..... 135333067us : i915_request_retire.part.0: 0000:03:00.0 rcs'0: fence 8a:1, current 1

<0> [561.691025] kms_fron-2179 5..... 135333119us : __engine_park: 0000:03:00.0 rcs'0: parked

<0> [561.691094] kms_fron-2179 5.l... 135333452us : __engine_unpark: 0000:03:00.0 bcs'0:

<0> [561.691181] kms_fron-2179 5.l... 135333473us : __engine_unpark: 0000:03:00.0 bcs'0: context:8b reset { seqno:0, *hwsp:0, ring:0 }

<0> [561.691262] kms_fron-2179 5.l... 135333536us : __i915_request_commit: 0000:03:00.0 bcs'0: fence 8b:1, current 0

<0> [561.691356] kms_fron-2179 5Dl.1. 135333540us : __i915_request_submit: 0000:03:00.0 bcs'0: fence 8b:1, current 0

<0> [561.691426] kms_fron-2179 5..... 135333863us : __engine_park: 0000:03:00.0 bcs'0: parked

<0> [561.691500] kworker/-50 5..... 135335101us : __gt_park: 0000:03:00.0

<0> [561.691598] kms_fron-2179 5..... 135335130us : __engine_unpark: 0000:03:00.0 vcs'0:

<0> [561.691670] kms_fron-2179 5..... 135335137us : __gt_unpark: 0000:03:00.0

<0> [561.691739] kms_fron-2179 5..... 135335177us : __engine_unpark: 0000:03:00.0 vcs'0: context:8c reset { seqno:0, *hwsp:0, ring:0 }

<0> [561.691820] kms_fron-2179 5..... 135335241us : __i915_request_commit: 0000:03:00.0 vcs'0: fence 8c:1, current 0

<0> [561.691900] kms_fron-2179 5D..1. 135335245us : __i915_request_submit: 0000:03:00.0 vcs'0: fence 8c:1, current 0

<0> [561.691970] kms_fron-2179 5..... 135335437us : __engine_unpark: 0000:03:00.0 vcs'2:

<0> [561.692039] kms_fron-2179 5..... 135335458us : __engine_unpark: 0000:03:00.0 vcs'2: context:8d reset { seqno:0, *hwsp:0, ring:0 }

<0> [561.692164] kms_fron-2179 5..... 135335518us : __i915_request_commit: 0000:03:00.0 vcs'2: fence 8d:1, current 0

<0> [561.692246] kms_fron-2179 5D..1. 135335522us : __i915_request_submit: 0000:03:00.0 vcs'2: fence 8d:1, current 0

<0> [561.692319] kms_fron-2179 5..... 135335803us : __engine_unpark: 0000:03:00.0 vecs'0:

<0> [561.692403] kms_fron-2179 5..... 135335824us : __engine_unpark: 0000:03:00.0 vecs'0: context:8e reset { seqno:0, *hwsp:0, ring:0 }

<0> [561.692483] kms_fron-2179 5..... 135335898us : __i915_request_commit: 0000:03:00.0 vecs'0: fence 8e:1, current 0

<0> [561.692592] kms_fron-2179 5D..1. 135335903us : __i915_request_submit: 0000:03:00.0 vecs'0: fence 8e:1, current 0

<0> [561.692662] kms_fron-2179 5..... 135336165us : __engine_park: 0000:03:00.0 vecs'0: parked

<0> [561.692761] kms_fron-2179 5.l... 135336209us : __engine_unpark: 0000:03:00.0 vecs'1:

<0> [561.692830] kms_fron-2179 5.l... 135336231us : __engine_unpark: 0000:03:00.0 vecs'1: context:8f reset { seqno:0, *hwsp:0, ring:0 }

<0> [561.692910] kms_fron-2179 5.l... 135336351us : __i915_request_commit: 0000:03:00.0 vecs'1: fence 8f:1, current 0

<0> [561.693021] kms_fron-2179 5Dl.1. 135336355us : __i915_request_submit: 0000:03:00.0 vecs'1: fence 8f:1, current 0

<0> [561.693091] kms_fron-2179 5..... 135336604us : __engine_park: 0000:03:00.0 vecs'1: parked

<0> [561.693161] kms_fron-2179 5.l... 135336655us : __engine_unpark: 0000:03:00.0 ccs'0:

<0> [561.693231] kms_fron-2179 5..... 135336816us : __engine_unpark: 0000:03:00.0 ccs'0: context:90 reset { seqno:0, *hwsp:0, ring:0 }

<0> [561.693329] kms_fron-2179 5..... 135336879us : __i915_request_commit: 0000:03:00.0 ccs'0: fence 90:1, current 0

<0> [561.693413] kms_fron-2179 5D..1. 135336884us : __i915_request_submit: 0000:03:00.0 ccs'0: fence 90:1, current 0

<0> [561.693485] kms_fron-2179 5..... 135337158us : __engine_park: 0000:03:00.0 ccs'0: parked

<0> [561.693559] kworker/-50 5..... 135337693us : __gt_park: 0000:03:00.0

<0> [561.693633] kms_fron-2179 5..... 135338499us : __gt_unpark: 0000:03:00.0

<0> [561.693706] kworker/-50 5..... 135339697us : __gt_park: 0000:03:00.0

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

<0> [561.693775] kms_fron-2179 5..... 135341091us : __intel_context_do_pin_ww: 0000:03:00.0 bcs'0: context:98 active

<0> [561.694007] kms_fron-2179 5..... 135341218us : intel_timeline_pin: 0000:03:00.0 timeline:98 using HWSP offset:fedee108

<0> [561.694090] kms_fron-2179 5..... 135341393us : __intel_context_do_pin_ww: 0000:03:00.0 bcs'0: context:98 pin ring:{start:fec70000, head:0000, tail:0000}

<0> [561.694176] kms_fron-2179 5.l... 135386488us : __gt_unpark: 0000:03:00.0

<0> [561.694246] kworker/-207 5..... 135387434us : __gt_park: 0000:03:00.0

<0> [561.694254] kms_curs-2231 5...1. 153998670us : tracing_mark_write: kms_cursor_legacy: finished subtest pipe-B, SUCCESS

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

<0> [561.694323] kms_curs-2231 5.l... 153999202us : __engine_unpark: 0000:03:00.0 bcs0:

<0> [561.694412] kms_curs-2231 5.l... 153999212us : __gt_unpark: 0000:03:00.0

<0> [561.694483] kms_curs-2231 5.l... 153999455us : __engine_unpark: 0000:03:00.0 bcs0: context:8b reset { seqno:1, *hwsp:1, ring:68 }

<0> [561.694577] kms_curs-2231 5.l... 153999482us : __i915_request_commit: 0000:03:00.0 bcs0: fence 98:10, current 9

<0> [561.694659] kms_curs-2231 5Dl.1. 153999488us : __i915_request_submit: 0000:03:00.0 bcs0: fence 98:10, current 9

<0> [561.694733] kms_curs-2231 5..... 154000041us : intel_rps_mark_interactive: 0000:03:00.0 mark interactive: yes

<0> [561.694738] kms_curs-2231 5...1. 159378053us : tracing_mark_write: kms_cursor_legacy: finished subtest pipe-C, SUCCESS

<0> [561.694807] kms_curs-2231 5..... 159378627us : __engine_unpark: 0000:03:00.0 bcs0:

<0> [561.694878] kms_curs-2231 5..... 159378637us : __gt_unpark: 0000:03:00.0

<0> [561.694948] kms_curs-2231 5..... 159378876us : __engine_unpark: 0000:03:00.0 bcs0: context:8b reset { seqno:1, *hwsp:1, ring:68 }

<0> [561.695028] kms_curs-2231 5..... 159378904us : __i915_request_commit: 0000:03:00.0 bcs0: fence 98:11, current 10

<0> [561.695110] kms_curs-2231 5D..1. 159378909us : __i915_request_submit: 0000:03:00.0 bcs0: fence 98:11, current 10

<0> [561.695184] kms_curs-2231 5..... 159379141us : intel_rps_mark_interactive: 0000:03:00.0 mark interactive: yes

<0> [561.695190] kms_curs-2231 5...1. 164757469us : tracing_mark_write: kms_cursor_legacy: finished subtest pipe-D, SUCCESS

<0> [561.695260] kms_curs-2231 5..... 164757936us : __engine_unpark: 0000:03:00.0 bcs0:

<0> [561.695331] kms_curs-2231 5..... 164757946us : __gt_unpark: 0000:03:00.0

<0> [561.695399] kms_curs-2231 5..... 164758188us : __engine_unpark: 0000:03:00.0 bcs0: context:8b reset { seqno:1, *hwsp:1, ring:68 }

<0> [561.695479] kms_curs-2231 5..... 164758215us : __i915_request_commit: 0000:03:00.0 bcs0: fence 98:12, current 11

<0> [561.695577] kms_curs-2231 5D..1. 164758221us : __i915_request_submit: 0000:03:00.0 bcs0: fence 98:12, current 11

<0> [561.695683] kms_curs-2231 5..... 164759054us : intel_rps_mark_interactive: 0000:03:00.0 mark interactive: yes

<0> [561.695786] kworker/-81 5..... 164759101us : intel_rps_mark_interactive: 0000:03:00.0 mark interactive: no

<0> [561.695889] kms_curs-2231 5..... 164759242us : intel_rps_mark_interactive: 0000:03:00.0 mark interactive: yes

<0> [561.695978] kworker/-81 5..... 164759310us : intel_rps_mark_interactive: 0000:03:00.0 mark interactive: no

<0> [561.696052] kms_curs-2231 5..... 164759453us : intel_rps_mark_interactive: 0000:03:00.0 mark interactive: yes

<0> [561.696125] kworker/-81 5..... 164759492us : intel_rps_mark_interactive: 0000:03:00.0 mark interactive: no

<0> [561.696131] kms_curs-2231 5...1. 170137511us : tracing_mark_write: kms_cursor_legacy: finished subtest all-pipes, SUCCESS

<0> [561.696136] kms_curs-2231 5...1. 170137801us : tracing_mark_write: kms_cursor_legacy: finished subtest single-move, SUCCESS

<0> [561.696208] kms_curs-2231 5..... 170140680us : intel_rps_mark_interactive: 0000:03:00.0 mark interactive: yes

<0> [561.696282] kworker/-81 5..... 170156823us : intel_rps_mark_interactive: 0000:03:00.0 mark interactive: no

<0> [561.696355] kms_curs-2231 5..... 170158188us : intel_rps_mark_interactive: 0000:03:00.0 mark interactive: yes

<0> [561.696429] kworker/-81 5..... 170174436us : intel_rps_mark_interactive: 0000:03:00.0 mark interactive: no

<0> [561.696502] kms_curs-2231 5..... 170174670us : intel_rps_mark_interactive: 0000:03:00.0 mark interactive: yes

<0> [561.696608] kworker/-81 5..... 170190543us : intel_rps_mark_interactive: 0000:03:00.0 mark interactive: no

<0> [561.696680] i915_pm_-2238 5..... 172030851us : __gt_unpark: 0000:03:00.0

<0> [561.696751] kworker/-50 5..... 172031989us : __gt_park: 0000:03:00.0

<0> [561.696757] kms_feat-2239 5...1. 172215373us : tracing_mark_write: kms_feature_discovery: executing

<0> [561.696827] kms_feat-2239 5..... 172233839us : __gt_unpark: 0000:03:00.0

<0> [561.696899] kworker/-50 5..... 172233988us : __gt_park: 0000:03:00.0

<0> [561.696970] kms_feat-2239 5..... 172248122us : __gt_unpark: 0000:03:00.0

<0> [561.697041] kworker/-50 5..... 172248261us : __gt_park: 0000:03:00.0

Results for igt@kms_setmode@basic@pipe-a-hdmi-a-3