igt@kms_atomic@test-only@pipe-a-edp-1 on shard-mtlp@CI_DRM

Detail	Value
Duration	unknown
Hostname	shard-mtlp-2
Igt-Version	IGT-Version: 2.3-gd92116c0c (x86_64) (Linux: 7.0.0-rc3-CI_DRM_18136-g72190ebdd4c0+ x86_64)
Out	Using IGT_SRANDOM=1773389327 for randomisation Opened device: /dev/dri/card0 Starting subtest: test-only Starting dynamic subtest: pipe-A-eDP-1 Dynamic subtest pipe-A-eDP-1: SUCCESS (2.521s) Subtest test-only: SUCCESS (2.577s) This test caused an abort condition: Child refuses to die, tainted 0x442c4.
Err	Starting subtest: test-only Starting dynamic subtest: pipe-A-eDP-1 Dynamic subtest pipe-A-eDP-1: SUCCESS (2.521s) Subtest test-only: SUCCESS (2.577s)
Dmesg Scroll to first warning	<6> [208.448232] Console: switching to colour dummy device 80x25 <6> [208.448534] [IGT] kms_atomic: executing <7> [208.462742] i915 0000:00:02.0: [drm:i915_gem_open [i915]] <7> [208.465803] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc] <7> [208.483033] i915 0000:00:02.0: [drm:i915_gem_open [i915]] <7> [208.492442] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:516:DP-1] <7> [208.492450] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:516:DP-1] <7> [208.496814] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:516:DP-1] disconnected <7> [208.497083] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:528:HDMI-A-1] <7> [208.497087] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:528:HDMI-A-1] <7> [208.501467] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:528:HDMI-A-1] disconnected <7> [208.501727] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:534:DP-2] <7> [208.501730] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:534:DP-2] <7> [208.502004] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:534:DP-2] disconnected <7> [208.502260] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:543:HDMI-A-2] <7> [208.502263] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:543:HDMI-A-2] <7> [208.506799] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:543:HDMI-A-2] disconnected <7> [208.507229] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:547:DP-3] <7> [208.507234] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:547:DP-3] <7> [208.507656] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:547:DP-3] disconnected <7> [208.508037] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:556:DP-4] <7> [208.508041] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:556:DP-4] <7> [208.508428] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:556:DP-4] disconnected <7> [208.508742] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:507:eDP-1] <7> [208.508745] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:507:eDP-1] <7> [208.509328] i915 0000:00:02.0: [drm:intel_dp_read_dsc_dpcd [i915]] DSC DPCD: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 <7> [208.509821] i915 0000:00:02.0: [drm:intel_dp_print_rates [i915]] source rates: 162000, 216000, 243000, 270000, 324000, 432000, 540000, 675000, 810000 <7> [208.509967] i915 0000:00:02.0: [drm:intel_dp_print_rates [i915]] sink rates: 162000, 216000, 243000, 270000, 324000, 378000, 432000, 540000 <7> [208.510105] i915 0000:00:02.0: [drm:intel_dp_print_rates [i915]] common rates: 162000, 216000, 243000, 270000, 324000, 432000, 540000 <7> [208.510267] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:507:eDP-1] Assigning EDID-1.4 digital sink color depth as 10 bpc. <7> [208.510273] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:507:eDP-1] ELD monitor <7> [208.510276] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:507:eDP-1] ELD size 20, SAD count 0 <7> [208.510294] i915 0000:00:02.0: [drm:intel_dp_set_edid [i915]] [CONNECTOR:507:eDP-1] VRR capable: no <7> [208.510451] i915 0000:00:02.0: [drm:intel_dp_set_edid [i915]] [CONNECTOR:507:eDP-1] DFP max bpc 0, max dotclock 0, TMDS clock 0-0, PCON Max FRL BW 0Gbps <7> [208.510603] i915 0000:00:02.0: [drm:intel_dp_set_edid [i915]] [CONNECTOR:507:eDP-1] RGB->YcbCr conversion? no, YCbCr 4:2:0 allowed? yes, YCbCr 4:4:4->4:2:0 conversion? no <7> [208.511151] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:507:eDP-1] probed modes: <7> [208.511154] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2880x1800": 90 513820 2880 2928 2960 3040 1800 1803 1809 1878 0x48 0xa <7> [208.511167] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:516:DP-1] <7> [208.511170] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:516:DP-1] <7> [208.515501] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:516:DP-1] disconnected <7> [208.515512] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:528:HDMI-A-1] <7> [208.515514] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:528:HDMI-A-1] <7> [208.519852] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:528:HDMI-A-1] disconnected <7> [208.519862] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:534:DP-2] <7> [208.519864] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:534:DP-2] <7> [208.520112] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:534:DP-2] disconnected <7> [208.520120] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:543:HDMI-A-2] <7> [208.520122] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:543:HDMI-A-2] <7> [208.524586] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:543:HDMI-A-2] disconnected <7> [208.524596] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:547:DP-3] <7> [208.524598] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:547:DP-3] <7> [208.524843] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:547:DP-3] disconnected <7> [208.524851] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:556:DP-4] <7> [208.524861] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:556:DP-4] <7> [208.525113] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:556:DP-4] disconnected <6> [208.525332] [IGT] kms_atomic: starting subtest test-only <7> [208.526575] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:568] <7> [208.528699] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509 <7> [208.529181] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509 <3> [208.550680] guc_lrc_desc_unpin:3418 GEM_BUG_ON(!ctx_id_mapped(guc, ce->guc_id.id)) <0> [208.550778] Dumping ftrace buffer: <0> [208.550786] --------------------------------- <0> [208.550981] kworker/-197 10..... 47399783us : __intel_context_do_unpin.part.0: 0000:00:02.0 ccs'0: context:a unpin <0> [208.551107] kworker/-197 10..... 47400585us : __intel_context_retire: 0000:00:02.0 ccs'0: context:a retire runtime: { total:90376ns, avg:90376ns } <0> [208.551241] kworker/-197 10..... 47400747us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs'0: context:9 unpin <0> [208.551353] kworker/-197 10..... 47400841us : __intel_context_retire: 0000:00:02.0 bcs'0: context:9 retire runtime: { total:14508ns, avg:14508ns } <0> [208.551462] kworker/-197 10..... 47400856us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs'0: context:8 unpin <0> [208.551612] kworker/-197 10..... 47400950us : __intel_context_retire: 0000:00:02.0 rcs'0: context:8 retire runtime: { total:109252ns, avg:109252ns } <0> [208.551742] kworker/-50 10..... 47401001us : __engine_park: 0000:00:02.0 ccs'0: parked <0> [208.551855] kworker/-50 10..... 47401069us : __engine_park: 0000:00:02.0 bcs'0: parked <0> [208.551967] kworker/-50 10..... 47401198us : __engine_park: 0000:00:02.0 rcs'0: parked <0> [208.552088] kworker/-50 10..... 47401770us : __gt_park: 0000:00:02.0 <0> [208.552220] kworker/-149 4..... 47466750us : __intel_context_do_unpin.part.0: 0000:00:02.0 vecs0: context:13 unpin <0> [208.552360] kworker/-149 4..... 47466834us : __intel_context_retire: 0000:00:02.0 vecs0: context:13 retire runtime: { total:12376ns, avg:12376ns } <0> [208.552503] kworker/-149 4..... 47466843us : __intel_context_do_unpin.part.0: 0000:00:02.0 vcs1: context:12 unpin <0> [208.552636] kworker/-149 4..... 47466923us : __intel_context_retire: 0000:00:02.0 vcs1: context:12 retire runtime: { total:12740ns, avg:12740ns } <0> [208.552760] kworker/-149 4..... 47466929us : __intel_context_do_unpin.part.0: 0000:00:02.0 vcs0: context:11 unpin <0> [208.552870] kworker/-149 4..... 47467008us : __intel_context_retire: 0000:00:02.0 vcs0: context:11 retire runtime: { total:12220ns, avg:12220ns } <0> [208.552980] kworker/-225 4..... 47467154us : __engine_park: 0000:00:02.0 vecs0: parked <0> [208.553091] kworker/-225 4..... 47467168us : __engine_park: 0000:00:02.0 vcs1: parked <0> [208.553220] kworker/-225 4..... 47467173us : __engine_park: 0000:00:02.0 vcs0: parked <0> [208.553359] i915_mod-2044 4.l... 47501990us : __gt_unpark: 0000:00:02.0 <0> [208.553496] kworker/-123 4..... 47502804us : __gt_park: 0000:00:02.0 <0> [208.553634] i915_mod-2044 4..... 47567893us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes <0> [208.553761] i915_mod-2044 4..... 48668882us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes <0> [208.553895] kworker/-262 4..... 48673970us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no <0> [208.554002] kworker/-225 4..... 48714228us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes <0> [208.554107] kworker/-262 4..... 48718480us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no <0> [208.554222] setfont-2069 10..... 48719628us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes <0> [208.554331] kworker/-280 10..... 48729531us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no <0> [208.554435] i915_mod-2044 16..... 48813520us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:1d active <0> [208.554539] kworker/-149 4..... 50840021us : __intel_context_do_unpin.part.0: 0000:00:02.0 vcs0: context:1a unpin <0> [208.554644] kworker/-149 4..... 50840133us : __intel_context_retire: 0000:00:02.0 vcs0: context:1a retire runtime: { total:17108ns, avg:17108ns } <0> [208.554756] kworker/-149 4..... 50841641us : __intel_context_do_unpin.part.0: 0000:00:02.0 vcs1: context:1b unpin <0> [208.554861] kworker/-149 4..... 50841746us : __intel_context_retire: 0000:00:02.0 vcs1: context:1b retire runtime: { total:11336ns, avg:11336ns } <0> [208.554964] kworker/-149 4..... 50841774us : __intel_context_do_unpin.part.0: 0000:00:02.0 vecs0: context:1c unpin <0> [208.555070] kworker/-149 4..... 50841878us : __intel_context_retire: 0000:00:02.0 vecs0: context:1c retire runtime: { total:17836ns, avg:17836ns } <0> [208.555176] kworker/-225 4..... 50841923us : __engine_park: 0000:00:02.0 vcs0: parked <0> [208.555282] kworker/-225 4..... 50841969us : __engine_park: 0000:00:02.0 vcs1: parked <0> [208.555389] kworker/-225 4..... 50842001us : __engine_park: 0000:00:02.0 vecs0: parked <0> [208.555497] kworker/-225 4..... 50842532us : __gt_park: 0000:00:02.0 <0> [208.555606] kworker/-149 4..... 50842758us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:17 unpin <0> [208.555712] kworker/-149 4..... 50842862us : __intel_context_retire: 0000:00:02.0 rcs0: context:17 retire runtime: { total:376844ns, avg:376844ns } <0> [208.555816] kworker/-149 4..... 50844355us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:18 unpin <0> [208.555921] kworker/-149 4.l... 50844463us : __intel_context_retire: 0000:00:02.0 rcs0: context:18 retire runtime: { total:90844ns, avg:90844ns } <0> [208.556027] kworker/-225 4..... 50844520us : __engine_park: 0000:00:02.0 rcs0: parked <0> [208.556128] kworker/-149 4..... 50845099us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:19 unpin <0> [208.556229] kworker/-149 4.l... 50845197us : __intel_context_retire: 0000:00:02.0 bcs0: context:19 retire runtime: { total:17940ns, avg:17940ns } <7> [208.556213] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509 <0> [208.556332] kworker/-225 4..... 50845232us : __engine_park: 0000:00:02.0 bcs0: parked <0> [208.556440] kworker/-225 4..... 50861193us : __gt_park: 0000:00:02.0 <0> [208.556461] gem_exec-2314 2...1. 57361828us : tracing_mark_write: gem_exec_reloc: executing <0> [208.556564] gem_exec-2314 2..... 57389172us : __gt_unpark: 0000:00:02.0 <7> [208.556558] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509 <0> [208.556670] kworker/-223 2..... 57389529us : __gt_park: 0000:00:02.0 <0> [208.556771] gem_exec-2314 2..... 57407399us : __gt_unpark: 0000:00:02.0 <0> [208.556890] kworker/-223 2..... 57407862us : __gt_park: 0000:00:02.0 <0> [208.556994] gem_exec-2314 2..... 57408592us : __gt_unpark: 0000:00:02.0 <0> [208.557105] kworker/-223 2..... 57408890us : __gt_park: 0000:00:02.0 <0> [208.557240] gem_exec-2314 2..... 57409687us : __engine_unpark: 0000:00:02.0 vecs0: <0> [208.557432] gem_exec-2314 2..... 57409712us : __gt_unpark: 0000:00:02.0 <0> [208.557601] gem_exec-2314 2..... 57410095us : __engine_unpark: 0000:00:02.0 vecs0: context:e reset { seqno:1, hwsp:1, ring:78 } <0> [208.557741] gem_exec-2314 2..... 57410107us : __engine_park: 0000:00:02.0 vecs0: parked <0> [208.557864] kworker/-223 2..... 57410344us : __gt_park: 0000:00:02.0 <0> [208.557968] gem_exec-2314 2..... 57411305us : __engine_unpark: 0000:00:02.0 ccs0: <0> [208.558074] gem_exec-2314 2..... 57411309us : __gt_unpark: 0000:00:02.0 <0> [208.558151] gem_exec-2314 2..... 57411516us : __engine_unpark: 0000:00:02.0 ccs0: context:7 reset { seqno:1, hwsp:1, ring:130 } <0> [208.558223] gem_exec-2314 2..... 57411517us : __engine_park: 0000:00:02.0 ccs0: parked <0> [208.558297] kworker/-223 2..... 57411535us : __gt_park: 0000:00:02.0 <0> [208.558373] gem_exec-2314 2..... 57411944us : __engine_unpark: 0000:00:02.0 vcs0: <0> [208.558447] gem_exec-2314 2..... 57411948us : __gt_unpark: 0000:00:02.0 <0> [208.558518] gem_exec-2314 2..... 57412018us : __engine_unpark: 0000:00:02.0 vcs0: context:c reset { seqno:1, hwsp:1, ring:78 } <0> [208.558586] gem_exec-2314 2..... 57412019us : __engine_park: 0000:00:02.0 vcs0: parked <0> [208.558680] kworker/-223 2..... 57412033us : __gt_park: 0000:00:02.0 <0> [208.558750] gem_exec-2314 2..... 57412471us : __engine_unpark: 0000:00:02.0 bcs0: <0> [208.558824] gem_exec-2314 2..... 57412475us : __gt_unpark: 0000:00:02.0 <0> [208.558926] gem_exec-2314 2..... 57412666us : __engine_unpark: 0000:00:02.0 bcs0: context:6 reset { seqno:1, hwsp:1, ring:88 } <0> [208.559018] gem_exec-2314 2..... 57412667us : __engine_park: 0000:00:02.0 bcs0: parked <0> [208.559107] kworker/-223 2..... 57412679us : __gt_park: 0000:00:02.0 <0> [208.559177] gem_exec-2314 2..... 57413099us : __engine_unpark: 0000:00:02.0 rcs0: <0> [208.559250] gem_exec-2314 2..... 57413104us : __gt_unpark: 0000:00:02.0 <0> [208.559363] gem_exec-2314 2..... 57413284us : __engine_unpark: 0000:00:02.0 rcs0: context:5 reset { seqno:2, hwsp:2, ring:260 } <0> [208.559452] gem_exec-2314 2..... 57413285us : __engine_park: 0000:00:02.0 rcs0: parked <0> [208.559533] kworker/-223 2..... 57413298us : __gt_park: 0000:00:02.0 <0> [208.559602] gem_exec-2314 2..... 57413724us : __engine_unpark: 0000:00:02.0 vcs1: <0> [208.559683] gem_exec-2314 2..... 57413728us : __gt_unpark: 0000:00:02.0 <0> [208.559762] gem_exec-2314 2..... 57413800us : __engine_unpark: 0000:00:02.0 vcs1: context:d reset { seqno:1, hwsp:1, ring:78 } <0> [208.559861] gem_exec-2314 2..... 57413801us : __engine_park: 0000:00:02.0 vcs1: parked <0> [208.559974] kworker/-223 2..... 57413811us : __gt_park: 0000:00:02.0 <0> [208.560088] gem_exec-2314 2..... 57414229us : __gt_unpark: 0000:00:02.0 <0> [208.560218] gem_exec-2314 2..... 57414777us : __gt_park: 0000:00:02.0 <0> [208.560338] gem_exec-2314 7..... 57454640us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes <0> [208.560449] kworker/-298 7..... 57459710us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no <0> [208.560555] api_inte-2315 8..... 57527636us : __gt_unpark: 0000:00:02.0 <0> [208.560661] kworker/-44 8..... 57528948us : __gt_park: 0000:00:02.0 <0> [208.560777] api_inte-2315 2..... 57561016us : __gt_unpark: 0000:00:02.0 <0> [208.560884] api_inte-2315 2..... 57561241us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:1f active <0> [208.560997] api_inte-2315 2..... 57563633us : intel_timeline_pin: 0000:00:02.0 timeline:1f using HWSP offset:fedd1000 <0> [208.561098] api_inte-2315 2..... 57564226us : __engine_unpark: 0000:00:02.0 rcs0: <0> [208.561174] api_inte-2315 2..... 57564236us : __engine_unpark: 0000:00:02.0 rcs0: context:5 reset { seqno:2, hwsp:2, ring:260 } <0> [208.561249] api_inte-2315 2..... 57564238us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:1f pin ring:{start:fecfe000, head:0000, tail:0000} <0> [208.561353] api_inte-2315 2..... 57565369us : __i915_request_commit: 0000:00:02.0 rcs0: fence 1f:2, current 0 <0> [208.561457] api_inte-2315 2D..1. 57565374us : __i915_request_submit: 0000:00:02.0 rcs0: fence 1f:2, current 0 <0> [208.561543] api_inte-2315 2..... 57565434us : __i915_request_commit: 0000:00:02.0 rcs0: fence 5:3, current 2 <0> [208.561630] api_inte-2315 2D..1. 57565437us : __i915_request_submit: 0000:00:02.0 rcs0: fence 5:3, current 2 <0> [208.561642] api_inte-2315 2...1. 57566125us : tracing_mark_write: api_intel_allocator: finished subtest reopen, SUCCESS <0> [208.561726] api_inte-2315 2..... 57567208us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 1f:2, current 2 <0> [208.561810] api_inte-2315 2..... 57567722us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 5:3, current 3 <0> [208.561884] kworker/-160 2..... 57567938us : __intel_context_retire: 0000:00:02.0 rcs0: context:1f retire runtime: { total:110136ns, avg:110136ns } <0> [208.561959] kworker/-160 2..... 57581238us : __gt_unpark: 0000:00:02.0 <0> [208.562047] kworker/-262 4..... 57642959us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no <0> [208.562121] kms_curs-2316 11..... 57779833us : __gt_unpark: 0000:00:02.0 <0> [208.562197] kms_curs-2316 11..... 57780366us : __gt_park: 0000:00:02.0 <0> [208.562273] kms_curs-2316 11..... 57780532us : __gt_unpark: 0000:00:02.0 <0> [208.562348] kms_curs-2316 11..... 57780717us : __gt_park: 0000:00:02.0 <0> [208.562424] kms_curs-2316 11..... 57781512us : __gt_unpark: 0000:00:02.0 <0> [208.562497] kms_curs-2316 11..... 57782071us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:21 active <0> [208.562577] kms_curs-2316 11..... 57783538us : intel_timeline_pin: 0000:00:02.0 timeline:21 using HWSP offset:fedd1000 <0> [208.562652] kms_curs-2316 11..... 57784065us : __engine_unpark: 0000:00:02.0 rcs0: <0> [208.562726] kms_curs-2316 11..... 57784097us : __engine_unpark: 0000:00:02.0 rcs0: context:5 reset { seqno:3, hwsp:3, ring:380 } <0> [208.562799] kms_curs-2316 11..... 57784101us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:21 pin ring:{start:fedbb000, head:0000, tail:0000} <0> [208.562934] kms_curs-2316 11..... 57784651us : __i915_request_commit: 0000:00:02.0 rcs0: fence 21:2, current 0 <0> [208.563069] kms_curs-2316 11D..1. 57784656us : __i915_request_submit: 0000:00:02.0 rcs0: fence 21:2, current 0 <0> [208.563197] kms_curs-2316 11..... 57784717us : __i915_request_commit: 0000:00:02.0 rcs0: fence 5:4, current 3 <0> [208.563310] kms_curs-2316 11D..1. 57784720us : __i915_request_submit: 0000:00:02.0 rcs0: fence 5:4, current 3 <0> [208.563425] kms_curs-2316 11..... 57785537us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:22 active <0> [208.563543] kms_curs-2316 11..... 57786196us : intel_timeline_pin: 0000:00:02.0 timeline:22 using HWSP offset:fedd0000 <0> [208.563654] kms_curs-2316 11..... 57786647us : __engine_unpark: 0000:00:02.0 bcs0: <0> [208.563764] kms_curs-2316 11..... 57786655us : __engine_unpark: 0000:00:02.0 bcs0: context:6 reset { seqno:1, hwsp:1, ring:88 } <0> [208.563873] kms_curs-2316 11..... 57786656us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:22 pin ring:{start:fedb7000, head:0000, tail:0000} <0> [208.563991] kms_curs-2316 11..... 57787530us : __i915_request_commit: 0000:00:02.0 bcs0: fence 22:2, current 0 <7> [208.564005] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] level wm0,wm1,wm2,wm3,wm4,wm5, wm6, wm7,twm,swm,stwm -> wm0,wm1,wm2,wm3,wm4,wm5, wm6, wm7,twm,swm,stwm <0> [208.564124] kms_curs-2316 11D..1. 57787534us : __i915_request_submit: 0000:00:02.0 bcs0: fence 22:2, current 0 <0> [208.564250] kms_curs-2316 11..... 57787617us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:2, current 1 <7> [208.564290] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] lines 2, 16, 16, 19, 19, 28, 0, 0, 0, 3, 0 -> 2, 16, 16, 19, 19, 28, 0, 0, 0, 3, 0 <0> [208.564409] kms_curs-2316 11D..1. 57787620us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:2, current 1 <0> [208.564517] kworker/-160 8..... 57788948us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:22 unpin <7> [208.564494] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] blocks 49, 385, 385, 457, 457, 673, 0, 0, 63, 73, 87 -> 27, 209, 209, 248, 248, 365, 0, 0, 41, 40, 54 <0> [208.564627] kworker/-160 8..... 57789064us : __intel_context_retire: 0000:00:02.0 bcs0: context:22 retire runtime: { total:73684ns, avg:73684ns } <0> [208.564741] kms_curs-2316 11..... 57791761us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:23 active <7> [208.564705] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] min_ddb 55, 425, 425, 504, 504, 742, 0, 0, 64, 82, 88 -> 31, 231, 231, 274, 274, 403, 0, 0, 42, 45, 55 <0> [208.564862] kms_curs-2316 11..... 57792754us : intel_timeline_pin: 0000:00:02.0 timeline:23 using HWSP offset:fedcf000 <0> [208.564988] kms_curs-2316 11..... 57793094us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:23 pin ring:{start:fecfe000, head:0000, tail:0000} <7> [208.564951] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:150:pipe A] data rate 1027640 num active planes 1 <0> [208.565124] kms_curs-2316 11..... 57794125us : __i915_request_commit: 0000:00:02.0 bcs0: fence 23:2, current 0 <0> [208.565246] kms_curs-2316 11D..1. 57794129us : __i915_request_submit: 0000:00:02.0 bcs0: fence 23:2, current 0 <7> [208.565199] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 28913 required 1080 qgv_peak_bw: 51200 <0> [208.565369] kms_curs-2316 11..... 57794171us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:3, current 2 <0> [208.565489] kms_curs-2316 11D..1. 57794276us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:3, current 2 <7> [208.565409] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 1: max bw 38000 required 1080 qgv_peak_bw: 51200 <0> [208.565605] kms_curs-2316 11..... 57794295us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:23:2 <0> [208.565714] kworker/-160 8..... 57795099us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:23 unpin <7> [208.565619] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 2: max bw 38000 required 1080 qgv_peak_bw: 51200 <0> [208.565822] kworker/-160 8..... 57795181us : __intel_context_retire: 0000:00:02.0 bcs0: context:23 retire runtime: { total:135096ns, avg:135096ns } <7> [208.565826] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 3: max bw 38000 required 1080 qgv_peak_bw: 51200 <0> [208.565929] kms_curs-2316 11..... 57796789us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:24 active <0> [208.566044] kms_curs-2316 11..... 57797869us : intel_timeline_pin: 0000:00:02.0 timeline:24 using HWSP offset:fedb6000 <7> [208.566034] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] Matching peaks QGV bw: 51200 for required data rate: 1080 <0> [208.566163] kms_curs-2316 11..... 57798216us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:24 pin ring:{start:fecf2000, head:0000, tail:0000} <0> [208.566285] kms_curs-2316 11..... 57799021us : __i915_request_commit: 0000:00:02.0 bcs0: fence 24:2, current 0 <0> [208.566393] kms_curs-2316 11D..1. 57799027us : __i915_request_submit: 0000:00:02.0 bcs0: fence 24:2, current 0 <0> [208.566506] kms_curs-2316 11..... 57799157us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:4, current 3 <0> [208.566596] kms_curs-2316 11D..1. 57799169us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:4, current 3 <0> [208.566673] kms_curs-2316 11..... 57801027us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:25 active <0> [208.566756] kms_curs-2316 8..... 57802624us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:25 pin ring:{start:03104000, head:0000, tail:0000} <0> [208.566837] kms_curs-2316 8..... 57804760us : __i915_request_commit: 0000:00:02.0 bcs0: fence 25:2, current 0 <0> [208.566921] kms_curs-2316 8D..1. 57804767us : __i915_request_submit: 0000:00:02.0 bcs0: fence 25:2, current 0 <0> [208.567016] kms_curs-2316 8..... 57804856us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:5, current 4 <0> [208.567106] kms_curs-2316 8D..1. 57804863us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:5, current 4 <0> [208.567185] kms_curs-2316 4..... 57809596us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:26 active <0> [208.567276] kms_curs-2316 4..... 57812827us : __i915_request_commit: 0000:00:02.0 bcs0: fence 26:2, current 0 <0> [208.567372] kms_curs-2316 4D..1. 57812832us : __i915_request_submit: 0000:00:02.0 bcs0: fence 26:2, current 0 <0> [208.567469] kms_curs-2316 4..... 57812878us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:6, current 5 <0> [208.567561] kms_curs-2316 4D..1. 57812882us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:6, current 5 <0> [208.567638] kms_curs-2316 4..... 57812902us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:26:2 <0> [208.567712] kms_curs-2316 4..... 57814907us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:27 active <0> [208.567802] kms_curs-2316 4..... 57816118us : intel_timeline_pin: 0000:00:02.0 timeline:27 using HWSP offset:fedd0000 <0> [208.567906] kms_curs-2316 4..... 57816513us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:27 pin ring:{start:feda6000, head:0000, tail:0000} <0> [208.567993] kms_curs-2316 4..... 57816523us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:27 unpin <0> [208.568095] kms_curs-2316 4..... 57816588us : __intel_context_retire: 0000:00:02.0 bcs0: context:27 retire runtime: { total:0ns, avg:0ns } <0> [208.568186] kms_curs-2316 4..... 57816590us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:27 active <0> [208.568274] kms_curs-2316 4..... 57816591us : intel_timeline_pin: 0000:00:02.0 timeline:27 using HWSP offset:fedd0000 <0> [208.568358] kms_curs-2316 4..... 57816594us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:27 pin ring:{start:feda6000, head:0000, tail:0000} <0> [208.568444] kms_curs-2316 4..... 57817559us : __i915_request_commit: 0000:00:02.0 bcs0: fence 27:2, current 0 <0> [208.568528] kms_curs-2316 4D..1. 57817562us : __i915_request_submit: 0000:00:02.0 bcs0: fence 27:2, current 0 <0> [208.568612] kms_curs-2316 4..... 57817599us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:7, current 6 <0> [208.568695] kms_curs-2316 4D..1. 57817602us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:7, current 6 <0> [208.568772] kms_curs-2316 4..... 57817617us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:27:2 <0> [208.568844] kms_curs-2316 4..... 57819748us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:28 active <0> [208.568922] kms_curs-2316 4..... 57820543us : intel_timeline_pin: 0000:00:02.0 timeline:28 using HWSP offset:fece8000 <0> [208.568993] kms_curs-2316 4..... 57820810us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:28 pin ring:{start:fece9000, head:0000, tail:0000} <0> [208.569066] kms_curs-2316 4..... 57820819us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:28 unpin <0> [208.569138] kms_curs-2316 4..... 57820884us : __intel_context_retire: 0000:00:02.0 bcs0: context:28 retire runtime: { total:0ns, avg:0ns } <0> [208.569213] kms_curs-2316 4..... 57820886us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:28 active <0> [208.569296] kms_curs-2316 4..... 57820887us : intel_timeline_pin: 0000:00:02.0 timeline:28 using HWSP offset:fece8000 <0> [208.569367] kms_curs-2316 4..... 57820890us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:28 pin ring:{start:fece9000, head:0000, tail:0000} <0> [208.569438] kms_curs-2316 4..... 57820897us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:28 unpin <0> [208.569510] kms_curs-2316 4..... 57820962us : __intel_context_retire: 0000:00:02.0 bcs0: context:28 retire runtime: { total:0ns, avg:0ns } <0> [208.569581] kms_curs-2316 4..... 57820964us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:28 active <0> [208.569659] kms_curs-2316 4..... 57820964us : intel_timeline_pin: 0000:00:02.0 timeline:28 using HWSP offset:fece8000 <0> [208.569730] kms_curs-2316 4..... 57820967us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:28 pin ring:{start:fece9000, head:0000, tail:0000} <0> [208.569814] kms_curs-2316 4..... 57821922us : __i915_request_commit: 0000:00:02.0 bcs0: fence 28:2, current 0 <0> [208.569897] kms_curs-2316 4D..1. 57821925us : __i915_request_submit: 0000:00:02.0 bcs0: fence 28:2, current 0 <0> [208.569981] kms_curs-2316 4..... 57821964us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:8, current 7 <0> [208.570062] kms_curs-2316 4D..1. 57821967us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:8, current 7 <0> [208.570141] kms_curs-2316 4..... 57821983us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:28:2 <0> [208.570217] kms_curs-2316 4..... 57823816us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:29 active <0> [208.570306] kms_curs-2316 4..... 57824660us : intel_timeline_pin: 0000:00:02.0 timeline:29 using HWSP offset:fedcf000 <0> [208.570378] kms_curs-2316 4..... 57825010us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:29 pin ring:{start:feced000, head:0000, tail:0000} <0> [208.570451] kms_curs-2316 4..... 57825020us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:29 unpin <0> [208.570524] kms_curs-2316 4..... 57825085us : __intel_context_retire: 0000:00:02.0 bcs0: context:29 retire runtime: { total:0ns, avg:0ns } <0> [208.570595] kms_curs-2316 4..... 57825087us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:29 active <0> [208.570670] kms_curs-2316 4..... 57825088us : intel_timeline_pin: 0000:00:02.0 timeline:29 using HWSP offset:fedcf000 <0> [208.570742] kms_curs-2316 4..... 57825091us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:29 pin ring:{start:feced000, head:0000, tail:0000} <0> [208.570813] kms_curs-2316 4..... 57825098us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:29 unpin <0> [208.570887] kms_curs-2316 4..... 57825163us : __intel_context_retire: 0000:00:02.0 bcs0: context:29 retire runtime: { total:0ns, avg:0ns } <0> [208.570961] kms_curs-2316 4..... 57825165us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:29 active <0> [208.571050] kms_curs-2316 4..... 57825165us : intel_timeline_pin: 0000:00:02.0 timeline:29 using HWSP offset:fedcf000 <0> [208.571139] kms_curs-2316 4..... 57825168us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:29 pin ring:{start:feced000, head:0000, tail:0000} <0> [208.571227] kms_curs-2316 4..... 57826161us : __i915_request_commit: 0000:00:02.0 bcs0: fence 29:2, current 0 <0> [208.571317] kms_curs-2316 4D..1. 57826164us : __i915_request_submit: 0000:00:02.0 bcs0: fence 29:2, current 0 <0> [208.571399] kms_curs-2316 4..... 57826201us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:9, current 8 <0> [208.571481] kms_curs-2316 4D..1. 57826204us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:9, current 8 <0> [208.571559] kms_curs-2316 4..... 57826219us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:29:2 <0> [208.571633] kworker/-339 18..... 57826897us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:29 unpin <0> [208.571705] kworker/-339 18..... 57826970us : __intel_context_retire: 0000:00:02.0 bcs0: context:29 retire runtime: { total:110604ns, avg:110604ns } <0> [208.571778] kms_curs-2316 4..... 57828529us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:2a active <0> [208.571857] kms_curs-2316 4..... 57829386us : intel_timeline_pin: 0000:00:02.0 timeline:2a using HWSP offset:fecf1000 <0> [208.571930] kms_curs-2316 4..... 57829665us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:2a pin ring:{start:fecda000, head:0000, tail:0000} <0> [208.572012] kms_curs-2316 4..... 57830619us : __i915_request_commit: 0000:00:02.0 bcs0: fence 2a:2, current 0 <0> [208.572094] kms_curs-2316 4D..1. 57830623us : __i915_request_submit: 0000:00:02.0 bcs0: fence 2a:2, current 0 <0> [208.572177] kms_curs-2316 4..... 57830661us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:10, current 9 <0> [208.572261] kms_curs-2316 4D..1. 57830663us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:10, current 9 <0> [208.572352] kms_curs-2316 4..... 57830677us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:2a:2 <0> [208.572424] kworker/-339 18..... 57831054us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:2a unpin <0> [208.572495] kworker/-339 18..... 57831126us : __intel_context_retire: 0000:00:02.0 bcs0: context:2a retire runtime: { total:19968ns, avg:19968ns } <0> [208.572569] kms_curs-2316 4..... 57832274us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:2b active <0> [208.572647] kms_curs-2316 4..... 57833429us : intel_timeline_pin: 0000:00:02.0 timeline:2b using HWSP offset:fedae000 <0> [208.572719] kms_curs-2316 4..... 57833826us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:2b pin ring:{start:fedb7000, head:0000, tail:0000} <0> [208.572803] kms_curs-2316 4..... 57834726us : __i915_request_commit: 0000:00:02.0 bcs0: fence 2b:2, current 0 <0> [208.572887] kms_curs-2316 4D..1. 57834730us : __i915_request_submit: 0000:00:02.0 bcs0: fence 2b:2, current 0 <0> [208.572971] kms_curs-2316 4..... 57834768us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:11, current 10 <0> [208.573054] kms_curs-2316 4D..1. 57834770us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:11, current 10 <0> [208.573133] kms_curs-2316 4..... 57834785us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:2b:2 <0> [208.573207] kworker/-339 18..... 57835106us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:2b unpin <0> [208.573283] kworker/-339 18..... 57835170us : __intel_context_retire: 0000:00:02.0 bcs0: context:2b retire runtime: { total:19240ns, avg:19240ns } <0> [208.573359] kms_curs-2316 4..... 57836470us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:2c active <0> [208.573437] kms_curs-2316 4..... 57837302us : intel_timeline_pin: 0000:00:02.0 timeline:2c using HWSP offset:feccf000 <0> [208.573508] kms_curs-2316 4..... 57837612us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:2c pin ring:{start:fedaa000, head:0000, tail:0000} <0> [208.573594] kms_curs-2316 4..... 57838301us : __i915_request_commit: 0000:00:02.0 bcs0: fence 2c:2, current 0 <0> [208.573679] kms_curs-2316 4D..1. 57838305us : __i915_request_submit: 0000:00:02.0 bcs0: fence 2c:2, current 0 <0> [208.573762] kms_curs-2316 4..... 57838341us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:12, current 11 <0> [208.573846] kms_curs-2316 4D..1. 57838344us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:12, current 11 <0> [208.573924] kms_curs-2316 4..... 57838359us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:2c:2 <0> [208.573998] kms_curs-2316 4..... 57840842us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:2d active <0> [208.574077] kms_curs-2316 4..... 57841577us : intel_timeline_pin: 0000:00:02.0 timeline:2d using HWSP offset:feda9000 <0> [208.574150] kms_curs-2316 4..... 57841842us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:2d pin ring:{start:fecff000, head:0000, tail:0000} <0> [208.574234] kms_curs-2316 4..... 57842666us : __i915_request_commit: 0000:00:02.0 bcs0: fence 2d:2, current 0 <0> [208.574318] kms_curs-2316 4D..1. 57842669us : __i915_request_submit: 0000:00:02.0 bcs0: fence 2d:2, current 0 <0> [208.574406] kms_curs-2316 4..... 57842708us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:13, current 12 <0> [208.574488] kms_curs-2316 4D..1. 57842710us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:13, current 12 <0> [208.574567] kms_curs-2316 4..... 57842725us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:2d:2 <0> [208.574640] kms_curs-2316 8..... 57847304us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:2e active <0> [208.574718] kms_curs-2316 8..... 57848253us : intel_timeline_pin: 0000:00:02.0 timeline:2e using HWSP offset:fedaf000 <0> [208.574791] kms_curs-2316 8..... 57848734us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:2e pin ring:{start:feda5000, head:0000, tail:0000} <0> [208.574864] kworker/-160 18..... 57857662us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:30 unpin <0> [208.574937] kworker/-160 18..... 57857825us : __intel_context_retire: 0000:00:02.0 bcs0: context:30 retire runtime: { total:17472ns, avg:17472ns } <0> [208.575009] kworker/-160 19..... 57869754us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:33 unpin <0> [208.575080] kworker/-160 19..... 57869819us : __intel_context_retire: 0000:00:02.0 bcs0: context:33 retire runtime: { total:24700ns, avg:24700ns } <0> [208.575152] kworker/-160 19..... 57876668us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:35 unpin <0> [208.575224] kworker/-160 19..... 57876740us : __intel_context_retire: 0000:00:02.0 bcs0: context:35 retire runtime: { total:25792ns, avg:25792ns } <0> [208.575296] kms_curs-2316 18..... 57879300us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:36 active <0> [208.575376] kms_curs-2316 18..... 57879986us : intel_timeline_pin: 0000:00:02.0 timeline:36 using HWSP offset:fedad000 <0> [208.575455] kms_curs-2316 18..... 57880348us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:36 pin ring:{start:feda5000, head:0000, tail:0000} <0> [208.575524] kworker/-160 19..... 57881743us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:36 unpin <0> [208.575595] kworker/-160 19..... 57881881us : __intel_context_retire: 0000:00:02.0 bcs0: context:36 retire runtime: { total:26260ns, avg:26260ns } <0> [208.575666] kworker/-160 19..... 57885337us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:37 unpin <0> [208.575739] kworker/-160 19..... 57885401us : __intel_context_retire: 0000:00:02.0 bcs0: context:37 retire runtime: { total:19240ns, avg:19240ns } <0> [208.575811] kworker/-160 19..... 57889418us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:38 unpin <0> [208.575883] kworker/-160 19..... 57889483us : __intel_context_retire: 0000:00:02.0 bcs0: context:38 retire runtime: { total:19708ns, avg:19708ns } <0> [208.575954] kms_curs-2316 4..... 57890818us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:39 active <0> [208.576026] kms_curs-2316 19..... 57896656us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:3a active <0> [208.576122] kms_curs-2316 19..... 57897222us : intel_timeline_pin: 0000:00:02.0 timeline:3a using HWSP offset:fedae000 <0> [208.576209] kms_curs-2316 19..... 57897548us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:3a pin ring:{start:feda9000, head:0000, tail:0000} <0> [208.576292] kms_curs-2316 19..... 57898737us : __i915_request_commit: 0000:00:02.0 bcs0: fence 3a:2, current 0 <0> [208.576378] kms_curs-2316 19D..1. 57898742us : __i915_request_submit: 0000:00:02.0 bcs0: fence 3a:2, current 0 <0> [208.576468] kms_curs-2316 19..... 57898800us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:26, current 25 <0> [208.576548] kms_curs-2316 19D..1. 57898805us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:26, current 25 <0> [208.576627] kms_curs-2316 19..... 57898826us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:3a:2 <0> [208.576700] kworker/-339 16..... 57899129us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:3a unpin <0> [208.576773] kworker/-339 16..... 57899195us : __intel_context_retire: 0000:00:02.0 bcs0: context:3a retire runtime: { total:38636ns, avg:38636ns } <0> [208.576847] kms_curs-2316 19..... 57900835us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:3b active <0> [208.576923] kms_curs-2316 19..... 57901486us : intel_timeline_pin: 0000:00:02.0 timeline:3b using HWSP offset:fece0000 <0> [208.576995] kms_curs-2316 19..... 57901854us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:3b pin ring:{start:feda5000, head:0000, tail:0000} <0> [208.577075] kms_curs-2316 19..... 57903056us : __i915_request_commit: 0000:00:02.0 bcs0: fence 3b:2, current 0 <0> [208.577159] kms_curs-2316 19D..1. 57903061us : __i915_request_submit: 0000:00:02.0 bcs0: fence 3b:2, current 0 <0> [208.577242] kms_curs-2316 19..... 57903120us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:27, current 26 <0> [208.577325] kms_curs-2316 19D..1. 57903125us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:27, current 26 <0> [208.577404] kms_curs-2316 19..... 57903147us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:3b:2 <0> [208.577481] kworker/-339 16..... 57903462us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:3b unpin <0> [208.577557] kworker/-339 16..... 57903597us : __intel_context_retire: 0000:00:02.0 bcs0: context:3b retire runtime: { total:49868ns, avg:49868ns } <0> [208.577631] kms_curs-2316 4..... 57907737us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:3c active <0> [208.577710] kms_curs-2316 4..... 57908800us : intel_timeline_pin: 0000:00:02.0 timeline:3c using HWSP offset:fedaf000 <0> [208.577783] kms_curs-2316 4..... 57909197us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:3c pin ring:{start:fecff000, head:0000, tail:0000} <0> [208.577867] kms_curs-2316 4..... 57910189us : __i915_request_commit: 0000:00:02.0 bcs0: fence 3c:2, current 0 <0> [208.577951] kms_curs-2316 4D..1. 57910194us : __i915_request_submit: 0000:00:02.0 bcs0: fence 3c:2, current 0 <0> [208.578035] kms_curs-2316 4..... 57910242us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:28, current 27 <0> [208.578118] kms_curs-2316 4D..1. 57910245us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:28, current 27 <0> [208.578196] kms_curs-2316 4..... 57910262us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:3c:2 <0> [208.578270] kworker/-339 16..... 57910634us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:3c unpin <0> [208.578343] kworker/-339 16..... 57910766us : __intel_context_retire: 0000:00:02.0 bcs0: context:3c retire runtime: { total:52416ns, avg:52416ns } <0> [208.578416] kms_curs-2316 4..... 57911792us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:3d active <0> [208.578495] kms_curs-2316 4..... 57912525us : intel_timeline_pin: 0000:00:02.0 timeline:3d using HWSP offset:feda9000 <0> [208.578582] kms_curs-2316 4..... 57912859us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:3d pin ring:{start:fedaa000, head:0000, tail:0000} <0> [208.578691] kms_curs-2316 4..... 57913623us : __i915_request_commit: 0000:00:02.0 bcs0: fence 3d:2, current 0 <0> [208.578799] kms_curs-2316 4D..1. 57913627us : __i915_request_submit: 0000:00:02.0 bcs0: fence 3d:2, current 0 <0> [208.578907] kms_curs-2316 4..... 57913665us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:29, current 28 <0> [208.579048] kms_curs-2316 4D..1. 57913668us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:29, current 28 <7> [208.578952] i915 0000:00:02.0: [drm:i915_fifo_underrun_reset_write [i915]] Re-arming FIFO underruns on pipe A <0> [208.579184] kms_curs-2316 4..... 57913683us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:3d:2 <0> [208.579317] kworker/-339 16..... 57914024us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:3d unpin <0> [208.579463] kworker/-339 16..... 57914172us : __intel_context_retire: 0000:00:02.0 bcs0: context:3d retire runtime: { total:49140ns, avg:49140ns } <0> [208.579577] kms_curs-2316 4..... 57915646us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:3e active <0> [208.579708] kms_curs-2316 4..... 57916341us : intel_timeline_pin: 0000:00:02.0 timeline:3e using HWSP offset:fecf1000 <0> [208.579836] kms_curs-2316 4..... 57916723us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:3e pin ring:{start:03104000, head:0000, tail:0000} <0> [208.579998] kms_curs-2316 4..... 57917464us : __i915_request_commit: 0000:00:02.0 bcs0: fence 3e:2, current 0 <0> [208.580151] kms_curs-2316 4D..1. 57917468us : __i915_request_submit: 0000:00:02.0 bcs0: fence 3e:2, current 0 <6> [208.580242] [IGT] kms_atomic: starting dynamic subtest pipe-A-eDP-1 <0> [208.580281] kms_curs-2316 4..... 57917506us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:30, current 29 <0> [208.580416] kms_curs-2316 4D..1. 57917509us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:30, current 29 <0> [208.580547] kms_curs-2316 4..... 57917524us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:3e:2 <0> [208.580653] kworker/-339 16..... 57917810us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:3e unpin <0> [208.580760] kworker/-339 16..... 57917874us : __intel_context_retire: 0000:00:02.0 bcs0: context:3e retire runtime: { total:23036ns, avg:23036ns } <0> [208.580863] kms_curs-2316 4..... 57919045us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:3f active <7> [208.580815] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.enable (expected yes, found no) <0> [208.580977] kms_curs-2316 4..... 57919688us : intel_timeline_pin: 0000:00:02.0 timeline:3f using HWSP offset:fedd0000 <0> [208.581090] kms_curs-2316 4..... 57920015us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:3f pin ring:{start:feda5000, head:0000, tail:0000} <7> [208.581030] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.active (expected yes, found no) <0> [208.581201] kms_curs-2316 4..... 57920025us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:3f unpin <0> [208.581311] kms_curs-2316 4..... 57920089us : __intel_context_retire: 0000:00:02.0 bcs0: context:3f retire runtime: { total:0ns, avg:0ns } <7> [208.581243] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1) <0> [208.581422] kms_curs-2316 4..... 57920091us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:3f active <0> [208.581538] kms_curs-2316 4..... 57920092us : intel_timeline_pin: 0000:00:02.0 timeline:3f using HWSP offset:fedd0000 <7> [208.581457] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in lane_count (expected 4, found 0) <0> [208.581662] kms_curs-2316 4..... 57920095us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:3f pin ring:{start:feda5000, head:0000, tail:0000} <0> [208.581785] kms_curs-2316 4..... 57920797us : __i915_request_commit: 0000:00:02.0 bcs0: fence 3f:2, current 0 <7> [208.581692] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in dp_m_n (expected tu 64 data 7483046/8388608 link 498869/524288, found tu 0, data 0/0 link 0/0) <0> [208.581909] kms_curs-2316 4D..1. 57920801us : __i915_request_submit: 0000:00:02.0 bcs0: fence 3f:2, current 0 <7> [208.581908] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000) <0> [208.582030] kms_curs-2316 4..... 57920837us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:31, current 30 <0> [208.582151] kms_curs-2316 4D..1. 57920839us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:31, current 30 <7> [208.582122] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0) <0> [208.582270] kms_curs-2316 4..... 57920853us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:3f:2 <0> [208.582380] kworker/-339 16..... 57921110us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:3f unpin <7> [208.582341] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 2880, found 0) <0> [208.582492] kworker/-339 16..... 57921184us : __intel_context_retire: 0000:00:02.0 bcs0: context:3f retire runtime: { total:23036ns, avg:23036ns } <0> [208.582604] kms_curs-2316 4..... 57922590us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:40 active <7> [208.582553] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 3040, found 0) <0> [208.582732] kms_curs-2316 4..... 57923576us : intel_timeline_pin: 0000:00:02.0 timeline:40 using HWSP offset:fedcf000 <0> [208.582844] kms_curs-2316 4..... 57923961us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:40 pin ring:{start:feccc000, head:0000, tail:0000} <7> [208.582800] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 2880, found 0) <0> [208.582956] kms_curs-2316 4..... 57923971us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:40 unpin <0> [208.583067] kms_curs-2316 4..... 57924036us : __intel_context_retire: 0000:00:02.0 bcs0: context:40 retire runtime: { total:0ns, avg:0ns } <7> [208.583008] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 3040, found 0) <0> [208.583178] kms_curs-2316 4..... 57924038us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:40 active <0> [208.583298] kms_curs-2316 4..... 57924038us : intel_timeline_pin: 0000:00:02.0 timeline:40 using HWSP offset:fedcf000 <7> [208.583219] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2928, found 0) <0> [208.583410] kms_curs-2316 4..... 57924041us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:40 pin ring:{start:feccc000, head:0000, tail:0000} <0> [208.583522] kms_curs-2316 4..... 57924048us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:40 unpin <7> [208.583433] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2960, found 0) <0> [208.583634] kms_curs-2316 4..... 57924112us : __intel_context_retire: 0000:00:02.0 bcs0: context:40 retire runtime: { total:0ns, avg:0ns } <0> [208.583757] kms_curs-2316 4..... 57924114us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:40 active <7> [208.583648] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1800, found 0) <0> [208.583883] kms_curs-2316 4..... 57924115us : intel_timeline_pin: 0000:00:02.0 timeline:40 using HWSP offset:fedcf000 <0> [208.583995] kms_curs-2316 4..... 57924117us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:40 pin ring:{start:feccc000, head:0000, tail:0000} <7> [208.583898] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1801, found 0) <0> [208.584121] kms_curs-2316 4..... 57924767us : __i915_request_commit: 0000:00:02.0 bcs0: fence 40:2, current 0 <7> [208.584119] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1803, found 0) <0> [208.584266] kms_curs-2316 4D..1. 57924770us : __i915_request_submit: 0000:00:02.0 bcs0: fence 40:2, current 0 <0> [208.584412] kms_curs-2316 4..... 57924808us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:32, current 31 <7> [208.584383] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1809, found 0) <0> [208.584554] kms_curs-2316 4D..1. 57924811us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:32, current 31 <0> [208.584686] kms_curs-2316 4..... 57924824us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:40:2 <7> [208.584638] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1878, found 0) <0> [208.584811] kworker/-339 16..... 57925078us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:40 unpin <0> [208.584927] kworker/-339 16..... 57925144us : __intel_context_retire: 0000:00:02.0 bcs0: context:40 retire runtime: { total:23816ns, avg:23816ns } <7> [208.584883] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1878, found 0) <0> [208.585039] kms_curs-2316 4..... 57926154us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:41 active <0> [208.585158] kms_curs-2316 4..... 57926984us : intel_timeline_pin: 0000:00:02.0 timeline:41 using HWSP offset:fedae000 <7> [208.585095] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 2880, found 0) <0> [208.585270] kms_curs-2316 4..... 57927627us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:41 pin ring:{start:03112000, head:0000, tail:0000} <0> [208.585381] kms_curs-2316 4..... 57927637us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:41 unpin <7> [208.585305] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 3040, found 0) <0> [208.585493] kms_curs-2316 4..... 57927702us : __intel_context_retire: 0000:00:02.0 bcs0: context:41 retire runtime: { total:0ns, avg:0ns } <0> [208.585604] kms_curs-2316 4..... 57927704us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:41 active <7> [208.585517] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 2880, found 0) <0> [208.585724] kms_curs-2316 4..... 57927705us : intel_timeline_pin: 0000:00:02.0 timeline:41 using HWSP offset:fedae000 <0> [208.585832] kms_curs-2316 4..... 57927707us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:41 pin ring:{start:03112000, head:0000, tail:0000} <7> [208.585740] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 3040, found 0) <0> [208.585942] kms_curs-2316 4..... 57927714us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:41 unpin <0> [208.586051] kms_curs-2316 4..... 57927780us : __intel_context_retire: 0000:00:02.0 bcs0: context:41 retire runtime: { total:0ns, avg:0ns } <7> [208.585953] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2928, found 0) <0> [208.586165] kms_curs-2316 4..... 57927781us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:41 active <7> [208.586175] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2960, found 0) <0> [208.586284] kms_curs-2316 4..... 57927782us : intel_timeline_pin: 0000:00:02.0 timeline:41 using HWSP offset:fedae000 <0> [208.586395] kms_curs-2316 4..... 57927784us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:41 pin ring:{start:03112000, head:0000, tail:0000} <7> [208.586391] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1800, found 0) <0> [208.586517] kms_curs-2316 4.l... 57928549us : __i915_request_commit: 0000:00:02.0 bcs0: fence 41:2, current 0 <0> [208.586649] kms_curs-2316 4Dl.1. 57928552us : __i915_request_submit: 0000:00:02.0 bcs0: fence 41:2, current 0 <7> [208.586608] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1801, found 0) <0> [208.586775] kms_curs-2316 4..... 57930482us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:42 active <0> [208.586899] kms_curs-2316 4..... 57931321us : intel_timeline_pin: 0000:00:02.0 timeline:42 using HWSP offset:fed02000 <7> [208.586863] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1803, found 0) <0> [208.587010] kms_curs-2316 4..... 57931943us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:42 pin ring:{start:fedaa000, head:0000, tail:0000} <0> [208.587133] kms_curs-2316 4..... 57932627us : __i915_request_commit: 0000:00:02.0 bcs0: fence 42:2, current 0 <7> [208.587076] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1809, found 0) <0> [208.587258] kms_curs-2316 4D..1. 57932630us : __i915_request_submit: 0000:00:02.0 bcs0: fence 42:2, current 0 <0> [208.587379] kms_curs-2316 4..... 57932667us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:33, current 32 <7> [208.587290] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1878, found 0) <0> [208.587507] kms_curs-2316 4D..1. 57932670us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:33, current 32 <7> [208.587505] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1878, found 0) <0> [208.587624] kms_curs-2316 4..... 57932683us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:42:2 <0> [208.587732] kworker/-339 16..... 57932992us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:42 unpin <7> [208.587718] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0) <0> [208.587852] kworker/-339 16..... 57933057us : __intel_context_retire: 0000:00:02.0 bcs0: context:42 retire runtime: { total:80912ns, avg:80912ns } <0> [208.587968] kms_curs-2316 4..... 57934026us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:43 active <7> [208.587953] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0) <0> [208.588087] kms_curs-2316 4..... 57934864us : intel_timeline_pin: 0000:00:02.0 timeline:43 using HWSP offset:fed01000 <0> [208.588197] kms_curs-2316 4..... 57935274us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:43 pin ring:{start:fece3000, head:0000, tail:0000} <7> [208.588169] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0) <0> [208.588320] kms_curs-2316 4..... 57935899us : __i915_request_commit: 0000:00:02.0 bcs0: fence 43:2, current 0 <0> [208.588442] kms_curs-2316 4D..1. 57935902us : __i915_request_submit: 0000:00:02.0 bcs0: fence 43:2, current 0 <7> [208.588381] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in enhanced_framing (expected yes, found no) <0> [208.588564] kms_curs-2316 4..... 57935938us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:34, current 33 <0> [208.588686] kms_curs-2316 4D..1. 57935941us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:34, current 33 <7> [208.588592] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in pipe_bpp (expected 30, found 0) <0> [208.588802] kms_curs-2316 4..... 57935954us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:43:2 <0> [208.588921] kworker/-339 16..... 57936269us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:43 unpin <7> [208.588807] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 513820, found 0) <0> [208.589044] kworker/-339 16..... 57936334us : __intel_context_retire: 0000:00:02.0 bcs0: context:43 retire runtime: { total:78884ns, avg:78884ns } <7> [208.589049] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 513820, found 0) <0> [208.589155] kms_curs-2316 4.l... 57937760us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:44 active <0> [208.589264] kworker/-339 7..... 57941994us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:44 unpin <7> [208.589261] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in port_clock (expected 540000, found 0) <0> [208.589373] kworker/-339 7..... 57942070us : __intel_context_retire: 0000:00:02.0 bcs0: context:44 retire runtime: { total:81172ns, avg:81172ns } <0> [208.589480] kworker/-339 7..... 57946170us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:45 unpin <7> [208.589473] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in set_context_latency (expected 1, found 0) <0> [208.589589] kworker/-339 7..... 57946300us : __intel_context_retire: 0000:00:02.0 bcs0: context:45 retire runtime: { total:98332ns, avg:98332ns } <0> [208.589701] kworker/-339 7..... 57950284us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:46 unpin <7> [208.589687] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:150:pipe A] fastset requirement not met, forcing full modeset <0> [208.589810] kworker/-339 7..... 57950356us : __intel_context_retire: 0000:00:02.0 bcs0: context:46 retire runtime: { total:37856ns, avg:37856ns } <0> [208.589925] kworker/-339 7..... 57953788us : __intel_context_retire: 0000:00:02.0 bcs0: context:47 retire runtime: { total:36868ns, avg:36868ns } <7> [208.589914] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:150:pipe A] releasing DPLL 0 <0> [208.590035] kworker/-339 7..... 57960591us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:49 unpin <0> [208.590147] kworker/-339 7..... 57960660us : __intel_context_retire: 0000:00:02.0 bcs0: context:49 retire runtime: { total:38064ns, avg:38064ns } <0> [208.590168] kms_curs-2316 2...1. 58011759us : tracing_mark_write: kms_cursor_crc: finished subtest cursor-sliding-256x85, SKIP <0> [208.590289] kms_curs-2316 2..... 58013448us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:4a active <7> [208.590184] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no <0> [208.590418] kms_curs-2316 2..... 58014324us : intel_timeline_pin: 0000:00:02.0 timeline:4a using HWSP offset:fedd0000 <7> [208.590410] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:150:pipe A] dbuf slices 0xf -> 0x0, ddb (0 - 4096) -> (0 - 0), active pipes 0x1 -> 0x0 <0> [208.590538] kms_curs-2316 2..... 58014718us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:4a pin ring:{start:feda5000, head:0000, tail:0000} <0> [208.590671] kms_curs-2316 2..... 58015539us : __i915_request_commit: 0000:00:02.0 bcs0: fence 4a:2, current 0 <7> [208.590638] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:34:plane 1A] ddb ( 0 - 4001) -> ( 0 - 0), size 4001 -> 0 <0> [208.590796] kms_curs-2316 2D..1. 58015542us : __i915_request_submit: 0000:00:02.0 bcs0: fence 4a:2, current 0 <0> [208.590924] kms_curs-2316 2..... 58015585us : i915_request_retire_upto: 0000:00:02.0 bcs0: fence 6:4, current 40 <7> [208.590848] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:144:cursor A] ddb (4001 - 4096) -> ( 0 - 0), size 95 -> 0 <0> [208.591051] kms_curs-2316 2..... 58015586us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 6:4, current 40 <0> [208.591174] kms_curs-2316 2..... 58015593us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:41, current 40 <7> [208.591084] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] level wm0,wm1,wm2,wm3,wm4,wm5, wm6, wm7,twm,swm,stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm <0> [208.591296] kms_curs-2316 2D..1. 58015595us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:41, current 40 <7> [208.591291] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] lines 2, 16, 16, 19, 19, 28, 0, 0, 0, 3, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 <0> [208.591412] kms_curs-2316 2..... 58015611us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:4a:2 <0> [208.591522] kworker/-160 8..... 58015982us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:4a unpin <7> [208.591499] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] blocks 27, 209, 209, 248, 248, 365, 0, 0, 41, 40, 54 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 <0> [208.591633] kworker/-160 8..... 58016066us : __intel_context_retire: 0000:00:02.0 bcs0: context:4a retire runtime: { total:168012ns, avg:168012ns } <0> [208.591742] kms_curs-2316 2..... 58017170us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:4b active <7> [208.591703] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] min_ddb 31, 231, 231, 274, 274, 403, 0, 0, 42, 45, 55 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 <0> [208.591863] kms_curs-2316 2..... 58018195us : intel_timeline_pin: 0000:00:02.0 timeline:4b using HWSP offset:fedcf000 <0> [208.591975] kms_curs-2316 2..... 58018776us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:4b pin ring:{start:fecff000, head:0000, tail:0000} <7> [208.591939] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:150:pipe A] data rate 0 num active planes 0 <0> [208.592100] kms_curs-2316 2..... 58019459us : __i915_request_commit: 0000:00:02.0 bcs0: fence 4b:2, current 0 <0> [208.592223] kms_curs-2316 2D..1. 58019463us : __i915_request_submit: 0000:00:02.0 bcs0: fence 4b:2, current 0 <7> [208.592152] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 28913 required 0 qgv_peak_bw: 51200 <0> [208.592356] kms_curs-2316 2..... 58019503us : i915_request_retire_upto: 0000:00:02.0 bcs0: fence 6:5, current 41 <7> [208.592376] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 1: max bw 38000 required 0 qgv_peak_bw: 51200 <0> [208.592486] kms_curs-2316 2..... 58019503us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 6:5, current 41 <0> [208.592618] kms_curs-2316 2..... 58019511us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:42, current 41 <7> [208.592606] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 2: max bw 38000 required 0 qgv_peak_bw: 51200 <0> [208.592750] kms_curs-2316 2D..1. 58019513us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:42, current 41 <0> [208.592883] kms_curs-2316 2..... 58019529us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:4b:2 <7> [208.592840] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 3: max bw 38000 required 0 qgv_peak_bw: 51200 <0> [208.593013] kworker/-160 8..... 58019875us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:4b unpin <0> [208.593129] kworker/-160 8..... 58019957us : __intel_context_retire: 0000:00:02.0 bcs0: context:4b retire runtime: { total:165776ns, avg:165776ns } <7> [208.593088] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] Matching peaks QGV bw: 51200 for required data rate: 0 <0> [208.593242] kms_curs-2316 2..... 58021286us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:4c active <0> [208.593359] kms_curs-2316 2..... 58022171us : intel_timeline_pin: 0000:00:02.0 timeline:4c using HWSP offset:fecf1000 <7> [208.593299] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:150:pipe A] min cdclk: 256910 kHz -> 0 kHz <0> [208.593471] kms_curs-2316 2..... 58022478us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:4c pin ring:{start:fecda000, head:0000, tail:0000} <0> [208.593589] kms_curs-2316 2..... 58023180us : __i915_request_commit: 0000:00:02.0 bcs0: fence 4c:2, current 0 <7> [208.593526] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 32114 kHz -> 0 kHz <0> [208.593712] kms_curs-2316 2D..1. 58023183us : __i915_request_submit: 0000:00:02.0 bcs0: fence 4c:2, current 0 <0> [208.593833] kms_curs-2316 2..... 58023222us : i915_request_retire_upto: 0000:00:02.0 bcs0: fence 6:6, current 42 <7> [208.593741] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Can change cdclk via squashing <0> [208.593958] kms_curs-2316 2..... 58023222us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 6:6, current 42 <7> [208.593957] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 172800 kHz, actual 172800 kHz <0> [208.594088] kms_curs-2316 2..... 58023229us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:43, current 42 <0> [208.594216] kms_curs-2316 2D..1. 58023231us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:43, current 42 <7> [208.594202] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0 <0> [208.594333] kms_curs-2316 2..... 58023246us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:4c:2 <0> [208.594442] kworker/-160 8..... 58023618us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:4c unpin <0> [208.594553] kworker/-160 8..... 58023702us : __intel_context_retire: 0000:00:02.0 bcs0: context:4c retire runtime: { total:168428ns, avg:168428ns } <7> [208.594448] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:150:pipe A] enable: no [modeset] <0> [208.594664] kms_curs-2316 2..... 58024950us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:4d active <7> [208.594663] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:plane 1A] fb: [NOFB], visible: no <0> [208.594781] kms_curs-2316 2..... 58025587us : intel_timeline_pin: 0000:00:02.0 timeline:4d using HWSP offset:fecc6000 <0> [208.594890] kms_curs-2316 2..... 58025855us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:4d pin ring:{start:fecc7000, head:0000, tail:0000} <7> [208.594873] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:64:plane 2A] fb: [NOFB], visible: no <0> [208.595013] kms_curs-2316 2..... 58026511us : __i915_request_commit: 0000:00:02.0 bcs0: fence 4d:2, current 0 <0> [208.595142] kms_curs-2316 2D..1. 58026515us : __i915_request_submit: 0000:00:02.0 bcs0: fence 4d:2, current 0 <7> [208.595112] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:94:plane 3A] fb: [NOFB], visible: no <0> [208.595263] kms_curs-2316 2..... 58026553us : i915_request_retire_upto: 0000:00:02.0 bcs0: fence 6:7, current 43 <0> [208.595385] kms_curs-2316 2..... 58026553us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 6:7, current 43 <7> [208.595325] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:124:plane 4A] fb: [NOFB], visible: no <0> [208.595520] kms_curs-2316 2..... 58026559us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:44, current 43 <0> [208.595658] kms_curs-2316 2D..1. 58026562us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:44, current 43 <7> [208.595558] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:134:plane 5A] fb: [NOFB], visible: no <0> [208.595794] kms_curs-2316 2..... 58026575us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:4d:2 <0> [208.595919] kworker/-160 8..... 58026939us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:4d unpin <7> [208.595799] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:144:cursor A] fb: [NOFB], visible: no <0> [208.596047] kworker/-160 8..... 58027021us : __intel_context_retire: 0000:00:02.0 bcs0: context:4d retire runtime: { total:166712ns, avg:166712ns } <0> [208.596152] kms_curs-2316 2..... 58028548us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:4e active <7> [208.596123] i915 0000:00:02.0: [drm:intel_psr_disable_locked [i915]] Disabling PSR2 <0> [208.596270] kms_curs-2316 2..... 58029022us : intel_timeline_pin: 0000:00:02.0 timeline:4e using HWSP offset:fecbc000 <0> [208.596373] kms_curs-2316 2..... 58029296us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:4e pin ring:{start:fecbd000, head:0000, tail:0000} <0> [208.596488] kms_curs-2316 2..... 58029994us : __i915_request_commit: 0000:00:02.0 bcs0: fence 4e:2, current 0 <0> [208.596578] kms_curs-2316 2D..1. 58029997us : __i915_request_submit: 0000:00:02.0 bcs0: fence 4e:2, current 0 <0> [208.596666] kms_curs-2316 2..... 58030036us : i915_request_retire_upto: 0000:00:02.0 bcs0: fence 6:8, current 44 <0> [208.596751] kms_curs-2316 2..... 58030036us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 6:8, current 44 <0> [208.596837] kms_curs-2316 2..... 58030043us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:45, current 44 <0> [208.596921] kms_curs-2316 2D..1. 58030046us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:45, current 44 <0> [208.597002] kms_curs-2316 2..... 58030078us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:4e:2 <0> [208.597083] kworker/-160 8..... 58030400us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:4e unpin <0> [208.597163] kworker/-160 8..... 58030540us : __intel_context_retire: 0000:00:02.0 bcs0: context:4e retire runtime: { total:67600ns, avg:67600ns } <0> [208.597236] kms_curs-2316 2..... 58031612us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:4f active <0> [208.597319] kms_curs-2316 2..... 58032451us : intel_timeline_pin: 0000:00:02.0 timeline:4f using HWSP offset:fecec000 <0> [208.597395] kms_curs-2316 2..... 58032710us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:4f pin ring:{start:feda5000, head:0000, tail:0000} <0> [208.597470] kms_curs-2316 2..... 58032720us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:4f unpin <0> [208.597543] kms_curs-2316 2..... 58032785us : __intel_context_retire: 0000:00:02.0 bcs0: context:4f retire runtime: { total:0ns, avg:0ns } <0> [208.597616] kms_curs-2316 2..... 58032787us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:4f active <0> [208.597695] kms_curs-2316 2..... 58032788us : intel_timeline_pin: 0000:00:02.0 timeline:4f using HWSP offset:fecec000 <0> [208.597772] kms_curs-2316 2..... 58032790us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:4f pin ring:{start:feda5000, head:0000, tail:0000} <0> [208.597852] kms_curs-2316 2..... 58033504us : __i915_request_commit: 0000:00:02.0 bcs0: fence 4f:2, current 0 <0> [208.597931] kms_curs-2316 2D..1. 58033508us : __i915_request_submit: 0000:00:02.0 bcs0: fence 4f:2, current 0 <0> [208.598014] kms_curs-2316 2..... 58033545us : i915_request_retire_upto: 0000:00:02.0 bcs0: fence 6:9, current 45 <0> [208.598100] kms_curs-2316 2..... 58033546us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 6:9, current 45 <0> [208.598187] kms_curs-2316 2..... 58033551us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:46, current 45 <0> [208.598266] kms_curs-2316 2D..1. 58033554us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:46, current 45 <0> [208.598345] kms_curs-2316 2..... 58033569us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:4f:2 <0> [208.598419] kworker/-160 8..... 58033883us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:4f unpin <0> [208.598493] kworker/-160 8..... 58033964us : __intel_context_retire: 0000:00:02.0 bcs0: context:4f retire runtime: { total:66144ns, avg:66144ns } <0> [208.598566] kms_curs-2316 2..... 58035220us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:50 active <0> [208.598644] kms_curs-2316 2..... 58035981us : intel_timeline_pin: 0000:00:02.0 timeline:50 using HWSP offset:fecb1000 <0> [208.598719] kms_curs-2316 2..... 58036275us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:50 pin ring:{start:fece8000, head:0000, tail:0000} <0> [208.598795] kms_curs-2316 2..... 58036285us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:50 unpin <0> [208.598870] kms_curs-2316 2..... 58036350us : __intel_context_retire: 0000:00:02.0 bcs0: context:50 retire runtime: { total:0ns, avg:0ns } <0> [208.598944] kms_curs-2316 2..... 58036352us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:50 active <0> [208.599023] kms_curs-2316 2..... 58036353us : intel_timeline_pin: 0000:00:02.0 timeline:50 using HWSP offset:fecb1000 <0> [208.599099] kms_curs-2316 2..... 58036356us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:50 pin ring:{start:fece8000, head:0000, tail:0000} <0> [208.599175] kms_curs-2316 2..... 58036364us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:50 unpin <0> [208.599247] kms_curs-2316 2..... 58036428us : __intel_context_retire: 0000:00:02.0 bcs0: context:50 retire runtime: { total:0ns, avg:0ns } <0> [208.599319] kms_curs-2316 2..... 58036430us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:50 active <0> [208.599396] kms_curs-2316 2..... 58036430us : intel_timeline_pin: 0000:00:02.0 timeline:50 using HWSP offset:fecb1000 <0> [208.599468] kms_curs-2316 2..... 58036433us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:50 pin ring:{start:fece8000, head:0000, tail:0000} <0> [208.599548] kms_curs-2316 2..... 58037125us : __i915_request_commit: 0000:00:02.0 bcs0: fence 50:2, current 0 <0> [208.599629] kms_curs-2316 2D..1. 58037129us : __i915_request_submit: 0000:00:02.0 bcs0: fence 50:2, current 0 <0> [208.599712] kms_curs-2316 2..... 58037407us : i915_request_retire_upto: 0000:00:02.0 bcs0: fence 6:10, current 46 <0> [208.599795] kms_curs-2316 2..... 58037408us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 6:10, current 46 <0> [208.599878] kms_curs-2316 2..... 58037414us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:47, current 46 <0> [208.599961] kms_curs-2316 2D..1. 58037416us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:47, current 46 <0> [208.600041] kms_curs-2316 2..... 58037430us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:50:2 <0> [208.600115] kworker/-160 8..... 58037669us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:50 unpin <0> [208.600193] kworker/-160 8..... 58037749us : __intel_context_retire: 0000:00:02.0 bcs0: context:50 retire runtime: { total:66924ns, avg:66924ns } <0> [208.600266] kms_curs-2316 2.l... 58039463us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:51 active <0> [208.600340] kms_curs-2316 0..... 58040855us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:51 pin ring:{start:feca8000, head:0000, tail:0000} <0> [208.600413] kms_curs-2316 0..... 58040891us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:51 unpin <0> [208.600485] kms_curs-2316 0..... 58040971us : __intel_context_retire: 0000:00:02.0 bcs0: context:51 retire runtime: { total:0ns, avg:0ns } <0> [208.600559] kms_curs-2316 0..... 58040979us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:51 active <0> [208.600639] kms_curs-2316 0..... 58040983us : intel_timeline_pin: 0000:00:02.0 timeline:51 using HWSP offset:feca7000 <0> [208.600711] kms_curs-2316 0..... 58040991us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:51 pin ring:{start:feca8000, head:0000, tail:0000} <0> [208.600783] kms_curs-2316 0..... 58041017us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:51 unpin <0> [208.600855] kms_curs-2316 0..... 58041096us : __intel_context_retire: 0000:00:02.0 bcs0: context:51 retire runtime: { total:0ns, avg:0ns } <0> [208.600926] kms_curs-2316 0..... 58041102us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:51 active <0> [208.601006] kms_curs-2316 0..... 58041105us : intel_timeline_pin: 0000:00:02.0 timeline:51 using HWSP offset:feca7000 <0> [208.601079] kms_curs-2316 0..... 58041113us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:51 pin ring:{start:feca8000, head:0000, tail:0000} <0> [208.601150] kworker/-160 8..... 58044227us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:51 unpin <0> [208.601235] kworker/-160 8..... 58044307us : __intel_context_retire: 0000:00:02.0 bcs0: context:51 retire runtime: { total:65832ns, avg:65832ns } <0> [208.601305] kworker/-160 8..... 58048164us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:52 unpin <0> [208.601378] kworker/-160 8..... 58048336us : __intel_context_retire: 0000:00:02.0 bcs0: context:52 retire runtime: { total:79092ns, avg:79092ns } <0> [208.601450] kworker/-160 8..... 58051865us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:53 unpin <0> [208.601522] kworker/-160 8..... 58051944us : __intel_context_retire: 0000:00:02.0 bcs0: context:53 retire runtime: { total:77428ns, avg:77428ns } <0> [208.601595] kworker/-160 8..... 58055167us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:54 unpin <0> [208.601667] kworker/-160 8..... 58055245us : __intel_context_retire: 0000:00:02.0 bcs0: context:54 retire runtime: { total:81172ns, avg:81172ns } <0> [208.601739] kworker/-160 8..... 58059018us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:55 unpin <0> [208.601810] kworker/-160 8..... 58059099us : __intel_context_retire: 0000:00:02.0 bcs0: context:55 retire runtime: { total:79144ns, avg:79144ns } <0> [208.601883] kworker/-160 8..... 58060154us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:21 unpin <0> [208.601954] kworker/-160 8..... 58060243us : __intel_context_retire: 0000:00:02.0 rcs0: context:21 retire runtime: { total:71864ns, avg:71864ns } <0> [208.602026] kworker/-44 8..... 58060270us : __engine_park: 0000:00:02.0 rcs0: parked <0> [208.602098] kworker/-160 8..... 58060435us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:24 unpin <0> [208.602169] kworker/-160 8..... 58060514us : __intel_context_retire: 0000:00:02.0 bcs0: context:24 retire runtime: { total:72644ns, avg:72644ns } <0> [208.602245] kworker/-160 8..... 58060539us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:2f unpin <0> [208.602317] kworker/-160 8..... 58060618us : __intel_context_retire: 0000:00:02.0 bcs0: context:2f retire runtime: { total:19188ns, avg:19188ns } <0> [208.602388] kworker/-160 8..... 58060639us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:34 unpin <0> [208.602459] kworker/-160 8..... 58060718us : __intel_context_retire: 0000:00:02.0 bcs0: context:34 retire runtime: { total:24752ns, avg:24752ns } <0> [208.602530] kworker/-160 8..... 58060738us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:41 unpin <0> [208.602600] kworker/-160 8..... 58060816us : __intel_context_retire: 0000:00:02.0 bcs0: context:41 retire runtime: { total:23400ns, avg:23400ns } <0> [208.602671] kworker/-160 8..... 58060836us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:48 unpin <0> [208.602743] kworker/-160 8..... 58060918us : __intel_context_retire: 0000:00:02.0 bcs0: context:48 retire runtime: { total:38532ns, avg:38532ns } <0> [208.602815] kworker/-44 8..... 58060945us : __engine_park: 0000:00:02.0 bcs0: parked <0> [208.602891] kworker/-44 8..... 58060973us : __gt_park: 0000:00:02.0 <0> [208.602965] kworker/-160 8..... 58063463us : __gt_unpark: 0000:00:02.0 <0> [208.603037] kworker/-225 4..... 58064569us : __gt_park: 0000:00:02.0 <0> [208.603112] kworker/-339 7..... 58073489us : __gt_unpark: 0000:00:02.0 <0> [208.603184] kworker/-225 4..... 58074233us : __gt_park: 0000:00:02.0 <0> [208.603259] kworker/-339 7..... 58081572us : __gt_unpark: 0000:00:02.0 <0> [208.603344] kworker/-225 4..... 58083582us : __gt_park: 0000:00:02.0 <0> [208.603419] kms_curs-2316 2..... 58132223us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes <0> [208.603497] kworker/-243 2..... 58142494us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no <0> [208.603510] kms_plan-2317 4...1. 58217445us : tracing_mark_write: kms_plane: executing <0> [208.603585] kms_plan-2317 7..... 58223569us : __gt_unpark: 0000:00:02.0 <0> [208.603662] kworker/-226 7..... 58223974us : __gt_park: 0000:00:02.0 <0> [208.603737] kms_plan-2317 4..... 58247625us : __gt_unpark: 0000:00:02.0 <0> [208.603812] kworker/-225 4..... 58249601us : __gt_park: 0000:00:02.0 <0> [208.603890] gem_part-2318 2..... 58405133us : __gt_unpark: 0000:00:02.0 <0> [208.603965] gem_part-2318 2..... 58405794us : __engine_unpark: 0000:00:02.0 vecs0: <0> [208.604041] gem_part-2318 2..... 58405799us : __gt_unpark: 0000:00:02.0 <0> [208.604116] gem_part-2318 2..... 58405890us : __engine_unpark: 0000:00:02.0 vecs0: context:e reset { seqno:1, hwsp:1, ring:78 } <0> [208.604192] gem_part-2318 2..... 58405891us : __engine_park: 0000:00:02.0 vecs0: parked <0> [208.604265] gem_part-2318 2..... 58406204us : __engine_unpark: 0000:00:02.0 ccs0: <0> [208.604345] gem_part-2318 2..... 58406211us : __engine_unpark: 0000:00:02.0 ccs0: context:7 reset { seqno:1, hwsp:1, ring:130 } <0> [208.604418] gem_part-2318 2..... 58406212us : __engine_park: 0000:00:02.0 ccs0: parked <0> [208.604490] gem_part-2318 2..... 58406536us : __engine_unpark: 0000:00:02.0 vcs0: <0> [208.604562] gem_part-2318 2..... 58406543us : __engine_unpark: 0000:00:02.0 vcs0: context:c reset { seqno:1, hwsp:1, ring:78 } <0> [208.604634] gem_part-2318 2..... 58406544us : __engine_park: 0000:00:02.0 vcs0: parked <0> [208.604706] gem_part-2318 2..... 58406840us : __engine_unpark: 0000:00:02.0 bcs0: <0> [208.604778] gem_part-2318 2..... 58406846us : __engine_unpark: 0000:00:02.0 bcs0: context:6 reset { seqno:34, hwsp:34, ring:548 } <0> [208.604851] gem_part-2318 2..... 58406847us : __engine_park: 0000:00:02.0 bcs0: parked <0> [208.604922] gem_part-2318 2..... 58407140us : __engine_unpark: 0000:00:02.0 rcs0: <0> [208.604994] gem_part-2318 2..... 58407147us : __engine_unpark: 0000:00:02.0 rcs0: context:5 reset { seqno:4, hwsp:4, ring:4a0 } <0> [208.605066] gem_part-2318 2..... 58407148us : __engine_park: 0000:00:02.0 rcs0: parked <0> [208.605141] kworker/-223 2..... 58407308us : __gt_park: 0000:00:02.0 <0> [208.605212] kworker/-223 2..... 58407326us : __gt_park: 0000:00:02.0 <0> [208.605284] gem_part-2318 2..... 58407500us : __engine_unpark: 0000:00:02.0 vcs1: <0> [208.605367] gem_part-2318 2..... 58407504us : __gt_unpark: 0000:00:02.0 <0> [208.605437] gem_part-2318 2..... 58407605us : __engine_unpark: 0000:00:02.0 vcs1: context:d reset { seqno:1, hwsp:1, ring:78 } <0> [208.605509] gem_part-2318 2..... 58407606us : __engine_park: 0000:00:02.0 vcs1: parked <0> [208.605583] kworker/-223 2..... 58407617us : __gt_park: 0000:00:02.0 <0> [208.605658] gem_part-2318 2..... 58407928us : __gt_unpark: 0000:00:02.0 <0> [208.605732] gem_part-2318 2..... 58408205us : __gt_park: 0000:00:02.0 <0> [208.605808] gem_part-2318 2..... 58432139us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes <0> [208.605885] kworker/-243 2..... 58440034us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no <0> [208.605895] syncobj_-2319 4...1. 58493255us : tracing_mark_write: syncobj_timeline: executing <0> [208.605968] syncobj_-2319 4..... 58497825us : __gt_unpark: 0000:00:02.0 <0> [208.606041] kworker/-225 4..... 58498575us : __gt_park: 0000:00:02.0 <0> [208.606115] syncobj_-2319 4..... 58512017us : __gt_unpark: 0000:00:02.0 <0> [208.606188] kworker/-225 4..... 58512284us : __gt_park: 0000:00:02.0

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

