Results for igt@gem_exec_schedule@in-order@vcs0

Result: Dmesg-Warn 720 Warning(s)

integration-manifest git-log-oneline i915_display_info3 igt_runner3 runtimes3 results3.json results3-i915-load.json i915_display_info_post_exec3 boot3 dmesg3

DetailValue
Duration 0.11 seconds
Hostname 
shard-mtlp-5
Igt-Version 
IGT-Version: 2.1-gc4a9bee16 (x86_64) (Linux: 6.16.0-CI_DRM_16968-gc59a466c9c1d+ x86_64)
Out 
Starting dynamic subtest: vcs0
Dynamic subtest vcs0: SUCCESS (0.108s)
Err 
Starting dynamic subtest: vcs0
Dynamic subtest vcs0: SUCCESS (0.108s)
Dmesg

Scroll to first warning
<6> [518.073496] [IGT] gem_exec_schedule: starting dynamic subtest vcs0
<7> [518.073649] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508
<7> [518.080036] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508
<7> [518.082462] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508
<7> [518.084278] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508
<7> [518.086385] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508
<3> [518.088222] guc_lrc_desc_unpin:3418 GEM_BUG_ON(!ctx_id_mapped(guc, ce->guc_id.id))
<0> [518.088444] Dumping ftrace buffer:
<0> [518.088469] ---------------------------------
<7> [518.088453] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508
<0> [518.089250] kworker/-183 21..... 49721895us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 1a:2, current 2
<0> [518.089576] kworker/-183 21..... 49722353us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 1b:2, current 2
<0> [518.089815] kworker/-183 21..... 49722394us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 1c:2, current 2
<4> [518.090053] kauditd_printk_skb: 113 callbacks suppressed
<5> [518.090059] audit: type=1400 audit(1754616099.018:125): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/run/systemd/sessions/" pid=854 comm=72733A6D61696E20513A526567 requested_mask="r" denied_mask="r" fsuid=103 ouid=0
<5> [518.090087] audit: type=1400 audit(1754616099.018:126): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/run/systemd/sessions/" pid=854 comm=72733A6D61696E20513A526567 requested_mask="r" denied_mask="r" fsuid=103 ouid=0
<0> [518.090122] kworker/-183 21..... 49722664us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 17:2, current 2
<0> [518.090402] kworker/-183 21..... 49726053us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 5:2, current 2
<7> [518.090336] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508
<0> [518.090655] kworker/-183 21..... 49728474us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 18:2, current 2
<5> [518.090663] audit: type=1400 audit(1754616099.019:127): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/run/systemd/sessions/" pid=854 comm=72733A6D61696E20513A526567 requested_mask="r" denied_mask="r" fsuid=103 ouid=0
<5> [518.090822] audit: type=1400 audit(1754616099.019:128): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/run/systemd/sessions/" pid=854 comm=72733A6D61696E20513A526567 requested_mask="r" denied_mask="r" fsuid=103 ouid=0
<5> [518.090843] audit: type=1400 audit(1754616099.019:129): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/run/systemd/sessions/" pid=854 comm=72733A6D61696E20513A526567 requested_mask="r" denied_mask="r" fsuid=103 ouid=0
<5> [518.090872] audit: type=1400 audit(1754616099.019:130): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/run/systemd/sessions/" pid=854 comm=72733A6D61696E20513A526567 requested_mask="r" denied_mask="r" fsuid=103 ouid=0
<0> [518.090950] kworker/-183 21..... 49728679us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 19:2, current 2
<5> [518.091216] audit: type=1400 audit(1754616099.020:131): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/run/systemd/sessions/" pid=854 comm=72733A6D61696E20513A526567 requested_mask="r" denied_mask="r" fsuid=103 ouid=0
<5> [518.091241] audit: type=1400 audit(1754616099.020:132): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/run/systemd/sessions/" pid=854 comm=72733A6D61696E20513A526567 requested_mask="r" denied_mask="r" fsuid=103 ouid=0
<0> [518.091247] kworker/-183 21..... 49728720us : i915_request_retire.part.0: 0000:00:02.0 ccs0: fence 1d:2, current 2
<5> [518.091274] audit: type=1400 audit(1754616099.020:133): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/run/systemd/sessions/" pid=854 comm=72733A6D61696E20513A526567 requested_mask="r" denied_mask="r" fsuid=103 ouid=0
<0> [518.091542] kworker/-183 21..... 80627527us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 31:2, current 2
<0> [518.091840] kworker/-183 21..... 80628140us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 5:5, current 5
<0> [518.092136] kworker/-183 21..... 80628167us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 6:6, current 7
<5> [518.092218] audit: type=1400 audit(1754616099.021:134): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/run/systemd/sessions/" pid=854 comm=72733A6D61696E20513A526567 requested_mask="r" denied_mask="r" fsuid=103 ouid=0
<0> [518.092349] kworker/-183 21..... 80628174us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 6:7, current 7
<7> [518.092338] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508
<0> [518.092574] kworker/-183 21..... 80628192us : __engine_park: 0000:00:02.0 bcs0: parked
<0> [518.092847] kworker/-183 21..... 80629793us : __gt_park: 0000:00:02.0
<0> [518.093110] kworker/-179 20..... 80753116us : intel_gt_runtime_suspend: 0000:00:02.0
<0> [518.093327] kworker/-179 20..... 80754679us : intel_gt_runtime_suspend: 0000:00:02.0
<0> [518.093454] kworker/-183 21..... 86578226us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 4d:4, current 4
<0> [518.093574] kms_big_-2337 20..... 183622562us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:1bc active
<0> [518.093786] kms_big_-2337 20..... 183622576us : intel_timeline_pin: 0000:00:02.0 timeline:1bc using HWSP offset:fedc1000
<0> [518.094027] kms_big_-2337 20..... 183622603us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:1bc pin ring:{start:fedc2000, head:3990, tail:3990}
<0> [518.094300] kms_big_-2337 20..... 183622666us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:1bc unpin
<0> [518.094494] kms_big_-2337 20..... 183622772us : __intel_context_retire: 0000:00:02.0 rcs0: context:1bc retire runtime: { total:75042223ns, avg:75042223ns }
<0> [518.094642] kms_big_-2337 20..... 183622784us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:1bc active
<0> [518.094956] kms_big_-2337 20..... 183622789us : intel_timeline_pin: 0000:00:02.0 timeline:1bc using HWSP offset:fedc1000
<0> [518.095235] kms_big_-2337 20..... 183622803us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:1bc pin ring:{start:fedc2000, head:3990, tail:3990}
<0> [518.095510] kms_big_-2337 20..... 183622850us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:1bc unpin
<0> [518.095679] kms_big_-2337 20..... 183622953us : __intel_context_retire: 0000:00:02.0 rcs0: context:1bc retire runtime: { total:75042223ns, avg:75042223ns }
<0> [518.095792] kms_big_-2337 20..... 183622962us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:1bc active
<7> [518.095611] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508
<0> [518.095908] kms_big_-2337 20..... 183622966us : intel_timeline_pin: 0000:00:02.0 timeline:1bc using HWSP offset:fedc1000
<0> [518.096001] kms_big_-2337 20..... 183622979us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:1bc pin ring:{start:fedc2000, head:3990, tail:3990}
<0> [518.096108] kms_big_-2337 20..... 183624035us : __i915_request_commit: 0000:00:02.0 rcs0: fence 1bc:902, current 900
<0> [518.096220] kms_big_-2337 20D..1. 183624046us : __i915_request_submit: 0000:00:02.0 rcs0: fence 1bc:902, current 900
<0> [518.096338] kms_big_-2337 20..... 183624843us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes
<0> [518.096453] kworker/-105 20..... 183627815us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no
<0> [518.096568] kworker/-105 20..... 183999835us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no
<0> [518.096691] kworker/-183 21..... 186603005us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 1c3:2, current 2
<0> [518.096814] kworker/-183 21..... 186604439us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 5:21, current 21
<0> [518.096937] kworker/-105 20..... 201068576us : __i915_request_commit: 0000:00:02.0 rcs0: fence 5:22, current 21
<0> [518.097058] kworker/-105 20D..1. 201068600us : __i915_request_submit: 0000:00:02.0 rcs0: fence 5:22, current 21
<0> [518.097181] kworker/-183 21..... 212585342us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 5:22, current 22
<0> [518.097295] kworker/-183 21..... 212585398us : __engine_park: 0000:00:02.0 rcs0: parked
<0> [518.097406] kworker/-183 21..... 212586115us : __gt_park: 0000:00:02.0
<0> [518.097523] kworker/-449 21..... 216617400us : __i915_request_commit: 0000:00:02.0 rcs0: fence 5:23, current 22
<0> [518.097644] kworker/-449 21D..1. 216617429us : __i915_request_submit: 0000:00:02.0 rcs0: fence 5:23, current 22
<0> [518.097766] kworker/-183 21..... 230633430us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 1cc:2, current 2
<0> [518.097888] kworker/-183 21..... 230634465us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence c:4, current 5
<0> [518.098009] kworker/-183 21..... 230634472us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence c:5, current 5
<0> [518.098123] kworker/-183 21..... 230634611us : __gt_park: 0000:00:02.0
<0> [518.098240] kworker/-183 21..... 232616844us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 1ce:2, current 2
<0> [518.098361] kworker/-183 21..... 232618686us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence d:4, current 5
<0> [518.098482] kworker/-183 21..... 232618696us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence d:5, current 5
<0> [518.098597] kworker/-183 21..... 232618777us : __gt_park: 0000:00:02.0
<0> [518.098713] kworker/-183 21..... 234600699us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 1d0:2, current 2
<0> [518.098834] kworker/-183 21..... 234600948us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence e:4, current 5
<0> [518.098955] kworker/-183 21..... 234600958us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence e:5, current 5
<0> [518.099076] kworker/-183 21..... 262631685us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 291:2, current 2
<0> [518.099196] kworker/-183 21..... 262632162us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 5:29, current 29
<0> [518.099316] kworker/-183 21..... 262632203us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 6:506, current 509
<0> [518.099437] kworker/-183 21..... 262632210us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 6:507, current 509
<0> [518.099574] kworker/-183 21..... 262632258us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 6:508, current 509
<0> [518.099710] kworker/-183 21..... 262632276us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 6:509, current 509
<0> [518.099840] kworker/-183 21..... 262632356us : __engine_park: 0000:00:02.0 bcs0: parked
<0> [518.099958] kms_curs-2358 20..... 270726630us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes
<0> [518.100084] gem_exec-2398 20..... 279572841us : __i915_request_commit: 0000:00:02.0 vcs1: fence 312:4, current 2
<0> [518.100209] gem_exec-2398 20D..1. 279572861us : __i915_request_submit: 0000:00:02.0 vcs1: fence 312:4, current 2
<0> [518.100334] gem_exec-2398 20..... 279573151us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:1760, current 1756
<0> [518.100458] gem_exec-2398 20D..1. 279573170us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:1760, current 1756
<0> [518.100580] gem_exec-2398 20..... 279573305us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:1758, current 1759
<7> [518.100485] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508
<0> [518.100702] gem_exec-2398 20..... 279573353us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:1762, current 1760
<0> [518.100823] gem_exec-2398 20D..1. 279573367us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:1762, current 1760
<0> [518.100939] gem_exec-2408 20..... 279966907us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:473 active
<0> [518.101060] gem_exec-2408 20..... 279998262us : intel_timeline_pin: 0000:00:02.0 timeline:473 using HWSP offset:faf08000
<0> [518.101176] gem_exec-2408 20..... 280013427us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:473 pin ring:{start:fb0e2000, head:0000, tail:0000}
<0> [518.101295] gem_exec-2408 20..... 280013484us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:473 unpin
<0> [518.101409] gem_exec-2408 20..... 280013576us : __intel_context_retire: 0000:00:02.0 bcs0: context:473 retire runtime: { total:0ns, avg:0ns }
<0> [518.101525] gem_exec-2408 20..... 280013726us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:473 active
<0> [518.101644] gem_exec-2408 20..... 280013733us : intel_timeline_pin: 0000:00:02.0 timeline:473 using HWSP offset:faf08000
<0> [518.101755] gem_exec-2408 20..... 280013746us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:473 pin ring:{start:fb0e2000, head:0000, tail:0000}
<0> [518.101870] gem_exec-2408 20..... 280013788us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:473 unpin
<0> [518.101981] gem_exec-2408 20..... 280013879us : __intel_context_retire: 0000:00:02.0 bcs0: context:473 retire runtime: { total:0ns, avg:0ns }
<0> [518.102100] gem_exec-2408 20..... 280013888us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:473 active
<0> [518.102230] gem_exec-2408 20..... 280013893us : intel_timeline_pin: 0000:00:02.0 timeline:473 using HWSP offset:faf08000
<0> [518.102360] gem_exec-2408 20..... 280013905us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:473 pin ring:{start:fb0e2000, head:0000, tail:0000}
<0> [518.102496] gem_exec-2408 20..... 280014102us : __i915_request_commit: 0000:00:02.0 bcs0: fence 473:2, current 0
<0> [518.102624] gem_exec-2408 20D..1. 280014121us : __i915_request_submit: 0000:00:02.0 bcs0: fence 473:2, current 0
<0> [518.102755] gem_exec-2408 20..... 280014300us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:3732, current 3732
<0> [518.102894] gem_exec-2408 20..... 280014355us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:3736, current 3732
<0> [518.103026] gem_exec-2408 20D..1. 280014375us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:3736, current 3732
<0> [518.103146] gem_exec-2398 20..... 280196014us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:4e1 pin ring:{start:fa207000, head:0000, tail:0000}
<7> [518.103128] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508
<0> [518.103280] gem_exec-2398 20..... 280196252us : __i915_request_commit: 0000:00:02.0 vcs1: fence 4e1:2, current 0
<0> [518.103389] gem_exec-2398 20D..1. 280196272us : __i915_request_submit: 0000:00:02.0 vcs1: fence 4e1:2, current 0
<0> [518.103512] gem_exec-2405 20..... 280505520us : intel_timeline_pin: 0000:00:02.0 timeline:59d using HWSP offset:f8831000
<0> [518.103632] gem_exec-2405 20..... 280520634us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:59d pin ring:{start:f89b2000, head:0000, tail:0000}
<0> [518.103751] gem_exec-2405 20..... 280520701us : __intel_context_do_unpin.part.0: 0000:00:02.0 vecs0: context:59d unpin
<0> [518.103861] gem_exec-2405 20..... 280520797us : __intel_context_retire: 0000:00:02.0 vecs0: context:59d retire runtime: { total:0ns, avg:0ns }
<0> [518.103973] gem_exec-2405 20..... 280520810us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:59d active
<0> [518.104091] gem_exec-2405 20..... 280520817us : intel_timeline_pin: 0000:00:02.0 timeline:59d using HWSP offset:f8831000
<0> [518.104203] gem_exec-2405 20..... 280520832us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:59d pin ring:{start:f89b2000, head:0000, tail:0000}
<0> [518.104320] gem_exec-2405 20..... 280520877us : __intel_context_do_unpin.part.0: 0000:00:02.0 vecs0: context:59d unpin
<0> [518.104433] gem_exec-2405 20..... 280520969us : __intel_context_retire: 0000:00:02.0 vecs0: context:59d retire runtime: { total:0ns, avg:0ns }
<0> [518.104545] gem_exec-2405 20..... 280520978us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:59d active
<0> [518.104662] gem_exec-2405 20..... 280520983us : intel_timeline_pin: 0000:00:02.0 timeline:59d using HWSP offset:f8831000
<0> [518.104773] gem_exec-2405 20..... 280520996us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:59d pin ring:{start:f89b2000, head:0000, tail:0000}
<0> [518.104902] gem_exec-2405 20..... 280521206us : __i915_request_commit: 0000:00:02.0 vecs0: fence 59d:2, current 0
<0> [518.105027] gem_exec-2405 20D..1. 280521222us : __i915_request_submit: 0000:00:02.0 vecs0: fence 59d:2, current 0
<0> [518.105154] gem_exec-2405 20..... 280521495us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:6224, current 6222
<0> [518.105295] gem_exec-2405 20D..1. 280521509us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:6224, current 6222
<0> [518.105428] gem_exec-2405 20..... 280521628us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:6222, current 6222
<0> [518.105559] gem_exec-2405 20..... 280521670us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:6226, current 6224
<0> [518.105688] gem_exec-2405 20D..1. 280521681us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:6226, current 6224
<0> [518.105815] gem_exec-2396 20..... 280861973us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:8640, current 8640
<0> [518.105938] gem_exec-2387 20..... 280973116us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:9254, current 9252
<0> [518.106066] gem_exec-2387 20D..1. 280973147us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:9254, current 9252
<0> [518.106194] gem_exec-2387 20..... 280973361us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:9252, current 9254
<7> [518.106098] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508
<0> [518.106318] gem_exec-2387 20..... 280973423us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:9256, current 9254
<0> [518.106440] gem_exec-2387 20D..1. 280973438us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:9256, current 9254
<0> [518.106554] gem_exec-2374 20..... 281350003us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:53c active
<0> [518.106671] gem_exec-2374 20..... 281350274us : intel_timeline_pin: 0000:00:02.0 timeline:53c using HWSP offset:f95e7000
<0> [518.106783] gem_exec-2374 20..... 281350330us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:53c pin ring:{start:f96cf000, head:0160, tail:0160}
<0> [518.106909] gem_exec-2378 20..... 281462070us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:12520, current 12520
<0> [518.107033] gem_exec-2378 20..... 281462157us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:12530, current 12524
<0> [518.107161] gem_exec-2378 20..... 281462180us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:12522, current 12524
<0> [518.107282] gem_exec-2378 20..... 281462186us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:12524, current 12524
<0> [518.107407] gem_exec-2383 20..... 282683058us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:17474, current 17472
<0> [518.107537] gem_exec-2383 20D..1. 282683091us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:17474, current 17472
<0> [518.107665] gem_exec-2365 20..... 284166294us : intel_timeline_pin: 0000:00:02.0 timeline:b52 using HWSP offset:ec97b000
<0> [518.107786] gem_exec-2408 20..... 284300481us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:b75 pin ring:{start:ec4fc000, head:0000, tail:0000}
<0> [518.107904] gem_exec-2408 20..... 284300556us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:b75 unpin
<0> [518.108015] gem_exec-2408 20..... 284300733us : __intel_context_retire: 0000:00:02.0 bcs0: context:b75 retire runtime: { total:0ns, avg:0ns }
<0> [518.108132] gem_exec-2408 20..... 284300747us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:b75 active
<0> [518.108251] gem_exec-2408 20..... 284300756us : intel_timeline_pin: 0000:00:02.0 timeline:b75 using HWSP offset:ec411000
<0> [518.108364] gem_exec-2408 20..... 284300772us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:b75 pin ring:{start:ec4fc000, head:0000, tail:0000}
<0> [518.108492] gem_exec-2380 20..... 284366063us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:28424, current 28426
<0> [518.108625] gem_exec-2380 20..... 284366169us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:28428, current 28426
<0> [518.108753] gem_exec-2380 20D..1. 284366192us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:28428, current 28426
<0> [518.108867] gem_exec-2380 20..... 284366848us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:535 active
<0> [518.108986] gem_exec-2380 20..... 284366863us : intel_timeline_pin: 0000:00:02.0 timeline:535 using HWSP offset:f964a000
<0> [518.109100] gem_exec-2380 20..... 284366888us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:535 pin ring:{start:f97e3000, head:00b0, tail:00b0}
<0> [518.109229] gem_exec-2380 20..... 284367053us : __i915_request_commit: 0000:00:02.0 vcs1: fence 535:4, current 2
<0> [518.109356] gem_exec-2380 20D..1. 284367072us : __i915_request_submit: 0000:00:02.0 vcs1: fence 535:4, current 2
<0> [518.109484] gem_exec-2380 20..... 284367241us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:28426, current 28428
<0> [518.109610] gem_exec-2380 20..... 284367352us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:28430, current 28428
<0> [518.109734] gem_exec-2380 20..... 284367618us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:28428, current 28428
<0> [518.109857] gem_exec-2380 20..... 284367674us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:28432, current 28428
<0> [518.109977] gem_exec-2380 20..... 284367908us : intel_rps_boost: 0000:00:02.0 boost fence:298:6f0e
<0> [518.110100] gem_exec-2380 20..... 284369031us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:28430, current 28432
<0> [518.110225] gem_exec-2380 20..... 284369115us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:28434, current 28432
<0> [518.110354] gem_exec-2380 20D..1. 284369134us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:28434, current 28432
<0> [518.110485] gem_exec-2366 20..... 284434144us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:28856, current 28858
<7> [518.110455] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508
<0> [518.110612] gem_exec-2366 20..... 284434228us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:28864, current 28858
<0> [518.110716] gem_exec-2366 20..... 284434248us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:28858, current 28858
<0> [518.110839] gem_exec-2396 20..... 284808686us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:33100, current 33102
<0> [518.110961] gem_exec-2396 20..... 284808926us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:33134, current 33102
<0> [518.111083] gem_exec-2396 20..... 284808957us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:33102, current 33102
<0> [518.111203] gem_exec-2396 20..... 284809473us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:6a2 active
<0> [518.111328] gem_exec-2396 20..... 284809489us : intel_timeline_pin: 0000:00:02.0 timeline:6a2 using HWSP offset:f668d000
<0> [518.111456] gem_exec-2396 20..... 284809521us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:6a2 pin ring:{start:f68a0000, head:00b0, tail:00b0}
<0> [518.111596] gem_exec-2396 20..... 284809682us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6a2:4, current 2
<0> [518.111725] gem_exec-2396 20D..1. 284809699us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6a2:4, current 2
<0> [518.111848] gem_exec-2396 20..... 284809911us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:33136, current 33102
<0> [518.111969] gem_exec-2396 20..... 284810080us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:33140, current 33102
<0> [518.112092] gem_exec-2396 20..... 284814628us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:33188, current 33155
<0> [518.112215] gem_exec-2396 20..... 284814651us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:33154, current 33157
<0> [518.112338] gem_exec-2396 20..... 284814659us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:33156, current 33158
<0> [518.112460] gem_exec-2396 20..... 284814669us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:33158, current 33158
<0> [518.112583] gem_exec-2369 20..... 285535826us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:43884, current 43834
<0> [518.112705] gem_exec-2369 20..... 285535848us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43834, current 43834
<0> [518.112827] gem_exec-2369 20..... 285536633us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:360 active
<0> [518.112953] gem_exec-2369 20..... 285536651us : intel_timeline_pin: 0000:00:02.0 timeline:360 using HWSP offset:fd3e7000
<0> [518.113073] gem_exec-2369 20..... 285536684us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:360 pin ring:{start:fd4b3000, head:0420, tail:0420}
<0> [518.113198] gem_exec-2369 20..... 285536752us : __intel_context_do_unpin.part.0: 0000:00:02.0 vecs0: context:360 unpin
<0> [518.113315] gem_exec-2369 20.N... 285536930us : __intel_context_retire: 0000:00:02.0 vecs0: context:360 retire runtime: { total:185288ns, avg:35828ns }
<0> [518.113433] gem_exec-2374 20..... 285718915us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:d15 active
<0> [518.113564] gem_exec-2404 20..... 285735203us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46984, current 46958
<7> [518.113561] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508
<0> [518.113694] gem_exec-2404 20..... 285735232us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:46958, current 46958
<0> [518.113802] gem_exec-2403 20..... 285742249us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:47116, current 47067
<0> [518.113924] gem_exec-2403 20D..1. 285742272us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:47116, current 47068
<0> [518.114059] gem_exec-2403 20..... 285742299us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:47068, current 47070
<0> [518.114185] gem_exec-2403 20..... 285742335us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:47070, current 47072
<0> [518.114311] gem_exec-2403 20..... 285742343us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:47072, current 47072
<0> [518.114437] gem_exec-2378 20..... 285911802us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:49712, current 49664
<0> [518.114563] gem_exec-2378 20D..1. 285911832us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:49712, current 49664
<0> [518.114689] gem_exec-2378 20.N... 285912223us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:49664, current 49666
<0> [518.114814] gem_exec-2378 20.N... 285912231us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:49666, current 49666
<0> [518.114937] gem_exec-2408 20..... 286043823us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:51810, current 51795
<0> [518.115061] gem_exec-2408 20D..1. 286043854us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:51810, current 51796
<0> [518.115186] gem_exec-2408 20..... 286043886us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:51794, current 51796
<0> [518.115315] gem_exec-2408 20..... 286043908us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:51796, current 51796
<0> [518.115440] gem_exec-2408 20..... 286045032us : __i915_request_commit: 0000:00:02.0 bcs0: fence 606:10, current 8
<0> [518.115562] gem_exec-2408 20D..1. 286045079us : __i915_request_submit: 0000:00:02.0 bcs0: fence 606:10, current 8
<0> [518.115684] gem_exec-2383 21..... 286045183us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:51846, current 51818
<0> [518.115805] gem_exec-2383 21D..1. 286045243us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:51846, current 51820
<0> [518.115927] gem_exec-2383 21..... 286045328us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:51814, current 51820
<0> [518.116055] gem_exec-2383 21..... 286045337us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:51816, current 51820
<0> [518.116179] gem_exec-2383 21..... 286045357us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:51818, current 51820
<0> [518.116305] gem_exec-2383 21..... 286045371us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:51820, current 51821
<0> [518.116443] gem_exec-2383 21..... 286045381us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:51822, current 51822
<0> [518.116581] gem_exec-2408 20..... 286046245us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:51874, current 51838
<0> [518.116705] gem_exec-2408 20D..1. 286046270us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:51874, current 51838
<0> [518.116827] gem_exec-2408 20..... 286046622us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:51840, current 51840
<0> [518.116951] gem_exec-2383 21..... 286047495us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:51904, current 51844
<7> [518.116882] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508
<0> [518.117082] gem_exec-2408 20..... 286048142us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:51920, current 51846
<0> [518.117186] gem_exec-2391 21..... 286051824us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:51968, current 51911
<0> [518.117308] gem_exec-2391 21..... 286051864us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:51908, current 51913
<0> [518.117431] gem_exec-2391 21..... 286051889us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:51910, current 51914
<0> [518.117553] gem_exec-2391 21..... 286051904us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:51912, current 51915
<0> [518.117674] gem_exec-2391 21..... 286051923us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:51914, current 51916
<0> [518.117796] gem_exec-2391 21..... 286051938us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:51916, current 51916
<0> [518.117918] gem_exec-2383 20..... 286052647us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:51996, current 51928
<0> [518.118040] gem_exec-2391 21..... 286052717us : __intel_context_do_pin_ww: 0000:00:02.0 vcs0: context:652 active
<0> [518.118163] gem_exec-2391 21..... 286052732us : intel_timeline_pin: 0000:00:02.0 timeline:652 using HWSP offset:f71cb000
<0> [518.118281] gem_exec-2391 21..... 286052760us : __intel_context_do_pin_ww: 0000:00:02.0 vcs0: context:652 pin ring:{start:f72c9000, head:02c0, tail:02c0}
<0> [518.118413] gem_exec-2391 21..... 286052918us : __i915_request_commit: 0000:00:02.0 vcs0: fence 652:10, current 8
<0> [518.118540] gem_exec-2391 21D..1. 286052931us : __i915_request_submit: 0000:00:02.0 vcs0: fence 652:10, current 8
<0> [518.118667] gem_exec-2391 21..... 286061446us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:52150, current 52080
<0> [518.118791] gem_exec-2378 21..... 286061905us : i915_request_retire_upto: 0000:00:02.0 rcs0: fence 298:52082, current 52082
<0> [518.118914] gem_exec-2378 21..... 286061907us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52082, current 52082
<0> [518.119039] gem_exec-2378 21..... 286061934us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:52170, current 52082
<0> [518.119167] gem_exec-2391 21.N... 286067654us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:52208, current 52154
<0> [518.119294] gem_exec-2391 21DN.1. 286067681us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:52208, current 52154
<0> [518.119425] gem_exec-2384 21..... 286068665us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:52254, current 52190
<0> [518.119565] gem_exec-2384 21..... 286068682us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52192, current 52192
<0> [518.119688] gem_exec-2376 20..... 286074261us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:52338, current 52292
<0> [518.119809] gem_exec-2376 20D..1. 286074281us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:52338, current 52293
<0> [518.119930] gem_exec-2376 20..... 286074313us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52290, current 52296
<0> [518.120063] gem_exec-2376 20..... 286074326us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52292, current 52296
<7> [518.120069] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508
<0> [518.120202] gem_exec-2376 20..... 286074330us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52294, current 52296
<0> [518.120326] gem_exec-2376 20..... 286074341us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52296, current 52297
<0> [518.120450] gem_exec-2376 20..... 286074343us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52298, current 52298
<0> [518.120581] gem_exec-2408 21..... 286074392us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:52340, current 52298
<0> [518.120703] gem_exec-2408 21D..1. 286074408us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:52340, current 52298
<0> [518.120824] gem_exec-2385 20..... 286075446us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:52364, current 52344
<0> [518.120947] gem_exec-2385 20D..1. 286075465us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:52364, current 52344
<0> [518.121070] gem_exec-2408 21..... 286075635us : __i915_request_commit: 0000:00:02.0 bcs0: fence 3fa:14, current 12
<0> [518.121194] gem_exec-2385 20..... 286075796us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52344, current 52360
<0> [518.121319] gem_exec-2408 21D..1. 286075817us : __i915_request_submit: 0000:00:02.0 bcs0: fence 3fa:14, current 12
<0> [518.121442] gem_exec-2385 20..... 286075867us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52346, current 52360
<0> [518.121565] gem_exec-2385 20..... 286075878us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52348, current 52360
<0> [518.121686] gem_exec-2385 20..... 286075888us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52350, current 52360
<0> [518.121806] gem_exec-2385 20..... 286075899us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52352, current 52360
<0> [518.121926] gem_exec-2385 20..... 286075910us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52354, current 52360
<0> [518.122047] gem_exec-2385 20..... 286075919us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52356, current 52360
<0> [518.122171] gem_exec-2385 20..... 286075928us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52358, current 52360
<0> [518.122300] gem_exec-2385 20..... 286075937us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52360, current 52361
<0> [518.122437] gem_exec-2408 21..... 286079105us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:52462, current 52384
<0> [518.122563] gem_exec-2377 20..... 286088499us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:52596, current 52562
<0> [518.122683] gem_exec-2377 20D..1. 286088543us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:52596, current 52562
<0> [518.122804] gem_exec-2377 20..... 286088895us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52562, current 52568
<0> [518.122925] gem_exec-2377 20..... 286088908us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52564, current 52568
<0> [518.123046] gem_exec-2377 20..... 286088922us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52566, current 52569
<0> [518.123169] gem_exec-2377 20..... 286088929us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52568, current 52569
<0> [518.123298] gem_exec-2377 20..... 286088939us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52570, current 52570
<0> [518.123424] gem_exec-2371 21..... 286089083us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52576, current 52576
<0> [518.123548] gem_exec-2371 21..... 286089151us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:52602, current 52576
<7> [518.123488] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508
<0> [518.123670] gem_exec-2371 21D..1. 286089503us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:52602, current 52592
<0> [518.123774] gem_exec-2371 21..... 286089539us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52578, current 52594
<0> [518.123896] gem_exec-2371 21..... 286089618us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52580, current 52594
<0> [518.124018] gem_exec-2371 21..... 286089627us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52582, current 52594
<0> [518.124139] gem_exec-2371 21..... 286089638us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52584, current 52594
<0> [518.124260] gem_exec-2371 21..... 286089648us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52586, current 52594
<0> [518.124381] gem_exec-2371 21..... 286089658us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52588, current 52594
<0> [518.124501] gem_exec-2371 21..... 286089665us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52590, current 52594
<0> [518.124622] gem_exec-2371 21..... 286089675us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52592, current 52594
<0> [518.124744] gem_exec-2371 21..... 286089683us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52594, current 52594
<0> [518.124865] gem_exec-2371 21..... 286090623us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:52664, current 52600
<0> [518.124985] gem_exec-2389 20..... 286090678us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:52666, current 52602
<0> [518.125104] gem_exec-2389 20..... 286090689us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52602, current 52602
<0> [518.125225] gem_exec-2389 20..... 286090821us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:52668, current 52602
<0> [518.125344] gem_exec-2377 20..... 286093699us : i915_request_retire_upto: 0000:00:02.0 rcs0: fence 298:52604, current 52628
<0> [518.125465] gem_exec-2377 20..... 286093702us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52604, current 52628
<0> [518.125587] gem_exec-2377 20..... 286093758us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:52692, current 52628
<0> [518.125707] gem_exec-2377 20D..1. 286093782us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:52692, current 52628
<0> [518.125828] gem_exec-2377 20..... 286094066us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52606, current 52630
<0> [518.125949] gem_exec-2377 20..... 286094068us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52608, current 52630
<0> [518.126070] gem_exec-2377 20..... 286094072us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52610, current 52630
<0> [518.126191] gem_exec-2377 20..... 286094074us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52612, current 52630
<0> [518.126322] gem_exec-2377 20..... 286094087us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52614, current 52630
<0> [518.126458] gem_exec-2377 20..... 286094092us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52616, current 52630
<0> [518.126594] gem_exec-2377 20..... 286094099us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52618, current 52630
<0> [518.126723] gem_exec-2377 20..... 286094103us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52620, current 52630
<0> [518.126843] gem_exec-2377 20..... 286094106us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52622, current 52630
<0> [518.126964] gem_exec-2377 20..... 286094108us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52624, current 52630
<0> [518.127090] gem_exec-2377 20..... 286094117us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52626, current 52631
<0> [518.127227] gem_exec-2377 20..... 286094119us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52628, current 52631
<0> [518.127363] gem_exec-2377 20..... 286094121us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52630, current 52631
<0> [518.127500] gem_exec-2377 20..... 286094130us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52632, current 52632
<0> [518.127621] gem_exec-2373 21..... 286097936us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:52752, current 52736
<0> [518.127741] gem_exec-2373 21..... 286097952us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52736, current 52736
<0> [518.127861] gem_exec-2403 20..... 286098414us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:52756, current 52738
<0> [518.127981] gem_exec-2389 20..... 286098770us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52744, current 52744
<0> [518.128107] gem_exec-2389 20..... 286098817us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:52774, current 52746
<0> [518.128236] gem_exec-2389 20..... 286098827us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52746, current 52747
<0> [518.128364] gem_exec-2389 20..... 286098839us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52748, current 52748
<0> [518.128488] gem_exec-2392 21..... 286099148us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:52792, current 52756
<0> [518.128608] gem_exec-2389 20..... 286099154us : __i915_request_commit: 0000:00:02.0 rcs0: fence 3e6:14, current 12
<0> [518.128729] gem_exec-2389 20D..1. 286099166us : __i915_request_submit: 0000:00:02.0 rcs0: fence 3e6:14, current 12
<0> [518.128849] gem_exec-2394 20..... 286099738us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:52814, current 52760
<0> [518.128969] gem_exec-2392 21..... 286100143us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 98f:4, current 6
<0> [518.129093] gem_exec-2375 20..... 286100506us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:52846, current 52760
<0> [518.129220] gem_exec-2396 21..... 286100570us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:52848, current 52760
<0> [518.129334] gem_exec-2375 20..... 286100694us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:797 active
<0> [518.129451] gem_exec-2375 20..... 286100703us : intel_timeline_pin: 0000:00:02.0 timeline:797 using HWSP offset:f4746000
<0> [518.129564] gem_exec-2375 20..... 286100725us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:797 pin ring:{start:f48fb000, head:0370, tail:0370}
<0> [518.129690] gem_exec-2396 21..... 286100825us : __i915_request_commit: 0000:00:02.0 bcs0: fence 835:8, current 6
<0> [518.129811] gem_exec-2375 20..... 286100837us : __i915_request_commit: 0000:00:02.0 vecs0: fence 797:12, current 10
<0> [518.129931] gem_exec-2375 20D..1. 286100849us : __i915_request_submit: 0000:00:02.0 vecs0: fence 797:12, current 10
<0> [518.130057] gem_exec-2396 21D..1. 286101003us : __i915_request_submit: 0000:00:02.0 bcs0: fence 835:8, current 6
<0> [518.130193] gem_exec-2375 20..... 286103916us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:52878, current 52804
<0> [518.130329] gem_exec-2396 21..... 286103958us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:52880, current 52804
<0> [518.130450] gem_exec-2375 20..... 286107231us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:52932, current 52858
<0> [518.130570] gem_exec-2375 20D..1. 286107244us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:52932, current 52859
<0> [518.130690] gem_exec-2375 20..... 286107254us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52860, current 52860
<0> [518.130810] gem_exec-2396 21..... 286107437us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:52938, current 52866
<0> [518.130930] gem_exec-2396 21D..1. 286107450us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:52938, current 52867
<0> [518.131050] gem_exec-2396 21..... 286107814us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52868, current 52874
<0> [518.131172] gem_exec-2396 21..... 286107837us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52870, current 52874
<0> [518.131299] gem_exec-2396 21..... 286107841us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52872, current 52874
<0> [518.131427] gem_exec-2396 21..... 286107844us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52874, current 52874
<0> [518.131555] gem_exec-2406 20..... 286108901us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:52972, current 52914
<0> [518.131677] gem_exec-2406 20D..1. 286108913us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:52972, current 52915
<0> [518.131796] gem_exec-2406 20..... 286108923us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52914, current 52916
<0> [518.131916] gem_exec-2406 20..... 286108931us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52916, current 52916
<0> [518.132039] gem_exec-2385 21..... 286108970us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:52974, current 52917
<0> [518.132161] gem_exec-2385 21D..1. 286108982us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:52974, current 52918
<0> [518.132284] gem_exec-2385 21..... 286108991us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52918, current 52918
<0> [518.132406] gem_exec-2385 21..... 286110110us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:53010, current 52958
<0> [518.132525] gem_exec-2368 20..... 286110593us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:53036, current 52974
<0> [518.132646] gem_exec-2396 21..... 286110796us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:53048, current 52980
<0> [518.132765] gem_exec-2396 21..... 286110807us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52980, current 52980
<0> [518.132886] gem_exec-2375 20..... 286111048us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:53060, current 52980
<0> [518.133010] gem_exec-2396 21..... 286111119us : __i915_request_commit: 0000:00:02.0 bcs0: fence a27:6, current 4
<0> [518.133145] gem_exec-2396 21Dn.1. 286111161us : __i915_request_submit: 0000:00:02.0 bcs0: fence a27:6, current 4
<0> [518.133281] gem_exec-2405 20..... 286155906us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:53812, current 53732
<0> [518.133411] gem_exec-2405 20..... 286156532us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:4b6 active
<0> [518.133535] gem_exec-2405 20..... 286156546us : intel_timeline_pin: 0000:00:02.0 timeline:4b6 using HWSP offset:fa6d6000
<0> [518.133648] gem_exec-2405 20..... 286156577us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:4b6 pin ring:{start:fa747000, head:0370, tail:0370}
<0> [518.133774] gem_exec-2405 20..... 286156750us : __i915_request_commit: 0000:00:02.0 vecs0: fence 4b6:12, current 10
<0> [518.133895] gem_exec-2405 20D..1. 286156769us : __i915_request_submit: 0000:00:02.0 vecs0: fence 4b6:12, current 10
<0> [518.134016] gem_exec-2405 20..... 286160814us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:53856, current 53800
<0> [518.134153] gem_exec-2405 20D..1. 286160842us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:53856, current 53802
<0> [518.134288] gem_exec-2405 20..... 286160865us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:53798, current 53803
<0> [518.134417] gem_exec-2405 20..... 286160873us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:53800, current 53804
<0> [518.134543] gem_exec-2405 20..... 286160887us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:53802, current 53804
<0> [518.134666] gem_exec-2405 20..... 286160902us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:53804, current 53804
<0> [518.134787] gem_exec-2389 21..... 286161667us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:53872, current 53810
<0> [518.134910] gem_exec-2389 21D..1. 286161690us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:53872, current 53810
<0> [518.135039] gem_exec-2389 21..... 286161717us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:53812, current 53812
<0> [518.135175] gem_exec-2405 20..... 286163534us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:53904, current 53854
<0> [518.135311] gem_exec-2405 20D..1. 286163547us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:53904, current 53854
<0> [518.135436] gem_exec-2389 21..... 286163683us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:53912, current 53856
<0> [518.135555] gem_exec-2405 20..... 286169910us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:53998, current 53946
<0> [518.135674] gem_exec-2405 20D..1. 286169933us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:53998, current 53946
<0> [518.135794] gem_exec-2405 20..... 286169955us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:53946, current 53946
<0> [518.135915] gem_exec-2389 21..... 286170018us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:54000, current 53948
<0> [518.136041] gem_exec-2389 21D..1. 286170036us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:54000, current 53948
<0> [518.136168] gem_exec-2389 21..... 286170051us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:53948, current 53948
<0> [518.136288] gem_exec-2389 21..... 286172124us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:8f1 active
<0> [518.136409] gem_exec-2389 21..... 286172138us : intel_timeline_pin: 0000:00:02.0 timeline:8f1 using HWSP offset:f1a91000
<0> [518.136524] gem_exec-2389 21..... 286172158us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:8f1 pin ring:{start:f1a92000, head:05a0, tail:05a0}
<0> [518.136639] gem_exec-2389 21..... 286172197us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:8f1 unpin
<0> [518.136751] gem_exec-2389 21.N... 286172390us : __intel_context_retire: 0000:00:02.0 rcs0: context:8f1 retire runtime: { total:630806ns, avg:103721ns }
<0> [518.136882] gem_exec-2387 20..... 286201365us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:54536, current 54480
<0> [518.137013] gem_exec-2387 20D..1. 286201725us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:54536, current 54494
<0> [518.137140] gem_exec-2387 20..... 286201747us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54478, current 54494
<0> [518.137268] gem_exec-2387 20..... 286201763us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54480, current 54494
<0> [518.137393] gem_exec-2387 20..... 286201778us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54482, current 54495
<0> [518.137519] gem_exec-2387 20..... 286201784us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54484, current 54496
<0> [518.137643] gem_exec-2387 20..... 286201789us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54486, current 54496
<0> [518.137767] gem_exec-2387 20..... 286201793us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54488, current 54496
<0> [518.137889] gem_exec-2387 20..... 286201798us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54490, current 54496
<0> [518.138009] gem_exec-2387 20..... 286201802us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54492, current 54496
<0> [518.138129] gem_exec-2387 20..... 286201815us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54494, current 54496
<0> [518.138249] gem_exec-2387 20..... 286201819us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54496, current 54496
<0> [518.138363] gem_exec-2390 21..... 286202978us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:d52 active
<0> [518.138483] gem_exec-2387 20..... 286203425us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:54574, current 54516
<0> [518.138604] gem_exec-2396 21..... 286203773us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:54596, current 54516
<0> [518.138725] gem_exec-2392 20..... 286224236us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54854, current 54854
<0> [518.138846] gem_exec-2392 20..... 286224330us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:54908, current 54858
<0> [518.138967] gem_exec-2392 20..... 286224350us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54856, current 54859
<0> [518.139090] gem_exec-2392 20..... 286224358us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54858, current 54859
<0> [518.139218] gem_exec-2392 20..... 286224363us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54860, current 54860
<0> [518.139346] gem_exec-2392 20..... 286225082us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54862, current 54870
<0> [518.139473] gem_exec-2392 20..... 286225093us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54864, current 54870
<0> [518.139592] gem_exec-2392 20..... 286225102us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54866, current 54870
<0> [518.139714] gem_exec-2392 20..... 286225117us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54868, current 54870
<0> [518.139838] gem_exec-2392 20..... 286225125us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54870, current 54870
<0> [518.139966] gem_exec-2386 21..... 286225612us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:54940, current 54881
<0> [518.140101] gem_exec-2386 21..... 286225624us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54882, current 54882
<0> [518.140232] gem_exec-2386 21..... 286226081us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:9c5 active
<0> [518.140365] gem_exec-2386 21..... 286226096us : intel_timeline_pin: 0000:00:02.0 timeline:9c5 using HWSP offset:efd5e000
<0> [518.140482] gem_exec-2386 21..... 286226120us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:9c5 pin ring:{start:efe58000, head:00b0, tail:00b0}
<0> [518.140612] gem_exec-2386 21..... 286226232us : __i915_request_commit: 0000:00:02.0 vcs1: fence 9c5:4, current 2
<0> [518.140736] gem_exec-2386 21D..1. 286226245us : __i915_request_submit: 0000:00:02.0 vcs1: fence 9c5:4, current 2
<0> [518.140857] gem_exec-2398 20..... 286226835us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:54972, current 54892
<0> [518.140977] gem_exec-2386 21..... 286230678us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54962, current 54962
<0> [518.141099] gem_exec-2386 21..... 286230773us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:55010, current 54968
<0> [518.141219] gem_exec-2386 21..... 286230802us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54964, current 54970
<0> [518.141339] gem_exec-2386 21..... 286230810us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54966, current 54970
<0> [518.141459] gem_exec-2386 21..... 286230819us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54968, current 54971
<0> [518.141580] gem_exec-2386 21..... 286230826us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54970, current 54972
<0> [518.141700] gem_exec-2386 21..... 286230832us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54972, current 54972
<0> [518.141821] gem_exec-2392 20..... 286230871us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:55012, current 54974
<0> [518.141941] gem_exec-2392 20..... 286230881us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54974, current 54975
<0> [518.142066] gem_exec-2392 20..... 286230889us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54976, current 54976
<0> [518.142188] gem_exec-2392 20..... 286231991us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:9bc active
<0> [518.142309] gem_exec-2392 20..... 286231999us : intel_timeline_pin: 0000:00:02.0 timeline:9bc using HWSP offset:efe6b000
<0> [518.142421] gem_exec-2392 20..... 286232013us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:9bc pin ring:{start:eff69000, head:00b0, tail:00b0}
<0> [518.142537] gem_exec-2392 20..... 286232049us : __intel_context_do_unpin.part.0: 0000:00:02.0 vcs1: context:9bc unpin
<0> [518.142649] gem_exec-2392 20..... 286232136us : __intel_context_retire: 0000:00:02.0 vcs1: context:9bc retire runtime: { total:20299ns, avg:20299ns }
<0> [518.142773] gem_exec-2392 20..... 286232144us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:9bc active
<0> [518.142898] gem_exec-2392 20..... 286232148us : intel_timeline_pin: 0000:00:02.0 timeline:9bc using HWSP offset:efe6b000
<0> [518.143013] gem_exec-2392 20..... 286232157us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:9bc pin ring:{start:eff69000, head:00b0, tail:00b0}
<0> [518.143146] gem_exec-2392 20..... 286232250us : __i915_request_commit: 0000:00:02.0 vcs1: fence 9bc:4, current 2
<0> [518.143276] gem_exec-2392 20D..1. 286232262us : __i915_request_submit: 0000:00:02.0 vcs1: fence 9bc:4, current 2
<0> [518.143405] gem_exec-2392 20..... 286232909us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:55066, current 54996
<0> [518.143538] gem_exec-2396 21..... 286236067us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:55090, current 55030
<0> [518.143677] gem_exec-2396 21D..1. 286236086us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:55090, current 55031
<0> [518.143811] gem_exec-2396 21..... 286236101us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55032, current 55032
<0> [518.143940] gem_exec-2367 20..... 286236146us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:55092, current 55035
<0> [518.144060] gem_exec-2367 20D..1. 286236162us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:55092, current 55036
<0> [518.144180] gem_exec-2367 20..... 286236174us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55034, current 55036
<0> [518.144302] gem_exec-2367 20..... 286236178us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55036, current 55036
<0> [518.144415] gem_exec-2396 21..... 286236863us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:863 active
<0> [518.144532] gem_exec-2396 21..... 286236872us : intel_timeline_pin: 0000:00:02.0 timeline:863 using HWSP offset:f2c88000
<0> [518.144644] gem_exec-2396 21..... 286236892us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:863 pin ring:{start:f2c89000, head:02c0, tail:02c0}
<0> [518.144770] gem_exec-2396 21..... 286237009us : __i915_request_commit: 0000:00:02.0 bcs0: fence 863:10, current 8
<0> [518.144891] gem_exec-2396 21D..1. 286237022us : __i915_request_submit: 0000:00:02.0 bcs0: fence 863:10, current 8
<0> [518.145013] gem_exec-2381 20..... 286237346us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:55132, current 55076
<0> [518.145140] gem_exec-2381 20DN.1. 286237367us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:55132, current 55078
<0> [518.145261] gem_exec-2381 20.N... 286237694us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55074, current 55086
<0> [518.145382] gem_exec-2381 20.N... 286237699us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55076, current 55086
<0> [518.145503] gem_exec-2381 20.N... 286237702us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55078, current 55086
<0> [518.145624] gem_exec-2381 20.N... 286237710us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55080, current 55086
<0> [518.145750] gem_exec-2381 20.N... 286237717us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55082, current 55086
<0> [518.145878] gem_exec-2381 20.N... 286237724us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55084, current 55086
<0> [518.146014] gem_exec-2396 21..... 286237963us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:55140, current 55096
<0> [518.146148] gem_exec-2396 21D..1. 286237980us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:55140, current 55096
<0> [518.146272] gem_exec-2367 20..... 286238122us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:55148, current 55096
<0> [518.146396] gem_exec-2367 20D..1. 286238139us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:55148, current 55096
<0> [518.146520] gem_exec-2367 20.N... 286238543us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55098, current 55110
<0> [518.146644] gem_exec-2406 21..... 286240773us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:55226, current 55164
<0> [518.146774] gem_exec-2396 21..... 286249148us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:55274, current 55250
<0> [518.146911] gem_exec-2396 21D..1. 286249161us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:55274, current 55250
<0> [518.147040] gem_exec-2396 21..... 286251227us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:55328, current 55294
<0> [518.147168] gem_exec-2365 20..... 286295727us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:56068, current 56007
<0> [518.147294] gem_exec-2365 20..... 286295751us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56004, current 56008
<0> [518.147420] gem_exec-2365 20..... 286295774us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56006, current 56010
<0> [518.147541] gem_exec-2365 20..... 286295780us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56008, current 56010
<0> [518.147661] gem_exec-2365 20..... 286295786us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56010, current 56010
<0> [518.147776] gem_exec-2384 21..... 286296396us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:6a5 active
<0> [518.147898] gem_exec-2384 21..... 286296412us : intel_timeline_pin: 0000:00:02.0 timeline:6a5 using HWSP offset:f666b000
<0> [518.148011] gem_exec-2384 21..... 286296440us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:6a5 pin ring:{start:f6791000, head:0210, tail:0210}
<0> [518.148126] gem_exec-2384 21..... 286296502us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:6a5 unpin
<0> [518.148236] gem_exec-2384 21..... 286296675us : __intel_context_retire: 0000:00:02.0 bcs0: context:6a5 retire runtime: { total:51569ns, avg:15582ns }
<0> [518.148350] gem_exec-2384 21..... 286296684us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:6a5 active
<0> [518.148466] gem_exec-2384 21..... 286296687us : intel_timeline_pin: 0000:00:02.0 timeline:6a5 using HWSP offset:f666b000
<0> [518.148577] gem_exec-2384 21..... 286296699us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:6a5 pin ring:{start:f6791000, head:0210, tail:0210}
<0> [518.148704] gem_exec-2384 21..... 286296835us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6a5:8, current 6
<0> [518.148830] gem_exec-2384 21D..1. 286296851us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6a5:8, current 6
<0> [518.148959] gem_exec-2393 20..... 286297118us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:56116, current 56038
<0> [518.149083] gem_exec-2374 20..... 286297605us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:56126, current 56038
<0> [518.149206] gem_exec-2384 21..... 286303434us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:56168, current 56136
<0> [518.149334] gem_exec-2384 21D..1. 286303450us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:56168, current 56136
<0> [518.149463] gem_exec-2365 20..... 286304064us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:56176, current 56154
<0> [518.149587] gem_exec-2365 20D..1. 286304085us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:56176, current 56154
<0> [518.149710] gem_exec-2365 20..... 286304100us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56154, current 56154
<0> [518.149828] gem_exec-2365 20..... 286304852us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:329 active
<0> [518.149948] gem_exec-2365 20..... 286304865us : intel_timeline_pin: 0000:00:02.0 timeline:329 using HWSP offset:fda81000
<0> [518.150060] gem_exec-2365 20..... 286304890us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:329 pin ring:{start:fdb2d000, head:0b40, tail:0b40}
<0> [518.150179] gem_exec-2365 20..... 286304934us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:329 unpin
<0> [518.150309] gem_exec-2384 21..... 286305082us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:56210, current 56176
<0> [518.150435] gem_exec-2384 21..... 286305094us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56176, current 56177
<0> [518.150548] gem_exec-2365 20..... 286305100us : __intel_context_retire: 0000:00:02.0 rcs0: context:329 retire runtime: { total:1215184ns, avg:156456ns }
<0> [518.150673] gem_exec-2384 21..... 286305104us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56178, current 56178
<0> [518.150789] gem_exec-2365 20..... 286305108us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:329 active
<0> [518.150913] gem_exec-2365 20..... 286305111us : intel_timeline_pin: 0000:00:02.0 timeline:329 using HWSP offset:fda81000
<0> [518.151031] gem_exec-2365 20..... 286305120us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:329 pin ring:{start:fdb2d000, head:0b40, tail:0b40}
<0> [518.151148] gem_exec-2365 20..... 286305177us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:329 unpin
<0> [518.151258] gem_exec-2365 20.N... 286305362us : __intel_context_retire: 0000:00:02.0 rcs0: context:329 retire runtime: { total:1215184ns, avg:156456ns }
<0> [518.151384] gem_exec-2376 21..... 286311463us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:56316, current 56248
<0> [518.151506] gem_exec-2376 21D..1. 286311477us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:56316, current 56248
<0> [518.151628] gem_exec-2384 20..... 286312633us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56264, current 56268
<0> [518.151756] gem_exec-2384 20..... 286312694us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:56344, current 56268
<0> [518.151877] gem_exec-2384 20D..1. 286312715us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:56344, current 56268
<0> [518.152001] gem_exec-2384 20..... 286313010us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56266, current 56268
<0> [518.152128] gem_exec-2384 20..... 286313015us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56268, current 56268
<0> [518.152245] gem_exec-2384 20..... 286313247us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:562 active
<0> [518.152365] gem_exec-2384 20..... 286313257us : intel_timeline_pin: 0000:00:02.0 timeline:562 using HWSP offset:f9079000
<0> [518.152480] gem_exec-2384 20..... 286313302us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:562 pin ring:{start:f91ed000, head:02c0, tail:02c0}
<0> [518.152618] gem_exec-2384 20..... 286313427us : __i915_request_commit: 0000:00:02.0 bcs0: fence 562:10, current 8
<0> [518.152757] gem_exec-2384 20D..1. 286313440us : __i915_request_submit: 0000:00:02.0 bcs0: fence 562:10, current 8
<0> [518.152887] gem_exec-2388 21..... 286316837us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:56380, current 56338
<0> [518.153014] gem_exec-2388 21D..1. 286316853us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:56380, current 56339
<0> [518.153135] gem_exec-2388 21..... 286316866us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56340, current 56340
<0> [518.153260] gem_exec-2384 20..... 286317495us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:56388, current 56361
<0> [518.153381] gem_exec-2384 20D..1. 286317512us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:56388, current 56362
<0> [518.153501] gem_exec-2384 20..... 286317525us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56360, current 56362
<0> [518.153622] gem_exec-2384 20..... 286317536us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56362, current 56362
<0> [518.153744] gem_exec-2388 21..... 286317880us : __i915_request_commit: 0000:00:02.0 ccs0: fence c67:8, current 6
<0> [518.153869] gem_exec-2388 21D..1. 286317995us : __i915_request_submit: 0000:00:02.0 ccs0: fence c67:8, current 6
<0> [518.153989] gem_exec-2388 21..... 286318601us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:56418, current 56378
<0> [518.154110] gem_exec-2388 21..... 286318613us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56378, current 56378
<0> [518.154233] gem_exec-2373 20..... 286319989us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:56476, current 56411
<0> [518.154354] gem_exec-2373 20..... 286320006us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56410, current 56412
<0> [518.154475] gem_exec-2373 20..... 286320016us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56412, current 56412
<0> [518.154596] gem_exec-2373 20..... 286328190us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:56568, current 56494
<0> [518.154717] gem_exec-2373 20D..1. 286328206us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:56568, current 56496
<0> [518.154843] gem_exec-2373 20..... 286328218us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56496, current 56496
<0> [518.154971] gem_exec-2389 20..... 286330744us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56554, current 56560
<0> [518.155100] gem_exec-2389 20..... 286330814us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:56624, current 56560
<0> [518.155229] gem_exec-2389 20..... 286330828us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56556, current 56560
<0> [518.155358] gem_exec-2389 20..... 286330836us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56558, current 56560
<0> [518.155482] gem_exec-2389 20..... 286330843us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56560, current 56560
<0> [518.155606] gem_exec-2389 20..... 286331145us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 516:8, current 10
<0> [518.155731] gem_exec-2389 20..... 286331212us : __i915_request_commit: 0000:00:02.0 rcs0: fence 516:12, current 10
<0> [518.155867] gem_exec-2389 20D..1. 286331228us : __i915_request_submit: 0000:00:02.0 rcs0: fence 516:12, current 10
<0> [518.155996] gem_exec-2373 20..... 286335172us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:56682, current 56616
<0> [518.156122] gem_exec-2373 20D..1. 286335233us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:56682, current 56616
<0> [518.156242] gem_exec-2373 20..... 286335591us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56618, current 56618
<0> [518.156356] gem_exec-2373 20..... 286335974us : __intel_context_do_pin_ww: 0000:00:02.0 vcs0: context:c77 active
<0> [518.156473] gem_exec-2373 20..... 286335985us : intel_timeline_pin: 0000:00:02.0 timeline:c77 using HWSP offset:ea317000
<0> [518.156585] gem_exec-2373 20..... 286336006us : __intel_context_do_pin_ww: 0000:00:02.0 vcs0: context:c77 pin ring:{start:ea3a6000, head:00b0, tail:00b0}
<0> [518.156710] gem_exec-2373 20..... 286336144us : __i915_request_commit: 0000:00:02.0 vcs0: fence c77:4, current 2
<0> [518.156838] gem_exec-2373 20D..1. 286336160us : __i915_request_submit: 0000:00:02.0 vcs0: fence c77:4, current 2
<0> [518.156966] gem_exec-2403 20..... 286348735us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:56882, current 56866
<0> [518.157095] gem_exec-2403 20D..1. 286348755us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:56882, current 56866
<0> [518.157223] gem_exec-2403 20..... 286348777us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56866, current 56868
<0> [518.157352] gem_exec-2403 20..... 286348787us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56868, current 56868
<0> [518.157480] gem_exec-2406 21..... 286349002us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:56892, current 56874
<0> [518.157609] gem_exec-2406 21D..1. 286349021us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:56892, current 56874
<0> [518.157737] gem_exec-2406 21..... 286349407us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56876, current 56876
<0> [518.157858] gem_exec-2403 20..... 286349644us : __intel_context_do_pin_ww: 0000:00:02.0 vcs0: context:d0e active
<0> [518.157977] gem_exec-2403 20..... 286349653us : intel_timeline_pin: 0000:00:02.0 timeline:d0e using HWSP offset:e8f40000
<0> [518.158089] gem_exec-2403 20..... 286349672us : __intel_context_do_pin_ww: 0000:00:02.0 vcs0: context:d0e pin ring:{start:e8f8e000, head:00b0, tail:00b0}
<0> [518.158215] gem_exec-2406 21..... 286349779us : i915_request_retire.part.0: 0000:00:02.0 ccs0: fence c92:4, current 6
<0> [518.158337] gem_exec-2403 20..... 286349799us : __i915_request_commit: 0000:00:02.0 vcs0: fence d0e:4, current 2
<0> [518.158458] gem_exec-2403 20D..1. 286349814us : __i915_request_submit: 0000:00:02.0 vcs0: fence d0e:4, current 2
<0> [518.158579] gem_exec-2406 21..... 286349839us : __i915_request_commit: 0000:00:02.0 ccs0: fence c92:8, current 6
<0> [518.158706] gem_exec-2406 21D..1. 286350002us : __i915_request_submit: 0000:00:02.0 ccs0: fence c92:8, current 6
<0> [518.158833] gem_exec-2408 20..... 286359532us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57044, current 57030
<0> [518.158955] gem_exec-2408 20D..1. 286359552us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:57044, current 57031
<0> [518.159076] gem_exec-2408 20..... 286359565us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57032, current 57032
<0> [518.159196] gem_exec-2407 20..... 286361041us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57088, current 57068
<0> [518.159316] gem_exec-2407 20D..1. 286361057us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:57088, current 57069
<0> [518.159436] gem_exec-2407 20..... 286361067us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57070, current 57070
<0> [518.159556] gem_exec-2398 21..... 286362159us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57110, current 57096
<0> [518.159684] gem_exec-2398 21..... 286362600us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57126, current 57096
<0> [518.159804] gem_exec-2398 21..... 286369921us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57220, current 57200
<0> [518.159924] gem_exec-2398 21D..1. 286369934us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:57220, current 57201
<0> [518.160044] gem_exec-2398 21..... 286369946us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57200, current 57202
<0> [518.160164] gem_exec-2398 21..... 286369955us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57202, current 57203
<0> [518.160284] gem_exec-2398 21..... 286370222us : __i915_request_commit: 0000:00:02.0 vcs1: fence d0c:4, current 2
<0> [518.160404] gem_exec-2398 21D..1. 286370232us : __i915_request_submit: 0000:00:02.0 vcs1: fence d0c:4, current 2
<0> [518.160522] gem_exec-2399 21..... 286371134us : __i915_request_commit: 0000:00:02.0 vecs0: fence 645:12, current 10
<0> [518.160647] gem_exec-2399 21DN.1. 286371150us : __i915_request_submit: 0000:00:02.0 vecs0: fence 645:12, current 10
<0> [518.160768] gem_exec-2398 20..... 286371531us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57264, current 57246
<0> [518.160889] gem_exec-2367 20..... 286372314us : __i915_request_commit: 0000:00:02.0 vcs0: fence a91:10, current 8
<0> [518.161009] gem_exec-2367 20D..1. 286372329us : __i915_request_submit: 0000:00:02.0 vcs0: fence a91:10, current 8
<0> [518.161128] gem_exec-2369 21..... 286379449us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57366, current 57324
<0> [518.161248] gem_exec-2369 21D..1. 286379463us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:57366, current 57324
<0> [518.161369] gem_exec-2369 21..... 286379474us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57324, current 57324
<0> [518.161490] gem_exec-2367 20..... 286379523us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57368, current 57326
<0> [518.161615] gem_exec-2367 20D..1. 286379537us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:57368, current 57326
<0> [518.161739] gem_exec-2367 20..... 286379548us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57326, current 57327
<0> [518.161860] gem_exec-2367 20..... 286379557us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57328, current 57328
<0> [518.161981] gem_exec-2369 21..... 286380571us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence aa4:8, current 10
<0> [518.162101] gem_exec-2369 21..... 286380632us : __i915_request_commit: 0000:00:02.0 vecs0: fence aa4:12, current 10
<0> [518.162221] gem_exec-2369 21D..1. 286380643us : __i915_request_submit: 0000:00:02.0 vecs0: fence aa4:12, current 10
<0> [518.162341] gem_exec-2367 20..... 286381222us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57408, current 57370
<0> [518.162461] gem_exec-2367 20D..1. 286381236us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:57408, current 57370
<0> [518.162583] gem_exec-2369 21..... 286381508us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57416, current 57374
<0> [518.162711] gem_exec-2369 21..... 286381521us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57374, current 57374
<0> [518.162836] gem_exec-2384 20..... 286382076us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57456, current 57390
<0> [518.162955] gem_exec-2384 20..... 286382089us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57390, current 57391
<0> [518.163075] gem_exec-2369 21..... 286382107us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57392, current 57392
<0> [518.163195] gem_exec-2391 21..... 286382458us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57464, current 57396
<0> [518.163315] gem_exec-2374 20..... 286383065us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57486, current 57398
<0> [518.163435] gem_exec-2374 20..... 286383390us : __i915_request_commit: 0000:00:02.0 vcs1: fence 86d:16, current 14
<0> [518.163554] gem_exec-2374 20D..1. 286383402us : __i915_request_submit: 0000:00:02.0 vcs1: fence 86d:16, current 14
<0> [518.163678] gem_exec-2374 21..... 286388816us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57546, current 57468
<0> [518.163799] gem_exec-2374 21..... 286388829us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57468, current 57468
<0> [518.163919] gem_exec-2374 21..... 286391713us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57582, current 57536
<0> [518.164039] gem_exec-2374 21D..1. 286391727us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:57582, current 57536
<0> [518.164159] gem_exec-2374 21..... 286391739us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57536, current 57536
<0> [518.164279] gem_exec-2385 20..... 286397503us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57662, current 57644
<0> [518.164399] gem_exec-2385 20D..1. 286397514us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:57662, current 57644
<0> [518.164519] gem_exec-2374 21..... 286398232us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57680, current 57666
<0> [518.164647] gem_exec-2374 21D..1. 286398248us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:57680, current 57666
<0> [518.164767] gem_exec-2385 20..... 286398489us : __i915_request_commit: 0000:00:02.0 vcs0: fence 8ce:16, current 14
<0> [518.164887] gem_exec-2385 20D..1. 286398502us : __i915_request_submit: 0000:00:02.0 vcs0: fence 8ce:16, current 14
<0> [518.165006] gem_exec-2374 21..... 286398621us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57668, current 57678
<0> [518.165126] gem_exec-2374 21..... 286398634us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57670, current 57679
<0> [518.165246] gem_exec-2374 21..... 286398646us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57672, current 57680
<0> [518.165366] gem_exec-2374 21..... 286398657us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57674, current 57680
<0> [518.165485] gem_exec-2374 21..... 286398667us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57676, current 57680
<0> [518.165610] gem_exec-2385 20..... 286399112us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57698, current 57686
<0> [518.165732] gem_exec-2374 21..... 286399125us : __i915_request_commit: 0000:00:02.0 vcs1: fence 3f6:8, current 6
<0> [518.165851] gem_exec-2385 20..... 286399126us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57686, current 57686
<0> [518.165971] gem_exec-2374 21D..1. 286399140us : __i915_request_submit: 0000:00:02.0 vcs1: fence 3f6:8, current 6
<0> [518.166090] gem_exec-2408 20..... 286399744us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57722, current 57686
<0> [518.166209] gem_exec-2374 21..... 286399947us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57730, current 57686
<0> [518.166329] gem_exec-2398 20..... 286406158us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57794, current 57764
<0> [518.166449] gem_exec-2398 20D..1. 286406172us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:57794, current 57765
<0> [518.166574] gem_exec-2398 20..... 286406183us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57764, current 57766
<0> [518.166702] gem_exec-2398 20..... 286406193us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57766, current 57767
<0> [518.166830] gem_exec-2398 20..... 286406200us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57768, current 57768
<0> [518.166958] gem_exec-2374 21..... 286407523us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57822, current 57814
<0> [518.167085] gem_exec-2374 21D..1. 286407534us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:57822, current 57816
<0> [518.167212] gem_exec-2374 21..... 286407543us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57816, current 57816
<0> [518.167339] gem_exec-2374 21..... 286407649us : intel_rps_boost: 0000:00:02.0 boost fence:298:e1de
<0> [518.167464] gem_exec-2398 20..... 286408134us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57850, current 57830
<0> [518.167592] gem_exec-2398 20D..1. 286408150us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:57850, current 57831
<0> [518.167719] gem_exec-2398 20..... 286408160us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57830, current 57832
<0> [518.167847] gem_exec-2398 20..... 286408171us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57832, current 57832
<0> [518.167974] gem_exec-2374 21..... 286408386us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57860, current 57832
<0> [518.168095] gem_exec-2374 21..... 286408849us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:2c3 active
<0> [518.168220] gem_exec-2374 21..... 286408859us : intel_timeline_pin: 0000:00:02.0 timeline:2c3 using HWSP offset:fe86d000
<0> [518.168349] gem_exec-2406 20..... 286415642us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57964, current 57942
<0> [518.168477] gem_exec-2406 20D..1. 286415654us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:57964, current 57942
<0> [518.168605] gem_exec-2378 21..... 286416294us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57974, current 57950
<0> [518.168733] gem_exec-2378 21D..1. 286416306us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:57974, current 57951
<0> [518.168861] gem_exec-2378 21..... 286416322us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57952, current 57952
<0> [518.168983] gem_exec-2378 21..... 286416677us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:b29 active
<0> [518.169109] gem_exec-2378 21..... 286416687us : intel_timeline_pin: 0000:00:02.0 timeline:b29 using HWSP offset:ece10000
<0> [518.169228] gem_exec-2378 21..... 286416709us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:b29 pin ring:{start:ecf48000, head:00b0, tail:00b0}
<0> [518.169362] gem_exec-2378 21..... 286416848us : __i915_request_commit: 0000:00:02.0 bcs0: fence b29:4, current 2
<0> [518.169490] gem_exec-2378 21D..1. 286416864us : __i915_request_submit: 0000:00:02.0 bcs0: fence b29:4, current 2
<0> [518.169617] gem_exec-2406 20..... 286417101us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:58008, current 57974
<0> [518.169744] gem_exec-2378 21..... 286419362us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:58076, current 58022
<0> [518.169873] gem_exec-2378 21..... 286419384us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:58018, current 58022
<0> [518.170001] gem_exec-2378 21..... 286419397us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:58020, current 58022
<0> [518.170129] gem_exec-2378 21..... 286419403us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:58022, current 58022
<0> [518.170257] gem_exec-2392 20..... 286420463us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:58100, current 58042
<0> [518.170384] gem_exec-2392 20..... 286420479us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:58038, current 58042
<0> [518.170511] gem_exec-2392 20..... 286420484us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:58040, current 58042
<0> [518.170638] gem_exec-2392 20..... 286420505us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:58042, current 58042
<0> [518.170766] gem_exec-2378 21..... 286420646us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:58110, current 58042
<0> [518.170893] gem_exec-2406 21..... 286425542us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:58192, current 58118
<0> [518.171020] gem_exec-2406 21..... 286426048us : __i915_request_commit: 0000:00:02.0 ccs0: fence 3ee:14, current 12
<0> [518.171146] gem_exec-2406 21D..1. 286426138us : __i915_request_submit: 0000:00:02.0 ccs0: fence 3ee:14, current 12
<0> [518.171272] gem_exec-2376 21..... 286427375us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:58148, current 58148
<0> [518.171400] gem_exec-2376 21..... 286427444us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:58220, current 58151
<0> [518.171527] gem_exec-2376 21..... 286427455us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:58150, current 58152
<0> [518.171656] gem_exec-2376 21..... 286427462us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:58152, current 58152
<0> [518.171784] gem_exec-2378 20..... 286427867us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:58238, current 58162
<0> [518.171911] gem_exec-2371 21..... 286579889us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:60698, current 60669
<0> [518.172038] gem_exec-2371 21D..1. 286579919us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:60698, current 60671
<0> [518.172165] gem_exec-2371 21..... 286580314us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:60668, current 60689
<0> [518.172293] gem_exec-2371 21..... 286580326us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:60670, current 60690
<0> [518.172420] gem_exec-2371 21..... 286580340us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:60672, current 60690
<0> [518.172548] gem_exec-2371 21..... 286580357us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:60674, current 60690
<0> [518.172675] gem_exec-2371 21..... 286580371us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:60676, current 60690
<0> [518.172802] gem_exec-2371 21..... 286580385us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:60678, current 60690
<0> [518.172929] gem_exec-2371 21..... 286580396us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:60680, current 60690
<0> [518.173056] gem_exec-2371 21..... 286580411us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:60682, current 60690
<0> [518.173183] gem_exec-2371 21..... 286580421us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:60684, current 60690
<0> [518.173310] gem_exec-2371 21..... 286580501us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:60686, current 60692
<0> [518.173437] gem_exec-2371 21..... 286580511us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:60688, current 60692
<0> [518.173565] gem_exec-2371 21..... 286580524us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:60690, current 60692
<0> [518.173692] gem_exec-2371 21..... 286580534us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:60692, current 60692
<0> [518.173820] gem_exec-2371 21..... 286581944us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:60736, current 60710
<0> [518.173948] gem_exec-2371 21D..1. 286581972us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:60736, current 60712
<0> [518.174075] gem_exec-2371 21..... 286582367us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:60710, current 60721
<0> [518.174202] gem_exec-2371 21..... 286582387us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:60712, current 60722
<0> [518.174330] gem_exec-2371 21..... 286582403us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:60714, current 60722
<0> [518.174458] gem_exec-2371 21..... 286582414us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:60716, current 60722
<0> [518.174586] gem_exec-2371 21..... 286582421us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:60718, current 60722
<0> [518.174712] gem_exec-2371 21..... 286582431us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:60720, current 60722
<0> [518.174840] gem_exec-2371 21..... 286582443us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:60722, current 60722
<0> [518.174968] gem_exec-2371 21.N... 286583586us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:60802, current 60748
<0> [518.175095] gem_exec-2369 20..... 286676598us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:61666, current 61612
<0> [518.175223] gem_exec-2369 20D..1. 286676627us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:61666, current 61614
<0> [518.175350] gem_exec-2369 20..... 286676649us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:61614, current 61614
<0> [518.175472] gem_exec-2369 20..... 286677695us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:596 active
<0> [518.175598] gem_exec-2369 20..... 286677710us : intel_timeline_pin: 0000:00:02.0 timeline:596 using HWSP offset:f8a40000
<0> [518.175718] gem_exec-2369 20..... 286677739us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:596 pin ring:{start:f8b22000, head:0420, tail:0420}
<0> [518.175853] gem_exec-2369 20..... 286677930us : __i915_request_commit: 0000:00:02.0 vecs0: fence 596:14, current 12
<0> [518.175982] gem_exec-2369 20D..1. 286677949us : __i915_request_submit: 0000:00:02.0 vecs0: fence 596:14, current 12
<0> [518.176111] gem_exec-2385 21..... 286678457us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:61690, current 61670
<0> [518.176239] gem_exec-2385 21D..1. 286678480us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:61690, current 61672
<0> [518.176367] gem_exec-2385 21..... 286678496us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:61670, current 61673
<0> [518.176496] gem_exec-2385 21..... 286678510us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:61672, current 61674
<0> [518.176624] gem_exec-2385 21..... 286678522us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:61674, current 61674
<0> [518.176750] gem_exec-2391 20..... 286679653us : intel_timeline_pin: 0000:00:02.0 timeline:d6d using HWSP offset:e8077000
<0> [518.176878] gem_exec-2404 21..... 286680985us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:61736, current 61700
<0> [518.177007] gem_exec-2404 21D..1. 286681007us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:61736, current 61700
<0> [518.177135] gem_exec-2404 21..... 286681364us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:61702, current 61702
<0> [518.177264] gem_exec-2369 20..... 286682253us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:61754, current 61734
<0> [518.177391] gem_exec-2369 20D..1. 286682299us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:61754, current 61736
<0> [518.177518] gem_exec-2369 20..... 286682316us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:61734, current 61736
<0> [518.177645] gem_exec-2369 20..... 286682378us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:61736, current 61736
<0> [518.177773] gem_exec-2405 21..... 286683209us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:61772, current 61742
<0> [518.177900] gem_exec-2405 21DN.1. 286683226us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:61772, current 61742
<0> [518.178027] gem_exec-2405 21.N... 286683641us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:61742, current 61742
<0> [518.178155] gem_exec-2400 20..... 286684047us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:61748, current 61748
<0> [518.178283] gem_exec-2400 20..... 286684158us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:61790, current 61748
<0> [518.178410] gem_exec-2380 21..... 286684218us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:61792, current 61748
<0> [518.178537] gem_exec-2390 21..... 286690319us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:61890, current 61856
<0> [518.178664] gem_exec-2390 21D..1. 286690346us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:61890, current 61856
<0> [518.178785] gem_exec-2390 21..... 286690952us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:b44 active
<0> [518.178909] gem_exec-2390 21..... 286690964us : intel_timeline_pin: 0000:00:02.0 timeline:b44 using HWSP offset:ecadb000
<0> [518.179028] gem_exec-2390 21..... 286690991us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:b44 pin ring:{start:ecbb6000, head:00b0, tail:00b0}
<0> [518.179161] gem_exec-2390 21..... 286691139us : __i915_request_commit: 0000:00:02.0 bcs0: fence b44:4, current 2
<0> [518.179288] gem_exec-2390 21D..1. 286691312us : __i915_request_submit: 0000:00:02.0 bcs0: fence b44:4, current 2
<0> [518.179418] gem_exec-2402 20..... 286691441us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:61914, current 61858
<0> [518.179539] gem_exec-2402 20..... 286691800us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:52c active
<0> [518.179666] gem_exec-2402 20..... 286691812us : intel_timeline_pin: 0000:00:02.0 timeline:52c using HWSP offset:f98db000
<0> [518.179785] gem_exec-2402 20..... 286691831us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:52c pin ring:{start:f9ac5000, head:0370, tail:0370}
<0> [518.179921] gem_exec-2402 20..... 286691950us : __i915_request_commit: 0000:00:02.0 bcs0: fence 52c:12, current 10
<0> [518.180050] gem_exec-2390 21..... 286691961us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:61938, current 61858
<0> [518.180178] gem_exec-2402 20D..1. 286691962us : __i915_request_submit: 0000:00:02.0 bcs0: fence 52c:12, current 10
<0> [518.180306] gem_exec-2378 20..... 286741957us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:62752, current 62754
<0> [518.180435] gem_exec-2378 20..... 286742126us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:62756, current 62754
<0> [518.180563] gem_exec-2378 20D..1. 286742169us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:62756, current 62754
<0> [518.180691] gem_exec-2388 21..... 286744788us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:62808, current 62774
<0> [518.180811] gem_exec-2388 21..... 286745332us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:a35 active
<0> [518.180935] gem_exec-2388 21..... 286745343us : intel_timeline_pin: 0000:00:02.0 timeline:a35 using HWSP offset:eee88000
<0> [518.181056] gem_exec-2388 21..... 286745367us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:a35 pin ring:{start:eeee9000, head:0168, tail:0168}
<0> [518.181181] gem_exec-2388 21..... 286745421us : __intel_context_do_unpin.part.0: 0000:00:02.0 ccs0: context:a35 unpin
<0> [518.181300] gem_exec-2388 21.N... 286745516us : __intel_context_retire: 0000:00:02.0 ccs0: context:a35 retire runtime: { total:114957ns, avg:114957ns }
<0> [518.181438] gem_exec-2378 20..... 286745749us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:62776, current 62776
<0> [518.181575] gem_exec-2374 20..... 286748327us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:62892, current 62826
<6> [518.181677] [IGT] gem_exec_schedule: finished subtest vcs0, SUCCESS
<0> [518.181697] gem_exec-2374 20..... 286748745us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:8f7 active
<0> [518.181824] gem_exec-2374 20..... 286748755us : intel_timeline_pin: 0000:00:02.0 timeline:8f7 using HWSP offset:f194c000
<0> [518.181958] CPU:11 [LOST 6708 EVENTS]
gem_exec-2407 11..... 286788477us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:63380, current 63384
<0> [518.182098] gem_exec-2407 11..... 286788479us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:63382, current 63384
Created at 2025-08-08 02:38:58