Result: 557 Warning(s)
integration-manifest git-log-oneline i915_display_info0 igt_runner0 run0 runtimes0 results0.json guc_logs0.tar i915_display_info_post_exec0 boot0 dmesg0
| Detail | Value |
|---|---|
| Duration | unknown |
| Igt-Version |
IGT-Version: 2.2-gb1ad5288c (x86_64) (Linux: 6.18.0-Patchwork_158651v2-g48deab361d3b+ x86_64) |
| Out |
Starting dynamic subtest: hugepages runner: This test was killed due to a kernel taint (0x42c4). This test caused an abort condition: Lockdep not active /proc/lockdep_stats contents: lock-classes: 2070 [max: 8192] dynamic-keys: 309 direct dependencies: 21562 [max: 524288] indirect dependencies: 167182 all direct dependencies: 413751 dependency chains: 31341 [max: 524288] dependency chain hlocks used: 132127 [max: 2621440] dependency chain hlocks lost: 0 in-hardirq chains: 124 in-softirq chains: 676 in-process chains: 30541 stack-trace entries: 320657 [max: 524288] number of stack traces: 13568 number of stack hash chains: 9165 combined max dependencies: 2584616750 hardirq-safe locks: 77 hardirq-unsafe locks: 1253 softirq-safe locks: 225 softirq-unsafe locks: 1152 irq-safe locks: 236 irq-unsafe locks: 1253 hardirq-read-safe locks: 4 hardirq-read-unsafe locks: 390 softirq-read-safe locks: 10 softirq-read-unsafe locks: 387 irq-read-safe locks: 10 irq-read-unsafe locks: 390 uncategorized locks: 343 unused locks: 1 max locking depth: 20 max bfs queue depth: 361 max lock class index: 2134 debug_locks: 0 zapped classes: 2758 zapped lock chains: 31357 large chain blocks: 1 |
| Err |
Starting dynamic subtest: hugepages |
| Dmesg |
<6> [334.451764] [IGT] i915_selftest: starting dynamic subtest hugepages
<5> [335.137098] Setting dangerous option force_probe - tainting kernel
<5> [335.137107] Setting dangerous option enable_guc - tainting kernel
<5> [335.137112] Setting dangerous option live_selftests - tainting kernel
<6> [335.144001] i915 0000:00:02.0: [drm] Found coffeelake (device ID 3e92) integrated display version 9.00 stepping N/A
<7> [335.145337] i915 0000:00:02.0: [drm:intel_wopcm_init_early [i915]] WOPCM: 1024K
<7> [335.145589] i915 0000:00:02.0: [drm:intel_uc_init_early [i915]] GT0: enable_guc=3 (guc:yes submission:no huc:yes slpc:no)
<6> [335.145784] i915 0000:00:02.0: [drm] GT0: Incompatible option enable_guc=3 - GuC submission is N/A
<7> [335.145791] i915 0000:00:02.0: [drm:intel_pch_type [i915]] Found Kaby Lake PCH (KBP)
<7> [335.145988] i915 0000:00:02.0: [drm:intel_dmc_wl_init [i915]] Sanitized enable_dmc_wl value: 0 (disabled)
<7> [335.146326] i915 0000:00:02.0: [drm:intel_gt_probe_all [i915]] GT0: Setting up Primary GT
<7> [335.146629] i915 0000:00:02.0: [drm:intel_uncore_init_mmio [i915]] unclaimed mmio detected on uncore init, clearing
<7> [335.146827] i915 0000:00:02.0: [drm:intel_display_device_info_runtime_init [i915]] rawclk rate: 24000 kHz
<7> [335.147497] i915 0000:00:02.0: [drm:i915_ggtt_probe_hw [i915]] GGTT size = 4096M
<7> [335.147709] i915 0000:00:02.0: [drm:i915_ggtt_probe_hw [i915]] GMADR size = 256M
<7> [335.147927] i915 0000:00:02.0: [drm:i915_ggtt_probe_hw [i915]] DSM size = 32M
<6> [335.148180] i915 0000:00:02.0: vgaarb: deactivate vga console
<7> [335.148352] i915 0000:00:02.0: [drm:i915_gem_init_stolen [i915]] GEN6_STOLEN_RESERVED = 8ff00047
<7> [335.148595] i915 0000:00:02.0: [drm:i915_gem_init_stolen [i915]] Memory reserved for graphics device: 32768K, usable: 31744K
<7> [335.148886] i915 0000:00:02.0: [drm:intel_memory_regions_hw_probe [i915]] Memory region(0): system: 7782 MiB [mem 0x00000000-0x1e6679fff], io: n/a
<7> [335.149062] i915 0000:00:02.0: [drm:intel_memory_regions_hw_probe [i915]] Memory region(5): stolen-system: 31 MiB [mem 0x8e000000-0x8fefffff], io: n/a
<7> [335.149587] i915 0000:00:02.0: [drm:intel_opregion_setup [i915]] graphic opregion physical addr: 0x8c90a018
<7> [335.149800] i915 0000:00:02.0: [drm:intel_opregion_setup [i915]] ACPI OpRegion version 2.0.0
<7> [335.149993] i915 0000:00:02.0: [drm:intel_opregion_setup [i915]] Public ACPI methods supported
<7> [335.150274] i915 0000:00:02.0: [drm:intel_opregion_setup [i915]] SWSCI Mailbox #2 present for opregion v2.x
<7> [335.150520] i915 0000:00:02.0: [drm:intel_opregion_setup [i915]] SWSCI supported
<7> [335.155109] i915 0000:00:02.0: [drm:intel_opregion_setup [i915]] SWSCI GBDA callbacks 00000cb3, SBCB callbacks 00300483
<7> [335.155378] i915 0000:00:02.0: [drm:intel_opregion_setup [i915]] ASLE supported
<7> [335.155578] i915 0000:00:02.0: [drm:intel_opregion_setup [i915]] ASLE extension supported
<7> [335.155778] i915 0000:00:02.0: [drm:intel_opregion_setup [i915]] Found valid VBT in ACPI OpRegion (Mailbox #4)
<7> [335.155985] i915 0000:00:02.0: [drm:skl_dram_print_dimm_info.isra.0 [i915]] CH0 DIMM L size: 32 Gb, width: X8, ranks: 1, 16Gb+ DIMMs: no
<7> [335.156315] i915 0000:00:02.0: [drm:skl_dram_print_dimm_info.isra.0 [i915]] CH0 DIMM S size: 0 Gb, width: X0, ranks: 0, 16Gb+ DIMMs: no
<7> [335.156601] i915 0000:00:02.0: [drm:skl_dram_get_channel_info [i915]] CH0 ranks: 1, 16Gb+ DIMMs: no
<7> [335.156816] i915 0000:00:02.0: [drm:skl_dram_print_dimm_info.isra.0 [i915]] CH1 DIMM L size: 32 Gb, width: X8, ranks: 1, 16Gb+ DIMMs: no
<7> [335.157029] i915 0000:00:02.0: [drm:skl_dram_print_dimm_info.isra.0 [i915]] CH1 DIMM S size: 0 Gb, width: X0, ranks: 0, 16Gb+ DIMMs: no
<7> [335.157358] i915 0000:00:02.0: [drm:skl_dram_get_channel_info [i915]] CH1 ranks: 1, 16Gb+ DIMMs: no
<7> [335.157624] i915 0000:00:02.0: [drm:skl_dram_get_channels_info [i915]] Memory configuration is symmetric? yes
<7> [335.157838] i915 0000:00:02.0: [drm:skl_dram_get_channels_info [i915]] 16Gb+ DIMMs: no
<7> [335.158051] i915 0000:00:02.0: [drm:intel_dram_detect [i915]] DRAM type: DDR4
<7> [335.158325] i915 0000:00:02.0: [drm:intel_dram_detect [i915]] DRAM channels: 2
<7> [335.158612] i915 0000:00:02.0: [drm:intel_dram_detect [i915]] Num QGV points 0
<7> [335.158825] i915 0000:00:02.0: [drm:intel_dram_detect [i915]] Num PSF GV points 0
<7> [335.159819] i915 0000:00:02.0: [drm:intel_bios_init [i915]] Set default to SSC at 120000 kHz
<7> [335.160068] i915 0000:00:02.0: [drm:intel_bios_init [i915]] VBT signature "$VBT SKYLAKE ", BDB version 209
<7> [335.160401] i915 0000:00:02.0: [drm:intel_bios_init [i915]] Found BDB block 1 (size 5, min size 7)
<7> [335.160706] i915 0000:00:02.0: [drm:intel_bios_init [i915]] Found BDB block 2 (size 309, min size 5)
<7> [335.160935] i915 0000:00:02.0: [drm:intel_bios_init [i915]] Found BDB block 9 (size 96, min size 100)
<7> [335.161296] i915 0000:00:02.0: [drm:intel_bios_init [i915]] Found BDB block 12 (size 19, min size 19)
<7> [335.161606] i915 0000:00:02.0: [drm:intel_bios_init [i915]] Found BDB block 27 (size 748, min size 848)
<7> [335.161874] i915 0000:00:02.0: [drm:intel_bios_init [i915]] Found BDB block 40 (size 26, min size 34)
<7> [335.162119] i915 0000:00:02.0: [drm:intel_bios_init [i915]] Found BDB block 41 (size 148, min size 148)
<7> [335.162391] i915 0000:00:02.0: [drm:intel_bios_init [i915]] Found BDB block 42 (size 1298, min size 1366)
<7> [335.162608] i915 0000:00:02.0: [drm:intel_bios_init [i915]] Found BDB block 43 (size 129, min size 305)
<7> [335.162844] i915 0000:00:02.0: [drm:intel_bios_init [i915]] Found BDB block 44 (size 21, min size 136)
<7> [335.163065] i915 0000:00:02.0: [drm:intel_bios_init [i915]] BDB_GENERAL_FEATURES int_tv_support 0 int_crt_support 0 lvds_use_ssc 0 lvds_ssc_freq 120000 display_clock_mode 1 fdi_rx_polarity_inverted 0
<7> [335.163379] i915 0000:00:02.0: [drm:intel_bios_init [i915]] crt_ddc_bus_pin: 2
<7> [335.163645] i915 0000:00:02.0: [drm:intel_bios_init [i915]] Found VBT child device with type 0x78c6
<7> [335.163871] i915 0000:00:02.0: [drm:intel_bios_init [i915]] Found VBT child device with type 0x60d2
<7> [335.164118] i915 0000:00:02.0: [drm:intel_bios_init [i915]] Found VBT child device with type 0x60d2
<7> [335.164440] i915 0000:00:02.0: [drm:intel_bios_init [i915]] Found VBT child device with type 0x60d6
<7> [335.164653] i915 0000:00:02.0: [drm:intel_bios_init [i915]] Found VBT child device with type 0x68c6
<7> [335.164893] i915 0000:00:02.0: [drm:intel_bios_init [i915]] Skipping SDVO device mapping
<7> [335.165205] i915 0000:00:02.0: [drm:intel_bios_init [i915]] Port A VBT info: CRT:0 DVI:0 HDMI:0 DP:1 eDP:1 DSI:0 DP++:0 LSPCON:0 USB-Type-C:0 TBT:0 DSC:0
<7> [335.165482] i915 0000:00:02.0: [drm:intel_bios_init [i915]] Port A VBT HDMI level shift: 0
<7> [335.165749] i915 0000:00:02.0: [drm:intel_bios_init [i915]] Port B VBT info: CRT:0 DVI:1 HDMI:1 DP:0 eDP:0 DSI:0 DP++:0 LSPCON:0 USB-Type-C:0 TBT:0 DSC:0
<7> [335.166053] i915 0000:00:02.0: [drm:intel_bios_init [i915]] Port B VBT HDMI level shift: 9
<7> [335.166328] i915 0000:00:02.0: [drm:intel_bios_init [i915]] Port C VBT info: CRT:0 DVI:1 HDMI:1 DP:0 eDP:0 DSI:0 DP++:0 LSPCON:0 USB-Type-C:0 TBT:0 DSC:0
<7> [335.166612] i915 0000:00:02.0: [drm:intel_bios_init [i915]] Port C VBT HDMI level shift: 10
<7> [335.166824] i915 0000:00:02.0: [drm:intel_bios_init [i915]] Port D VBT info: CRT:0 DVI:1 HDMI:1 DP:1 eDP:0 DSI:0 DP++:1 LSPCON:0 USB-Type-C:0 TBT:0 DSC:0
<7> [335.167037] i915 0000:00:02.0: [drm:intel_bios_init [i915]] Port D VBT HDMI level shift: 10
<7> [335.167321] i915 0000:00:02.0: [drm:intel_bios_init [i915]] Port E VBT info: CRT:0 DVI:0 HDMI:0 DP:1 eDP:0 DSI:0 DP++:0 LSPCON:0 USB-Type-C:0 TBT:0 DSC:0
<7> [335.167588] i915 0000:00:02.0: [drm:intel_bios_init [i915]] Port E VBT HDMI level shift: 0
<7> [335.167806] i915 0000:00:02.0: [drm:intel_power_domains_init [i915]] Allowed DC state mask 02
<7> [335.168040] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 00
<7> [335.168407] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_1
<7> [335.168669] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling MISC_IO
<7> [335.168890] i915 0000:00:02.0: [drm:intel_cdclk_init_hw [i915]] Current CDCLK 337500 kHz, VCO 8100000 kHz, ref 24000 kHz, bypass 24000 kHz, voltage level 0
<7> [335.169164] i915 0000:00:02.0: [drm:intel_update_max_cdclk [i915]] Max CD clock rate: 675000 kHz
<7> [335.169493] i915 0000:00:02.0: [drm:intel_update_max_cdclk [i915]] Max dotclock rate: 675000 kHz
<7> [335.169747] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [335.170012] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7> [335.170348] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [335.170698] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 00
<7> [335.170935] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [335.171287] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_A_E
<7> [335.171574] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [335.171791] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_C
<7> [335.172250] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_D
<7> [335.172889] i915 0000:00:02.0: [drm:intel_dmc_init [i915]] Loading i915/kbl_dmc_ver1_04.bin
<7> [335.173522] i915 0000:00:02.0: [drm:intel_fbc_init [i915]] Sanitized enable_fbc value: 1
<7> [335.174026] i915 0000:00:02.0: [drm:skl_sagv_disable [i915]] Disabling SAGV
<7> [335.174922] i915 0000:00:02.0: [drm:dmc_load_work_fn [i915]] DMC 0:
<7> [335.175235] i915 0000:00:02.0: [drm:dmc_load_work_fn [i915]] mmio[0]: 0x8f074 = 0x2fc0
<7> [335.175310] i915 0000:00:02.0: [drm:skl_wm_init [i915]] SAGV supported: yes, original SAGV block time: 30 us
<7> [335.175538] i915 0000:00:02.0: [drm:dmc_load_work_fn [i915]] mmio[1]: 0x8f004 = 0xb40068 (EVT_HTP)
<7> [335.175605] i915 0000:00:02.0: [drm:intel_print_wm_latency [i915]] original WM0 latency not provided
<7> [335.175835] i915 0000:00:02.0: [drm:dmc_load_work_fn [i915]] mmio[2]: 0x8f034 = 0xc003b400 (EVT_CTL)
<7> [335.175898] i915 0000:00:02.0: [drm:intel_print_wm_latency [i915]] original WM1 latency 17 (17.0 usec)
<7> [335.176334] i915 0000:00:02.0: [drm:intel_print_wm_latency [i915]] original WM2 latency 26 (26.0 usec)
<7> [335.176613] i915 0000:00:02.0: [drm:intel_print_wm_latency [i915]] original WM3 latency 30 (30.0 usec)
<6> [335.176799] i915 0000:00:02.0: [drm] Finished loading DMC firmware i915/kbl_dmc_ver1_04.bin (v1.4)
<7> [335.176844] i915 0000:00:02.0: [drm:intel_print_wm_latency [i915]] original WM4 latency 61 (61.0 usec)
<7> [335.177039] i915 0000:00:02.0: [drm:intel_print_wm_latency [i915]] original WM5 latency 75 (75.0 usec)
<7> [335.177375] i915 0000:00:02.0: [drm:intel_print_wm_latency [i915]] original WM6 latency 81 (81.0 usec)
<7> [335.177624] i915 0000:00:02.0: [drm:intel_print_wm_latency [i915]] original WM7 latency 97 (97.0 usec)
<7> [335.177817] i915 0000:00:02.0: [drm:intel_print_wm_latency [i915]] adjusted WM0 latency 2 (2.0 usec)
<7> [335.178071] i915 0000:00:02.0: [drm:intel_print_wm_latency [i915]] adjusted WM1 latency 19 (19.0 usec)
<7> [335.178331] i915 0000:00:02.0: [drm:intel_print_wm_latency [i915]] adjusted WM2 latency 28 (28.0 usec)
<7> [335.178566] i915 0000:00:02.0: [drm:intel_print_wm_latency [i915]] adjusted WM3 latency 32 (32.0 usec)
<7> [335.178873] i915 0000:00:02.0: [drm:intel_print_wm_latency [i915]] adjusted WM4 latency 63 (63.0 usec)
<7> [335.179108] i915 0000:00:02.0: [drm:intel_print_wm_latency [i915]] adjusted WM5 latency 77 (77.0 usec)
<7> [335.179390] i915 0000:00:02.0: [drm:intel_print_wm_latency [i915]] adjusted WM6 latency 83 (83.0 usec)
<7> [335.179583] i915 0000:00:02.0: [drm:intel_print_wm_latency [i915]] adjusted WM7 latency 99 (99.0 usec)
<7> [335.181433] i915 0000:00:02.0: [drm:intel_display_driver_probe_nogem [i915]] 3 display pipes available.
<7> [335.182992] i915 0000:00:02.0: [drm:intel_cdclk_read_hw [i915]] Current CDCLK 337500 kHz, VCO 8100000 kHz, ref 24000 kHz, bypass 24000 kHz, voltage level 0
<7> [335.183306] i915 0000:00:02.0: [drm:intel_dp_aux_ch [i915]] [ENCODER:108:DDI A/PHY A] Using AUX CH A (VBT)
<7> [335.183534] i915 0000:00:02.0: [drm:intel_dp_init_connector [i915]] Adding eDP connector on [ENCODER:108:DDI A/PHY A]
<7> [335.186143] i915 0000:00:02.0: [drm:intel_opregion_get_panel_type [i915]] Ignoring OpRegion panel type (0)
<7> [335.186354] i915 0000:00:02.0: [drm:intel_bios_init_panel [i915]] Panel type (VBT): 2
<7> [335.186565] i915 0000:00:02.0: [drm:intel_bios_init_panel [i915]] Selected panel type (VBT): 2
<7> [335.186774] i915 0000:00:02.0: [drm:intel_bios_init_panel [i915]] DRRS supported mode is static
<7> [335.186990] i915 0000:00:02.0: [drm:intel_bios_init_panel [i915]] Found panel mode in BIOS VBT legacy lfp table: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x8 0xa
<7> [335.187251] i915 0000:00:02.0: [drm:intel_bios_init_panel [i915]] VBT initial LVDS value 300
<7> [335.187472] i915 0000:00:02.0: [drm:intel_bios_init_panel [i915]] Panel manufacturer name: MS_, product code: 3, serial number: 3, year of manufacture: 2002
<7> [335.187680] i915 0000:00:02.0: [drm:intel_bios_init_panel [i915]] Panel name: LFP_PanelName
<7> [335.187888] i915 0000:00:02.0: [drm:intel_bios_init_panel [i915]] Seamless DRRS min refresh rate: 0 Hz
<7> [335.188127] i915 0000:00:02.0: [drm:intel_bios_init_panel [i915]] VBT backlight PWM modulation frequency 200 Hz, active high, min brightness 0, level 255, controller 0
<7> [335.188379] i915 0000:00:02.0: [drm:intel_bios_init_panel [i915]] DRRS State Enabled:1
<7> [335.188672] i915 0000:00:02.0: [drm:intel_pps_init [i915]] [ENCODER:108:DDI A/PHY A] initial power sequencer: PPS 0
<7> [335.188910] i915 0000:00:02.0: [drm:intel_pps_dump_state [i915]] bios power_up 2000 backlight_on 1 backlight_off 1 power_down 500 power_cycle 5000
<7> [335.189120] i915 0000:00:02.0: [drm:intel_pps_dump_state [i915]] vbt power_up 2000 backlight_on 10 backlight_off 2000 power_down 500 power_cycle 5000
<7> [335.189329] i915 0000:00:02.0: [drm:intel_pps_dump_state [i915]] spec power_up 2100 backlight_on 500 backlight_off 500 power_down 5000 power_cycle 5100
<7> [335.189614] i915 0000:00:02.0: [drm:pps_init_delays [i915]] panel power up delay 200, power down delay 50, power cycle delay 500
<7> [335.189807] i915 0000:00:02.0: [drm:pps_init_delays [i915]] backlight on delay 1, off delay 200
<7> [335.190045] i915 0000:00:02.0: [drm:pps_init_registers [i915]] panel power sequencer register settings: PP_ON 0x7d00001, PP_OFF 0x1f40001, PP_DIV 0x4af06
<7> [335.190402] i915 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [i915]] [ENCODER:108:DDI A/PHY A] PPS 0 turning VDD on
<7> [335.190614] i915 0000:00:02.0: [drm:wait_panel_power_cycle [i915]] [ENCODER:108:DDI A/PHY A] PPS 0 wait for panel power cycle (0 ms remaining)
<7> [335.190884] i915 0000:00:02.0: [drm:wait_panel_status [i915]] [ENCODER:108:DDI A/PHY A] PPS 0 mask: 0xb800000f value: 0x00000000 PP_STATUS: 0x00000000 PP_CONTROL: 0x00000000
<7> [335.201246] i915 0000:00:02.0: [drm:wait_panel_status [i915]] Wait complete
<7> [335.201517] i915 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [i915]] [ENCODER:108:DDI A/PHY A] PPS 0 PP_STATUS: 0x00000000 PP_CONTROL: 0x00000008
<7> [335.201723] i915 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [i915]] [ENCODER:108:DDI A/PHY A] PPS 0 panel power wasn't enabled
<7> [335.414898] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.423675] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.432381] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.441092] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.449802] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.458574] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.467334] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.476096] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.484857] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.493623] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.502378] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.511137] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.519894] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.528662] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.537413] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.546172] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.554931] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.563697] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.572418] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.581177] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.589936] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.598702] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.607423] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.616166] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.624925] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.633694] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.642394] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.651154] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.659913] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.668684] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.677383] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.686127] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.686410] i915 0000:00:02.0: [drm:drm_dp_dpcd_access [drm_display_helper]] AUX A/DDI A/PHY A: Too many retries, giving up. First error: -110
<7> [335.694904] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.703671] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.712420] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.721162] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.729905] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.738672] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.747423] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.756183] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.764927] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.773694] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.782415] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.791173] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.799930] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.808695] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.817395] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.826154] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.834913] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.843663] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.852415] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.861174] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.869933] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.878699] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.887400] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.896160] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.904918] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.913683] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.922383] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.931145] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.939891] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.948663] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.957417] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.966177] i915 0000:00:02.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [335.966460] i915 0000:00:02.0: [drm:drm_dp_dpcd_access [drm_display_helper]] AUX A/DDI A/PHY A: Too many retries, giving up. First error: -110
<6> [335.966492] i915 0000:00:02.0: [drm] [ENCODER:108:DDI A/PHY A] failed to retrieve link info, disabling eDP
<7> [335.966509] i915 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [i915]] [ENCODER:108:DDI A/PHY A] PPS 0 turning VDD off
<7> [335.966729] i915 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [i915]] [ENCODER:108:DDI A/PHY A] PPS 0 PP_STATUS: 0x00000000 PP_CONTROL: 0x00000000
<7> [335.967073] i915 0000:00:02.0: [drm:intel_hdmi_init_connector [i915]] Adding HDMI connector on [ENCODER:108:DDI B/PHY B]
<7> [335.967343] i915 0000:00:02.0: [drm:intel_hdmi_init_connector [i915]] [ENCODER:108:DDI B/PHY B] Using DDC pin 0x5 (VBT)
<7> [335.967874] i915 0000:00:02.0: [drm:intel_hdmi_init_connector [i915]] Adding HDMI connector on [ENCODER:118:DDI C/PHY C]
<7> [335.968132] i915 0000:00:02.0: [drm:intel_hdmi_init_connector [i915]] [ENCODER:118:DDI C/PHY C] Using DDC pin 0x4 (VBT)
<7> [335.968508] i915 0000:00:02.0: [drm:intel_dp_aux_ch [i915]] [ENCODER:122:DDI D/PHY D] Using AUX CH D (VBT)
<7> [335.968716] i915 0000:00:02.0: [drm:intel_dp_init_connector [i915]] Adding DP connector on [ENCODER:122:DDI D/PHY D]
<7> [335.969265] i915 0000:00:02.0: [drm:intel_psr_init [i915]] PSR condition failed: Port not supported
<7> [335.969543] i915 0000:00:02.0: [drm:intel_hdmi_init_connector [i915]] Adding HDMI connector on [ENCODER:122:DDI D/PHY D]
<7> [335.969731] i915 0000:00:02.0: [drm:intel_hdmi_init_connector [i915]] [ENCODER:122:DDI D/PHY D] Using DDC pin 0x6 (VBT)
<7> [335.970019] i915 0000:00:02.0: [drm:intel_dp_aux_ch [i915]] [ENCODER:133:DDI E/PHY E] Using AUX CH A (VBT)
<7> [335.970325] i915 0000:00:02.0: [drm:intel_dp_init_connector [i915]] Adding DP connector on [ENCODER:133:DDI E/PHY E]
<7> [335.970669] i915 0000:00:02.0: [drm:intel_psr_init [i915]] PSR condition failed: Port not supported
<7> [335.970992] i915 0000:00:02.0: [drm:intel_vga_disable [i915]] VGA plane is disabled
<6> [335.971297] i915 0000:00:02.0: vgaarb: VGA decodes changed: olddecodes=io,decodes=io:owns=io+mem
<7> [335.971668] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [CRTC:57:pipe A] hw state readout: disabled
<7> [335.971941] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [CRTC:82:pipe B] hw state readout: disabled
<7> [335.972332] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [CRTC:107:pipe C] hw state readout: disabled
<7> [335.972639] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:33:plane 1A] hw state readout: disabled, pipe A
<7> [335.972850] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:42:plane 2A] hw state readout: disabled, pipe A
<7> [335.973061] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:51:cursor A] hw state readout: disabled, pipe A
<7> [335.973369] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:58:plane 1B] hw state readout: disabled, pipe B
<7> [335.973600] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:67:plane 2B] hw state readout: disabled, pipe B
<7> [335.973867] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:76:cursor B] hw state readout: disabled, pipe B
<7> [335.974081] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:83:plane 1C] hw state readout: disabled, pipe C
<7> [335.974404] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:92:plane 2C] hw state readout: disabled, pipe C
<7> [335.974717] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:101:cursor C] hw state readout: disabled, pipe C
<7> [335.974945] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [ENCODER:108:DDI B/PHY B] hw state readout: disabled, pipe A
<7> [335.975267] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [ENCODER:118:DDI C/PHY C] hw state readout: disabled, pipe A
<7> [335.975516] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [ENCODER:122:DDI D/PHY D] hw state readout: disabled, pipe A
<7> [335.975714] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [ENCODER:124:DP-MST A] hw state readout: disabled, pipe A
<7> [335.975930] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [ENCODER:125:DP-MST B] hw state readout: disabled, pipe B
<7> [335.976211] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [ENCODER:126:DP-MST C] hw state readout: disabled, pipe C
<7> [335.976464] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [ENCODER:133:DDI E/PHY E] hw state readout: disabled, pipe A
<7> [335.976676] i915 0000:00:02.0: [drm:intel_dpll_readout_hw_state [i915]] DPLL 0 hw state readout: pipe_mask 0x0, on 1
<7> [335.976891] i915 0000:00:02.0: [drm:intel_dpll_readout_hw_state [i915]] DPLL 1 hw state readout: pipe_mask 0x0, on 0
<7> [335.977201] i915 0000:00:02.0: [drm:intel_dpll_readout_hw_state [i915]] DPLL 2 hw state readout: pipe_mask 0x0, on 0
<7> [335.977441] i915 0000:00:02.0: [drm:intel_dpll_readout_hw_state [i915]] DPLL 3 hw state readout: pipe_mask 0x0, on 0
<7> [335.977710] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [CONNECTOR:109:HDMI-A-1] hw state readout: disabled
<7> [335.978024] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [CONNECTOR:119:HDMI-A-2] hw state readout: disabled
<7> [335.978344] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [CONNECTOR:123:DP-1] hw state readout: disabled
<7> [335.978586] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [CONNECTOR:130:HDMI-A-3] hw state readout: disabled
<7> [335.978808] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [CONNECTOR:134:DP-2] hw state readout: disabled
<7> [335.979007] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:33:plane 1A] min_cdclk 0 kHz
<7> [335.979290] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:42:plane 2A] min_cdclk 0 kHz
<7> [335.979569] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:51:cursor A] min_cdclk 0 kHz
<7> [335.979767] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [CRTC:57:pipe A] min_cdclk 0 kHz
<7> [335.979964] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:58:plane 1B] min_cdclk 0 kHz
<7> [335.980229] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:67:plane 2B] min_cdclk 0 kHz
<7> [335.980470] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:76:cursor B] min_cdclk 0 kHz
<7> [335.980722] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [CRTC:82:pipe B] min_cdclk 0 kHz
<7> [335.980924] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:83:plane 1C] min_cdclk 0 kHz
<7> [335.981189] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:92:plane 2C] min_cdclk 0 kHz
<7> [335.981514] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:101:cursor C] min_cdclk 0 kHz
<7> [335.981800] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [CRTC:107:pipe C] min_cdclk 0 kHz
<7> [335.982016] i915 0000:00:02.0: [drm:skl_wm_get_hw_state [i915]] [CRTC:57:pipe A] dbuf slices 0x0, ddb (0 - 0), active pipes 0x0, mbus joined: no
<7> [335.982297] i915 0000:00:02.0: [drm:skl_wm_get_hw_state [i915]] [CRTC:82:pipe B] dbuf slices 0x0, ddb (0 - 0), active pipes 0x0, mbus joined: no
<7> [335.982554] i915 0000:00:02.0: [drm:skl_wm_get_hw_state [i915]] [CRTC:107:pipe C] dbuf slices 0x0, ddb (0 - 0), active pipes 0x0, mbus joined: no
<7> [335.982832] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:57:pipe A] enable: no [setup_hw_state]
<7> [335.983035] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:82:pipe B] enable: no [setup_hw_state]
<7> [335.983361] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:107:pipe C] enable: no [setup_hw_state]
<7> [335.983643] i915 0000:00:02.0: [drm:intel_dpll_sanitize_state [i915]] DPLL 0 enabled but not in use, disabling
<7> [335.985825] i915 0000:00:02.0: [drm:intel_wopcm_init [i915]] GuC WOPCM is already locked [240K, 784K)
<7> [335.986018] i915 0000:00:02.0: [drm:i915_init_ggtt [i915]] Reserved GGTT:[0, 2000] for use by error capture
<7> [335.986297] i915 0000:00:02.0: [drm:i915_init_ggtt [i915]] clearing unused GTT space: [2000, fee00000]
<7> [335.986527] i915 0000:00:02.0: [drm:gen9_gt_workarounds_init.isra.0 [i915]] MCR slice:0/subslice:0 = 0
<7> [335.986730] i915 0000:00:02.0: [drm:wa_init_finish [i915]] GT0: Initialized 5 GT workarounds on global
<7> [335.987975] i915 0000:00:02.0: [drm:wa_init_finish [i915]] GT0: Initialized 8 engine workarounds on rcs'0
<7> [335.988259] i915 0000:00:02.0: [drm:wa_init_finish [i915]] GT0: Initialized 5 whitelist workarounds on rcs'0
<7> [335.988495] i915 0000:00:02.0: [drm:wa_init_finish [i915]] GT0: Initialized 14 context workarounds on rcs'0
<7> [335.993555] i915 0000:00:02.0: [drm:intel_guc_log_create [i915]] GT0: GUC: guc_log_level=5 (enabled, verbose:yes, verbosity:3)
<7> [335.993775] i915 0000:00:02.0: [drm:intel_guc_capture_init [i915]] GT0: GUC: capture found 6 ext-regs.
<7> [335.993989] i915 0000:00:02.0: [drm:intel_guc_ads_create [i915]] GT0: GUC: Used 4 KB for temporary ADS regset
<7> [336.029527] i915 0000:00:02.0: [drm:intel_guc_init [i915]] GT0: GUC: param[ 0] = 0xc404f
<7> [336.029759] i915 0000:00:02.0: [drm:intel_guc_init [i915]] GT0: GUC: param[ 1] = 0x0
<7> [336.029952] i915 0000:00:02.0: [drm:intel_guc_init [i915]] GT0: GUC: param[ 2] = 0x4000
<7> [336.030173] i915 0000:00:02.0: [drm:intel_guc_init [i915]] GT0: GUC: param[ 3] = 0x3
<7> [336.030459] i915 0000:00:02.0: [drm:intel_guc_init [i915]] GT0: GUC: param[ 4] = 0x98a
<7> [336.030658] i915 0000:00:02.0: [drm:intel_guc_init [i915]] GT0: GUC: param[ 5] = 0x3e920000
<7> [336.030858] i915 0000:00:02.0: [drm:intel_guc_init [i915]] GT0: GUC: param[ 6] = 0x0
<7> [336.031076] i915 0000:00:02.0: [drm:intel_guc_init [i915]] GT0: GUC: param[ 7] = 0x0
<7> [336.031370] i915 0000:00:02.0: [drm:intel_guc_init [i915]] GT0: GUC: param[ 8] = 0x0
<7> [336.031611] i915 0000:00:02.0: [drm:intel_guc_init [i915]] GT0: GUC: param[ 9] = 0x0
<7> [336.031806] i915 0000:00:02.0: [drm:intel_guc_init [i915]] GT0: GUC: param[10] = 0x0
<7> [336.032002] i915 0000:00:02.0: [drm:intel_guc_init [i915]] GT0: GUC: param[11] = 0x0
<7> [336.032282] i915 0000:00:02.0: [drm:intel_guc_init [i915]] GT0: GUC: param[12] = 0x0
<7> [336.032610] i915 0000:00:02.0: [drm:intel_guc_init [i915]] GT0: GUC: param[13] = 0x0
<7> [336.032802] i915 0000:00:02.0: [drm:intel_huc_init [i915]] GT0: HuC: loaded by GSC = no
<6> [336.034048] i915 0000:00:02.0: [drm] GT0: GuC firmware i915/kbl_guc_70.1.1.bin version 70.1.1
<6> [336.034052] i915 0000:00:02.0: [drm] GT0: HuC firmware i915/kbl_huc_4.0.0.bin version 4.0.0
<7> [336.039025] i915 0000:00:02.0: [drm:intel_guc_fw_upload [i915]] GT0: GUC: init took 2ms, freq = 350MHz -> 350MHz vs 1200MHz, status = 0x8002F0EC, count = 0, ret = 0
<7> [336.039878] i915 0000:00:02.0: [drm:guc_enable_communication [i915]] GT0: GUC: communication enabled
<6> [336.040124] i915 0000:00:02.0: [drm] GT0: GUC: submission disabled
<6> [336.040128] i915 0000:00:02.0: [drm] GT0: GUC: SLPC disabled
<7> [336.048928] i915 0000:00:02.0: [drm:intel_engines_driver_register [i915]] renamed rcs'0 to rcs0
<7> [336.049136] i915 0000:00:02.0: [drm:intel_engines_driver_register [i915]] renamed bcs'0 to bcs0
<7> [336.049324] i915 0000:00:02.0: [drm:intel_engines_driver_register [i915]] renamed vcs'0 to vcs0
<7> [336.049512] i915 0000:00:02.0: [drm:intel_engines_driver_register [i915]] renamed vecs'0 to vecs0
<7> [336.049745] i915 0000:00:02.0: [drm:i915_hdcp_component_bind [i915]] I915 HDCP comp bind
<6> [336.049964] mei_hdcp 0000:00:16.0-b638ab7e-94e2-4ea2-a552-d1c54b627f04: bound 0000:00:02.0 (ops i915_hdcp_ops [i915])
<7> [336.050135] i915 0000:00:02.0: [drm:intel_flipq_init [i915]] [CRTC:57:pipe A] FQ 0: start 0x8
<7> [336.050357] i915 0000:00:02.0: [drm:intel_flipq_init [i915]] [CRTC:57:pipe A] FQ 1: start 0x108
<7> [336.050566] i915 0000:00:02.0: [drm:intel_flipq_init [i915]] [CRTC:57:pipe A] FQ 2: start 0x208
<7> [336.050773] i915 0000:00:02.0: [drm:intel_flipq_init [i915]] [CRTC:57:pipe A] FQ 3: start 0x308
<7> [336.050980] i915 0000:00:02.0: [drm:intel_flipq_init [i915]] [CRTC:57:pipe A] FQ 4: start 0x3c8
<7> [336.051200] i915 0000:00:02.0: [drm:intel_flipq_init [i915]] [CRTC:82:pipe B] FQ 0: start 0x8
<7> [336.051418] i915 0000:00:02.0: [drm:intel_flipq_init [i915]] [CRTC:82:pipe B] FQ 1: start 0x108
<7> [336.051624] i915 0000:00:02.0: [drm:intel_flipq_init [i915]] [CRTC:82:pipe B] FQ 2: start 0x208
<7> [336.051831] i915 0000:00:02.0: [drm:intel_flipq_init [i915]] [CRTC:82:pipe B] FQ 3: start 0x308
<7> [336.052038] i915 0000:00:02.0: [drm:intel_flipq_init [i915]] [CRTC:82:pipe B] FQ 4: start 0x3c8
<7> [336.052269] i915 0000:00:02.0: [drm:intel_flipq_init [i915]] [CRTC:107:pipe C] FQ 0: start 0x8
<7> [336.052485] i915 0000:00:02.0: [drm:intel_flipq_init [i915]] [CRTC:107:pipe C] FQ 1: start 0x108
<7> [336.052693] i915 0000:00:02.0: [drm:intel_flipq_init [i915]] [CRTC:107:pipe C] FQ 2: start 0x208
<7> [336.052901] i915 0000:00:02.0: [drm:intel_flipq_init [i915]] [CRTC:107:pipe C] FQ 3: start 0x308
<7> [336.053136] i915 0000:00:02.0: [drm:intel_flipq_init [i915]] [CRTC:107:pipe C] FQ 4: start 0x3c8
<7> [336.053778] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:57:pipe A] enable: no [fastset]
<7> [336.053994] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:82:pipe B] enable: no [fastset]
<7> [336.054217] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:107:pipe C] enable: no [fastset]
<7> [336.054531] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:57:pipe A]
<7> [336.054874] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:82:pipe B]
<7> [336.055189] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:107:pipe C]
<7> [336.055475] i915 0000:00:02.0: [drm:intel_sagv_post_plane_update [i915]] Enabling SAGV
<7> [336.058252] i915 0000:00:02.0: [drm:drm_sysfs_connector_add] [CONNECTOR:109:HDMI-A-1] adding connector to sysfs
<7> [336.058756] i915 0000:00:02.0: [drm:drm_sysfs_connector_hotplug_event] [CONNECTOR:109:HDMI-A-1] generating connector hotplug event
<7> [336.058811] i915 0000:00:02.0: [drm:drm_sysfs_connector_add] [CONNECTOR:119:HDMI-A-2] adding connector to sysfs
<7> [336.059322] i915 0000:00:02.0: [drm:drm_sysfs_connector_hotplug_event] [CONNECTOR:119:HDMI-A-2] generating connector hotplug event
<7> [336.059375] i915 0000:00:02.0: [drm:drm_sysfs_connector_add] [CONNECTOR:123:DP-1] adding connector to sysfs
<7> [336.059884] i915 0000:00:02.0: [drm:intel_dp_connector_register [i915]] registering AUX D/DDI D/PHY D bus for card0-DP-1
<7> [336.060981] i915 0000:00:02.0: [drm:drm_sysfs_connector_hotplug_event] [CONNECTOR:123:DP-1] generating connector hotplug event
<7> [336.061036] i915 0000:00:02.0: [drm:drm_sysfs_connector_add] [CONNECTOR:130:HDMI-A-3] adding connector to sysfs
<7> [336.061544] i915 0000:00:02.0: [drm:drm_sysfs_connector_hotplug_event] [CONNECTOR:130:HDMI-A-3] generating connector hotplug event
<7> [336.061596] i915 0000:00:02.0: [drm:drm_sysfs_connector_add] [CONNECTOR:134:DP-2] adding connector to sysfs
<7> [336.062086] i915 0000:00:02.0: [drm:intel_dp_connector_register [i915]] registering AUX A/DDI E/PHY E bus for card0-DP-2
<7> [336.063165] i915 0000:00:02.0: [drm:drm_sysfs_connector_hotplug_event] [CONNECTOR:134:DP-2] generating connector hotplug event
<6> [336.063208] [drm] Initialized i915 1.6.0 for 0000:00:02.0 on minor 0
<7> [336.065678] i915 0000:00:02.0: [drm:intel_opregion_resume [i915]] 5 outputs detected
<6> [336.084074] ACPI: video: Video Device [GFX0] (multi-head: yes rom: no post: no)
<6> [336.086576] input: Video Bus as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/LNXVIDEO:00/input/input45
<7> [336.087234] i915 0000:00:02.0: [drm:intel_audio_init [i915]] use AUD_FREQ_CNTRL of 0x10 (init value 0x10)
<7> [336.089072] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:109:HDMI-A-1]
<7> [336.089334] i915 0000:00:02.0: [drm:intel_hotplug_detect_connector [i915]] [CONNECTOR:109:HDMI-A-1] status updated from unknown to disconnected (epoch counter 0->1)
<7> [336.089386] i915 0000:00:02.0: [drm:intel_fbdev_setup [i915]] [CRTC:57:pipe A] not active, skipping
<7> [336.089635] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:119:HDMI-A-2]
<7> [336.089697] i915 0000:00:02.0: [drm:intel_fbdev_setup [i915]] [CRTC:82:pipe B] not active, skipping
<7> [336.089939] i915 0000:00:02.0: [drm:intel_hotplug_detect_connector [i915]] [CONNECTOR:119:HDMI-A-2] status updated from unknown to disconnected (epoch counter 0->1)
<7> [336.090002] i915 0000:00:02.0: [drm:intel_fbdev_setup [i915]] [CRTC:107:pipe C] not active, skipping
<7> [336.090351] i915 0000:00:02.0: [drm:intel_fbdev_setup [i915]] no active fbs found, not using BIOS config
<7> [336.090402] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:123:DP-1]
<7> [336.090701] i915 0000:00:02.0: [drm:intel_hotplug_detect_connector [i915]] [CONNECTOR:123:DP-1] status updated from unknown to disconnected (epoch counter 0->1)
<7> [336.090809] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [336.091009] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:130:HDMI-A-3]
<7> [336.091527] i915 0000:00:02.0: [drm:drm_client_modeset_probe]
<7> [336.091487] i915 0000:00:02.0: [drm:intel_hotplug_detect_connector [i915]] [CONNECTOR:130:HDMI-A-3] status updated from unknown to disconnected (epoch counter 0->1)
<7> [336.091749] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:134:DP-2]
<7> [336.091978] i915 0000:00:02.0: [drm:intel_hotplug_detect_connector [i915]] [CONNECTOR:134:DP-2] status updated from unknown to disconnected (epoch counter 0->1)
<7> [336.092263] i915 0000:00:02.0: [drm:drm_sysfs_hotplug_event] generating hotplug event
<7> [336.092264] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:109:HDMI-A-1]
<7> [336.092271] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:109:HDMI-A-1]
<7> [336.092529] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:109:HDMI-A-1] disconnected
<7> [336.092537] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:119:HDMI-A-2]
<7> [336.092543] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:119:HDMI-A-2]
<7> [336.092807] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:119:HDMI-A-2] disconnected
<7> [336.092813] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:123:DP-1]
<7> [336.092818] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:123:DP-1]
<7> [336.093085] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:123:DP-1] disconnected
<7> [336.093113] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:130:HDMI-A-3]
<7> [336.093118] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:130:HDMI-A-3]
<7> [336.093359] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:130:HDMI-A-3] disconnected
<7> [336.093364] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:134:DP-2]
<7> [336.093368] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:134:DP-2]
<7> [336.093594] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:134:DP-2] disconnected
<7> [336.093598] i915 0000:00:02.0: [drm:drm_client_modeset_probe] No connectors reported connected with modes
<7> [336.093602] i915 0000:00:02.0: [drm:drm_client_modeset_probe] [CONNECTOR:109:HDMI-A-1] enabled? no
<7> [336.093605] i915 0000:00:02.0: [drm:drm_client_modeset_probe] [CONNECTOR:119:HDMI-A-2] enabled? no
<7> [336.093608] i915 0000:00:02.0: [drm:drm_client_modeset_probe] [CONNECTOR:123:DP-1] enabled? no
<7> [336.093611] i915 0000:00:02.0: [drm:drm_client_modeset_probe] [CONNECTOR:130:HDMI-A-3] enabled? no
<7> [336.093614] i915 0000:00:02.0: [drm:drm_client_modeset_probe] [CONNECTOR:134:DP-2] enabled? no
<7> [336.093636] i915 0000:00:02.0: [drm:drm_client_firmware_config.isra.0] Not using firmware configuration
<7> [336.093645] i915 0000:00:02.0: [drm:drm_client_modeset_probe] picking CRTCs for 16384x16384 config
<7> [336.093689] i915 0000:00:02.0: [drm:__drm_fb_helper_initial_config_and_unlock] test CRTC 0 primary plane
<6> [336.093695] i915 0000:00:02.0: [drm] Cannot find any crtc or sizes
<7> [336.094283] i915 0000:00:02.0: [drm:drm_client_modeset_probe]
<7> [336.094249] i915 0000:00:02.0: [drm:intel_display_driver_register [i915]] i915 display info: display version: 9
<7> [336.094536] i915 0000:00:02.0: [drm:intel_display_driver_register [i915]] i915 display info: display stepping: **
<7> [336.094687] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:109:HDMI-A-1]
<7> [336.094698] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:109:HDMI-A-1]
<7> [336.094834] i915 0000:00:02.0: [drm:intel_display_driver_register [i915]] i915 display info: cursor_needs_physical: no
<7> [336.095004] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:109:HDMI-A-1] disconnected
<7> [336.095030] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:119:HDMI-A-2]
<7> [336.095036] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:119:HDMI-A-2]
<7> [336.095192] i915 0000:00:02.0: [drm:intel_display_driver_register [i915]] i915 display info: has_cdclk_crawl: no
<7> [336.095474] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:119:HDMI-A-2] disconnected
<7> [336.095492] i915 0000:00:02.0: [drm:intel_display_driver_register [i915]] i915 display info: has_cdclk_squash: no
<7> [336.095768] i915 0000:00:02.0: [drm:intel_display_driver_register [i915]] i915 display info: has_ddi: yes
<7> [336.096149] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:123:DP-1]
<7> [336.096065] i915 0000:00:02.0: [drm:intel_display_driver_register [i915]] i915 display info: has_dp_mst: yes
<7> [336.096171] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:123:DP-1]
<7> [336.096487] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:123:DP-1] disconnected
<7> [336.096493] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:130:HDMI-A-3]
<7> [336.096393] i915 0000:00:02.0: [drm:intel_display_driver_register [i915]] i915 display info: has_dsb: no
<7> [336.096499] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:130:HDMI-A-3]
<7> [336.096807] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:130:HDMI-A-3] disconnected
<7> [336.096814] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:134:DP-2]
<7> [336.096708] i915 0000:00:02.0: [drm:intel_display_driver_register [i915]] i915 display info: has_fpga_dbg: yes
<7> [336.096819] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:134:DP-2]
<7> [336.097170] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:134:DP-2] disconnected
<7> [336.097176] i915 0000:00:02.0: [drm:drm_client_modeset_probe] No connectors reported connected with modes
<7> [336.097185] i915 0000:00:02.0: [drm:drm_client_modeset_probe] [CONNECTOR:109:HDMI-A-1] enabled? no
<7> [336.097190] i915 0000:00:02.0: [drm:drm_client_modeset_probe] [CONNECTOR:119:HDMI-A-2] enabled? no
<7> [336.097194] i915 0000:00:02.0: [drm:drm_client_modeset_probe] [CONNECTOR:123:DP-1] enabled? no
<7> [336.097198] i915 0000:00:02.0: [drm:drm_client_modeset_probe] [CONNECTOR:130:HDMI-A-3] enabled? no
<7> [336.097203] i915 0000:00:02.0: [drm:drm_client_modeset_probe] [CONNECTOR:134:DP-2] enabled? no
<7> [336.097024] i915 0000:00:02.0: [drm:intel_display_driver_register [i915]] i915 display info: has_gmch: no
<7> [336.097320] i915 0000:00:02.0: [drm:drm_client_firmware_config.isra.0] Not using firmware configuration
<7> [336.097338] i915 0000:00:02.0: [drm:drm_client_modeset_probe] picking CRTCs for 16384x16384 config
<7> [336.097394] i915 0000:00:02.0: [drm:__drm_fb_helper_initial_config_and_unlock] test CRTC 0 primary plane
<6> [336.097410] i915 0000:00:02.0: [drm] Cannot find any crtc or sizes
<7> [336.097451] i915 0000:00:02.0: [drm:drm_client_hotplug] fbdev: ret=0
<7> [336.097360] i915 0000:00:02.0: [drm:intel_display_driver_register [i915]] i915 display info: has_hotplug: yes
<7> [336.097603] i915 0000:00:02.0: [drm:intel_display_driver_register [i915]] i915 display info: has_hti: no
<7> [336.097816] i915 0000:00:02.0: [drm:intel_display_driver_register [i915]] i915 display info: has_ipc: yes
<7> [336.098026] i915 0000:00:02.0: [drm:intel_display_driver_register [i915]] i915 display info: has_overlay: no
<7> [336.098276] i915 0000:00:02.0: [drm:intel_display_driver_register [i915]] i915 display info: has_psr: yes
<7> [336.098487] i915 0000:00:02.0: [drm:intel_display_driver_register [i915]] i915 display info: has_psr_hw_tracking: yes
<7> [336.098697] i915 0000:00:02.0: [drm:intel_display_driver_register [i915]] i915 display info: overlay_needs_physical: no
<7> [336.098905] i915 0000:00:02.0: [drm:intel_display_driver_register [i915]] i915 display info: supports_tv: no
<7> [336.099154] i915 0000:00:02.0: [drm:intel_display_driver_register [i915]] i915 display info: has_hdcp: yes
<7> [336.099365] i915 0000:00:02.0: [drm:intel_display_driver_register [i915]] i915 display info: has_dmc: yes
<7> [336.099576] i915 0000:00:02.0: [drm:intel_display_driver_register [i915]] i915 display info: has_dsc: no
<7> [336.099834] i915 0000:00:02.0: [drm:intel_display_driver_register [i915]] i915 display info: rawclk rate: 24000 kHz
<7> [336.100518] [drm:intel_dsm_detect.isra.0 [i915]] no _DSM method for intel device
<7> [336.100728] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_D
<7> [336.100946] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_C
<7> [336.101252] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [336.101513] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_A_E
<7> [336.101728] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [336.102046] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: pciid=0x3e92 rev=0x00 platform=COFFEELAKE (subplatform=0x0) gen=9
<7> [336.102271] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: graphics version: 9
<7> [336.102482] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: media version: 9
<7> [336.102661] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: graphics stepping: **
<7> [336.102839] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: media stepping: **
<7> [336.103017] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: gt: 2
<7> [336.103236] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: memory-regions: 0x21
<7> [336.103415] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: page-sizes: 0x11000
<7> [336.103593] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: platform: COFFEELAKE
<7> [336.103771] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: ppgtt-size: 48
<7> [336.103950] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: ppgtt-type: 2
<7> [336.104155] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: dma_mask_size: 39
<7> [336.104383] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: is_mobile: no
<7> [336.104561] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: require_force_probe: no
<7> [336.104739] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: is_dgfx: no
<7> [336.104917] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: has_64bit_reloc: yes
<7> [336.105103] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: has_64k_pages: no
<7> [336.105316] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: gpu_reset_clobbers_display: no
<7> [336.105495] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: has_reset_engine: yes
<7> [336.105673] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: has_3d_pipeline: yes
<7> [336.105851] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: has_flat_ccs: no
<7> [336.106030] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: has_global_mocs: no
<7> [336.106264] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: has_gmd_id: no
<7> [336.106443] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: has_gt_uc: yes
<7> [336.106621] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: has_heci_pxp: no
<7> [336.106799] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: has_heci_gscfi: no
<7> [336.106978] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: has_guc_deprivilege: no
<7> [336.107195] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: has_guc_tlb_invalidation: no
<7> [336.107406] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: has_l3_ccs_read: no
<7> [336.107584] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: has_l3_dpf: no
<7> [336.107763] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: has_llc: yes
<7> [336.107942] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: has_logical_ring_contexts: yes
<7> [336.108161] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: has_logical_ring_elsq: no
<7> [336.108339] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: has_media_ratio_mode: no
<7> [336.108518] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: has_mslice_steering: no
<7> [336.108696] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: has_oa_bpc_reporting: no
<7> [336.108874] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: has_oa_slice_contrib_limits: no
<7> [336.109053] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: has_oam: no
<7> [336.109274] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: has_one_eu_per_fuse_bit: no
<7> [336.109452] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: has_pxp: no
<7> [336.109631] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: has_rc6: yes
<7> [336.109810] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: has_rc6p: no
<7> [336.109989] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: has_rps: yes
<7> [336.110238] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: has_runtime_pm: yes
<7> [336.110452] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: has_snoop: no
<7> [336.110630] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: has_coherent_ggtt: yes
<7> [336.110808] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: tuning_thread_rr_after_dep: no
<7> [336.110986] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: unfenced_needs_alignment: no
<7> [336.111208] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: hws_needs_physical: no
<7> [336.111387] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: has_pooled_eu: no
<7> [336.111565] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: iommu: disabled
<7> [336.111744] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: available engines: 40403
<7> [336.111922] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: slice total: 1, mask=0001
<7> [336.112132] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: subslice total: 3
<7> [336.112359] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: slice0: 3 subslices, mask=00000007
<7> [336.112538] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: slice1: 0 subslices, mask=00000000
<7> [336.112716] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: slice2: 0 subslices, mask=00000000
<7> [336.112895] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: EU total: 24
<7> [336.113073] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: EU per subslice: 8
<7> [336.113329] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: has slice power gating: no
<7> [336.113594] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: has subslice power gating: no
<7> [336.113699] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [336.113848] i915 0000:00:02.0: [drm:i915_driver_probe [i915]] device info: has EU power gating: yes
<6> [336.114135] i915 0000:00:02.0: [drm] DRM_I915_DEBUG enabled
<6> [336.114138] i915 0000:00:02.0: [drm] DRM_I915_DEBUG_GEM enabled
<6> [336.114140] i915 0000:00:02.0: [drm] DRM_I915_DEBUG_RUNTIME_PM enabled
<6> [336.114174] i915: Performing live selftests with st_random_seed=0x7f87af5c st_timeout=500
<6> [336.114177] i915: Running hugepages
<6> [336.114197] i915: Running i915_gem_huge_page_live_selftests/igt_shrink_thp
<6> [336.114202] missing THP support, skipping
<6> [336.114217] i915: Running i915_gem_huge_page_live_selftests/igt_tmpfs_fallback
<7> [336.114126] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [336.114392] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [336.114470] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [336.115242] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<3> [336.115638] __i915_gem_object_create_region:49 GEM_BUG_ON(flags & ~(((((1UL))) << (0)) | ((((1UL))) << (1)) | ((((1UL))) << (2)) | ((((1UL))) << (3)) | ((((1UL))) << (4)) | ((((1UL))) << (5)) | ((((1UL))) << (6)) | ((((1UL))) << (7)) | ((((1UL))) << (9))))
<0> [336.115644] Dumping ftrace buffer:
<0> [336.115647] ---------------------------------
<0> [336.115853] CPU:2 [LOST 103134 EVENTS]
gem_sync-5794 2..... 124851578us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35018, current 35016
<0> [336.115963] gem_sync-5794 2d.s2. 124851586us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35018, current 35016
<0> [336.116065] gem_sync-5794 2d.s1. 124851587us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.116203] gem_sync-5794 2d.s1. 124851587us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35018 prio 0 }
<0> [336.116312] gem_sync-5794 2..... 124851643us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence f0e:35016, current 35018
<0> [336.116420] gem_sync-5794 2..... 124851663us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35020, current 35018
<0> [336.116527] gem_sync-5794 2d.s2. 124851670us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35020, current 35018
<0> [336.116628] gem_sync-5794 2d.s1. 124851671us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.116729] gem_sync-5794 2d.s1. 124851672us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35020 prio 0 }
<0> [336.116837] gem_sync-5794 2..... 124851720us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence f0e:35018, current 35020
<0> [336.116946] gem_sync-5794 2..... 124851739us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35022, current 35020
<0> [336.117054] gem_sync-5794 2d.s2. 124851746us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35022, current 35020
<0> [336.117175] gem_sync-5794 2d.s1. 124851748us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.117321] gem_sync-5794 2d.s1. 124851748us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35022 prio 0 }
<0> [336.117448] gem_sync-5794 2..... 124851796us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence f0e:35020, current 35022
<0> [336.117570] gem_sync-5794 2..... 124851814us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35024, current 35022
<0> [336.117678] gem_sync-5794 2d.s2. 124851822us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35024, current 35022
<0> [336.117780] gem_sync-5794 2d.s1. 124851823us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.117880] gem_sync-5794 2d.s1. 124851824us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35024 prio 0 }
<0> [336.117988] gem_sync-5794 2..... 124851872us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence f0e:35022, current 35024
<0> [336.118115] gem_sync-5794 2..... 124851890us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35026, current 35024
<0> [336.118238] gem_sync-5794 2d.s2. 124851898us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35026, current 35024
<0> [336.118418] gem_sync-5794 2d.s1. 124851899us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.118519] gem_sync-5794 2d.s1. 124851900us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35026 prio 0 }
<0> [336.118619] gem_sync-5794 2..... 124851938us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e active
<0> [336.118719] gem_sync-5794 2..... 124852002us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e pin ring:{start:feda7000, head:1300, tail:1330}
<0> [336.118828] gem_sync-5794 2..... 124852038us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35028, current 35026
<0> [336.118936] gem_sync-5794 2d.s2. 124852046us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35028, current 35026
<0> [336.119037] gem_sync-5794 2d.s1. 124852047us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.119170] gem_sync-5794 2d.s1. 124852048us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35028 prio 0 }
<0> [336.119278] gem_sync-5794 2..... 124852109us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35030, current 35028
<0> [336.119386] gem_sync-5794 2d.s2. 124852116us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35030, current 35028
<0> [336.119487] gem_sync-5794 2d.s1. 124852118us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.119588] gem_sync-5794 2d.s1. 124852118us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35030 prio 0 }
<0> [336.119696] gem_sync-5794 2..... 124852198us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35032, current 35030
<0> [336.119804] gem_sync-5794 2d.s2. 124852205us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35032, current 35030
<0> [336.119905] gem_sync-5794 2d.s1. 124852206us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.120005] gem_sync-5794 2d.s1. 124852207us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35032 prio 0 }
<0> [336.120127] gem_sync-5794 2..... 124852245us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e active
<0> [336.120241] gem_sync-5794 2..... 124852253us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e pin ring:{start:feda7000, head:1468, tail:1498}
<0> [336.120350] gem_sync-5794 2..... 124852290us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35034, current 35032
<0> [336.120458] gem_sync-5794 2d.s2. 124852297us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35034, current 35032
<0> [336.120559] gem_sync-5794 2d.s1. 124852298us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.120660] gem_sync-5794 2d.s1. 124852299us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35034 prio 0 }
<0> [336.120760] gem_sync-5794 2..... 124852339us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e active
<0> [336.120859] gem_sync-5794 2..... 124852344us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e pin ring:{start:feda7000, head:14e0, tail:1510}
<0> [336.120968] gem_sync-5794 2..... 124852382us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35036, current 35034
<0> [336.121075] gem_sync-5794 2d.s2. 124852390us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35036, current 35034
<0> [336.121209] gem_sync-5794 2d.s1. 124852391us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.121309] gem_sync-5794 2d.s1. 124852392us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35036 prio 0 }
<0> [336.121418] gem_sync-5794 2..... 124852456us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35038, current 35036
<0> [336.121525] gem_sync-5794 2d.s2. 124852463us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35038, current 35036
<0> [336.121626] gem_sync-5794 2d.s1. 124852464us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.121727] gem_sync-5794 2d.s1. 124852465us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35038 prio 0 }
<0> [336.121836] gem_sync-5794 2..... 124852548us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35040, current 35038
<0> [336.121962] gem_sync-5794 2d.s2. 124852556us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35040, current 35038
<0> [336.122065] gem_sync-5794 2d.s1. 124852557us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.122183] gem_sync-5794 2d.s1. 124852558us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35040 prio 0 }
<0> [336.122283] gem_sync-5794 2..... 124852596us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e active
<0> [336.122435] gem_sync-5794 2..s3. 124852609us : rps_timer: 0000:00:02.0 busy:1158939 [58%], max:[794360, 518735, 420851], interval:2
<0> [336.122536] gem_sync-5794 2..... 124852658us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e pin ring:{start:feda7000, head:1648, tail:1678}
<0> [336.122640] kworker/-165 2..... 124852891us : rps_work: 0000:00:02.0 pm_iir:10, client_boost:no, last:0, cur:48, min:15, max:48
<0> [336.122751] kworker/-165 2..... 124852891us : rps_set: 0000:00:02.0 set val:47, freq:1183, swreq:23800000
<0> [336.122860] gem_sync-5794 2..... 124852927us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35042, current 35040
<0> [336.122968] gem_sync-5794 2d.s2. 124852934us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35042, current 35040
<0> [336.123069] gem_sync-5794 2d.s1. 124852935us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.123207] gem_sync-5794 2d.s1. 124852936us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35042 prio 0 }
<0> [336.123307] gem_sync-5794 2..... 124852980us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e active
<0> [336.123407] gem_sync-5794 2..... 124852983us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e pin ring:{start:feda7000, head:16c0, tail:16f0}
<0> [336.123515] gem_sync-5794 2..... 124853022us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35044, current 35042
<0> [336.123622] gem_sync-5794 2d.s2. 124853029us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35044, current 35042
<0> [336.123723] gem_sync-5794 2d.s1. 124853031us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.123824] gem_sync-5794 2d.s1. 124853031us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35044 prio 0 }
<0> [336.123932] gem_sync-5794 2..... 124853120us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35046, current 35044
<0> [336.124041] gem_sync-5794 2d.s2. 124853128us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35046, current 35044
<0> [336.124175] gem_sync-5794 2d.s1. 124853129us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.124276] gem_sync-5794 2d.s1. 124853130us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35046 prio 0 }
<0> [336.124384] gem_sync-5794 2..... 124853198us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35048, current 35046
<0> [336.124492] gem_sync-5794 2d.s2. 124853206us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35048, current 35046
<0> [336.124592] gem_sync-5794 2d.s1. 124853207us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.124693] gem_sync-5794 2d.s1. 124853208us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35048 prio 0 }
<0> [336.124802] gem_sync-5794 2..... 124853269us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35050, current 35048
<0> [336.124910] gem_sync-5794 2d.s2. 124853277us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35050, current 35048
<0> [336.125011] gem_sync-5794 2d.s1. 124853279us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.125139] gem_sync-5794 2d.s1. 124853279us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35050 prio 0 }
<0> [336.125262] gem_sync-5794 2..... 124853355us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35052, current 35050
<0> [336.125371] gem_sync-5794 2d.s2. 124853363us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35052, current 35050
<0> [336.125472] gem_sync-5794 2d.s1. 124853365us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.125573] gem_sync-5794 2d.s1. 124853365us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35052 prio 0 }
<0> [336.125682] gem_sync-5794 2..... 124853425us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35054, current 35052
<0> [336.125790] gem_sync-5794 2d.s2. 124853433us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35054, current 35052
<0> [336.125891] gem_sync-5794 2d.s1. 124853434us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.125991] gem_sync-5794 2d.s1. 124853434us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35054 prio 0 }
<0> [336.126120] gem_sync-5794 2..... 124853481us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence f0e:35052, current 35054
<0> [336.126243] gem_sync-5794 2..... 124853501us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35056, current 35054
<0> [336.126350] gem_sync-5794 2d.s2. 124853509us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35056, current 35054
<0> [336.126451] gem_sync-5794 2d.s1. 124853510us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.126567] gem_sync-5794 2d.s1. 124853511us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35056 prio 0 }
<0> [336.126676] gem_sync-5794 2..... 124853580us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35058, current 35056
<0> [336.126785] gem_sync-5794 2d.s2. 124853587us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35058, current 35056
<0> [336.126885] gem_sync-5794 2d.s1. 124853589us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.126986] gem_sync-5794 2d.s1. 124853589us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35058 prio 0 }
<0> [336.127095] gem_sync-5794 2..... 124853701us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35060, current 35058
<0> [336.127238] gem_sync-5794 2d.s2. 124853709us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35060, current 35058
<0> [336.127339] gem_sync-5794 2d.s1. 124853710us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.127440] gem_sync-5794 2d.s1. 124853711us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35060 prio 0 }
<0> [336.127548] gem_sync-5794 2..... 124853810us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35062, current 35060
<0> [336.127655] gem_sync-5794 2d.s2. 124853817us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35062, current 35060
<0> [336.127756] gem_sync-5794 2d.s1. 124853818us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.127857] gem_sync-5794 2d.s1. 124853819us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35062 prio 0 }
<0> [336.127957] gem_sync-5794 2..... 124853859us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e active
<0> [336.128056] gem_sync-5794 2..... 124853862us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e pin ring:{start:feda7000, head:1b70, tail:1ba0}
<0> [336.128198] gem_sync-5794 2..... 124853900us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35064, current 35062
<0> [336.128307] gem_sync-5794 2d.s2. 124853907us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35064, current 35062
<0> [336.128407] gem_sync-5794 2d.s1. 124853908us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.128508] gem_sync-5794 2d.s1. 124853909us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35064 prio 0 }
<0> [336.128616] gem_sync-5794 2..... 124853985us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35066, current 35064
<0> [336.128723] gem_sync-5794 2d.s2. 124853992us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35066, current 35064
<0> [336.128824] gem_sync-5794 2d.s1. 124853993us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.128925] gem_sync-5794 2d.s1. 124853994us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35066 prio 0 }
<0> [336.129032] gem_sync-5794 2..... 124854065us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35068, current 35066
<0> [336.129172] gem_sync-5794 2d.s2. 124854073us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35068, current 35066
<0> [336.129273] gem_sync-5794 2d.s1. 124854074us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.129374] gem_sync-5794 2d.s1. 124854075us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35068 prio 0 }
<0> [336.129482] gem_sync-5794 2..... 124854160us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35070, current 35068
<0> [336.129591] gem_sync-5794 2d.s2. 124854167us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35070, current 35068
<0> [336.129692] gem_sync-5794 2d.s1. 124854168us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.129793] gem_sync-5794 2d.s1. 124854169us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35070 prio 0 }
<0> [336.129901] gem_sync-5794 2..... 124854231us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35072, current 35070
<0> [336.130009] gem_sync-5794 2d.s2. 124854238us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35072, current 35070
<0> [336.130134] gem_sync-5794 2d.s1. 124854239us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.130249] gem_sync-5794 2d.s1. 124854240us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35072 prio 0 }
<0> [336.130357] gem_sync-5794 2..... 124854298us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35074, current 35072
<0> [336.130479] gem_sync-5794 2d.s2. 124854307us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35074, current 35072
<0> [336.130582] gem_sync-5794 2d.s1. 124854308us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.130682] gem_sync-5794 2d.s1. 124854308us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35074 prio 0 }
<0> [336.130790] gem_sync-5794 2..... 124854388us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35076, current 35074
<0> [336.130898] gem_sync-5794 2d.s2. 124854396us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35076, current 35074
<0> [336.130999] gem_sync-5794 2d.s1. 124854397us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.131102] gem_sync-5794 2d.s1. 124854398us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35076 prio 0 }
<0> [336.131245] gem_sync-5794 2..... 124854457us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35078, current 35076
<0> [336.131352] gem_sync-5794 2d.s2. 124854464us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35078, current 35076
<0> [336.131453] gem_sync-5794 2d.s1. 124854465us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.131553] gem_sync-5794 2d.s1. 124854466us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35078 prio 0 }
<0> [336.131662] gem_sync-5794 2..... 124854559us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35080, current 35078
<0> [336.131769] gem_sync-5794 2d.s2. 124854566us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35080, current 35078
<0> [336.131870] gem_sync-5794 2d.s1. 124854567us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.131971] gem_sync-5794 2d.s1. 124854568us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35080 prio 0 }
<0> [336.132074] gem_sync-5794 2..s.. 124854608us : rps_timer: 0000:00:02.0 busy:1409730 [70%], max:[982013, 673642, 363586], interval:2
<0> [336.132212] kworker/-165 2..... 124854619us : rps_work: 0000:00:02.0 pm_iir:10, client_boost:yes, last:0, cur:48, min:15, max:48
<0> [336.132320] gem_sync-5794 2..... 124854725us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35082, current 35080
<0> [336.132428] gem_sync-5794 2d.s2. 124854732us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35082, current 35080
<0> [336.132529] gem_sync-5794 2d.s1. 124854733us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.132630] gem_sync-5794 2d.s1. 124854734us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35082 prio 0 }
<0> [336.132737] gem_sync-5794 2..... 124854794us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35084, current 35082
<0> [336.132845] gem_sync-5794 2d.s2. 124854801us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35084, current 35082
<0> [336.132946] gem_sync-5794 2d.s1. 124854802us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.133047] gem_sync-5794 2d.s1. 124854803us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35084 prio 0 }
<0> [336.133193] gem_sync-5794 2..... 124854851us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence f0e:35082, current 35084
<0> [336.133301] gem_sync-5794 2..... 124854869us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35086, current 35084
<0> [336.133408] gem_sync-5794 2d.s2. 124854877us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35086, current 35084
<0> [336.133509] gem_sync-5794 2d.s1. 124854878us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.133610] gem_sync-5794 2d.s1. 124854879us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35086 prio 0 }
<0> [336.133718] gem_sync-5794 2..... 124854956us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35088, current 35086
<0> [336.133825] gem_sync-5794 2d.s2. 124854964us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35088, current 35086
<0> [336.133926] gem_sync-5794 2d.s1. 124854965us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.134026] gem_sync-5794 2d.s1. 124854965us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35088 prio 0 }
<0> [336.134172] gem_sync-5794 2..... 124855040us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35090, current 35088
<0> [336.134280] gem_sync-5794 2d.s2. 124855048us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35090, current 35088
<0> [336.134381] gem_sync-5794 2d.s1. 124855049us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.134481] gem_sync-5794 2d.s1. 124855050us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35090 prio 0 }
<0> [336.134590] gem_sync-5794 2..... 124855127us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35092, current 35090
<0> [336.134705] gem_sync-5794 2d.s2. 124855136us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35092, current 35090
<0> [336.134815] gem_sync-5794 2d.s1. 124855137us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.134916] gem_sync-5794 2d.s1. 124855137us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35092 prio 0 }
<0> [336.135024] gem_sync-5794 2..... 124855196us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35094, current 35092
<0> [336.135167] gem_sync-5794 2d.s2. 124855204us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35094, current 35092
<0> [336.135268] gem_sync-5794 2d.s1. 124855205us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.135369] gem_sync-5794 2d.s1. 124855206us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35094 prio 0 }
<0> [336.135477] gem_sync-5794 2..... 124855274us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35096, current 35094
<0> [336.135585] gem_sync-5794 2d.s2. 124855280us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35096, current 35094
<0> [336.135685] gem_sync-5794 2d.s1. 124855281us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.135786] gem_sync-5794 2d.s1. 124855282us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35096 prio 0 }
<0> [336.135894] gem_sync-5794 2..... 124855340us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35098, current 35096
<0> [336.136002] gem_sync-5794 2d.s2. 124855348us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35098, current 35096
<0> [336.136126] gem_sync-5794 2d.s1. 124855349us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.136241] gem_sync-5794 2d.s1. 124855350us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35098 prio 0 }
<0> [336.136341] gem_sync-5794 2..... 124855390us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e active
<0> [336.136441] gem_sync-5794 2..... 124855393us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e pin ring:{start:feda7000, head:23e0, tail:2410}
<0> [336.136541] gem_sync-5794 2..... 124855410us : __intel_context_do_unpin.part.0: 0000:00:02.0 vecs0: context:f0e unpin
<0> [336.136640] gem_sync-5794 2..... 124855413us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e active
<0> [336.136740] gem_sync-5794 2..... 124855415us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e pin ring:{start:feda7000, head:23e0, tail:2410}
<0> [336.136848] gem_sync-5794 2..... 124855452us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35100, current 35098
<0> [336.136956] gem_sync-5794 2d.s2. 124855460us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35100, current 35098
<0> [336.137057] gem_sync-5794 2d.s1. 124855461us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.137194] gem_sync-5794 2d.s1. 124855461us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35100 prio 0 }
<0> [336.137303] gem_sync-5794 2..... 124855520us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35102, current 35100
<0> [336.137411] gem_sync-5794 2d.s2. 124855528us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35102, current 35100
<0> [336.137511] gem_sync-5794 2d.s1. 124855529us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.137612] gem_sync-5794 2d.s1. 124855530us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35102 prio 0 }
<0> [336.137719] gem_sync-5794 2..... 124855578us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence f0e:35100, current 35102
<0> [336.137827] gem_sync-5794 2..... 124855597us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35104, current 35102
<0> [336.137935] gem_sync-5794 2d.s2. 124855610us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35104, current 35102
<0> [336.138036] gem_sync-5794 2d.s1. 124855611us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.138177] gem_sync-5794 2d.s1. 124855612us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35104 prio 0 }
<0> [336.138286] gem_sync-5794 2..... 124855701us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35106, current 35104
<0> [336.138394] gem_sync-5794 2d.s2. 124855709us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35106, current 35104
<0> [336.138495] gem_sync-5794 2d.s1. 124855710us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.138594] gem_sync-5794 2d.s1. 124855711us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35106 prio 0 }
<0> [336.138702] gem_sync-5794 2..... 124855784us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35108, current 35106
<0> [336.138810] gem_sync-5794 2d.s2. 124855792us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35108, current 35106
<0> [336.138911] gem_sync-5794 2d.s1. 124855793us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.139026] gem_sync-5794 2d.s1. 124855794us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35108 prio 0 }
<0> [336.139169] gem_sync-5794 2..... 124855854us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35110, current 35108
<0> [336.139278] gem_sync-5794 2d.s2. 124855861us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35110, current 35108
<0> [336.139379] gem_sync-5794 2d.s1. 124855862us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.139479] gem_sync-5794 2d.s1. 124855863us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35110 prio 0 }
<0> [336.139587] gem_sync-5794 2..... 124855911us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence f0e:35108, current 35110
<0> [336.139695] gem_sync-5794 2..... 124855929us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35112, current 35110
<0> [336.139802] gem_sync-5794 2d.s2. 124855937us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35112, current 35110
<0> [336.139903] gem_sync-5794 2d.s1. 124855938us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.140004] gem_sync-5794 2d.s1. 124855939us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35112 prio 0 }
<0> [336.140138] gem_sync-5794 2..... 124856013us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35114, current 35112
<0> [336.140261] gem_sync-5794 2d.s2. 124856021us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35114, current 35112
<0> [336.140361] gem_sync-5794 2d.s1. 124856022us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.140462] gem_sync-5794 2d.s1. 124856023us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35114 prio 0 }
<0> [336.140570] gem_sync-5794 2..... 124856082us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35116, current 35114
<0> [336.140677] gem_sync-5794 2d.s2. 124856090us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35116, current 35114
<0> [336.140778] gem_sync-5794 2d.s1. 124856091us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.140879] gem_sync-5794 2d.s1. 124856092us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35116 prio 0 }
<0> [336.140993] gem_sync-5794 2..... 124856128us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e active
<0> [336.141097] gem_sync-5794 2..... 124856130us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e pin ring:{start:feda7000, head:2818, tail:2848}
<0> [336.141208] gem_sync-5794 2..... 124856167us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35118, current 35116
<0> [336.141319] gem_sync-5794 2d.s2. 124856175us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35118, current 35116
<0> [336.141422] gem_sync-5794 2d.s1. 124856176us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.141526] gem_sync-5794 2d.s1. 124856177us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35118 prio 0 }
<0> [336.141636] gem_sync-5794 2..... 124856268us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35120, current 35118
<0> [336.141747] gem_sync-5794 2d.s2. 124856273us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35120, current 35118
<0> [336.141850] gem_sync-5794 2d.s1. 124856274us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.141953] gem_sync-5794 2d.s1. 124856275us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35120 prio 0 }
<0> [336.142064] gem_sync-5794 2..... 124856334us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35122, current 35120
<0> [336.142177] gem_sync-5794 2d.s2. 124856341us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35122, current 35120
<0> [336.142280] gem_sync-5794 2d.s1. 124856343us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.142383] gem_sync-5794 2d.s1. 124856343us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35122 prio 0 }
<0> [336.142494] gem_sync-5794 2..... 124856409us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35124, current 35122
<0> [336.142605] gem_sync-5794 2d.s2. 124856416us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35124, current 35122
<0> [336.142708] gem_sync-5794 2d.s1. 124856417us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.142821] gem_sync-5794 2d.s1. 124856418us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35124 prio 0 }
<0> [336.142938] gem_sync-5794 2..... 124856477us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35126, current 35124
<0> [336.143049] gem_sync-5794 2d.s2. 124856485us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35126, current 35124
<0> [336.143154] gem_sync-5794 2d.s1. 124856486us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.143257] gem_sync-5794 2d.s1. 124856487us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35126 prio 0 }
<0> [336.143368] gem_sync-5794 2..... 124856551us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35128, current 35126
<0> [336.143478] gem_sync-5794 2d.s2. 124856558us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35128, current 35126
<0> [336.143582] gem_sync-5794 2d.s1. 124856559us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.143684] gem_sync-5794 2d.s1. 124856560us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35128 prio 0 }
<0> [336.143791] gem_sync-5794 2..s1. 124856609us : rps_timer: 0000:00:02.0 busy:1419046 [70%], max:[1006566, 595091, 459743], interval:2
<0> [336.143896] kworker/-165 2..... 124856621us : rps_work: 0000:00:02.0 pm_iir:10, client_boost:yes, last:0, cur:48, min:15, max:48
<0> [336.144007] gem_sync-5794 2..... 124856673us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35130, current 35128
<0> [336.144120] gem_sync-5794 2d.s2. 124856681us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35130, current 35128
<0> [336.144224] gem_sync-5794 2d.s1. 124856683us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.144327] gem_sync-5794 2d.s1. 124856683us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35130 prio 0 }
<0> [336.144437] gem_sync-5794 2..... 124856749us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35132, current 35130
<0> [336.144547] gem_sync-5794 2d.s2. 124856757us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35132, current 35130
<0> [336.144650] gem_sync-5794 2d.s1. 124856758us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.144753] gem_sync-5794 2d.s1. 124856759us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35132 prio 0 }
<0> [336.144864] gem_sync-5794 2..... 124856817us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35134, current 35132
<0> [336.144974] gem_sync-5794 2d.s2. 124856825us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35134, current 35132
<0> [336.145077] gem_sync-5794 2d.s1. 124856826us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.145182] gem_sync-5794 2d.s1. 124856827us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35134 prio 0 }
<0> [336.145293] gem_sync-5794 2..... 124856873us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence f0e:35132, current 35134
<0> [336.145403] gem_sync-5794 2..... 124856892us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35136, current 35134
<0> [336.145513] gem_sync-5794 2d.s2. 124856899us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35136, current 35134
<0> [336.145616] gem_sync-5794 2d.s1. 124856901us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.145719] gem_sync-5794 2d.s1. 124856901us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35136 prio 0 }
<0> [336.145830] gem_sync-5794 2..... 124856948us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence f0e:35134, current 35136
<0> [336.145940] gem_sync-5794 2..... 124856967us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35138, current 35136
<0> [336.146050] gem_sync-5794 2d.s2. 124856975us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35138, current 35136
<0> [336.146155] gem_sync-5794 2d.s1. 124856976us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.146259] gem_sync-5794 2d.s1. 124856976us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35138 prio 0 }
<0> [336.146379] gem_sync-5794 2..... 124857065us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35140, current 35138
<0> [336.146494] gem_sync-5794 2d.s2. 124857072us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35140, current 35138
<0> [336.146598] gem_sync-5794 2d.s1. 124857073us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.146700] gem_sync-5794 2d.s1. 124857074us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35140 prio 0 }
<0> [336.146803] gem_sync-5794 2..... 124857114us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e active
<0> [336.146904] gem_sync-5794 2..... 124857117us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e pin ring:{start:feda7000, head:2db8, tail:2de8}
<0> [336.147015] gem_sync-5794 2..... 124857154us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35142, current 35140
<0> [336.147127] gem_sync-5794 2d.s2. 124857162us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35142, current 35140
<0> [336.147231] gem_sync-5794 2d.s1. 124857164us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.147334] gem_sync-5794 2d.s1. 124857165us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35142 prio 0 }
<0> [336.147444] gem_sync-5794 2..... 124857228us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35144, current 35142
<0> [336.147554] gem_sync-5794 2d.s2. 124857236us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35144, current 35142
<0> [336.147657] gem_sync-5794 2d.s1. 124857237us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.147759] gem_sync-5794 2d.s1. 124857238us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35144 prio 0 }
<0> [336.147870] gem_sync-5794 2..... 124857316us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35146, current 35144
<0> [336.147979] gem_sync-5794 2d.s2. 124857324us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35146, current 35144
<0> [336.148082] gem_sync-5794 2d.s1. 124857325us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.148187] gem_sync-5794 2d.s1. 124857326us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35146 prio 0 }
<0> [336.148297] gem_sync-5794 2..... 124857401us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35148, current 35146
<0> [336.148408] gem_sync-5794 2d.s2. 124857409us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35148, current 35146
<0> [336.148512] gem_sync-5794 2d.s1. 124857410us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.148615] gem_sync-5794 2d.s1. 124857411us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35148 prio 0 }
<0> [336.148716] gem_sync-5794 2..... 124857449us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e active
<0> [336.148818] gem_sync-5794 2..... 124857452us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e pin ring:{start:feda7000, head:2f98, tail:2fc8}
<0> [336.148929] gem_sync-5794 2..... 124857488us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35150, current 35148
<0> [336.149039] gem_sync-5794 2d.s2. 124857496us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35150, current 35148
<0> [336.149144] gem_sync-5794 2d.s1. 124857497us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.149247] gem_sync-5794 2d.s1. 124857497us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35150 prio 0 }
<0> [336.149358] gem_sync-5794 2..... 124857556us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35152, current 35150
<0> [336.149468] gem_sync-5794 2d.s2. 124857564us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35152, current 35150
<0> [336.149572] gem_sync-5794 2d.s1. 124857565us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.149674] gem_sync-5794 2d.s1. 124857566us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35152 prio 0 }
<0> [336.149785] gem_sync-5794 2..... 124857619us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence f0e:35150, current 35152
<0> [336.149895] gem_sync-5794 2..... 124857665us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35154, current 35152
<0> [336.150010] gem_sync-5794 2d.s2. 124857689us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35154, current 35152
<0> [336.150127] gem_sync-5794 2d.s1. 124857690us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.150230] gem_sync-5794 2d.s1. 124857691us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35154 prio 0 }
<0> [336.150341] gem_sync-5794 2..... 124857739us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence f0e:35152, current 35154
<0> [336.150451] gem_sync-5794 2..... 124857758us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35156, current 35154
<0> [336.150561] gem_sync-5794 2d.s2. 124857765us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35156, current 35154
<0> [336.150664] gem_sync-5794 2d.s1. 124857766us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.150767] gem_sync-5794 2d.s1. 124857767us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35156 prio 0 }
<0> [336.150878] gem_sync-5794 2..... 124857876us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35158, current 35156
<0> [336.150988] gem_sync-5794 2d.s2. 124857884us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35158, current 35156
<0> [336.151092] gem_sync-5794 2d.s1. 124857885us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.151197] gem_sync-5794 2d.s1. 124857886us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35158 prio 0 }
<0> [336.151307] gem_sync-5794 2..... 124857943us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35160, current 35158
<0> [336.151417] gem_sync-5794 2d.s2. 124857948us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35160, current 35158
<0> [336.151521] gem_sync-5794 2d.s1. 124857950us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.151624] gem_sync-5794 2d.s1. 124857950us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35160 prio 0 }
<0> [336.151734] gem_sync-5794 2..... 124857998us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence f0e:35158, current 35160
<0> [336.151845] gem_sync-5794 2..... 124858017us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35162, current 35160
<0> [336.151955] gem_sync-5794 2d.s2. 124858025us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35162, current 35160
<0> [336.152058] gem_sync-5794 2d.s1. 124858026us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.152162] gem_sync-5794 2d.s1. 124858027us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35162 prio 0 }
<0> [336.152273] gem_sync-5794 2..... 124858116us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35164, current 35162
<0> [336.152384] gem_sync-5794 2d.s2. 124858124us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35164, current 35162
<0> [336.152487] gem_sync-5794 2d.s1. 124858125us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.152590] gem_sync-5794 2d.s1. 124858126us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35164 prio 0 }
<0> [336.152700] gem_sync-5794 2..... 124858189us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35166, current 35164
<0> [336.152810] gem_sync-5794 2d.s2. 124858196us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35166, current 35164
<0> [336.152914] gem_sync-5794 2d.s1. 124858197us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.153017] gem_sync-5794 2d.s1. 124858198us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35166 prio 0 }
<0> [336.153121] gem_sync-5794 2..... 124858238us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e active
<0> [336.153223] gem_sync-5794 2..... 124858240us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e pin ring:{start:feda7000, head:33d0, tail:3400}
<0> [336.153334] gem_sync-5794 2..... 124858277us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35168, current 35166
<0> [336.153444] gem_sync-5794 2d.s2. 124858285us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35168, current 35166
<0> [336.153547] gem_sync-5794 2d.s1. 124858286us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.153663] gem_sync-5794 2d.s1. 124858287us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35168 prio 0 }
<0> [336.153766] gem_sync-5794 2..... 124858326us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e active
<0> [336.153868] gem_sync-5794 2..... 124858329us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e pin ring:{start:feda7000, head:3448, tail:3478}
<0> [336.153979] gem_sync-5794 2..... 124858365us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35170, current 35168
<0> [336.154089] gem_sync-5794 2d.s2. 124858373us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35170, current 35168
<0> [336.154195] gem_sync-5794 2d.s1. 124858375us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.154298] gem_sync-5794 2d.s1. 124858375us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35170 prio 0 }
<0> [336.154400] gem_sync-5794 2..... 124858414us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e active
<0> [336.154502] gem_sync-5794 2..... 124858417us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e pin ring:{start:feda7000, head:34c0, tail:34f0}
<0> [336.154612] gem_sync-5794 2..... 124858452us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35172, current 35170
<0> [336.154723] gem_sync-5794 2d.s2. 124858459us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35172, current 35170
<0> [336.154826] gem_sync-5794 2d.s1. 124858460us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.154928] gem_sync-5794 2d.s1. 124858461us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35172 prio 0 }
<0> [336.155040] gem_sync-5794 2..... 124858549us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35174, current 35172
<0> [336.155153] gem_sync-5794 2d.s2. 124858556us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35174, current 35172
<0> [336.155257] gem_sync-5794 2d.s1. 124858557us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.155359] gem_sync-5794 2d.s1. 124858558us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35174 prio 0 }
<0> [336.155465] gem_sync-5794 2..s1. 124858613us : rps_timer: 0000:00:02.0 busy:1298468 [64%], max:[907940, 575244, 411627], interval:2
<0> [336.155571] kworker/-165 2..... 124858653us : rps_work: 0000:00:02.0 pm_iir:10, client_boost:no, last:0, cur:48, min:15, max:48
<0> [336.155676] kworker/-165 2..... 124858654us : rps_set: 0000:00:02.0 set val:47, freq:1183, swreq:23800000
<0> [336.155787] gem_sync-5794 2..... 124858698us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35176, current 35174
<0> [336.155898] gem_sync-5794 2d.s2. 124858706us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35176, current 35174
<0> [336.156001] gem_sync-5794 2d.s1. 124858707us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.156106] gem_sync-5794 2d.s1. 124858708us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35176 prio 0 }
<0> [336.156217] gem_sync-5794 2..... 124858768us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35178, current 35176
<0> [336.156327] gem_sync-5794 2d.s2. 124858775us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35178, current 35176
<0> [336.156430] gem_sync-5794 2d.s1. 124858776us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.156534] gem_sync-5794 2d.s1. 124858777us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35178 prio 0 }
<0> [336.156636] gem_sync-5794 2..... 124858827us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e active
<0> [336.156738] gem_sync-5794 2..... 124858830us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e pin ring:{start:feda7000, head:36a0, tail:36d0}
<0> [336.156848] gem_sync-5794 2..... 124858866us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35180, current 35178
<0> [336.156958] gem_sync-5794 2d.s2. 124858874us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35180, current 35178
<0> [336.157060] gem_sync-5794 2d.s1. 124858875us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.157179] gem_sync-5794 2d.s1. 124858876us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35180 prio 0 }
<0> [336.157290] gem_sync-5794 2..... 124858954us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35182, current 35180
<0> [336.157401] gem_sync-5794 2d.s2. 124858962us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35182, current 35180
<0> [336.157505] gem_sync-5794 2d.s1. 124858963us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.157608] gem_sync-5794 2d.s1. 124858964us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35182 prio 0 }
<0> [336.157709] gem_sync-5794 2..... 124859003us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e active
<0> [336.157811] gem_sync-5794 2..... 124859036us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e pin ring:{start:feda7000, head:3790, tail:37c0}
<0> [336.157921] gem_sync-5794 2..... 124859070us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35184, current 35182
<0> [336.158031] gem_sync-5794 2d.s2. 124859077us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35184, current 35182
<0> [336.158137] gem_sync-5794 2d.s1. 124859079us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.158241] gem_sync-5794 2d.s1. 124859079us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35184 prio 0 }
<0> [336.158351] gem_sync-5794 2..... 124859153us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35186, current 35184
<0> [336.158461] gem_sync-5794 2d.s2. 124859161us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35186, current 35184
<0> [336.158564] gem_sync-5794 2d.s1. 124859162us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.158667] gem_sync-5794 2d.s1. 124859163us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35186 prio 0 }
<0> [336.158778] gem_sync-5794 2..... 124859223us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35188, current 35186
<0> [336.158888] gem_sync-5794 2d.s2. 124859231us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35188, current 35186
<0> [336.158992] gem_sync-5794 2d.s1. 124859232us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.159096] gem_sync-5794 2d.s1. 124859232us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35188 prio 0 }
<0> [336.159207] gem_sync-5794 2..... 124859320us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35190, current 35188
<0> [336.159318] gem_sync-5794 2d.s2. 124859327us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35190, current 35188
<0> [336.159421] gem_sync-5794 2d.s1. 124859328us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.159524] gem_sync-5794 2d.s1. 124859329us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35190 prio 0 }
<0> [336.159634] gem_sync-5794 2..... 124859390us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35192, current 35190
<0> [336.159745] gem_sync-5794 2d.s2. 124859398us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35192, current 35190
<0> [336.159848] gem_sync-5794 2d.s1. 124859399us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.159950] gem_sync-5794 2d.s1. 124859399us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35192 prio 0 }
<0> [336.160061] gem_sync-5794 2..... 124859459us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35194, current 35192
<0> [336.160173] gem_sync-5794 2d.s2. 124859467us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35194, current 35192
<0> [336.160276] gem_sync-5794 2d.s1. 124859468us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.160379] gem_sync-5794 2d.s1. 124859469us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35194 prio 0 }
<0> [336.160490] gem_sync-5794 2..... 124859536us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35196, current 35194
<0> [336.160601] gem_sync-5794 2d.s2. 124859543us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35196, current 35194
<0> [336.160718] gem_sync-5794 2d.s1. 124859545us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.160822] gem_sync-5794 2d.s1. 124859545us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35196 prio 0 }
<0> [336.160932] gem_sync-5794 2..... 124860049us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35198, current 35196
<0> [336.161043] gem_sync-5794 2d.s2. 124860056us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35198, current 35196
<0> [336.161148] gem_sync-5794 2d.s1. 124860058us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.161251] gem_sync-5794 2d.s1. 124860058us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35198 prio 0 }
<0> [336.161361] gem_sync-5794 2..... 124860112us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence f0e:35196, current 35198
<0> [336.161472] gem_sync-5794 2..... 124860132us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35200, current 35198
<0> [336.161582] gem_sync-5794 2d.s2. 124860139us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35200, current 35198
<0> [336.161685] gem_sync-5794 2d.s1. 124860141us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.161788] gem_sync-5794 2d.s1. 124860141us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35200 prio 0 }
<0> [336.161899] gem_sync-5794 2..... 124860207us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35202, current 35200
<0> [336.162009] gem_sync-5794 2d.s2. 124860214us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35202, current 35200
<0> [336.162115] gem_sync-5794 2d.s1. 124860216us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.162218] gem_sync-5794 2d.s1. 124860216us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35202 prio 0 }
<0> [336.162329] gem_sync-5794 2..... 124860276us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35204, current 35202
<0> [336.162439] gem_sync-5794 2d.s2. 124860284us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35204, current 35202
<0> [336.162542] gem_sync-5794 2d.s1. 124860285us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.162646] gem_sync-5794 2d.s1. 124860286us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35204 prio 0 }
<0> [336.162756] gem_sync-5794 2..... 124860355us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35206, current 35204
<0> [336.162866] gem_sync-5794 2d.s2. 124860362us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35206, current 35204
<0> [336.162969] gem_sync-5794 2d.s1. 124860363us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.163072] gem_sync-5794 2d.s1. 124860364us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35206 prio 0 }
<0> [336.163176] gem_sync-5794 2..... 124860404us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e active
<0> [336.163278] gem_sync-5794 2..... 124860407us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e pin ring:{start:feda7000, head:3d30, tail:3d60}
<0> [336.163388] gem_sync-5794 2..... 124860448us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35208, current 35206
<0> [336.163498] gem_sync-5794 2d.s2. 124860456us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35208, current 35206
<0> [336.163602] gem_sync-5794 2d.s1. 124860457us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.163704] gem_sync-5794 2d.s1. 124860458us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35208 prio 0 }
<0> [336.163815] gem_sync-5794 2..... 124860543us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35210, current 35208
<0> [336.163925] gem_sync-5794 2d.s2. 124860551us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35210, current 35208
<0> [336.164029] gem_sync-5794 2d.s1. 124860552us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.164148] gem_sync-5794 2d.s1. 124860552us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35210 prio 0 }
<0> [336.164255] gem_sync-5794 2..s1. 124860608us : rps_timer: 0000:00:02.0 busy:1376941 [69%], max:[942991, 675622, 384557], interval:2
<0> [336.164361] kworker/-165 2..... 124860647us : rps_work: 0000:00:02.0 pm_iir:10, client_boost:yes, last:0, cur:48, min:15, max:48
<0> [336.164472] gem_sync-5794 2..... 124860665us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35212, current 35210
<0> [336.164582] gem_sync-5794 2d.s2. 124860670us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35212, current 35210
<0> [336.164685] gem_sync-5794 2d.s1. 124860671us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.164788] gem_sync-5794 2d.s1. 124860672us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35212 prio 0 }
<0> [336.164899] gem_sync-5794 2..... 124860751us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35214, current 35212
<0> [336.165009] gem_sync-5794 2d.s2. 124860756us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35214, current 35212
<0> [336.165115] gem_sync-5794 2d.s1. 124860757us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.165218] gem_sync-5794 2d.s1. 124860758us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35214 prio 0 }
<0> [336.165329] gem_sync-5794 2..... 124860827us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35216, current 35214
<0> [336.165440] gem_sync-5794 2d.s2. 124860831us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35216, current 35214
<0> [336.165543] gem_sync-5794 2d.s1. 124860832us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.165646] gem_sync-5794 2d.s1. 124860833us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35216 prio 0 }
<0> [336.165756] gem_sync-5794 2..... 124860899us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35218, current 35216
<0> [336.165866] gem_sync-5794 2d.s2. 124860904us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35218, current 35216
<0> [336.165969] gem_sync-5794 2d.s1. 124860905us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.166072] gem_sync-5794 2d.s1. 124860905us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35218 prio 0 }
<0> [336.166184] gem_sync-5794 2..... 124860971us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35220, current 35218
<0> [336.166295] gem_sync-5794 2d.s2. 124860976us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35220, current 35218
<0> [336.166397] gem_sync-5794 2d.s1. 124860977us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.166500] gem_sync-5794 2d.s1. 124860977us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35220 prio 0 }
<0> [336.166610] gem_sync-5794 2..... 124861060us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35222, current 35220
<0> [336.166720] gem_sync-5794 2d.s2. 124861070us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35222, current 35220
<0> [336.166825] gem_sync-5794 2d.s1. 124861071us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.166927] gem_sync-5794 2d.s1. 124861072us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35222 prio 0 }
<0> [336.167038] gem_sync-5794 2..... 124861135us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35224, current 35222
<0> [336.167150] gem_sync-5794 2d.s2. 124861142us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35224, current 35222
<0> [336.167254] gem_sync-5794 2d.s1. 124861144us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.167357] gem_sync-5794 2d.s1. 124861144us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35224 prio 0 }
<0> [336.167467] gem_sync-5794 2..... 124861204us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35226, current 35224
<0> [336.167581] gem_sync-5794 2d.s2. 124861212us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35226, current 35224
<0> [336.167696] gem_sync-5794 2d.s1. 124861213us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.167799] gem_sync-5794 2d.s1. 124861214us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35226 prio 0 }
<0> [336.167910] gem_sync-5794 2..... 124861273us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35228, current 35226
<0> [336.168021] gem_sync-5794 2d.s2. 124861281us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35228, current 35226
<0> [336.168127] gem_sync-5794 2d.s1. 124861282us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.168230] gem_sync-5794 2d.s1. 124861283us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35228 prio 0 }
<0> [336.168342] gem_sync-5794 2..... 124861345us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35230, current 35228
<0> [336.168452] gem_sync-5794 2d.s2. 124861350us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35230, current 35228
<0> [336.168556] gem_sync-5794 2d.s1. 124861352us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.168658] gem_sync-5794 2d.s1. 124861352us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35230 prio 0 }
<0> [336.168769] gem_sync-5794 2..... 124861419us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35232, current 35230
<0> [336.168879] gem_sync-5794 2d.s2. 124861425us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35232, current 35230
<0> [336.168982] gem_sync-5794 2d.s1. 124861426us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.169085] gem_sync-5794 2d.s1. 124861427us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35232 prio 0 }
<0> [336.169198] gem_sync-5794 2..... 124861488us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35234, current 35232
<0> [336.169308] gem_sync-5794 2d.s2. 124861494us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35234, current 35232
<0> [336.169411] gem_sync-5794 2d.s1. 124861495us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.169514] gem_sync-5794 2d.s1. 124861496us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35234 prio 0 }
<0> [336.169625] gem_sync-5794 2..... 124861557us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35236, current 35234
<0> [336.169735] gem_sync-5794 2d.s2. 124861564us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35236, current 35234
<0> [336.169838] gem_sync-5794 2d.s1. 124861565us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.169941] gem_sync-5794 2d.s1. 124861566us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35236 prio 0 }
<0> [336.170051] gem_sync-5794 2..... 124861692us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35238, current 35236
<0> [336.170164] gem_sync-5794 2d.s2. 124861699us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35238, current 35236
<0> [336.170267] gem_sync-5794 2d.s1. 124861700us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.170370] gem_sync-5794 2d.s1. 124861701us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35238 prio 0 }
<0> [336.170481] gem_sync-5794 2..... 124861784us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35240, current 35238
<0> [336.170592] gem_sync-5794 2d.s2. 124861791us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35240, current 35238
<0> [336.170695] gem_sync-5794 2d.s1. 124861792us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.170798] gem_sync-5794 2d.s1. 124861793us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35240 prio 0 }
<0> [336.170899] gem_sync-5794 2..... 124861831us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e active
<0> [336.171012] gem_sync-5794 2..... 124861834us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e pin ring:{start:feda7000, head:0528, tail:0558}
<0> [336.171129] gem_sync-5794 2..... 124861871us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35242, current 35240
<0> [336.171240] gem_sync-5794 2d.s2. 124861879us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35242, current 35240
<0> [336.171343] gem_sync-5794 2d.s1. 124861880us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.171446] gem_sync-5794 2d.s1. 124861881us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35242 prio 0 }
<0> [336.171548] gem_sync-5794 2..... 124861922us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e active
<0> [336.171650] gem_sync-5794 2..... 124861924us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e pin ring:{start:feda7000, head:05a0, tail:05d0}
<0> [336.171760] gem_sync-5794 2..... 124861962us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35244, current 35242
<0> [336.171870] gem_sync-5794 2d.s2. 124861970us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35244, current 35242
<0> [336.171973] gem_sync-5794 2d.s1. 124861971us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.172075] gem_sync-5794 2d.s1. 124861972us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35244 prio 0 }
<0> [336.172187] gem_sync-5794 2..... 124862032us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35246, current 35244
<0> [336.172297] gem_sync-5794 2d.s2. 124862037us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35246, current 35244
<0> [336.172400] gem_sync-5794 2d.s1. 124862038us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.172503] gem_sync-5794 2d.s1. 124862039us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35246 prio 0 }
<0> [336.172614] gem_sync-5794 2..... 124862085us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence f0e:35244, current 35246
<0> [336.172724] gem_sync-5794 2..... 124862104us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35248, current 35246
<0> [336.172842] gem_sync-5794 2d.s2. 124862112us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35248, current 35246
<0> [336.172946] gem_sync-5794 2d.s1. 124862113us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.173049] gem_sync-5794 2d.s1. 124862114us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35248 prio 0 }
<0> [336.173161] gem_sync-5794 2..... 124862179us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35250, current 35248
<0> [336.173273] gem_sync-5794 2d.s2. 124862184us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35250, current 35248
<0> [336.173376] gem_sync-5794 2d.s1. 124862185us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.173479] gem_sync-5794 2d.s1. 124862186us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35250 prio 0 }
<0> [336.173590] gem_sync-5794 2..... 124862245us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35252, current 35250
<0> [336.173700] gem_sync-5794 2d.s2. 124862253us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35252, current 35250
<0> [336.173803] gem_sync-5794 2d.s1. 124862254us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.173906] gem_sync-5794 2d.s1. 124862255us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35252 prio 0 }
<0> [336.174017] gem_sync-5794 2..... 124862332us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35254, current 35252
<0> [336.174129] gem_sync-5794 2d.s2. 124862340us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35254, current 35252
<0> [336.174232] gem_sync-5794 2d.s1. 124862341us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.174335] gem_sync-5794 2d.s1. 124862342us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35254 prio 0 }
<0> [336.174459] gem_sync-5794 2..... 124862402us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35256, current 35254
<0> [336.174570] gem_sync-5794 2d.s2. 124862409us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35256, current 35254
<0> [336.174674] gem_sync-5794 2d.s1. 124862410us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.174777] gem_sync-5794 2d.s1. 124862411us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35256 prio 0 }
<0> [336.174888] gem_sync-5794 2..... 124862493us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35258, current 35256
<0> [336.175000] gem_sync-5794 2d.s2. 124862502us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35258, current 35256
<0> [336.175106] gem_sync-5794 2d.s1. 124862503us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.175209] gem_sync-5794 2d.s1. 124862504us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35258 prio 0 }
<0> [336.175320] gem_sync-5794 2..... 124862586us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35260, current 35258
<0> [336.175430] gem_sync-5794 2d.s2. 124862594us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35260, current 35258
<0> [336.175533] gem_sync-5794 2d.s1. 124862595us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.175636] gem_sync-5794 2d.s1. 124862596us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35260 prio 0 }
<0> [336.175742] gem_sync-5794 2..s1. 124862635us : rps_timer: 0000:00:02.0 busy:1384636 [68%], max:[965205, 565151, 547426], interval:2
<0> [336.175849] kworker/-165 2..... 124862650us : rps_work: 0000:00:02.0 pm_iir:10, client_boost:no, last:0, cur:48, min:15, max:48
<0> [336.175954] kworker/-165 2..... 124862650us : rps_set: 0000:00:02.0 set val:47, freq:1183, swreq:23800000
<0> [336.176056] gem_sync-5794 2..... 124862704us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e active
<0> [336.176161] gem_sync-5794 2..... 124862707us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e pin ring:{start:feda7000, head:09d8, tail:0a08}
<0> [336.176272] gem_sync-5794 2..... 124862753us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35262, current 35260
<0> [336.176382] gem_sync-5794 2d.s2. 124862760us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35262, current 35260
<0> [336.176485] gem_sync-5794 2d.s1. 124862762us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.176588] gem_sync-5794 2d.s1. 124862762us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35262 prio 0 }
<0> [336.176698] gem_sync-5794 2..... 124862822us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35264, current 35262
<0> [336.176808] gem_sync-5794 2d.s2. 124862829us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35264, current 35262
<0> [336.176912] gem_sync-5794 2d.s1. 124862830us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.177015] gem_sync-5794 2d.s1. 124862831us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35264 prio 0 }
<0> [336.177127] gem_sync-5794 2..... 124862890us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35266, current 35264
<0> [336.177237] gem_sync-5794 2d.s2. 124862898us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35266, current 35264
<0> [336.177341] gem_sync-5794 2d.s1. 124862899us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.177444] gem_sync-5794 2d.s1. 124862900us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35266 prio 0 }
<0> [336.177555] gem_sync-5794 2..... 124862947us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence f0e:35264, current 35266
<0> [336.177666] gem_sync-5794 2..... 124862962us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35268, current 35266
<0> [336.177777] gem_sync-5794 2d.s2. 124862969us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35268, current 35266
<0> [336.177893] gem_sync-5794 2d.s1. 124862970us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.177996] gem_sync-5794 2d.s1. 124862971us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35268 prio 0 }
<0> [336.178109] gem_sync-5794 2..... 124863049us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35270, current 35268
<0> [336.178219] gem_sync-5794 2d.s2. 124863057us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35270, current 35268
<0> [336.178323] gem_sync-5794 2d.s1. 124863058us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.178426] gem_sync-5794 2d.s1. 124863059us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35270 prio 0 }
<0> [336.178527] gem_sync-5794 2..... 124863098us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e active
<0> [336.178630] gem_sync-5794 2..... 124863101us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e pin ring:{start:feda7000, head:0c30, tail:0c60}
<0> [336.178741] gem_sync-5794 2..... 124863138us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35272, current 35270
<0> [336.178851] gem_sync-5794 2d.s2. 124863146us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35272, current 35270
<0> [336.178954] gem_sync-5794 2d.s1. 124863147us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.179056] gem_sync-5794 2d.s1. 124863148us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35272 prio 0 }
<0> [336.179168] gem_sync-5794 2..... 124863208us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35274, current 35272
<0> [336.179279] gem_sync-5794 2d.s2. 124863216us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35274, current 35272
<0> [336.179382] gem_sync-5794 2d.s1. 124863217us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.179485] gem_sync-5794 2d.s1. 124863219us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35274 prio 0 }
<0> [336.179596] gem_sync-5794 2..... 124863307us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35276, current 35274
<0> [336.179706] gem_sync-5794 2d.s2. 124863315us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35276, current 35274
<0> [336.179810] gem_sync-5794 2d.s1. 124863316us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.179913] gem_sync-5794 2d.s1. 124863317us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35276 prio 0 }
<0> [336.180023] gem_sync-5794 2..... 124863382us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35278, current 35276
<0> [336.180136] gem_sync-5794 2d.s2. 124863390us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35278, current 35276
<0> [336.180239] gem_sync-5794 2d.s1. 124863391us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.180343] gem_sync-5794 2d.s1. 124863391us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35278 prio 0 }
<0> [336.180445] gem_sync-5794 2..... 124863430us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e active
<0> [336.180546] gem_sync-5794 2..... 124863433us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e pin ring:{start:feda7000, head:0e10, tail:0e40}
<0> [336.180657] gem_sync-5794 2..... 124863469us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35280, current 35278
<0> [336.180768] gem_sync-5794 2d.s2. 124863476us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35280, current 35278
<0> [336.180872] gem_sync-5794 2d.s1. 124863478us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.180975] gem_sync-5794 2d.s1. 124863479us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35280 prio 0 }
<0> [336.181085] gem_sync-5794 2..... 124863538us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35282, current 35280
<0> [336.181211] gem_sync-5794 2d.s2. 124863546us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35282, current 35280
<0> [336.181315] gem_sync-5794 2d.s1. 124863547us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.181418] gem_sync-5794 2d.s1. 124863548us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35282 prio 0 }
<0> [336.181529] gem_sync-5794 2..... 124863595us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence f0e:35280, current 35282
<0> [336.181639] gem_sync-5794 2..... 124863621us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35284, current 35282
<0> [336.181749] gem_sync-5794 2d.s2. 124863629us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35284, current 35282
<0> [336.181853] gem_sync-5794 2d.s1. 124863630us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.181956] gem_sync-5794 2d.s1. 124863631us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35284 prio 0 }
<0> [336.182066] gem_sync-5794 2..... 124863722us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35286, current 35284
<0> [336.182179] gem_sync-5794 2d.s2. 124863729us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35286, current 35284
<0> [336.182282] gem_sync-5794 2d.s1. 124863730us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.182384] gem_sync-5794 2d.s1. 124863731us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35286 prio 0 }
<0> [336.182495] gem_sync-5794 2..... 124863791us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35288, current 35286
<0> [336.182605] gem_sync-5794 2d.s2. 124863799us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35288, current 35286
<0> [336.182708] gem_sync-5794 2d.s1. 124863800us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.182811] gem_sync-5794 2d.s1. 124863801us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35288 prio 0 }
<0> [336.182921] gem_sync-5794 2..... 124863875us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35290, current 35288
<0> [336.183031] gem_sync-5794 2d.s2. 124863883us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35290, current 35288
<0> [336.183136] gem_sync-5794 2d.s1. 124863884us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.183239] gem_sync-5794 2d.s1. 124863885us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35290 prio 0 }
<0> [336.183350] gem_sync-5794 2..... 124863958us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35292, current 35290
<0> [336.183460] gem_sync-5794 2d.s2. 124863966us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35292, current 35290
<0> [336.183563] gem_sync-5794 2d.s1. 124863967us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.183666] gem_sync-5794 2d.s1. 124863968us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35292 prio 0 }
<0> [336.183776] gem_sync-5794 2..... 124864069us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35294, current 35292
<0> [336.183887] gem_sync-5794 2d.s2. 124864077us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35294, current 35292
<0> [336.183990] gem_sync-5794 2d.s1. 124864078us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.184094] gem_sync-5794 2d.s1. 124864078us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35294 prio 0 }
<0> [336.184205] gem_sync-5794 2..... 124864156us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35296, current 35294
<0> [336.184315] gem_sync-5794 2d.s2. 124864164us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35296, current 35294
<0> [336.184418] gem_sync-5794 2d.s1. 124864165us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.184521] gem_sync-5794 2d.s1. 124864166us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35296 prio 0 }
<0> [336.184632] gem_sync-5794 2..... 124864251us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35298, current 35296
<0> [336.184757] gem_sync-5794 2d.s2. 124864258us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35298, current 35296
<0> [336.184860] gem_sync-5794 2d.s1. 124864260us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.184963] gem_sync-5794 2d.s1. 124864260us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35298 prio 0 }
<0> [336.185074] gem_sync-5794 2..... 124864345us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35300, current 35298
<0> [336.185187] gem_sync-5794 2d.s2. 124864353us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35300, current 35298
<0> [336.185290] gem_sync-5794 2d.s1. 124864354us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.185392] gem_sync-5794 2d.s1. 124864355us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35300 prio 0 }
<0> [336.185494] gem_sync-5794 2..... 124864395us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e active
<0> [336.185595] gem_sync-5794 2..... 124864398us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e pin ring:{start:feda7000, head:1338, tail:1368}
<0> [336.185706] gem_sync-5794 2..... 124864435us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35302, current 35300
<0> [336.185816] gem_sync-5794 2d.s2. 124864444us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35302, current 35300
<0> [336.185919] gem_sync-5794 2d.s1. 124864445us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.186021] gem_sync-5794 2d.s1. 124864446us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35302 prio 0 }
<0> [336.186133] gem_sync-5794 2..... 124864505us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35304, current 35302
<0> [336.186244] gem_sync-5794 2d.s2. 124864513us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35304, current 35302
<0> [336.186347] gem_sync-5794 2d.s1. 124864514us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.186450] gem_sync-5794 2d.s1. 124864515us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35304 prio 0 }
<0> [336.186560] gem_sync-5794 2..... 124864563us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence f0e:35302, current 35304
<0> [336.186671] gem_sync-5794 2..... 124864581us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35306, current 35304
<0> [336.186781] gem_sync-5794 2d.s2. 124864588us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35306, current 35304
<0> [336.186884] gem_sync-5794 2d.s1. 124864590us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.186987] gem_sync-5794 2d.s1. 124864590us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35306 prio 0 }
<0> [336.187099] gem_sync-5794 2..... 124864678us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35308, current 35306
<0> [336.187208] gem_sync-5794 2d.s2. 124864687us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35308, current 35306
<0> [336.187311] gem_sync-5794 2d.s1. 124864688us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.187414] gem_sync-5794 2d.s1. 124864689us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35308 prio 0 }
<0> [336.187524] gem_sync-5794 2..... 124864748us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35310, current 35308
<0> [336.187634] gem_sync-5794 2d.s2. 124864757us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35310, current 35308
<0> [336.187738] gem_sync-5794 2d.s1. 124864758us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.187840] gem_sync-5794 2d.s1. 124864758us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35310 prio 0 }
<0> [336.187950] gem_sync-5794 2..... 124864822us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35312, current 35310
<0> [336.188060] gem_sync-5794 2d.s2. 124864830us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35312, current 35310
<0> [336.188165] gem_sync-5794 2d.s1. 124864831us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.188274] gem_sync-5794 2d.s1. 124864832us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35312 prio 0 }
<0> [336.188393] gem_sync-5794 2..... 124864895us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35314, current 35312
<0> [336.188504] gem_sync-5794 2d.s2. 124864900us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35314, current 35312
<0> [336.188607] gem_sync-5794 2d.s1. 124864901us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.188710] gem_sync-5794 2d.s1. 124864902us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35314 prio 0 }
<0> [336.188821] gem_sync-5794 2..... 124864960us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35316, current 35314
<0> [336.188931] gem_sync-5794 2d.s2. 124864968us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35316, current 35314
<0> [336.189034] gem_sync-5794 2d.s1. 124864969us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.189139] gem_sync-5794 2d.s1. 124864970us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35316 prio 0 }
<0> [336.189250] gem_sync-5794 2..... 124865036us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35318, current 35316
<0> [336.189360] gem_sync-5794 2d.s2. 124865043us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35318, current 35316
<0> [336.189463] gem_sync-5794 2d.s1. 124865044us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.189565] gem_sync-5794 2d.s1. 124865045us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35318 prio 0 }
<0> [336.189676] gem_sync-5794 2..... 124865114us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35320, current 35318
<0> [336.189786] gem_sync-5794 2d.s2. 124865122us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35320, current 35318
<0> [336.189889] gem_sync-5794 2d.s1. 124865123us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.189991] gem_sync-5794 2d.s1. 124865124us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35320 prio 0 }
<0> [336.190104] gem_sync-5794 2..... 124865183us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35322, current 35320
<0> [336.190214] gem_sync-5794 2d.s2. 124865191us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35322, current 35320
<0> [336.190318] gem_sync-5794 2d.s1. 124865192us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.190421] gem_sync-5794 2d.s1. 124865192us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35322 prio 0 }
<0> [336.190531] gem_sync-5794 2..... 124865269us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35324, current 35322
<0> [336.190641] gem_sync-5794 2d.s2. 124865277us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35324, current 35322
<0> [336.190744] gem_sync-5794 2d.s1. 124865278us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.190847] gem_sync-5794 2d.s1. 124865279us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35324 prio 0 }
<0> [336.190949] gem_sync-5794 2..... 124865316us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e active
<0> [336.191051] gem_sync-5794 2..... 124865323us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e pin ring:{start:feda7000, head:18d8, tail:1908}
<0> [336.191164] gem_sync-5794 2..... 124865364us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35326, current 35324
<0> [336.191274] gem_sync-5794 2d.s2. 124865372us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35326, current 35324
<0> [336.191377] gem_sync-5794 2d.s1. 124865373us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.191480] gem_sync-5794 2d.s1. 124865374us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35326 prio 0 }
<0> [336.191590] gem_sync-5794 2..... 124865438us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35328, current 35326
<0> [336.191700] gem_sync-5794 2d.s2. 124865445us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35328, current 35326
<0> [336.191803] gem_sync-5794 2d.s1. 124865446us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.191906] gem_sync-5794 2d.s1. 124865447us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35328 prio 0 }
<0> [336.192031] gem_sync-5794 2..... 124865505us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35330, current 35328
<0> [336.192144] gem_sync-5794 2d.s2. 124865513us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35330, current 35328
<0> [336.192247] gem_sync-5794 2d.s1. 124865515us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.192351] gem_sync-5794 2d.s1. 124865516us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35330 prio 0 }
<0> [336.192461] gem_sync-5794 2..... 124865562us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence f0e:35328, current 35330
<0> [336.192571] gem_sync-5794 2..... 124865580us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35332, current 35330
<0> [336.192681] gem_sync-5794 2d.s2. 124865585us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35332, current 35330
<0> [336.192785] gem_sync-5794 2d.s1. 124865586us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.192888] gem_sync-5794 2d.s1. 124865587us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35332 prio 0 }
<0> [336.192994] gem_sync-5794 2..s.. 124865608us : rps_timer: 0000:00:02.0 busy:2175144 [73%], max:[1542688, 908345, 713139], interval:2
<0> [336.193101] kworker/-165 2..... 124865645us : rps_work: 0000:00:02.0 pm_iir:10, client_boost:yes, last:0, cur:48, min:15, max:48
<0> [336.193212] gem_sync-5794 2..... 124865739us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35334, current 35332
<0> [336.193322] gem_sync-5794 2d.s2. 124865748us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35334, current 35332
<0> [336.193425] gem_sync-5794 2d.s1. 124865749us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.193528] gem_sync-5794 2d.s1. 124865750us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35334 prio 0 }
<0> [336.193638] gem_sync-5794 2..... 124865808us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35336, current 35334
<0> [336.193748] gem_sync-5794 2d.s2. 124865816us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35336, current 35334
<0> [336.193851] gem_sync-5794 2d.s1. 124865817us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.193954] gem_sync-5794 2d.s1. 124865817us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35336 prio 0 }
<0> [336.194065] gem_sync-5794 2..... 124865906us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35338, current 35336
<0> [336.194176] gem_sync-5794 2d.s2. 124865913us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35338, current 35336
<0> [336.194280] gem_sync-5794 2d.s1. 124865914us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.194383] gem_sync-5794 2d.s1. 124865914us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35338 prio 0 }
<0> [336.194494] gem_sync-5794 2..... 124865972us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35340, current 35338
<0> [336.194604] gem_sync-5794 2d.s2. 124865980us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35340, current 35338
<0> [336.194706] gem_sync-5794 2d.s1. 124865981us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.194809] gem_sync-5794 2d.s1. 124865982us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35340 prio 0 }
<0> [336.194919] gem_sync-5794 2..... 124866030us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence f0e:35338, current 35340
<0> [336.195030] gem_sync-5794 2..... 124866045us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35342, current 35340
<0> [336.195143] gem_sync-5794 2d.s2. 124866051us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35342, current 35340
<0> [336.195246] gem_sync-5794 2d.s1. 124866052us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.195350] gem_sync-5794 2d.s1. 124866053us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35342 prio 0 }
<0> [336.195460] gem_sync-5794 2..... 124866100us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence f0e:35340, current 35342
<0> [336.195571] gem_sync-5794 2..... 124866120us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35344, current 35342
<0> [336.195690] gem_sync-5794 2d.s2. 124866128us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35344, current 35342
<0> [336.195799] gem_sync-5794 2d.s1. 124866129us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.195903] gem_sync-5794 2d.s1. 124866130us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35344 prio 0 }
<0> [336.196014] gem_sync-5794 2..... 124866195us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35346, current 35344
<0> [336.196126] gem_sync-5794 2d.s2. 124866203us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35346, current 35344
<0> [336.196229] gem_sync-5794 2d.s1. 124866204us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.196332] gem_sync-5794 2d.s1. 124866205us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35346 prio 0 }
<0> [336.196443] gem_sync-5794 2..... 124866265us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35348, current 35346
<0> [336.196553] gem_sync-5794 2d.s2. 124866272us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35348, current 35346
<0> [336.196657] gem_sync-5794 2d.s1. 124866273us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.196759] gem_sync-5794 2d.s1. 124866274us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35348 prio 0 }
<0> [336.196869] gem_sync-5794 2..... 124866357us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35350, current 35348
<0> [336.196980] gem_sync-5794 2d.s2. 124866365us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35350, current 35348
<0> [336.197082] gem_sync-5794 2d.s1. 124866366us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.197187] gem_sync-5794 2d.s1. 124866366us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35350 prio 0 }
<0> [336.197289] gem_sync-5794 2..... 124866406us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e active
<0> [336.197390] gem_sync-5794 2..... 124866409us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e pin ring:{start:feda7000, head:1ef0, tail:1f20}
<0> [336.197501] gem_sync-5794 2..... 124866445us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35352, current 35350
<0> [336.197611] gem_sync-5794 2d.s2. 124866454us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35352, current 35350
<0> [336.197714] gem_sync-5794 2d.s1. 124866455us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.197817] gem_sync-5794 2d.s1. 124866456us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35352 prio 0 }
<0> [336.197927] gem_sync-5794 2..... 124866520us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35354, current 35352
<0> [336.198037] gem_sync-5794 2d.s2. 124866528us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35354, current 35352
<0> [336.198143] gem_sync-5794 2d.s1. 124866529us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.198245] gem_sync-5794 2d.s1. 124866530us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35354 prio 0 }
<0> [336.198355] gem_sync-5794 2..... 124866587us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35356, current 35354
<0> [336.198466] gem_sync-5794 2d.s2. 124866593us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35356, current 35354
<0> [336.198569] gem_sync-5794 2d.s1. 124866594us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.198672] gem_sync-5794 2d.s1. 124866595us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35356 prio 0 }
<0> [336.198783] gem_sync-5794 2..... 124866698us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence f0e:35354, current 35356
<0> [336.198894] gem_sync-5794 2..... 124866718us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35358, current 35356
<0> [336.199005] gem_sync-5794 2d.s2. 124866725us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35358, current 35356
<0> [336.199110] gem_sync-5794 2d.s1. 124866727us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.199213] gem_sync-5794 2d.s1. 124866727us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35358 prio 0 }
<0> [336.199323] gem_sync-5794 2..... 124866798us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35360, current 35358
<0> [336.199433] gem_sync-5794 2d.s2. 124866806us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35360, current 35358
<0> [336.199535] gem_sync-5794 2d.s1. 124866807us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.199651] gem_sync-5794 2d.s1. 124866808us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35360 prio 0 }
<0> [336.199762] gem_sync-5794 2..... 124866870us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35362, current 35360
<0> [336.199873] gem_sync-5794 2d.s2. 124866877us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35362, current 35360
<0> [336.199976] gem_sync-5794 2d.s1. 124866878us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.200079] gem_sync-5794 2d.s1. 124866879us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35362 prio 0 }
<0> [336.200197] gem_sync-5794 2..... 124866941us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35364, current 35362
<0> [336.200308] gem_sync-5794 2d.s2. 124866948us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35364, current 35362
<0> [336.200411] gem_sync-5794 2d.s1. 124866949us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.200513] gem_sync-5794 2d.s1. 124866950us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35364 prio 0 }
<0> [336.200624] gem_sync-5794 2..... 124867036us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35366, current 35364
<0> [336.200734] gem_sync-5794 2d.s2. 124867043us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35366, current 35364
<0> [336.200837] gem_sync-5794 2d.s1. 124867044us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.200940] gem_sync-5794 2d.s1. 124867045us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35366 prio 0 }
<0> [336.201051] gem_sync-5794 2..... 124867126us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35368, current 35366
<0> [336.201163] gem_sync-5794 2d.s2. 124867134us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35368, current 35366
<0> [336.201267] gem_sync-5794 2d.s1. 124867135us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.201370] gem_sync-5794 2d.s1. 124867135us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35368 prio 0 }
<0> [336.201481] gem_sync-5794 2..... 124867215us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35370, current 35368
<0> [336.201591] gem_sync-5794 2d.s2. 124867222us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35370, current 35368
<0> [336.201694] gem_sync-5794 2d.s1. 124867223us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.201796] gem_sync-5794 2d.s1. 124867224us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35370 prio 0 }
<0> [336.201907] gem_sync-5794 2..... 124867305us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35372, current 35370
<0> [336.202017] gem_sync-5794 2d.s2. 124867312us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35372, current 35370
<0> [336.202122] gem_sync-5794 2d.s1. 124867313us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.202225] gem_sync-5794 2d.s1. 124867314us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35372 prio 0 }
<0> [336.202335] gem_sync-5794 2..... 124867379us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35374, current 35372
<0> [336.202445] gem_sync-5794 2d.s2. 124867387us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35374, current 35372
<0> [336.202548] gem_sync-5794 2d.s1. 124867388us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.202650] gem_sync-5794 2d.s1. 124867389us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35374 prio 0 }
<0> [336.202760] gem_sync-5794 2..... 124867447us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35376, current 35374
<0> [336.202871] gem_sync-5794 2d.s2. 124867455us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35376, current 35374
<0> [336.202973] gem_sync-5794 2d.s1. 124867456us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.203076] gem_sync-5794 2d.s1. 124867457us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35376 prio 0 }
<0> [336.203188] gem_sync-5794 2..... 124867496us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e active
<0> [336.203298] gem_sync-5794 2..... 124867499us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e pin ring:{start:feda7000, head:2508, tail:2538}
<0> [336.203408] gem_sync-5794 2..... 124867535us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35378, current 35376
<0> [336.203519] gem_sync-5794 2d.s2. 124867543us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35378, current 35376
<0> [336.203623] gem_sync-5794 2d.s1. 124867544us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.203725] gem_sync-5794 2d.s1. 124867545us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35378 prio 0 }
<0> [336.203831] gem_sync-5794 2..s1. 124867610us : rps_timer: 0000:00:02.0 busy:1428521 [71%], max:[984312, 633287, 510265], interval:2
<0> [336.203937] kworker/-165 2..... 124867669us : rps_work: 0000:00:02.0 pm_iir:10, client_boost:yes, last:0, cur:48, min:15, max:48
<0> [336.204048] gem_sync-5794 2..... 124868146us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35380, current 35378
<0> [336.204160] gem_sync-5794 2d.s2. 124868154us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35380, current 35378
<0> [336.204264] gem_sync-5794 2d.s1. 124868156us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.204367] gem_sync-5794 2d.s1. 124868157us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35380 prio 0 }
<0> [336.204478] gem_sync-5794 2..... 124868265us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35382, current 35380
<0> [336.204588] gem_sync-5794 2d.s2. 124868272us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35382, current 35380
<0> [336.204691] gem_sync-5794 2d.s1. 124868273us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.204794] gem_sync-5794 2d.s1. 124868274us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35382 prio 0 }
<0> [336.204905] gem_sync-5794 2..... 124868354us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35384, current 35382
<0> [336.205015] gem_sync-5794 2d.s2. 124868362us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35384, current 35382
<0> [336.205121] gem_sync-5794 2d.s1. 124868363us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.205225] gem_sync-5794 2d.s1. 124868364us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35384 prio 0 }
<0> [336.205327] gem_sync-5794 2..... 124868404us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e active
<0> [336.205429] gem_sync-5794 2..... 124868406us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e pin ring:{start:feda7000, head:26e8, tail:2718}
<0> [336.205540] gem_sync-5794 2..... 124868444us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35386, current 35384
<0> [336.205650] gem_sync-5794 2d.s2. 124868452us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35386, current 35384
<0> [336.205753] gem_sync-5794 2d.s1. 124868453us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.205857] gem_sync-5794 2d.s1. 124868454us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35386 prio 0 }
<0> [336.205968] gem_sync-5794 2..... 124868518us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35388, current 35386
<0> [336.206078] gem_sync-5794 2d.s2. 124868525us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35388, current 35386
<0> [336.206184] gem_sync-5794 2d.s1. 124868526us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.206287] gem_sync-5794 2d.s1. 124868527us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35388 prio 0 }
<0> [336.206397] gem_sync-5794 2..... 124868591us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35390, current 35388
<0> [336.206508] gem_sync-5794 2d.s2. 124868598us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35390, current 35388
<0> [336.206611] gem_sync-5794 2d.s1. 124868599us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.206714] gem_sync-5794 2d.s1. 124868600us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35390 prio 0 }
<0> [336.206830] gem_sync-5794 2..... 124868648us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e active
<0> [336.206933] gem_sync-5794 2..... 124868723us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e pin ring:{start:feda7000, head:2850, tail:2880}
<0> [336.207045] gem_sync-5794 2..... 124868760us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35392, current 35390
<0> [336.207157] gem_sync-5794 2d.s2. 124868768us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35392, current 35390
<0> [336.207261] gem_sync-5794 2d.s1. 124868769us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.207364] gem_sync-5794 2d.s1. 124868770us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35392 prio 0 }
<0> [336.207466] gem_sync-5794 2..... 124868811us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e active
<0> [336.207567] gem_sync-5794 2..... 124868870us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e pin ring:{start:feda7000, head:28c8, tail:28f8}
<0> [336.207678] gem_sync-5794 2..... 124868907us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35394, current 35392
<0> [336.207788] gem_sync-5794 2d.s2. 124868914us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35394, current 35392
<0> [336.207892] gem_sync-5794 2d.s1. 124868916us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.207995] gem_sync-5794 2d.s1. 124868916us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35394 prio 0 }
<0> [336.208107] gem_sync-5794 2..... 124869005us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35396, current 35394
<0> [336.208218] gem_sync-5794 2d.s2. 124869012us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35396, current 35394
<0> [336.208321] gem_sync-5794 2d.s1. 124869013us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.208423] gem_sync-5794 2d.s1. 124869014us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35396 prio 0 }
<0> [336.208524] gem_sync-5794 2..... 124869055us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e active
<0> [336.208626] gem_sync-5794 2..... 124869058us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e pin ring:{start:feda7000, head:29b8, tail:29e8}
<0> [336.208737] gem_sync-5794 2..... 124869093us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35398, current 35396
<0> [336.208847] gem_sync-5794 2d.s2. 124869101us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35398, current 35396
<0> [336.208949] gem_sync-5794 2d.s1. 124869102us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.209052] gem_sync-5794 2d.s1. 124869103us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35398 prio 0 }
<0> [336.209164] gem_sync-5794 2..... 124869183us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35400, current 35398
<0> [336.209275] gem_sync-5794 2d.s2. 124869191us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35400, current 35398
<0> [336.209378] gem_sync-5794 2d.s1. 124869192us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.209481] gem_sync-5794 2d.s1. 124869193us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35400 prio 0 }
<0> [336.209592] gem_sync-5794 2..... 124869255us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35402, current 35400
<0> [336.209702] gem_sync-5794 2d.s2. 124869263us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35402, current 35400
<0> [336.209806] gem_sync-5794 2d.s1. 124869264us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.209909] gem_sync-5794 2d.s1. 124869264us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35402 prio 0 }
<0> [336.210019] gem_sync-5794 2..... 124869324us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35404, current 35402
<0> [336.210132] gem_sync-5794 2d.s2. 124869332us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35404, current 35402
<0> [336.210235] gem_sync-5794 2d.s1. 124869333us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.210338] gem_sync-5794 2d.s1. 124869334us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35404 prio 0 }
<0> [336.210448] gem_sync-5794 2..... 124869380us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence f0e:35402, current 35404
<0> [336.210563] gem_sync-5794 2..... 124869400us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35406, current 35404
<0> [336.210684] gem_sync-5794 2d.s2. 124869408us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35406, current 35404
<0> [336.210787] gem_sync-5794 2d.s1. 124869409us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.210890] gem_sync-5794 2d.s1. 124869410us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35406 prio 0 }
<0> [336.211001] gem_sync-5794 2..... 124869485us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35408, current 35406
<0> [336.211114] gem_sync-5794 2d.s2. 124869492us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35408, current 35406
<0> [336.211217] gem_sync-5794 2d.s1. 124869493us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.211320] gem_sync-5794 2d.s1. 124869493us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35408 prio 0 }
<0> [336.211430] gem_sync-5794 2..... 124869555us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35410, current 35408
<0> [336.211540] gem_sync-5794 2d.s2. 124869563us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35410, current 35408
<0> [336.211643] gem_sync-5794 2d.s1. 124869564us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.211746] gem_sync-5794 2d.s1. 124869565us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35410 prio 0 }
<0> [336.211852] gem_sync-5794 2..s.. 124869608us : rps_timer: 0000:00:02.0 busy:1097088 [54%], max:[726985, 558489, 363436], interval:2
<0> [336.211957] kworker/-165 2..... 124869648us : rps_work: 0000:00:02.0 pm_iir:10, client_boost:yes, last:0, cur:48, min:15, max:48
<0> [336.212059] gem_sync-5794 2..... 124869654us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e active
<0> [336.212162] gem_sync-5794 2..... 124869658us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e pin ring:{start:feda7000, head:2d00, tail:2d30}
<0> [336.212263] gem_sync-5794 2..... 124869691us : __intel_context_do_unpin.part.0: 0000:00:02.0 vecs0: context:f0e unpin
<0> [336.212365] gem_sync-5794 2..... 124869693us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e active
<0> [336.212467] gem_sync-5794 2..... 124869694us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e pin ring:{start:feda7000, head:2d00, tail:2d30}
<0> [336.212578] gem_sync-5794 2..... 124869727us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35412, current 35410
<0> [336.212688] gem_sync-5794 2d.s2. 124869735us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35412, current 35410
<0> [336.212791] gem_sync-5794 2d.s1. 124869736us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.212894] gem_sync-5794 2d.s1. 124869737us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35412 prio 0 }
<0> [336.213004] gem_sync-5794 2..... 124869799us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35414, current 35412
<0> [336.213117] gem_sync-5794 2d.s2. 124869807us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35414, current 35412
<0> [336.213220] gem_sync-5794 2d.s1. 124869808us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.213323] gem_sync-5794 2d.s1. 124869809us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35414 prio 0 }
<0> [336.213434] gem_sync-5794 2..... 124869909us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35416, current 35414
<0> [336.213544] gem_sync-5794 2d.s2. 124869916us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35416, current 35414
<0> [336.213647] gem_sync-5794 2d.s1. 124869918us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.213750] gem_sync-5794 2d.s1. 124869918us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35416 prio 0 }
<0> [336.213861] gem_sync-5794 2..... 124869984us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35418, current 35416
<0> [336.213972] gem_sync-5794 2d.s2. 124869992us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35418, current 35416
<0> [336.214074] gem_sync-5794 2d.s1. 124869993us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.214194] gem_sync-5794 2d.s1. 124869994us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35418 prio 0 }
<0> [336.214297] gem_sync-5794 2..... 124870033us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e active
<0> [336.214399] gem_sync-5794 2..... 124870035us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e pin ring:{start:feda7000, head:2ee0, tail:2f10}
<0> [336.214509] gem_sync-5794 2..... 124870071us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35420, current 35418
<0> [336.214619] gem_sync-5794 2d.s2. 124870079us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35420, current 35418
<0> [336.214722] gem_sync-5794 2d.s1. 124870080us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.214825] gem_sync-5794 2d.s1. 124870081us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35420 prio 0 }
<0> [336.214935] gem_sync-5794 2..... 124870139us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35422, current 35420
<0> [336.215045] gem_sync-5794 2d.s2. 124870147us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35422, current 35420
<0> [336.215152] gem_sync-5794 2d.s1. 124870148us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.215255] gem_sync-5794 2d.s1. 124870149us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35422 prio 0 }
<0> [336.215366] gem_sync-5794 2..... 124870195us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence f0e:35420, current 35422
<0> [336.215477] gem_sync-5794 2..... 124870215us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35424, current 35422
<0> [336.215587] gem_sync-5794 2d.s2. 124870223us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35424, current 35422
<0> [336.215690] gem_sync-5794 2d.s1. 124870224us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.215793] gem_sync-5794 2d.s1. 124870224us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35424 prio 0 }
<0> [336.215903] gem_sync-5794 2..... 124870271us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence f0e:35422, current 35424
<0> [336.216013] gem_sync-5794 2..... 124870290us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35426, current 35424
<0> [336.216125] gem_sync-5794 2d.s2. 124870295us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35426, current 35424
<0> [336.216229] gem_sync-5794 2d.s1. 124870296us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.216332] gem_sync-5794 2d.s1. 124870296us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35426 prio 0 }
<0> [336.216442] gem_sync-5794 2..... 124870344us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence f0e:35424, current 35426
<0> [336.216552] gem_sync-5794 2..... 124870362us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35428, current 35426
<0> [336.216662] gem_sync-5794 2d.s2. 124870370us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35428, current 35426
<0> [336.216765] gem_sync-5794 2d.s1. 124870371us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.216868] gem_sync-5794 2d.s1. 124870372us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35428 prio 0 }
<0> [336.216978] gem_sync-5794 2..... 124870470us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35430, current 35428
<0> [336.217088] gem_sync-5794 2d.s2. 124870477us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35430, current 35428
<0> [336.217194] gem_sync-5794 2d.s1. 124870478us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.217297] gem_sync-5794 2d.s1. 124870479us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35430 prio 0 }
<0> [336.217399] gem_sync-5794 2..... 124870518us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e active
<0> [336.217500] gem_sync-5794 2..... 124870521us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e pin ring:{start:feda7000, head:31b0, tail:31e0}
<0> [336.217610] gem_sync-5794 2..... 124870557us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35432, current 35430
<0> [336.217721] gem_sync-5794 2d.s2. 124870565us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35432, current 35430
<0> [336.217824] gem_sync-5794 2d.s1. 124870567us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.217927] gem_sync-5794 2d.s1. 124870567us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35432 prio 0 }
<0> [336.218051] gem_sync-5794 2..... 124870713us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35434, current 35432
<0> [336.218164] gem_sync-5794 2d.s2. 124870720us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35434, current 35432
<0> [336.218268] gem_sync-5794 2d.s1. 124870721us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.218371] gem_sync-5794 2d.s1. 124870722us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35434 prio 0 }
<0> [336.218473] gem_sync-5794 2..... 124870761us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e active
<0> [336.218574] gem_sync-5794 2..... 124870764us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e pin ring:{start:feda7000, head:32a0, tail:32d0}
<0> [336.218684] gem_sync-5794 2..... 124870799us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35436, current 35434
<0> [336.218795] gem_sync-5794 2d.s2. 124870807us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35436, current 35434
<0> [336.218898] gem_sync-5794 2d.s1. 124870808us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.219001] gem_sync-5794 2d.s1. 124870809us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35436 prio 0 }
<0> [336.219113] gem_sync-5794 2..... 124870868us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35438, current 35436
<0> [336.219223] gem_sync-5794 2d.s2. 124870876us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35438, current 35436
<0> [336.219326] gem_sync-5794 2d.s1. 124870877us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.219429] gem_sync-5794 2d.s1. 124870878us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35438 prio 0 }
<0> [336.219539] gem_sync-5794 2..... 124870960us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35440, current 35438
<0> [336.219650] gem_sync-5794 2d.s2. 124870968us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35440, current 35438
<0> [336.219753] gem_sync-5794 2d.s1. 124870969us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.219855] gem_sync-5794 2d.s1. 124870969us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35440 prio 0 }
<0> [336.219966] gem_sync-5794 2..... 124871029us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35442, current 35440
<0> [336.220077] gem_sync-5794 2d.s2. 124871037us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35442, current 35440
<0> [336.220182] gem_sync-5794 2d.s1. 124871038us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.220285] gem_sync-5794 2d.s1. 124871038us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35442 prio 0 }
<0> [336.220395] gem_sync-5794 2..... 124871104us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35444, current 35442
<0> [336.220505] gem_sync-5794 2d.s2. 124871112us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35444, current 35442
<0> [336.220608] gem_sync-5794 2d.s1. 124871113us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.220710] gem_sync-5794 2d.s1. 124871114us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35444 prio 0 }
<0> [336.220820] gem_sync-5794 2..... 124871173us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35446, current 35444
<0> [336.220930] gem_sync-5794 2d.s2. 124871181us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35446, current 35444
<0> [336.221033] gem_sync-5794 2d.s1. 124871182us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.221139] gem_sync-5794 2d.s1. 124871183us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35446 prio 0 }
<0> [336.221249] gem_sync-5794 2..... 124871248us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35448, current 35446
<0> [336.221359] gem_sync-5794 2d.s2. 124871256us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35448, current 35446
<0> [336.221462] gem_sync-5794 2d.s1. 124871257us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.221564] gem_sync-5794 2d.s1. 124871258us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35448 prio 0 }
<0> [336.221675] gem_sync-5794 2..... 124871317us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35450, current 35448
<0> [336.221800] gem_sync-5794 2d.s2. 124871326us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35450, current 35448
<0> [336.221904] gem_sync-5794 2d.s1. 124871327us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.222007] gem_sync-5794 2d.s1. 124871327us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35450 prio 0 }
<0> [336.222120] gem_sync-5794 2..... 124871410us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35452, current 35450
<0> [336.222231] gem_sync-5794 2d.s2. 124871418us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35452, current 35450
<0> [336.222333] gem_sync-5794 2d.s1. 124871419us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.222436] gem_sync-5794 2d.s1. 124871420us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35452 prio 0 }
<0> [336.222538] gem_sync-5794 2..... 124871458us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e active
<0> [336.222640] gem_sync-5794 2..... 124871461us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:f0e pin ring:{start:feda7000, head:36d8, tail:3708}
<0> [336.222751] gem_sync-5794 2..... 124871497us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35454, current 35452
<0> [336.222868] gem_sync-5794 2d.s2. 124871503us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35454, current 35452
<0> [336.222971] gem_sync-5794 2d.s1. 124871504us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.223075] gem_sync-5794 2d.s1. 124871505us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35454 prio 0 }
<0> [336.223188] gem_sync-5794 2..... 124871563us : __i915_request_commit: 0000:00:02.0 vecs0: fence f0e:35456, current 35454
<0> [336.223298] gem_sync-5794 2d.s2. 124871571us : __i915_request_submit: 0000:00:02.0 vecs0: fence f0e:35456, current 35454
<0> [336.223402] gem_sync-5794 2d.s1. 124871572us : execlists_submission_tasklet: 0000:00:02.0 vecs0: context:f0e schedule-in, ccid:20
<0> [336.223505] gem_sync-5794 2d.s1. 124871573us : trace_ports: 0000:00:02.0 vecs0: submit { ccid:20 f0e:35456 prio 0 }
<0> [336.223610] gem_sync-5794 2.Ns2. 124871618us : rps_timer: 0000:00:02.0 busy:1483310 [73%], max:[1090213, 556385, 459621], interval:2
<0> [336.223716] kworker/-165 2..... 124871703us : rps_work: 0000:00:02.0 pm_iir:10, client_boost:no, last:0, cur:48, min:15, max:48
<0> [336.223821] kworker/-165 2..... 124871703us : rps_set: 0000:00:02.0 set val:47, freq:1183, swreq:23800000
<0> [336.223927] <idle>-0 2..s1. 124873608us : rps_timer: 0000:00:02.0 busy:1200010 [60%], max:[844662, 476910, 467574], interval:2
<0> [336.224032] kworker/-165 2..... 124873618us : rps_work: 0000:00:02.0 pm_iir:10, client_boost:no, last:0, cur:48, min:15, max:48
<0> [336.224140] kworker/-165 2..... 124873619us : rps_set: 0000:00:02.0 set val:47, freq:1183, swreq:23800000
<0> [336.224245] <idle>-0 2..s1. 124875608us : rps_timer: 0000:00:02.0 busy:1292953 [64%], max:[919968, 520829, 450284], interval:2
<0> [336.224350] kworker/-165 2..... 124876064us : rps_work: 0000:00:02.0 pm_iir:10, client_boost:yes, last:0, cur:48, min:15, max:48
<0> [336.224456] kworker/-157 2..s1. 124877609us : rps_timer: 0000:00:02.0 busy:1284566 [64%], max:[950418, 525289, 286018], interval:2
<0> [336.224561] kworker/-165 2..... 124878274us : rps_work: 0000:00:02.0 pm_iir:10, client_boost:yes, last:0, cur:48, min:15, max:48
<0> [336.224666] <idle>-0 2.Ns1. 124879628us : rps_timer: 0000:00:02.0 busy:1261970 [62%], max:[879122, 528444, 474506], interval:2
<0> [336.224771] kworker/-165 2..... 124880193us : rps_work: 0000:00:02.0 pm_iir:10, client_boost:yes, last:0, cur:48, min:15, max:48
<0> [336.224876] <idle>-0 2..s1. 124881625us : rps_timer: 0000:00:02.0 busy:1306999 [65%], max:[932897, 560852, 374705], interval:2
<0> [336.224982] kworker/-165 2..... 124881635us : rps_work: 0000:00:02.0 pm_iir:10, client_boost:yes, last:0, cur:48, min:15, max:48
<0> [336.225086] <idle>-0 2..s1. 124883627us : rps_timer: 0000:00:02.0 busy:1241169 [62%], max:[882347, 504687, 425919], interval:2
<0> [336.225195] kworker/-165 2..... 124883923us : rps_work: 0000:00:02.0 pm_iir:10, client_boost:no, last:0, cur:48, min:15, max:48
<0> [336.225300] kworker/-165 2..... 124883924us : rps_set: 0000:00:02.0 set val:47, freq:1183, swreq:23800000
<0> [336.225310] gem_sync-5788 2...1. 125820088us : tracing_mark_write: gem_sync: finished subtest basic-each, SUCCESS
<0> [336.225415] gem_sync-5788 2..... 125939906us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes
<0> [336.225520] kworker/-117 2..... 125942822us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no
<0> [336.225625] gem_sync-5788 2..... 125948867us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes
<0> [336.225743] kworker/-117 2..... 125959425us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no
<0> [336.225850] kworker/-160 2d..2. 126194881us : intel_gt_invalidate_tlb_full.part.0: 0000:00:02.0 invalidated engines 00000002
<0> [336.225957] kworker/-160 2d..2. 126194923us : intel_gt_invalidate_tlb_full.part.0: 0000:00:02.0 invalidated engines 00000002
<0> [336.226064] kworker/-160 2d..2. 126195379us : intel_gt_invalidate_tlb_full.part.0: 0000:00:02.0 invalidated engines 00000002
<0> [336.226171] kworker/-160 2d..2. 126196087us : intel_gt_invalidate_tlb_full.part.0: 0000:00:02.0 invalidated engines 00000002
<0> [336.226278] kworker/-161 2d..2. 126597635us : intel_gt_invalidate_tlb_full.part.0: 0000:00:02.0 invalidated engines 00000002
<0> [336.226286] gem_wait-5800 2...1. 127822053us : tracing_mark_write: gem_wait: finished subtest all-engines, SUCCESS
<0> [336.226294] gem_wait-5800 2...1. 127822274us : tracing_mark_write: gem_wait: finished subtest busy, SUCCESS
<0> [336.226398] gem_wait-5800 2..... 127939937us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes
<0> [336.226503] gem_wait-5800 2..... 127943607us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes
<0> [336.226607] kworker/-117 2..... 127943645us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no
<0> [336.226712] gem_wait-5800 2..... 127959551us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes
<0> [336.226816] kworker/-117 2..... 127959625us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no
<0> [336.226921] kworker/-117 2..... 127975828us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no
<0> [336.226929] kms_addf-5822 2...1. 131802575us : tracing_mark_write: kms_addfb_basic: executing
<0> [336.226937] kms_addf-5823 2...1. 132133074us : tracing_mark_write: kms_addfb_basic: executing
<0> [336.226944] kms_addf-5824 2...1. 132380121us : tracing_mark_write: kms_addfb_basic: executing
<0> [336.226951] kms_addf-5828 2...1. 133488671us : tracing_mark_write: kms_addfb_basic: executing
|