<6> [208.448534] [IGT] kms_atomic: executing

<7> [208.462742] i915 0000:00:02.0: [drm:i915_gem_open [i915]]

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

<7> [208.483033] i915 0000:00:02.0: [drm:i915_gem_open [i915]]

<7> [208.492442] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:516:DP-1]

<7> [208.492450] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:516:DP-1]

<7> [208.496814] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:516:DP-1] disconnected

<7> [208.497083] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:528:HDMI-A-1]

<7> [208.497087] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:528:HDMI-A-1]

<7> [208.501467] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:528:HDMI-A-1] disconnected

<7> [208.501727] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:534:DP-2]

<7> [208.501730] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:534:DP-2]

<7> [208.502004] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:534:DP-2] disconnected

<7> [208.502260] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:543:HDMI-A-2]

<7> [208.502263] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:543:HDMI-A-2]

<7> [208.506799] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:543:HDMI-A-2] disconnected

<7> [208.507229] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:547:DP-3]

<7> [208.507234] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:547:DP-3]

<7> [208.507656] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:547:DP-3] disconnected

<7> [208.508037] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:556:DP-4]

<7> [208.508041] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:556:DP-4]

<7> [208.508428] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:556:DP-4] disconnected

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

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

<7> [208.509328] i915 0000:00:02.0: [drm:intel_dp_read_dsc_dpcd [i915]] DSC DPCD: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00

<7> [208.509821] i915 0000:00:02.0: [drm:intel_dp_print_rates [i915]] source rates: 162000, 216000, 243000, 270000, 324000, 432000, 540000, 675000, 810000

<7> [208.509967] i915 0000:00:02.0: [drm:intel_dp_print_rates [i915]] sink rates: 162000, 216000, 243000, 270000, 324000, 378000, 432000, 540000

<7> [208.510105] i915 0000:00:02.0: [drm:intel_dp_print_rates [i915]] common rates: 162000, 216000, 243000, 270000, 324000, 432000, 540000

<7> [208.510267] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:507:eDP-1] Assigning EDID-1.4 digital sink color depth as 10 bpc.

<7> [208.510273] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:507:eDP-1] ELD monitor

<7> [208.510276] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:507:eDP-1] ELD size 20, SAD count 0

<7> [208.510294] i915 0000:00:02.0: [drm:intel_dp_set_edid [i915]] [CONNECTOR:507:eDP-1] VRR capable: no

<7> [208.510451] i915 0000:00:02.0: [drm:intel_dp_set_edid [i915]] [CONNECTOR:507:eDP-1] DFP max bpc 0, max dotclock 0, TMDS clock 0-0, PCON Max FRL BW 0Gbps

<7> [208.510603] i915 0000:00:02.0: [drm:intel_dp_set_edid [i915]] [CONNECTOR:507:eDP-1] RGB->YcbCr conversion? no, YCbCr 4:2:0 allowed? yes, YCbCr 4:4:4->4:2:0 conversion? no

<7> [208.511151] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:507:eDP-1] probed modes:

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

<7> [208.511167] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:516:DP-1]

<7> [208.511170] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:516:DP-1]

<7> [208.515501] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:516:DP-1] disconnected

<7> [208.515512] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:528:HDMI-A-1]

<7> [208.515514] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:528:HDMI-A-1]

<7> [208.519852] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:528:HDMI-A-1] disconnected

<7> [208.519862] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:534:DP-2]

<7> [208.519864] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:534:DP-2]

<7> [208.520112] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:534:DP-2] disconnected

<7> [208.520120] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:543:HDMI-A-2]

<7> [208.520122] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:543:HDMI-A-2]

<7> [208.524586] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:543:HDMI-A-2] disconnected

<7> [208.524596] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:547:DP-3]

<7> [208.524598] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:547:DP-3]

<7> [208.524843] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:547:DP-3] disconnected

<7> [208.524851] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:556:DP-4]

<7> [208.524861] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:556:DP-4]

<7> [208.525113] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:556:DP-4] disconnected

<6> [208.525332] [IGT] kms_atomic: starting subtest test-only

<7> [208.526575] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:568]

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

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

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

<0> [208.550786] ---------------------------------

<0> [208.550981] kworker/-197 10..... 47399783us : __intel_context_do_unpin.part.0: 0000:00:02.0 ccs'0: context:a unpin

<0> [208.551107] kworker/-197 10..... 47400585us : __intel_context_retire: 0000:00:02.0 ccs'0: context:a retire runtime: { total:90376ns, avg:90376ns }

<0> [208.551241] kworker/-197 10..... 47400747us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs'0: context:9 unpin

<0> [208.551353] kworker/-197 10..... 47400841us : __intel_context_retire: 0000:00:02.0 bcs'0: context:9 retire runtime: { total:14508ns, avg:14508ns }

<0> [208.551462] kworker/-197 10..... 47400856us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs'0: context:8 unpin

<0> [208.551612] kworker/-197 10..... 47400950us : __intel_context_retire: 0000:00:02.0 rcs'0: context:8 retire runtime: { total:109252ns, avg:109252ns }

<0> [208.551742] kworker/-50 10..... 47401001us : __engine_park: 0000:00:02.0 ccs'0: parked

<0> [208.551855] kworker/-50 10..... 47401069us : __engine_park: 0000:00:02.0 bcs'0: parked

<0> [208.551967] kworker/-50 10..... 47401198us : __engine_park: 0000:00:02.0 rcs'0: parked

<0> [208.552088] kworker/-50 10..... 47401770us : __gt_park: 0000:00:02.0

<0> [208.552220] kworker/-149 4..... 47466750us : __intel_context_do_unpin.part.0: 0000:00:02.0 vecs0: context:13 unpin

<0> [208.552360] kworker/-149 4..... 47466834us : __intel_context_retire: 0000:00:02.0 vecs0: context:13 retire runtime: { total:12376ns, avg:12376ns }

<0> [208.552503] kworker/-149 4..... 47466843us : __intel_context_do_unpin.part.0: 0000:00:02.0 vcs1: context:12 unpin

<0> [208.552636] kworker/-149 4..... 47466923us : __intel_context_retire: 0000:00:02.0 vcs1: context:12 retire runtime: { total:12740ns, avg:12740ns }

<0> [208.552760] kworker/-149 4..... 47466929us : __intel_context_do_unpin.part.0: 0000:00:02.0 vcs0: context:11 unpin

<0> [208.552870] kworker/-149 4..... 47467008us : __intel_context_retire: 0000:00:02.0 vcs0: context:11 retire runtime: { total:12220ns, avg:12220ns }

<0> [208.552980] kworker/-225 4..... 47467154us : __engine_park: 0000:00:02.0 vecs0: parked

<0> [208.553091] kworker/-225 4..... 47467168us : __engine_park: 0000:00:02.0 vcs1: parked

<0> [208.553220] kworker/-225 4..... 47467173us : __engine_park: 0000:00:02.0 vcs0: parked

<0> [208.553359] i915_mod-2044 4.l... 47501990us : __gt_unpark: 0000:00:02.0

<0> [208.553496] kworker/-123 4..... 47502804us : __gt_park: 0000:00:02.0

<0> [208.553634] i915_mod-2044 4..... 47567893us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes

<0> [208.553761] i915_mod-2044 4..... 48668882us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes

<0> [208.553895] kworker/-262 4..... 48673970us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no

<0> [208.554002] kworker/-225 4..... 48714228us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes

<0> [208.554107] kworker/-262 4..... 48718480us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no

<0> [208.554222] setfont-2069 10..... 48719628us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes

<0> [208.554331] kworker/-280 10..... 48729531us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no

<0> [208.554435] i915_mod-2044 16..... 48813520us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:1d active

<0> [208.554539] kworker/-149 4..... 50840021us : __intel_context_do_unpin.part.0: 0000:00:02.0 vcs0: context:1a unpin

<0> [208.554644] kworker/-149 4..... 50840133us : __intel_context_retire: 0000:00:02.0 vcs0: context:1a retire runtime: { total:17108ns, avg:17108ns }

<0> [208.554756] kworker/-149 4..... 50841641us : __intel_context_do_unpin.part.0: 0000:00:02.0 vcs1: context:1b unpin

<0> [208.554861] kworker/-149 4..... 50841746us : __intel_context_retire: 0000:00:02.0 vcs1: context:1b retire runtime: { total:11336ns, avg:11336ns }

<0> [208.554964] kworker/-149 4..... 50841774us : __intel_context_do_unpin.part.0: 0000:00:02.0 vecs0: context:1c unpin

<0> [208.555070] kworker/-149 4..... 50841878us : __intel_context_retire: 0000:00:02.0 vecs0: context:1c retire runtime: { total:17836ns, avg:17836ns }

<0> [208.555176] kworker/-225 4..... 50841923us : __engine_park: 0000:00:02.0 vcs0: parked

<0> [208.555282] kworker/-225 4..... 50841969us : __engine_park: 0000:00:02.0 vcs1: parked

<0> [208.555389] kworker/-225 4..... 50842001us : __engine_park: 0000:00:02.0 vecs0: parked

<0> [208.555497] kworker/-225 4..... 50842532us : __gt_park: 0000:00:02.0

<0> [208.555606] kworker/-149 4..... 50842758us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:17 unpin

<0> [208.555712] kworker/-149 4..... 50842862us : __intel_context_retire: 0000:00:02.0 rcs0: context:17 retire runtime: { total:376844ns, avg:376844ns }

<0> [208.555816] kworker/-149 4..... 50844355us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:18 unpin

<0> [208.555921] kworker/-149 4.l... 50844463us : __intel_context_retire: 0000:00:02.0 rcs0: context:18 retire runtime: { total:90844ns, avg:90844ns }

<0> [208.556027] kworker/-225 4..... 50844520us : __engine_park: 0000:00:02.0 rcs0: parked

<0> [208.556128] kworker/-149 4..... 50845099us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:19 unpin

<0> [208.556229] kworker/-149 4.l... 50845197us : __intel_context_retire: 0000:00:02.0 bcs0: context:19 retire runtime: { total:17940ns, avg:17940ns }

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

<0> [208.556332] kworker/-225 4..... 50845232us : __engine_park: 0000:00:02.0 bcs0: parked

<0> [208.556440] kworker/-225 4..... 50861193us : __gt_park: 0000:00:02.0

<0> [208.556461] gem_exec-2314 2...1. 57361828us : tracing_mark_write: gem_exec_reloc: executing

<0> [208.556564] gem_exec-2314 2..... 57389172us : __gt_unpark: 0000:00:02.0

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

<0> [208.556670] kworker/-223 2..... 57389529us : __gt_park: 0000:00:02.0

<0> [208.556771] gem_exec-2314 2..... 57407399us : __gt_unpark: 0000:00:02.0

<0> [208.556890] kworker/-223 2..... 57407862us : __gt_park: 0000:00:02.0

<0> [208.556994] gem_exec-2314 2..... 57408592us : __gt_unpark: 0000:00:02.0

<0> [208.557105] kworker/-223 2..... 57408890us : __gt_park: 0000:00:02.0

<0> [208.557240] gem_exec-2314 2..... 57409687us : __engine_unpark: 0000:00:02.0 vecs0:

<0> [208.557432] gem_exec-2314 2..... 57409712us : __gt_unpark: 0000:00:02.0

<0> [208.557601] gem_exec-2314 2..... 57410095us : __engine_unpark: 0000:00:02.0 vecs0: context:e reset { seqno:1, *hwsp:1, ring:78 }

<0> [208.557741] gem_exec-2314 2..... 57410107us : __engine_park: 0000:00:02.0 vecs0: parked

<0> [208.557864] kworker/-223 2..... 57410344us : __gt_park: 0000:00:02.0

<0> [208.557968] gem_exec-2314 2..... 57411305us : __engine_unpark: 0000:00:02.0 ccs0:

<0> [208.558074] gem_exec-2314 2..... 57411309us : __gt_unpark: 0000:00:02.0

<0> [208.558151] gem_exec-2314 2..... 57411516us : __engine_unpark: 0000:00:02.0 ccs0: context:7 reset { seqno:1, *hwsp:1, ring:130 }

<0> [208.558223] gem_exec-2314 2..... 57411517us : __engine_park: 0000:00:02.0 ccs0: parked

<0> [208.558297] kworker/-223 2..... 57411535us : __gt_park: 0000:00:02.0

<0> [208.558373] gem_exec-2314 2..... 57411944us : __engine_unpark: 0000:00:02.0 vcs0:

<0> [208.558447] gem_exec-2314 2..... 57411948us : __gt_unpark: 0000:00:02.0

<0> [208.558518] gem_exec-2314 2..... 57412018us : __engine_unpark: 0000:00:02.0 vcs0: context:c reset { seqno:1, *hwsp:1, ring:78 }

<0> [208.558586] gem_exec-2314 2..... 57412019us : __engine_park: 0000:00:02.0 vcs0: parked

<0> [208.558680] kworker/-223 2..... 57412033us : __gt_park: 0000:00:02.0

<0> [208.558750] gem_exec-2314 2..... 57412471us : __engine_unpark: 0000:00:02.0 bcs0:

<0> [208.558824] gem_exec-2314 2..... 57412475us : __gt_unpark: 0000:00:02.0

<0> [208.558926] gem_exec-2314 2..... 57412666us : __engine_unpark: 0000:00:02.0 bcs0: context:6 reset { seqno:1, *hwsp:1, ring:88 }

<0> [208.559018] gem_exec-2314 2..... 57412667us : __engine_park: 0000:00:02.0 bcs0: parked

<0> [208.559107] kworker/-223 2..... 57412679us : __gt_park: 0000:00:02.0

<0> [208.559177] gem_exec-2314 2..... 57413099us : __engine_unpark: 0000:00:02.0 rcs0:

<0> [208.559250] gem_exec-2314 2..... 57413104us : __gt_unpark: 0000:00:02.0

<0> [208.559363] gem_exec-2314 2..... 57413284us : __engine_unpark: 0000:00:02.0 rcs0: context:5 reset { seqno:2, *hwsp:2, ring:260 }

<0> [208.559452] gem_exec-2314 2..... 57413285us : __engine_park: 0000:00:02.0 rcs0: parked

<0> [208.559533] kworker/-223 2..... 57413298us : __gt_park: 0000:00:02.0

<0> [208.559602] gem_exec-2314 2..... 57413724us : __engine_unpark: 0000:00:02.0 vcs1:

<0> [208.559683] gem_exec-2314 2..... 57413728us : __gt_unpark: 0000:00:02.0

<0> [208.559762] gem_exec-2314 2..... 57413800us : __engine_unpark: 0000:00:02.0 vcs1: context:d reset { seqno:1, *hwsp:1, ring:78 }

<0> [208.559861] gem_exec-2314 2..... 57413801us : __engine_park: 0000:00:02.0 vcs1: parked

<0> [208.559974] kworker/-223 2..... 57413811us : __gt_park: 0000:00:02.0

<0> [208.560088] gem_exec-2314 2..... 57414229us : __gt_unpark: 0000:00:02.0

<0> [208.560218] gem_exec-2314 2..... 57414777us : __gt_park: 0000:00:02.0

<0> [208.560338] gem_exec-2314 7..... 57454640us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes

<0> [208.560449] kworker/-298 7..... 57459710us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no

<0> [208.560555] api_inte-2315 8..... 57527636us : __gt_unpark: 0000:00:02.0

<0> [208.560661] kworker/-44 8..... 57528948us : __gt_park: 0000:00:02.0

<0> [208.560777] api_inte-2315 2..... 57561016us : __gt_unpark: 0000:00:02.0

<0> [208.560884] api_inte-2315 2..... 57561241us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:1f active

<0> [208.560997] api_inte-2315 2..... 57563633us : intel_timeline_pin: 0000:00:02.0 timeline:1f using HWSP offset:fedd1000

<0> [208.561098] api_inte-2315 2..... 57564226us : __engine_unpark: 0000:00:02.0 rcs0:

<0> [208.561174] api_inte-2315 2..... 57564236us : __engine_unpark: 0000:00:02.0 rcs0: context:5 reset { seqno:2, *hwsp:2, ring:260 }

<0> [208.561249] api_inte-2315 2..... 57564238us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:1f pin ring:{start:fecfe000, head:0000, tail:0000}

<0> [208.561353] api_inte-2315 2..... 57565369us : __i915_request_commit: 0000:00:02.0 rcs0: fence 1f:2, current 0

<0> [208.561457] api_inte-2315 2D..1. 57565374us : __i915_request_submit: 0000:00:02.0 rcs0: fence 1f:2, current 0

<0> [208.561543] api_inte-2315 2..... 57565434us : __i915_request_commit: 0000:00:02.0 rcs0: fence 5:3, current 2

<0> [208.561630] api_inte-2315 2D..1. 57565437us : __i915_request_submit: 0000:00:02.0 rcs0: fence 5:3, current 2

<0> [208.561642] api_inte-2315 2...1. 57566125us : tracing_mark_write: api_intel_allocator: finished subtest reopen, SUCCESS

<0> [208.561726] api_inte-2315 2..... 57567208us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 1f:2, current 2

<0> [208.561810] api_inte-2315 2..... 57567722us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 5:3, current 3

<0> [208.561884] kworker/-160 2..... 57567938us : __intel_context_retire: 0000:00:02.0 rcs0: context:1f retire runtime: { total:110136ns, avg:110136ns }

<0> [208.561959] kworker/-160 2..... 57581238us : __gt_unpark: 0000:00:02.0

<0> [208.562047] kworker/-262 4..... 57642959us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no

<0> [208.562121] kms_curs-2316 11..... 57779833us : __gt_unpark: 0000:00:02.0

<0> [208.562197] kms_curs-2316 11..... 57780366us : __gt_park: 0000:00:02.0

<0> [208.562273] kms_curs-2316 11..... 57780532us : __gt_unpark: 0000:00:02.0

<0> [208.562348] kms_curs-2316 11..... 57780717us : __gt_park: 0000:00:02.0

<0> [208.562424] kms_curs-2316 11..... 57781512us : __gt_unpark: 0000:00:02.0

<0> [208.562497] kms_curs-2316 11..... 57782071us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:21 active

<0> [208.562577] kms_curs-2316 11..... 57783538us : intel_timeline_pin: 0000:00:02.0 timeline:21 using HWSP offset:fedd1000

<0> [208.562652] kms_curs-2316 11..... 57784065us : __engine_unpark: 0000:00:02.0 rcs0:

<0> [208.562726] kms_curs-2316 11..... 57784097us : __engine_unpark: 0000:00:02.0 rcs0: context:5 reset { seqno:3, *hwsp:3, ring:380 }

<0> [208.562799] kms_curs-2316 11..... 57784101us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:21 pin ring:{start:fedbb000, head:0000, tail:0000}

<0> [208.562934] kms_curs-2316 11..... 57784651us : __i915_request_commit: 0000:00:02.0 rcs0: fence 21:2, current 0

<0> [208.563069] kms_curs-2316 11D..1. 57784656us : __i915_request_submit: 0000:00:02.0 rcs0: fence 21:2, current 0

<0> [208.563197] kms_curs-2316 11..... 57784717us : __i915_request_commit: 0000:00:02.0 rcs0: fence 5:4, current 3

<0> [208.563310] kms_curs-2316 11D..1. 57784720us : __i915_request_submit: 0000:00:02.0 rcs0: fence 5:4, current 3

<0> [208.563425] kms_curs-2316 11..... 57785537us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:22 active

<0> [208.563543] kms_curs-2316 11..... 57786196us : intel_timeline_pin: 0000:00:02.0 timeline:22 using HWSP offset:fedd0000

<0> [208.563654] kms_curs-2316 11..... 57786647us : __engine_unpark: 0000:00:02.0 bcs0:

<0> [208.563764] kms_curs-2316 11..... 57786655us : __engine_unpark: 0000:00:02.0 bcs0: context:6 reset { seqno:1, *hwsp:1, ring:88 }

<0> [208.563873] kms_curs-2316 11..... 57786656us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:22 pin ring:{start:fedb7000, head:0000, tail:0000}

<0> [208.563991] kms_curs-2316 11..... 57787530us : __i915_request_commit: 0000:00:02.0 bcs0: fence 22:2, current 0

<7> [208.564005] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm

<0> [208.564124] kms_curs-2316 11D..1. 57787534us : __i915_request_submit: 0000:00:02.0 bcs0: fence 22:2, current 0

<0> [208.564250] kms_curs-2316 11..... 57787617us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:2, current 1

<7> [208.564290] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] lines 2, 16, 16, 19, 19, 28, 0, 0, 0, 3, 0 -> 2, 16, 16, 19, 19, 28, 0, 0, 0, 3, 0

<0> [208.564409] kms_curs-2316 11D..1. 57787620us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:2, current 1

<0> [208.564517] kworker/-160 8..... 57788948us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:22 unpin

<7> [208.564494] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] blocks 49, 385, 385, 457, 457, 673, 0, 0, 63, 73, 87 -> 27, 209, 209, 248, 248, 365, 0, 0, 41, 40, 54

<0> [208.564627] kworker/-160 8..... 57789064us : __intel_context_retire: 0000:00:02.0 bcs0: context:22 retire runtime: { total:73684ns, avg:73684ns }

<0> [208.564741] kms_curs-2316 11..... 57791761us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:23 active

<7> [208.564705] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] min_ddb 55, 425, 425, 504, 504, 742, 0, 0, 64, 82, 88 -> 31, 231, 231, 274, 274, 403, 0, 0, 42, 45, 55

<0> [208.564862] kms_curs-2316 11..... 57792754us : intel_timeline_pin: 0000:00:02.0 timeline:23 using HWSP offset:fedcf000

<0> [208.564988] kms_curs-2316 11..... 57793094us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:23 pin ring:{start:fecfe000, head:0000, tail:0000}

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

<0> [208.565124] kms_curs-2316 11..... 57794125us : __i915_request_commit: 0000:00:02.0 bcs0: fence 23:2, current 0

<0> [208.565246] kms_curs-2316 11D..1. 57794129us : __i915_request_submit: 0000:00:02.0 bcs0: fence 23:2, current 0

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

<0> [208.565369] kms_curs-2316 11..... 57794171us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:3, current 2

<0> [208.565489] kms_curs-2316 11D..1. 57794276us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:3, current 2

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

<0> [208.565605] kms_curs-2316 11..... 57794295us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:23:2

<0> [208.565714] kworker/-160 8..... 57795099us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:23 unpin

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

<0> [208.565822] kworker/-160 8..... 57795181us : __intel_context_retire: 0000:00:02.0 bcs0: context:23 retire runtime: { total:135096ns, avg:135096ns }

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

<0> [208.565929] kms_curs-2316 11..... 57796789us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:24 active

<0> [208.566044] kms_curs-2316 11..... 57797869us : intel_timeline_pin: 0000:00:02.0 timeline:24 using HWSP offset:fedb6000

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

<0> [208.566163] kms_curs-2316 11..... 57798216us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:24 pin ring:{start:fecf2000, head:0000, tail:0000}

<0> [208.566285] kms_curs-2316 11..... 57799021us : __i915_request_commit: 0000:00:02.0 bcs0: fence 24:2, current 0

<0> [208.566393] kms_curs-2316 11D..1. 57799027us : __i915_request_submit: 0000:00:02.0 bcs0: fence 24:2, current 0

<0> [208.566506] kms_curs-2316 11..... 57799157us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:4, current 3

<0> [208.566596] kms_curs-2316 11D..1. 57799169us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:4, current 3

<0> [208.566673] kms_curs-2316 11..... 57801027us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:25 active

<0> [208.566756] kms_curs-2316 8..... 57802624us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:25 pin ring:{start:03104000, head:0000, tail:0000}

<0> [208.566837] kms_curs-2316 8..... 57804760us : __i915_request_commit: 0000:00:02.0 bcs0: fence 25:2, current 0

<0> [208.566921] kms_curs-2316 8D..1. 57804767us : __i915_request_submit: 0000:00:02.0 bcs0: fence 25:2, current 0

<0> [208.567016] kms_curs-2316 8..... 57804856us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:5, current 4

<0> [208.567106] kms_curs-2316 8D..1. 57804863us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:5, current 4

<0> [208.567185] kms_curs-2316 4..... 57809596us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:26 active

<0> [208.567276] kms_curs-2316 4..... 57812827us : __i915_request_commit: 0000:00:02.0 bcs0: fence 26:2, current 0

<0> [208.567372] kms_curs-2316 4D..1. 57812832us : __i915_request_submit: 0000:00:02.0 bcs0: fence 26:2, current 0

<0> [208.567469] kms_curs-2316 4..... 57812878us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:6, current 5

<0> [208.567561] kms_curs-2316 4D..1. 57812882us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:6, current 5

<0> [208.567638] kms_curs-2316 4..... 57812902us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:26:2

<0> [208.567712] kms_curs-2316 4..... 57814907us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:27 active

<0> [208.567802] kms_curs-2316 4..... 57816118us : intel_timeline_pin: 0000:00:02.0 timeline:27 using HWSP offset:fedd0000

<0> [208.567906] kms_curs-2316 4..... 57816513us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:27 pin ring:{start:feda6000, head:0000, tail:0000}

<0> [208.567993] kms_curs-2316 4..... 57816523us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:27 unpin

<0> [208.568095] kms_curs-2316 4..... 57816588us : __intel_context_retire: 0000:00:02.0 bcs0: context:27 retire runtime: { total:0ns, avg:0ns }

<0> [208.568186] kms_curs-2316 4..... 57816590us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:27 active

<0> [208.568274] kms_curs-2316 4..... 57816591us : intel_timeline_pin: 0000:00:02.0 timeline:27 using HWSP offset:fedd0000

<0> [208.568358] kms_curs-2316 4..... 57816594us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:27 pin ring:{start:feda6000, head:0000, tail:0000}

<0> [208.568444] kms_curs-2316 4..... 57817559us : __i915_request_commit: 0000:00:02.0 bcs0: fence 27:2, current 0

<0> [208.568528] kms_curs-2316 4D..1. 57817562us : __i915_request_submit: 0000:00:02.0 bcs0: fence 27:2, current 0

<0> [208.568612] kms_curs-2316 4..... 57817599us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:7, current 6

<0> [208.568695] kms_curs-2316 4D..1. 57817602us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:7, current 6

<0> [208.568772] kms_curs-2316 4..... 57817617us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:27:2

<0> [208.568844] kms_curs-2316 4..... 57819748us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:28 active

<0> [208.568922] kms_curs-2316 4..... 57820543us : intel_timeline_pin: 0000:00:02.0 timeline:28 using HWSP offset:fece8000

<0> [208.568993] kms_curs-2316 4..... 57820810us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:28 pin ring:{start:fece9000, head:0000, tail:0000}

<0> [208.569066] kms_curs-2316 4..... 57820819us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:28 unpin

<0> [208.569138] kms_curs-2316 4..... 57820884us : __intel_context_retire: 0000:00:02.0 bcs0: context:28 retire runtime: { total:0ns, avg:0ns }

<0> [208.569213] kms_curs-2316 4..... 57820886us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:28 active

<0> [208.569296] kms_curs-2316 4..... 57820887us : intel_timeline_pin: 0000:00:02.0 timeline:28 using HWSP offset:fece8000

<0> [208.569367] kms_curs-2316 4..... 57820890us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:28 pin ring:{start:fece9000, head:0000, tail:0000}

<0> [208.569438] kms_curs-2316 4..... 57820897us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:28 unpin

<0> [208.569510] kms_curs-2316 4..... 57820962us : __intel_context_retire: 0000:00:02.0 bcs0: context:28 retire runtime: { total:0ns, avg:0ns }

<0> [208.569581] kms_curs-2316 4..... 57820964us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:28 active

<0> [208.569659] kms_curs-2316 4..... 57820964us : intel_timeline_pin: 0000:00:02.0 timeline:28 using HWSP offset:fece8000

<0> [208.569730] kms_curs-2316 4..... 57820967us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:28 pin ring:{start:fece9000, head:0000, tail:0000}

<0> [208.569814] kms_curs-2316 4..... 57821922us : __i915_request_commit: 0000:00:02.0 bcs0: fence 28:2, current 0

<0> [208.569897] kms_curs-2316 4D..1. 57821925us : __i915_request_submit: 0000:00:02.0 bcs0: fence 28:2, current 0

<0> [208.569981] kms_curs-2316 4..... 57821964us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:8, current 7

<0> [208.570062] kms_curs-2316 4D..1. 57821967us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:8, current 7

<0> [208.570141] kms_curs-2316 4..... 57821983us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:28:2

<0> [208.570217] kms_curs-2316 4..... 57823816us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:29 active

<0> [208.570306] kms_curs-2316 4..... 57824660us : intel_timeline_pin: 0000:00:02.0 timeline:29 using HWSP offset:fedcf000

<0> [208.570378] kms_curs-2316 4..... 57825010us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:29 pin ring:{start:feced000, head:0000, tail:0000}

<0> [208.570451] kms_curs-2316 4..... 57825020us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:29 unpin

<0> [208.570524] kms_curs-2316 4..... 57825085us : __intel_context_retire: 0000:00:02.0 bcs0: context:29 retire runtime: { total:0ns, avg:0ns }

<0> [208.570595] kms_curs-2316 4..... 57825087us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:29 active

<0> [208.570670] kms_curs-2316 4..... 57825088us : intel_timeline_pin: 0000:00:02.0 timeline:29 using HWSP offset:fedcf000

<0> [208.570742] kms_curs-2316 4..... 57825091us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:29 pin ring:{start:feced000, head:0000, tail:0000}

<0> [208.570813] kms_curs-2316 4..... 57825098us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:29 unpin

<0> [208.570887] kms_curs-2316 4..... 57825163us : __intel_context_retire: 0000:00:02.0 bcs0: context:29 retire runtime: { total:0ns, avg:0ns }

<0> [208.570961] kms_curs-2316 4..... 57825165us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:29 active

<0> [208.571050] kms_curs-2316 4..... 57825165us : intel_timeline_pin: 0000:00:02.0 timeline:29 using HWSP offset:fedcf000

<0> [208.571139] kms_curs-2316 4..... 57825168us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:29 pin ring:{start:feced000, head:0000, tail:0000}

<0> [208.571227] kms_curs-2316 4..... 57826161us : __i915_request_commit: 0000:00:02.0 bcs0: fence 29:2, current 0

<0> [208.571317] kms_curs-2316 4D..1. 57826164us : __i915_request_submit: 0000:00:02.0 bcs0: fence 29:2, current 0

<0> [208.571399] kms_curs-2316 4..... 57826201us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:9, current 8

<0> [208.571481] kms_curs-2316 4D..1. 57826204us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:9, current 8

<0> [208.571559] kms_curs-2316 4..... 57826219us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:29:2

<0> [208.571633] kworker/-339 18..... 57826897us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:29 unpin

<0> [208.571705] kworker/-339 18..... 57826970us : __intel_context_retire: 0000:00:02.0 bcs0: context:29 retire runtime: { total:110604ns, avg:110604ns }

<0> [208.571778] kms_curs-2316 4..... 57828529us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:2a active

<0> [208.571857] kms_curs-2316 4..... 57829386us : intel_timeline_pin: 0000:00:02.0 timeline:2a using HWSP offset:fecf1000

<0> [208.571930] kms_curs-2316 4..... 57829665us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:2a pin ring:{start:fecda000, head:0000, tail:0000}

<0> [208.572012] kms_curs-2316 4..... 57830619us : __i915_request_commit: 0000:00:02.0 bcs0: fence 2a:2, current 0

<0> [208.572094] kms_curs-2316 4D..1. 57830623us : __i915_request_submit: 0000:00:02.0 bcs0: fence 2a:2, current 0

<0> [208.572177] kms_curs-2316 4..... 57830661us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:10, current 9

<0> [208.572261] kms_curs-2316 4D..1. 57830663us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:10, current 9

<0> [208.572352] kms_curs-2316 4..... 57830677us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:2a:2

<0> [208.572424] kworker/-339 18..... 57831054us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:2a unpin

<0> [208.572495] kworker/-339 18..... 57831126us : __intel_context_retire: 0000:00:02.0 bcs0: context:2a retire runtime: { total:19968ns, avg:19968ns }

<0> [208.572569] kms_curs-2316 4..... 57832274us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:2b active

<0> [208.572647] kms_curs-2316 4..... 57833429us : intel_timeline_pin: 0000:00:02.0 timeline:2b using HWSP offset:fedae000

<0> [208.572719] kms_curs-2316 4..... 57833826us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:2b pin ring:{start:fedb7000, head:0000, tail:0000}

<0> [208.572803] kms_curs-2316 4..... 57834726us : __i915_request_commit: 0000:00:02.0 bcs0: fence 2b:2, current 0

<0> [208.572887] kms_curs-2316 4D..1. 57834730us : __i915_request_submit: 0000:00:02.0 bcs0: fence 2b:2, current 0

<0> [208.572971] kms_curs-2316 4..... 57834768us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:11, current 10

<0> [208.573054] kms_curs-2316 4D..1. 57834770us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:11, current 10

<0> [208.573133] kms_curs-2316 4..... 57834785us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:2b:2

<0> [208.573207] kworker/-339 18..... 57835106us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:2b unpin

<0> [208.573283] kworker/-339 18..... 57835170us : __intel_context_retire: 0000:00:02.0 bcs0: context:2b retire runtime: { total:19240ns, avg:19240ns }

<0> [208.573359] kms_curs-2316 4..... 57836470us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:2c active

<0> [208.573437] kms_curs-2316 4..... 57837302us : intel_timeline_pin: 0000:00:02.0 timeline:2c using HWSP offset:feccf000

<0> [208.573508] kms_curs-2316 4..... 57837612us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:2c pin ring:{start:fedaa000, head:0000, tail:0000}

<0> [208.573594] kms_curs-2316 4..... 57838301us : __i915_request_commit: 0000:00:02.0 bcs0: fence 2c:2, current 0

<0> [208.573679] kms_curs-2316 4D..1. 57838305us : __i915_request_submit: 0000:00:02.0 bcs0: fence 2c:2, current 0

<0> [208.573762] kms_curs-2316 4..... 57838341us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:12, current 11

<0> [208.573846] kms_curs-2316 4D..1. 57838344us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:12, current 11

<0> [208.573924] kms_curs-2316 4..... 57838359us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:2c:2

<0> [208.573998] kms_curs-2316 4..... 57840842us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:2d active

<0> [208.574077] kms_curs-2316 4..... 57841577us : intel_timeline_pin: 0000:00:02.0 timeline:2d using HWSP offset:feda9000

<0> [208.574150] kms_curs-2316 4..... 57841842us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:2d pin ring:{start:fecff000, head:0000, tail:0000}

<0> [208.574234] kms_curs-2316 4..... 57842666us : __i915_request_commit: 0000:00:02.0 bcs0: fence 2d:2, current 0

<0> [208.574318] kms_curs-2316 4D..1. 57842669us : __i915_request_submit: 0000:00:02.0 bcs0: fence 2d:2, current 0

<0> [208.574406] kms_curs-2316 4..... 57842708us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:13, current 12

<0> [208.574488] kms_curs-2316 4D..1. 57842710us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:13, current 12

<0> [208.574567] kms_curs-2316 4..... 57842725us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:2d:2

<0> [208.574640] kms_curs-2316 8..... 57847304us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:2e active

<0> [208.574718] kms_curs-2316 8..... 57848253us : intel_timeline_pin: 0000:00:02.0 timeline:2e using HWSP offset:fedaf000

<0> [208.574791] kms_curs-2316 8..... 57848734us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:2e pin ring:{start:feda5000, head:0000, tail:0000}

<0> [208.574864] kworker/-160 18..... 57857662us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:30 unpin

<0> [208.574937] kworker/-160 18..... 57857825us : __intel_context_retire: 0000:00:02.0 bcs0: context:30 retire runtime: { total:17472ns, avg:17472ns }

<0> [208.575009] kworker/-160 19..... 57869754us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:33 unpin

<0> [208.575080] kworker/-160 19..... 57869819us : __intel_context_retire: 0000:00:02.0 bcs0: context:33 retire runtime: { total:24700ns, avg:24700ns }

<0> [208.575152] kworker/-160 19..... 57876668us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:35 unpin

<0> [208.575224] kworker/-160 19..... 57876740us : __intel_context_retire: 0000:00:02.0 bcs0: context:35 retire runtime: { total:25792ns, avg:25792ns }

<0> [208.575296] kms_curs-2316 18..... 57879300us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:36 active

<0> [208.575376] kms_curs-2316 18..... 57879986us : intel_timeline_pin: 0000:00:02.0 timeline:36 using HWSP offset:fedad000

<0> [208.575455] kms_curs-2316 18..... 57880348us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:36 pin ring:{start:feda5000, head:0000, tail:0000}

<0> [208.575524] kworker/-160 19..... 57881743us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:36 unpin

<0> [208.575595] kworker/-160 19..... 57881881us : __intel_context_retire: 0000:00:02.0 bcs0: context:36 retire runtime: { total:26260ns, avg:26260ns }

<0> [208.575666] kworker/-160 19..... 57885337us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:37 unpin

<0> [208.575739] kworker/-160 19..... 57885401us : __intel_context_retire: 0000:00:02.0 bcs0: context:37 retire runtime: { total:19240ns, avg:19240ns }

<0> [208.575811] kworker/-160 19..... 57889418us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:38 unpin

<0> [208.575883] kworker/-160 19..... 57889483us : __intel_context_retire: 0000:00:02.0 bcs0: context:38 retire runtime: { total:19708ns, avg:19708ns }

<0> [208.575954] kms_curs-2316 4..... 57890818us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:39 active

<0> [208.576026] kms_curs-2316 19..... 57896656us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:3a active

<0> [208.576122] kms_curs-2316 19..... 57897222us : intel_timeline_pin: 0000:00:02.0 timeline:3a using HWSP offset:fedae000

<0> [208.576209] kms_curs-2316 19..... 57897548us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:3a pin ring:{start:feda9000, head:0000, tail:0000}

<0> [208.576292] kms_curs-2316 19..... 57898737us : __i915_request_commit: 0000:00:02.0 bcs0: fence 3a:2, current 0

<0> [208.576378] kms_curs-2316 19D..1. 57898742us : __i915_request_submit: 0000:00:02.0 bcs0: fence 3a:2, current 0

<0> [208.576468] kms_curs-2316 19..... 57898800us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:26, current 25

<0> [208.576548] kms_curs-2316 19D..1. 57898805us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:26, current 25

<0> [208.576627] kms_curs-2316 19..... 57898826us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:3a:2

<0> [208.576700] kworker/-339 16..... 57899129us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:3a unpin

<0> [208.576773] kworker/-339 16..... 57899195us : __intel_context_retire: 0000:00:02.0 bcs0: context:3a retire runtime: { total:38636ns, avg:38636ns }

<0> [208.576847] kms_curs-2316 19..... 57900835us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:3b active

<0> [208.576923] kms_curs-2316 19..... 57901486us : intel_timeline_pin: 0000:00:02.0 timeline:3b using HWSP offset:fece0000

<0> [208.576995] kms_curs-2316 19..... 57901854us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:3b pin ring:{start:feda5000, head:0000, tail:0000}

<0> [208.577075] kms_curs-2316 19..... 57903056us : __i915_request_commit: 0000:00:02.0 bcs0: fence 3b:2, current 0

<0> [208.577159] kms_curs-2316 19D..1. 57903061us : __i915_request_submit: 0000:00:02.0 bcs0: fence 3b:2, current 0

<0> [208.577242] kms_curs-2316 19..... 57903120us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:27, current 26

<0> [208.577325] kms_curs-2316 19D..1. 57903125us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:27, current 26

<0> [208.577404] kms_curs-2316 19..... 57903147us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:3b:2

<0> [208.577481] kworker/-339 16..... 57903462us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:3b unpin

<0> [208.577557] kworker/-339 16..... 57903597us : __intel_context_retire: 0000:00:02.0 bcs0: context:3b retire runtime: { total:49868ns, avg:49868ns }

<0> [208.577631] kms_curs-2316 4..... 57907737us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:3c active

<0> [208.577710] kms_curs-2316 4..... 57908800us : intel_timeline_pin: 0000:00:02.0 timeline:3c using HWSP offset:fedaf000

<0> [208.577783] kms_curs-2316 4..... 57909197us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:3c pin ring:{start:fecff000, head:0000, tail:0000}

<0> [208.577867] kms_curs-2316 4..... 57910189us : __i915_request_commit: 0000:00:02.0 bcs0: fence 3c:2, current 0

<0> [208.577951] kms_curs-2316 4D..1. 57910194us : __i915_request_submit: 0000:00:02.0 bcs0: fence 3c:2, current 0

<0> [208.578035] kms_curs-2316 4..... 57910242us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:28, current 27

<0> [208.578118] kms_curs-2316 4D..1. 57910245us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:28, current 27

<0> [208.578196] kms_curs-2316 4..... 57910262us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:3c:2

<0> [208.578270] kworker/-339 16..... 57910634us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:3c unpin

<0> [208.578343] kworker/-339 16..... 57910766us : __intel_context_retire: 0000:00:02.0 bcs0: context:3c retire runtime: { total:52416ns, avg:52416ns }

<0> [208.578416] kms_curs-2316 4..... 57911792us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:3d active

<0> [208.578495] kms_curs-2316 4..... 57912525us : intel_timeline_pin: 0000:00:02.0 timeline:3d using HWSP offset:feda9000

<0> [208.578582] kms_curs-2316 4..... 57912859us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:3d pin ring:{start:fedaa000, head:0000, tail:0000}

<0> [208.578691] kms_curs-2316 4..... 57913623us : __i915_request_commit: 0000:00:02.0 bcs0: fence 3d:2, current 0

<0> [208.578799] kms_curs-2316 4D..1. 57913627us : __i915_request_submit: 0000:00:02.0 bcs0: fence 3d:2, current 0

<0> [208.578907] kms_curs-2316 4..... 57913665us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:29, current 28

<0> [208.579048] kms_curs-2316 4D..1. 57913668us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:29, current 28

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

<0> [208.579184] kms_curs-2316 4..... 57913683us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:3d:2

<0> [208.579317] kworker/-339 16..... 57914024us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:3d unpin

<0> [208.579463] kworker/-339 16..... 57914172us : __intel_context_retire: 0000:00:02.0 bcs0: context:3d retire runtime: { total:49140ns, avg:49140ns }

<0> [208.579577] kms_curs-2316 4..... 57915646us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:3e active

<0> [208.579708] kms_curs-2316 4..... 57916341us : intel_timeline_pin: 0000:00:02.0 timeline:3e using HWSP offset:fecf1000

<0> [208.579836] kms_curs-2316 4..... 57916723us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:3e pin ring:{start:03104000, head:0000, tail:0000}

<0> [208.579998] kms_curs-2316 4..... 57917464us : __i915_request_commit: 0000:00:02.0 bcs0: fence 3e:2, current 0

<0> [208.580151] kms_curs-2316 4D..1. 57917468us : __i915_request_submit: 0000:00:02.0 bcs0: fence 3e:2, current 0

<6> [208.580242] [IGT] kms_atomic: starting dynamic subtest pipe-A-eDP-1

<0> [208.580281] kms_curs-2316 4..... 57917506us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:30, current 29

<0> [208.580416] kms_curs-2316 4D..1. 57917509us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:30, current 29

<0> [208.580547] kms_curs-2316 4..... 57917524us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:3e:2

<0> [208.580653] kworker/-339 16..... 57917810us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:3e unpin

<0> [208.580760] kworker/-339 16..... 57917874us : __intel_context_retire: 0000:00:02.0 bcs0: context:3e retire runtime: { total:23036ns, avg:23036ns }

<0> [208.580863] kms_curs-2316 4..... 57919045us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:3f active

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

<0> [208.580977] kms_curs-2316 4..... 57919688us : intel_timeline_pin: 0000:00:02.0 timeline:3f using HWSP offset:fedd0000

<0> [208.581090] kms_curs-2316 4..... 57920015us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:3f pin ring:{start:feda5000, head:0000, tail:0000}

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

<0> [208.581201] kms_curs-2316 4..... 57920025us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:3f unpin

<0> [208.581311] kms_curs-2316 4..... 57920089us : __intel_context_retire: 0000:00:02.0 bcs0: context:3f retire runtime: { total:0ns, avg:0ns }

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

<0> [208.581422] kms_curs-2316 4..... 57920091us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:3f active

<0> [208.581538] kms_curs-2316 4..... 57920092us : intel_timeline_pin: 0000:00:02.0 timeline:3f using HWSP offset:fedd0000

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

<0> [208.581662] kms_curs-2316 4..... 57920095us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:3f pin ring:{start:feda5000, head:0000, tail:0000}

<0> [208.581785] kms_curs-2316 4..... 57920797us : __i915_request_commit: 0000:00:02.0 bcs0: fence 3f:2, current 0

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

<0> [208.581909] kms_curs-2316 4D..1. 57920801us : __i915_request_submit: 0000:00:02.0 bcs0: fence 3f:2, current 0

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

<0> [208.582030] kms_curs-2316 4..... 57920837us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:31, current 30

<0> [208.582151] kms_curs-2316 4D..1. 57920839us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:31, current 30

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

<0> [208.582270] kms_curs-2316 4..... 57920853us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:3f:2

<0> [208.582380] kworker/-339 16..... 57921110us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:3f unpin

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

<0> [208.582492] kworker/-339 16..... 57921184us : __intel_context_retire: 0000:00:02.0 bcs0: context:3f retire runtime: { total:23036ns, avg:23036ns }

<0> [208.582604] kms_curs-2316 4..... 57922590us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:40 active

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

<0> [208.582732] kms_curs-2316 4..... 57923576us : intel_timeline_pin: 0000:00:02.0 timeline:40 using HWSP offset:fedcf000

<0> [208.582844] kms_curs-2316 4..... 57923961us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:40 pin ring:{start:feccc000, head:0000, tail:0000}

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

<0> [208.582956] kms_curs-2316 4..... 57923971us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:40 unpin

<0> [208.583067] kms_curs-2316 4..... 57924036us : __intel_context_retire: 0000:00:02.0 bcs0: context:40 retire runtime: { total:0ns, avg:0ns }

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

<0> [208.583178] kms_curs-2316 4..... 57924038us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:40 active

<0> [208.583298] kms_curs-2316 4..... 57924038us : intel_timeline_pin: 0000:00:02.0 timeline:40 using HWSP offset:fedcf000

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

<0> [208.583410] kms_curs-2316 4..... 57924041us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:40 pin ring:{start:feccc000, head:0000, tail:0000}

<0> [208.583522] kms_curs-2316 4..... 57924048us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:40 unpin

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

<0> [208.583634] kms_curs-2316 4..... 57924112us : __intel_context_retire: 0000:00:02.0 bcs0: context:40 retire runtime: { total:0ns, avg:0ns }

<0> [208.583757] kms_curs-2316 4..... 57924114us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:40 active

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

<0> [208.583883] kms_curs-2316 4..... 57924115us : intel_timeline_pin: 0000:00:02.0 timeline:40 using HWSP offset:fedcf000

<0> [208.583995] kms_curs-2316 4..... 57924117us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:40 pin ring:{start:feccc000, head:0000, tail:0000}

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

<0> [208.584121] kms_curs-2316 4..... 57924767us : __i915_request_commit: 0000:00:02.0 bcs0: fence 40:2, current 0

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

<0> [208.584266] kms_curs-2316 4D..1. 57924770us : __i915_request_submit: 0000:00:02.0 bcs0: fence 40:2, current 0

<0> [208.584412] kms_curs-2316 4..... 57924808us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:32, current 31

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

<0> [208.584554] kms_curs-2316 4D..1. 57924811us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:32, current 31

<0> [208.584686] kms_curs-2316 4..... 57924824us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:40:2

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

<0> [208.584811] kworker/-339 16..... 57925078us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:40 unpin

<0> [208.584927] kworker/-339 16..... 57925144us : __intel_context_retire: 0000:00:02.0 bcs0: context:40 retire runtime: { total:23816ns, avg:23816ns }

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

<0> [208.585039] kms_curs-2316 4..... 57926154us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:41 active

<0> [208.585158] kms_curs-2316 4..... 57926984us : intel_timeline_pin: 0000:00:02.0 timeline:41 using HWSP offset:fedae000

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

<0> [208.585270] kms_curs-2316 4..... 57927627us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:41 pin ring:{start:03112000, head:0000, tail:0000}

<0> [208.585381] kms_curs-2316 4..... 57927637us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:41 unpin

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

<0> [208.585493] kms_curs-2316 4..... 57927702us : __intel_context_retire: 0000:00:02.0 bcs0: context:41 retire runtime: { total:0ns, avg:0ns }

<0> [208.585604] kms_curs-2316 4..... 57927704us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:41 active

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

<0> [208.585724] kms_curs-2316 4..... 57927705us : intel_timeline_pin: 0000:00:02.0 timeline:41 using HWSP offset:fedae000

<0> [208.585832] kms_curs-2316 4..... 57927707us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:41 pin ring:{start:03112000, head:0000, tail:0000}

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

<0> [208.585942] kms_curs-2316 4..... 57927714us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:41 unpin

<0> [208.586051] kms_curs-2316 4..... 57927780us : __intel_context_retire: 0000:00:02.0 bcs0: context:41 retire runtime: { total:0ns, avg:0ns }

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

<0> [208.586165] kms_curs-2316 4..... 57927781us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:41 active

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

<0> [208.586284] kms_curs-2316 4..... 57927782us : intel_timeline_pin: 0000:00:02.0 timeline:41 using HWSP offset:fedae000

<0> [208.586395] kms_curs-2316 4..... 57927784us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:41 pin ring:{start:03112000, head:0000, tail:0000}

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

<0> [208.586517] kms_curs-2316 4.l... 57928549us : __i915_request_commit: 0000:00:02.0 bcs0: fence 41:2, current 0

<0> [208.586649] kms_curs-2316 4Dl.1. 57928552us : __i915_request_submit: 0000:00:02.0 bcs0: fence 41:2, current 0

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

<0> [208.586775] kms_curs-2316 4..... 57930482us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:42 active

<0> [208.586899] kms_curs-2316 4..... 57931321us : intel_timeline_pin: 0000:00:02.0 timeline:42 using HWSP offset:fed02000

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

<0> [208.587010] kms_curs-2316 4..... 57931943us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:42 pin ring:{start:fedaa000, head:0000, tail:0000}

<0> [208.587133] kms_curs-2316 4..... 57932627us : __i915_request_commit: 0000:00:02.0 bcs0: fence 42:2, current 0

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

<0> [208.587258] kms_curs-2316 4D..1. 57932630us : __i915_request_submit: 0000:00:02.0 bcs0: fence 42:2, current 0

<0> [208.587379] kms_curs-2316 4..... 57932667us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:33, current 32

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

<0> [208.587507] kms_curs-2316 4D..1. 57932670us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:33, current 32

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

<0> [208.587624] kms_curs-2316 4..... 57932683us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:42:2

<0> [208.587732] kworker/-339 16..... 57932992us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:42 unpin

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

<0> [208.587852] kworker/-339 16..... 57933057us : __intel_context_retire: 0000:00:02.0 bcs0: context:42 retire runtime: { total:80912ns, avg:80912ns }

<0> [208.587968] kms_curs-2316 4..... 57934026us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:43 active

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

<0> [208.588087] kms_curs-2316 4..... 57934864us : intel_timeline_pin: 0000:00:02.0 timeline:43 using HWSP offset:fed01000

<0> [208.588197] kms_curs-2316 4..... 57935274us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:43 pin ring:{start:fece3000, head:0000, tail:0000}

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

<0> [208.588320] kms_curs-2316 4..... 57935899us : __i915_request_commit: 0000:00:02.0 bcs0: fence 43:2, current 0

<0> [208.588442] kms_curs-2316 4D..1. 57935902us : __i915_request_submit: 0000:00:02.0 bcs0: fence 43:2, current 0

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

<0> [208.588564] kms_curs-2316 4..... 57935938us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:34, current 33

<0> [208.588686] kms_curs-2316 4D..1. 57935941us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:34, current 33

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

<0> [208.588802] kms_curs-2316 4..... 57935954us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:43:2

<0> [208.588921] kworker/-339 16..... 57936269us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:43 unpin

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

<0> [208.589044] kworker/-339 16..... 57936334us : __intel_context_retire: 0000:00:02.0 bcs0: context:43 retire runtime: { total:78884ns, avg:78884ns }

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

<0> [208.589155] kms_curs-2316 4.l... 57937760us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:44 active

<0> [208.589264] kworker/-339 7..... 57941994us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:44 unpin

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

<0> [208.589373] kworker/-339 7..... 57942070us : __intel_context_retire: 0000:00:02.0 bcs0: context:44 retire runtime: { total:81172ns, avg:81172ns }

<0> [208.589480] kworker/-339 7..... 57946170us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:45 unpin

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

<0> [208.589589] kworker/-339 7..... 57946300us : __intel_context_retire: 0000:00:02.0 bcs0: context:45 retire runtime: { total:98332ns, avg:98332ns }

<0> [208.589701] kworker/-339 7..... 57950284us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:46 unpin

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

<0> [208.589810] kworker/-339 7..... 57950356us : __intel_context_retire: 0000:00:02.0 bcs0: context:46 retire runtime: { total:37856ns, avg:37856ns }

<0> [208.589925] kworker/-339 7..... 57953788us : __intel_context_retire: 0000:00:02.0 bcs0: context:47 retire runtime: { total:36868ns, avg:36868ns }

<7> [208.589914] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:150:pipe A] releasing DPLL 0

<0> [208.590035] kworker/-339 7..... 57960591us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:49 unpin

<0> [208.590147] kworker/-339 7..... 57960660us : __intel_context_retire: 0000:00:02.0 bcs0: context:49 retire runtime: { total:38064ns, avg:38064ns }

<0> [208.590168] kms_curs-2316 2...1. 58011759us : tracing_mark_write: kms_cursor_crc: finished subtest cursor-sliding-256x85, SKIP

<0> [208.590289] kms_curs-2316 2..... 58013448us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:4a active

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

<0> [208.590418] kms_curs-2316 2..... 58014324us : intel_timeline_pin: 0000:00:02.0 timeline:4a using HWSP offset:fedd0000

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

<0> [208.590538] kms_curs-2316 2..... 58014718us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:4a pin ring:{start:feda5000, head:0000, tail:0000}

<0> [208.590671] kms_curs-2316 2..... 58015539us : __i915_request_commit: 0000:00:02.0 bcs0: fence 4a:2, current 0

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

<0> [208.590796] kms_curs-2316 2D..1. 58015542us : __i915_request_submit: 0000:00:02.0 bcs0: fence 4a:2, current 0

<0> [208.590924] kms_curs-2316 2..... 58015585us : i915_request_retire_upto: 0000:00:02.0 bcs0: fence 6:4, current 40

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

<0> [208.591051] kms_curs-2316 2..... 58015586us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 6:4, current 40

<0> [208.591174] kms_curs-2316 2..... 58015593us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:41, current 40

<7> [208.591084] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm

<0> [208.591296] kms_curs-2316 2D..1. 58015595us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:41, current 40

<7> [208.591291] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] lines 2, 16, 16, 19, 19, 28, 0, 0, 0, 3, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0

<0> [208.591412] kms_curs-2316 2..... 58015611us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:4a:2

<0> [208.591522] kworker/-160 8..... 58015982us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:4a unpin

<7> [208.591499] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] blocks 27, 209, 209, 248, 248, 365, 0, 0, 41, 40, 54 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0

<0> [208.591633] kworker/-160 8..... 58016066us : __intel_context_retire: 0000:00:02.0 bcs0: context:4a retire runtime: { total:168012ns, avg:168012ns }

<0> [208.591742] kms_curs-2316 2..... 58017170us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:4b active

<7> [208.591703] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] min_ddb 31, 231, 231, 274, 274, 403, 0, 0, 42, 45, 55 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0

<0> [208.591863] kms_curs-2316 2..... 58018195us : intel_timeline_pin: 0000:00:02.0 timeline:4b using HWSP offset:fedcf000

<0> [208.591975] kms_curs-2316 2..... 58018776us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:4b pin ring:{start:fecff000, head:0000, tail:0000}

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

<0> [208.592100] kms_curs-2316 2..... 58019459us : __i915_request_commit: 0000:00:02.0 bcs0: fence 4b:2, current 0

<0> [208.592223] kms_curs-2316 2D..1. 58019463us : __i915_request_submit: 0000:00:02.0 bcs0: fence 4b:2, current 0

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

<0> [208.592356] kms_curs-2316 2..... 58019503us : i915_request_retire_upto: 0000:00:02.0 bcs0: fence 6:5, current 41

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

<0> [208.592486] kms_curs-2316 2..... 58019503us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 6:5, current 41

<0> [208.592618] kms_curs-2316 2..... 58019511us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:42, current 41

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

<0> [208.592750] kms_curs-2316 2D..1. 58019513us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:42, current 41

<0> [208.592883] kms_curs-2316 2..... 58019529us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:4b:2

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

<0> [208.593013] kworker/-160 8..... 58019875us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:4b unpin

<0> [208.593129] kworker/-160 8..... 58019957us : __intel_context_retire: 0000:00:02.0 bcs0: context:4b retire runtime: { total:165776ns, avg:165776ns }

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

<0> [208.593242] kms_curs-2316 2..... 58021286us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:4c active

<0> [208.593359] kms_curs-2316 2..... 58022171us : intel_timeline_pin: 0000:00:02.0 timeline:4c using HWSP offset:fecf1000

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

<0> [208.593471] kms_curs-2316 2..... 58022478us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:4c pin ring:{start:fecda000, head:0000, tail:0000}

<0> [208.593589] kms_curs-2316 2..... 58023180us : __i915_request_commit: 0000:00:02.0 bcs0: fence 4c:2, current 0

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

<0> [208.593712] kms_curs-2316 2D..1. 58023183us : __i915_request_submit: 0000:00:02.0 bcs0: fence 4c:2, current 0

<0> [208.593833] kms_curs-2316 2..... 58023222us : i915_request_retire_upto: 0000:00:02.0 bcs0: fence 6:6, current 42

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

<0> [208.593958] kms_curs-2316 2..... 58023222us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 6:6, current 42

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

<0> [208.594088] kms_curs-2316 2..... 58023229us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:43, current 42

<0> [208.594216] kms_curs-2316 2D..1. 58023231us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:43, current 42

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

<0> [208.594333] kms_curs-2316 2..... 58023246us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:4c:2

<0> [208.594442] kworker/-160 8..... 58023618us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:4c unpin

<0> [208.594553] kworker/-160 8..... 58023702us : __intel_context_retire: 0000:00:02.0 bcs0: context:4c retire runtime: { total:168428ns, avg:168428ns }

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

<0> [208.594664] kms_curs-2316 2..... 58024950us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:4d active

<7> [208.594663] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:plane 1A] fb: [NOFB], visible: no

<0> [208.594781] kms_curs-2316 2..... 58025587us : intel_timeline_pin: 0000:00:02.0 timeline:4d using HWSP offset:fecc6000

<0> [208.594890] kms_curs-2316 2..... 58025855us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:4d pin ring:{start:fecc7000, head:0000, tail:0000}

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

<0> [208.595013] kms_curs-2316 2..... 58026511us : __i915_request_commit: 0000:00:02.0 bcs0: fence 4d:2, current 0

<0> [208.595142] kms_curs-2316 2D..1. 58026515us : __i915_request_submit: 0000:00:02.0 bcs0: fence 4d:2, current 0

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

<0> [208.595263] kms_curs-2316 2..... 58026553us : i915_request_retire_upto: 0000:00:02.0 bcs0: fence 6:7, current 43

<0> [208.595385] kms_curs-2316 2..... 58026553us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 6:7, current 43

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

<0> [208.595520] kms_curs-2316 2..... 58026559us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:44, current 43

<0> [208.595658] kms_curs-2316 2D..1. 58026562us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:44, current 43

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

<0> [208.595794] kms_curs-2316 2..... 58026575us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:4d:2

<0> [208.595919] kworker/-160 8..... 58026939us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:4d unpin

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

<0> [208.596047] kworker/-160 8..... 58027021us : __intel_context_retire: 0000:00:02.0 bcs0: context:4d retire runtime: { total:166712ns, avg:166712ns }

<0> [208.596152] kms_curs-2316 2..... 58028548us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:4e active

<7> [208.596123] i915 0000:00:02.0: [drm:intel_psr_disable_locked [i915]] Disabling PSR2

<0> [208.596270] kms_curs-2316 2..... 58029022us : intel_timeline_pin: 0000:00:02.0 timeline:4e using HWSP offset:fecbc000

<0> [208.596373] kms_curs-2316 2..... 58029296us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:4e pin ring:{start:fecbd000, head:0000, tail:0000}

<0> [208.596488] kms_curs-2316 2..... 58029994us : __i915_request_commit: 0000:00:02.0 bcs0: fence 4e:2, current 0

<0> [208.596578] kms_curs-2316 2D..1. 58029997us : __i915_request_submit: 0000:00:02.0 bcs0: fence 4e:2, current 0

<0> [208.596666] kms_curs-2316 2..... 58030036us : i915_request_retire_upto: 0000:00:02.0 bcs0: fence 6:8, current 44

<0> [208.596751] kms_curs-2316 2..... 58030036us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 6:8, current 44

<0> [208.596837] kms_curs-2316 2..... 58030043us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:45, current 44

<0> [208.596921] kms_curs-2316 2D..1. 58030046us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:45, current 44

<0> [208.597002] kms_curs-2316 2..... 58030078us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:4e:2

<0> [208.597083] kworker/-160 8..... 58030400us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:4e unpin

<0> [208.597163] kworker/-160 8..... 58030540us : __intel_context_retire: 0000:00:02.0 bcs0: context:4e retire runtime: { total:67600ns, avg:67600ns }

<0> [208.597236] kms_curs-2316 2..... 58031612us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:4f active

<0> [208.597319] kms_curs-2316 2..... 58032451us : intel_timeline_pin: 0000:00:02.0 timeline:4f using HWSP offset:fecec000

<0> [208.597395] kms_curs-2316 2..... 58032710us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:4f pin ring:{start:feda5000, head:0000, tail:0000}

<0> [208.597470] kms_curs-2316 2..... 58032720us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:4f unpin

<0> [208.597543] kms_curs-2316 2..... 58032785us : __intel_context_retire: 0000:00:02.0 bcs0: context:4f retire runtime: { total:0ns, avg:0ns }

<0> [208.597616] kms_curs-2316 2..... 58032787us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:4f active

<0> [208.597695] kms_curs-2316 2..... 58032788us : intel_timeline_pin: 0000:00:02.0 timeline:4f using HWSP offset:fecec000

<0> [208.597772] kms_curs-2316 2..... 58032790us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:4f pin ring:{start:feda5000, head:0000, tail:0000}

<0> [208.597852] kms_curs-2316 2..... 58033504us : __i915_request_commit: 0000:00:02.0 bcs0: fence 4f:2, current 0

<0> [208.597931] kms_curs-2316 2D..1. 58033508us : __i915_request_submit: 0000:00:02.0 bcs0: fence 4f:2, current 0

<0> [208.598014] kms_curs-2316 2..... 58033545us : i915_request_retire_upto: 0000:00:02.0 bcs0: fence 6:9, current 45

<0> [208.598100] kms_curs-2316 2..... 58033546us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 6:9, current 45

<0> [208.598187] kms_curs-2316 2..... 58033551us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:46, current 45

<0> [208.598266] kms_curs-2316 2D..1. 58033554us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:46, current 45

<0> [208.598345] kms_curs-2316 2..... 58033569us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:4f:2

<0> [208.598419] kworker/-160 8..... 58033883us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:4f unpin

<0> [208.598493] kworker/-160 8..... 58033964us : __intel_context_retire: 0000:00:02.0 bcs0: context:4f retire runtime: { total:66144ns, avg:66144ns }

<0> [208.598566] kms_curs-2316 2..... 58035220us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:50 active

<0> [208.598644] kms_curs-2316 2..... 58035981us : intel_timeline_pin: 0000:00:02.0 timeline:50 using HWSP offset:fecb1000

<0> [208.598719] kms_curs-2316 2..... 58036275us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:50 pin ring:{start:fece8000, head:0000, tail:0000}

<0> [208.598795] kms_curs-2316 2..... 58036285us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:50 unpin

<0> [208.598870] kms_curs-2316 2..... 58036350us : __intel_context_retire: 0000:00:02.0 bcs0: context:50 retire runtime: { total:0ns, avg:0ns }

<0> [208.598944] kms_curs-2316 2..... 58036352us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:50 active

<0> [208.599023] kms_curs-2316 2..... 58036353us : intel_timeline_pin: 0000:00:02.0 timeline:50 using HWSP offset:fecb1000

<0> [208.599099] kms_curs-2316 2..... 58036356us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:50 pin ring:{start:fece8000, head:0000, tail:0000}

<0> [208.599175] kms_curs-2316 2..... 58036364us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:50 unpin

<0> [208.599247] kms_curs-2316 2..... 58036428us : __intel_context_retire: 0000:00:02.0 bcs0: context:50 retire runtime: { total:0ns, avg:0ns }

<0> [208.599319] kms_curs-2316 2..... 58036430us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:50 active

<0> [208.599396] kms_curs-2316 2..... 58036430us : intel_timeline_pin: 0000:00:02.0 timeline:50 using HWSP offset:fecb1000

<0> [208.599468] kms_curs-2316 2..... 58036433us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:50 pin ring:{start:fece8000, head:0000, tail:0000}

<0> [208.599548] kms_curs-2316 2..... 58037125us : __i915_request_commit: 0000:00:02.0 bcs0: fence 50:2, current 0

<0> [208.599629] kms_curs-2316 2D..1. 58037129us : __i915_request_submit: 0000:00:02.0 bcs0: fence 50:2, current 0

<0> [208.599712] kms_curs-2316 2..... 58037407us : i915_request_retire_upto: 0000:00:02.0 bcs0: fence 6:10, current 46

<0> [208.599795] kms_curs-2316 2..... 58037408us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 6:10, current 46

<0> [208.599878] kms_curs-2316 2..... 58037414us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:47, current 46

<0> [208.599961] kms_curs-2316 2D..1. 58037416us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:47, current 46

<0> [208.600041] kms_curs-2316 2..... 58037430us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:50:2

<0> [208.600115] kworker/-160 8..... 58037669us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:50 unpin

<0> [208.600193] kworker/-160 8..... 58037749us : __intel_context_retire: 0000:00:02.0 bcs0: context:50 retire runtime: { total:66924ns, avg:66924ns }

<0> [208.600266] kms_curs-2316 2.l... 58039463us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:51 active

<0> [208.600340] kms_curs-2316 0..... 58040855us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:51 pin ring:{start:feca8000, head:0000, tail:0000}

<0> [208.600413] kms_curs-2316 0..... 58040891us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:51 unpin

<0> [208.600485] kms_curs-2316 0..... 58040971us : __intel_context_retire: 0000:00:02.0 bcs0: context:51 retire runtime: { total:0ns, avg:0ns }

<0> [208.600559] kms_curs-2316 0..... 58040979us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:51 active

<0> [208.600639] kms_curs-2316 0..... 58040983us : intel_timeline_pin: 0000:00:02.0 timeline:51 using HWSP offset:feca7000

<0> [208.600711] kms_curs-2316 0..... 58040991us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:51 pin ring:{start:feca8000, head:0000, tail:0000}

<0> [208.600783] kms_curs-2316 0..... 58041017us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:51 unpin

<0> [208.600855] kms_curs-2316 0..... 58041096us : __intel_context_retire: 0000:00:02.0 bcs0: context:51 retire runtime: { total:0ns, avg:0ns }

<0> [208.600926] kms_curs-2316 0..... 58041102us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:51 active

<0> [208.601006] kms_curs-2316 0..... 58041105us : intel_timeline_pin: 0000:00:02.0 timeline:51 using HWSP offset:feca7000

<0> [208.601079] kms_curs-2316 0..... 58041113us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:51 pin ring:{start:feca8000, head:0000, tail:0000}

<0> [208.601150] kworker/-160 8..... 58044227us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:51 unpin

<0> [208.601235] kworker/-160 8..... 58044307us : __intel_context_retire: 0000:00:02.0 bcs0: context:51 retire runtime: { total:65832ns, avg:65832ns }

<0> [208.601305] kworker/-160 8..... 58048164us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:52 unpin

<0> [208.601378] kworker/-160 8..... 58048336us : __intel_context_retire: 0000:00:02.0 bcs0: context:52 retire runtime: { total:79092ns, avg:79092ns }

<0> [208.601450] kworker/-160 8..... 58051865us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:53 unpin

<0> [208.601522] kworker/-160 8..... 58051944us : __intel_context_retire: 0000:00:02.0 bcs0: context:53 retire runtime: { total:77428ns, avg:77428ns }

<0> [208.601595] kworker/-160 8..... 58055167us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:54 unpin

<0> [208.601667] kworker/-160 8..... 58055245us : __intel_context_retire: 0000:00:02.0 bcs0: context:54 retire runtime: { total:81172ns, avg:81172ns }

<0> [208.601739] kworker/-160 8..... 58059018us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:55 unpin

<0> [208.601810] kworker/-160 8..... 58059099us : __intel_context_retire: 0000:00:02.0 bcs0: context:55 retire runtime: { total:79144ns, avg:79144ns }

<0> [208.601883] kworker/-160 8..... 58060154us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:21 unpin

<0> [208.601954] kworker/-160 8..... 58060243us : __intel_context_retire: 0000:00:02.0 rcs0: context:21 retire runtime: { total:71864ns, avg:71864ns }

<0> [208.602026] kworker/-44 8..... 58060270us : __engine_park: 0000:00:02.0 rcs0: parked

<0> [208.602098] kworker/-160 8..... 58060435us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:24 unpin

<0> [208.602169] kworker/-160 8..... 58060514us : __intel_context_retire: 0000:00:02.0 bcs0: context:24 retire runtime: { total:72644ns, avg:72644ns }

<0> [208.602245] kworker/-160 8..... 58060539us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:2f unpin

<0> [208.602317] kworker/-160 8..... 58060618us : __intel_context_retire: 0000:00:02.0 bcs0: context:2f retire runtime: { total:19188ns, avg:19188ns }

<0> [208.602388] kworker/-160 8..... 58060639us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:34 unpin

<0> [208.602459] kworker/-160 8..... 58060718us : __intel_context_retire: 0000:00:02.0 bcs0: context:34 retire runtime: { total:24752ns, avg:24752ns }

<0> [208.602530] kworker/-160 8..... 58060738us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:41 unpin

<0> [208.602600] kworker/-160 8..... 58060816us : __intel_context_retire: 0000:00:02.0 bcs0: context:41 retire runtime: { total:23400ns, avg:23400ns }

<0> [208.602671] kworker/-160 8..... 58060836us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:48 unpin

<0> [208.602743] kworker/-160 8..... 58060918us : __intel_context_retire: 0000:00:02.0 bcs0: context:48 retire runtime: { total:38532ns, avg:38532ns }

<0> [208.602815] kworker/-44 8..... 58060945us : __engine_park: 0000:00:02.0 bcs0: parked

<0> [208.602891] kworker/-44 8..... 58060973us : __gt_park: 0000:00:02.0

<0> [208.602965] kworker/-160 8..... 58063463us : __gt_unpark: 0000:00:02.0

<0> [208.603037] kworker/-225 4..... 58064569us : __gt_park: 0000:00:02.0

<0> [208.603112] kworker/-339 7..... 58073489us : __gt_unpark: 0000:00:02.0

<0> [208.603184] kworker/-225 4..... 58074233us : __gt_park: 0000:00:02.0

<0> [208.603259] kworker/-339 7..... 58081572us : __gt_unpark: 0000:00:02.0

<0> [208.603344] kworker/-225 4..... 58083582us : __gt_park: 0000:00:02.0

<0> [208.603419] kms_curs-2316 2..... 58132223us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes

<0> [208.603497] kworker/-243 2..... 58142494us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no

<0> [208.603510] kms_plan-2317 4...1. 58217445us : tracing_mark_write: kms_plane: executing

<0> [208.603585] kms_plan-2317 7..... 58223569us : __gt_unpark: 0000:00:02.0

<0> [208.603662] kworker/-226 7..... 58223974us : __gt_park: 0000:00:02.0

<0> [208.603737] kms_plan-2317 4..... 58247625us : __gt_unpark: 0000:00:02.0

<0> [208.603812] kworker/-225 4..... 58249601us : __gt_park: 0000:00:02.0

<0> [208.603890] gem_part-2318 2..... 58405133us : __gt_unpark: 0000:00:02.0

<0> [208.603965] gem_part-2318 2..... 58405794us : __engine_unpark: 0000:00:02.0 vecs0:

<0> [208.604041] gem_part-2318 2..... 58405799us : __gt_unpark: 0000:00:02.0

<0> [208.604116] gem_part-2318 2..... 58405890us : __engine_unpark: 0000:00:02.0 vecs0: context:e reset { seqno:1, *hwsp:1, ring:78 }

<0> [208.604192] gem_part-2318 2..... 58405891us : __engine_park: 0000:00:02.0 vecs0: parked

<0> [208.604265] gem_part-2318 2..... 58406204us : __engine_unpark: 0000:00:02.0 ccs0:

<0> [208.604345] gem_part-2318 2..... 58406211us : __engine_unpark: 0000:00:02.0 ccs0: context:7 reset { seqno:1, *hwsp:1, ring:130 }

<0> [208.604418] gem_part-2318 2..... 58406212us : __engine_park: 0000:00:02.0 ccs0: parked

<0> [208.604490] gem_part-2318 2..... 58406536us : __engine_unpark: 0000:00:02.0 vcs0:

<0> [208.604562] gem_part-2318 2..... 58406543us : __engine_unpark: 0000:00:02.0 vcs0: context:c reset { seqno:1, *hwsp:1, ring:78 }

<0> [208.604634] gem_part-2318 2..... 58406544us : __engine_park: 0000:00:02.0 vcs0: parked

<0> [208.604706] gem_part-2318 2..... 58406840us : __engine_unpark: 0000:00:02.0 bcs0:

<0> [208.604778] gem_part-2318 2..... 58406846us : __engine_unpark: 0000:00:02.0 bcs0: context:6 reset { seqno:34, *hwsp:34, ring:548 }

<0> [208.604851] gem_part-2318 2..... 58406847us : __engine_park: 0000:00:02.0 bcs0: parked

<0> [208.604922] gem_part-2318 2..... 58407140us : __engine_unpark: 0000:00:02.0 rcs0:

<0> [208.604994] gem_part-2318 2..... 58407147us : __engine_unpark: 0000:00:02.0 rcs0: context:5 reset { seqno:4, *hwsp:4, ring:4a0 }

<0> [208.605066] gem_part-2318 2..... 58407148us : __engine_park: 0000:00:02.0 rcs0: parked

<0> [208.605141] kworker/-223 2..... 58407308us : __gt_park: 0000:00:02.0

<0> [208.605212] kworker/-223 2..... 58407326us : __gt_park: 0000:00:02.0

<0> [208.605284] gem_part-2318 2..... 58407500us : __engine_unpark: 0000:00:02.0 vcs1:

<0> [208.605367] gem_part-2318 2..... 58407504us : __gt_unpark: 0000:00:02.0

<0> [208.605437] gem_part-2318 2..... 58407605us : __engine_unpark: 0000:00:02.0 vcs1: context:d reset { seqno:1, *hwsp:1, ring:78 }

<0> [208.605509] gem_part-2318 2..... 58407606us : __engine_park: 0000:00:02.0 vcs1: parked

<0> [208.605583] kworker/-223 2..... 58407617us : __gt_park: 0000:00:02.0

<0> [208.605658] gem_part-2318 2..... 58407928us : __gt_unpark: 0000:00:02.0

<0> [208.605732] gem_part-2318 2..... 58408205us : __gt_park: 0000:00:02.0

<0> [208.605808] gem_part-2318 2..... 58432139us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes

<0> [208.605885] kworker/-243 2..... 58440034us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no

<0> [208.605895] syncobj_-2319 4...1. 58493255us : tracing_mark_write: syncobj_timeline: executing

<0> [208.605968] syncobj_-2319 4..... 58497825us : __gt_unpark: 0000:00:02.0

<0> [208.606041] kworker/-225 4..... 58498575us : __gt_park: 0000:00:02.0

<0> [208.606115] syncobj_-2319 4..... 58512017us : __gt_unpark: 0000:00:02.0

<0> [208.606188] kworker/-225 4..... 58512284us : __gt_park: 0000:00:02.0

Results for igt@kms_atomic@test-only@pipe-a-edp-1